Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2023 07:48
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15248336 kB
MemAvailable 30408440 kB
Buffers 0 kB
Cached 16486700 kB
SwapCached 4556 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-06 07:46:34
DTS_END                         2023-01-06 07:46:34.9060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43970
TRN_ID                          211264
ATT_ID                          639
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8700.83 23607 1044099
customer order: creation 163.18 463 19582
customer order: refuse 13.90 388 1668
order to supplier: creation 43.53 1411 5223
order to supplier: removal 3.49 155559 419
invoice (draft): creation 43.50 3129 5220
invoice (draft): removal 49.43 932 5932
invoice accept: apply 39.71 25000 4765
invoice accept: cancel 44.41 1065 5329
customer reserve: creation 3636.86 241 436423
customer reserve: removal 4388.93 98 526671
realization accept: apply 77.43 267 9291
realization accept: cancel 77.15 161 9258
payment from customer: creation 53.72 180 6446
payment from customer: removal 5.38 119 646
payment to supplier: creation 45.53 188 5464
payment to supplier: removal 3.07 124 368
service: total inventory turnovers 3.82 4135 458
service: total monetary turnovers 7.05 340 846
service: aggregate perf. data 0.72 20522 86
service: refresh index statistics 0.03 257826 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-01-2023 07:48:21 to 06-01-2023 07:48:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3861
TEST_TIME 2 3461
TEST_TIME 3 4056
TEST_TIME 4 5005
TEST_TIME 5 5365
TEST_TIME 6 5222
TEST_TIME 7 5485
TEST_TIME 8 6001
TEST_TIME 9 6286
TEST_TIME 10 6268
TEST_TIME 11 6498
TEST_TIME 12 6391
TEST_TIME 13 6528
TEST_TIME 14 6590
TEST_TIME 15 6535
TEST_TIME 16 6643
TEST_TIME 17 6794
TEST_TIME 18 7063
TEST_TIME 19 7294
TEST_TIME 20 7544
TEST_TIME 21 8002
TEST_TIME 22 7919
TEST_TIME 23 8007
TEST_TIME 24 8245
TEST_TIME 25 8314
TEST_TIME 26 8497
TEST_TIME 27 8758
TEST_TIME 28 9406
TEST_TIME 29 9522
TEST_TIME 30 9862
TEST_TIME 31 10077
TEST_TIME 32 10562
TEST_TIME 33 10761
TEST_TIME 34 10523
TEST_TIME 35 10318
TEST_TIME 36 10157
TEST_TIME 37 10062
TEST_TIME 38 9902
TEST_TIME 39 9724
TEST_TIME 40 9568
TEST_TIME 41 9676
TEST_TIME 42 9690
TEST_TIME 43 9565
TEST_TIME 44 9511
TEST_TIME 45 9464
TEST_TIME 46 9423
TEST_TIME 47 9350
TEST_TIME 48 9196
TEST_TIME 49 9099
TEST_TIME 50 9049
TEST_TIME 51 9097
TEST_TIME 52 9048
TEST_TIME 53 8968
TEST_TIME 54 8884
TEST_TIME 55 8785
TEST_TIME 56 8824
TEST_TIME 57 8759
TEST_TIME 58 8808
TEST_TIME 59 8819
TEST_TIME 60 8822
TEST_TIME 61 8771
TEST_TIME 62 8800
TEST_TIME 63 8857
TEST_TIME 64 8951
TEST_TIME 65 8914
TEST_TIME 66 8904
TEST_TIME 67 9015
TEST_TIME 68 9009
TEST_TIME 69 8970
TEST_TIME 70 8975
TEST_TIME 71 9061
TEST_TIME 72 9049
TEST_TIME 73 8947
TEST_TIME 74 8883
TEST_TIME 75 8877
TEST_TIME 76 8894
TEST_TIME 77 8840
TEST_TIME 78 8782
TEST_TIME 79 8779
TEST_TIME 80 8749
TEST_TIME 81 8741
TEST_TIME 82 8745
TEST_TIME 83 8714
TEST_TIME 84 8685
TEST_TIME 85 8679
TEST_TIME 86 8685
TEST_TIME 87 8664
TEST_TIME 88 8666
TEST_TIME 89 8778
TEST_TIME 90 8866
TEST_TIME 91 8943
TEST_TIME 92 9010
TEST_TIME 93 8951
TEST_TIME 94 8964
TEST_TIME 95 8968
TEST_TIME 96 8941
TEST_TIME 97 8869
TEST_TIME 98 8859
TEST_TIME 99 8926
TEST_TIME 100 8933
TEST_TIME 101 9005
TEST_TIME 102 9009
TEST_TIME 103 9015
TEST_TIME 104 9023
TEST_TIME 105 8995
TEST_TIME 106 8976
TEST_TIME 107 8946
TEST_TIME 108 8887
TEST_TIME 109 8929
TEST_TIME 110 8923
TEST_TIME 111 8882
TEST_TIME 112 8896
TEST_TIME 113 8880
TEST_TIME 114 8846
TEST_TIME 115 8814
TEST_TIME 116 8783
TEST_TIME 117 8762
TEST_TIME 118 8784
TEST_TIME 119 8769
TEST_TIME 120 8717

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-01-2023 07:48:25 to 06-01-2023 07:48:28.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1447133 1447133 0 0.00 0 19124 12 0 0
doc_list_biud 1447132 1447132 0 0.00 0 38517 41 0 0
sp_add_invoice_to_stock 4871 4765 106 2.17 80 189526 25000 0 106
sp_cancel_adding_invoice 5329 5329 0 0.00 6 74187 1066 0 0
sp_cancel_client_order 1668 1668 0 0.00 5 11645 388 0 0
sp_cancel_customer_reserve 526671 526671 0 0.00 1 38519 98 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 4 4252 119 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 4 5612 125 0 0
sp_cancel_supplier_invoice 5943 5932 11 0.18 3 21850 933 0 11
sp_cancel_supplier_order 419 419 0 0.00 15 1547149 155559 0 0
sp_cancel_write_off 9258 9258 0 0.00 2 10296 162 0 0
sp_client_order 19582 19582 0 0.00 5 22083 464 0 0
sp_customer_reserve 436423 436423 0 0.00 0 77262 242 0 0
sp_fill_shopping_cart 30008 30008 0 0.00 1 26568 82 0 0
sp_fill_shopping_cart_clo_res 436400 436400 0 0.00 0 15721 20 0 0
sp_get_clo_for_invoice 4742 4742 0 0.00 0 36719 372 0 0
sp_kill_cost_storno 15601 15601 0 0.00 0 7947 72 0 0
sp_kill_qstorno_ret_qs2qd 1068951 1068951 0 0.00 0 38512 44 0 0
sp_kill_qty_storno 563310 563310 0 0.00 0 38516 98 0 0
sp_lock_selected_doc 19437 19437 0 0.00 0 10535 8 0 0
sp_make_cost_storno 25943 25943 0 0.00 0 19116 78 0 0
sp_multiply_rows_for_pdistr 25943 25943 0 0.00 0 10472 40 0 0
sp_multiply_rows_for_qdistr 19582 19582 0 0.00 0 15579 189 0 0
sp_pay_from_customer 6446 6446 0 0.00 5 23969 180 0 0
sp_pay_to_supplier 5464 5464 0 0.00 6 16628 188 0 0
sp_payment_common 11909 11909 0 0.00 2 13801 52 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 11638 330 0 0
sp_qd_handle_on_invoice_upd_sts 10071 10071 0 0.00 0 15215 485 0 0
sp_qd_handle_on_reserve_upd_sts 9291 9291 0 0.00 0 22362 61 0 0
sp_reserve_write_off 9291 9291 0 0.00 8 24740 268 0 0
sp_supplier_invoice 5220 5220 0 0.00 8 85086 3130 0 0
sp_supplier_order 5223 5223 0 0.00 7 99710 1412 0 0
srv_aggregate_perf_data 86 86 0 0.00 2960 155505 20522 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 909 114459 35958 0 0
srv_find_qd_qs_mism 998778 998778 0 0.00 0 11207 9 0 0
srv_make_invnt_saldo 458 458 0 0.00 73 120041 4135 0 0
srv_make_money_saldo 846 846 0 0.00 1 9184 340 0 0
srv_recalc_idx_stat 4 4 0 0.00 127290 365573 257827 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4765 4765 0 0.00 0 14784 803 0 0
t$perf-norm:sp_cancel_adding_invoice 374 374 0 0.00 1 6947 405 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 4654 31 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 674 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 3005 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 592 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 352 352 0 0.00 0 1072 31 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 88948 6369 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 4794 23 0 0
t$perf-norm:sp_client_order 19582 19582 0 0.00 0 8160 35 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 2529 19 0 0
t$perf-norm:sp_pay_from_customer 6446 6446 0 0.00 0 4956 19 0 0
t$perf-norm:sp_pay_to_supplier 5464 5464 0 0.00 0 4791 21 0 0
t$perf-norm:sp_reserve_write_off 9291 9291 0 0.00 0 3207 20 0 0
t$perf-norm:sp_supplier_invoice 5220 5220 0 0.00 0 3745 30 0 0
t$perf-norm:sp_supplier_order 5223 5223 0 0.00 0 5012 29 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 189 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 247 7 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 805 15 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 769 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 106 42 0 0
v_add_invoice_to_stock 9686 9474 212 2.18 0 10460 22 0 212
v_all_customers 35596 35596 0 0.00 0 179 3 0 0
v_all_suppliers 28912 28912 0 0.00 0 80 4 0 0
v_all_wares 39156 39156 0 0.00 0 64 4 0 0
v_cancel_adding_invoice 748 748 0 0.00 0 224 12 0 0
v_cancel_client_order 3336 3336 0 0.00 0 1954 9 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 1520 11 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 2315 11 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 2328 13 0 0
v_cancel_supplier_invoice 726 704 22 3.03 0 2784 24 0 22
v_cancel_supplier_order 838 838 0 0.00 0 2043 15 0 0
v_cancel_write_off 1884 1884 0 0.00 0 789 22 0 0
v_max_id_clo_ord 5220 5220 0 0.00 0 107 4 0 0
v_max_id_clo_res 930 930 0 0.00 0 1451 12 0 0
v_max_id_ord_sup 5210 5210 0 0.00 0 9341 10 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 58 7 0 0
v_max_non_paid_realizn 3294 3294 0 0.00 0 2433 8 0 0
v_min_id_clo_ord 5220 5220 0 0.00 0 3309 8 0 0
v_min_id_clo_res 930 930 0 0.00 0 1596 10 0 0
v_min_id_ord_sup 5210 5210 0 0.00 0 7285 9 0 0
v_min_non_paid_invoice 5464 5464 0 0.00 0 13775 26 0 0
v_min_non_paid_realizn 6445 6445 0 0.00 0 6646 25 0 0
v_our_firm 9862 9862 0 0.00 0 80 4 0 0
v_reserve_write_off 18582 18582 0 0.00 0 2538 10 0 0
x_lock_depdocs_on_canc_invoice 10909 10909 0 0.00 0 12493 124 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 23228 414 0 0
x_make_qstorno_1000_1200 5223 5223 0 0.00 2 99690 1260 0 0
x_make_qstorno_1200_2000 5220 5220 0 0.00 4 82350 2886 0 0
x_make_qstorno_2100_3300 436423 436423 0 0.00 0 77260 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2023 07:48:29 to 06-01-2023 07:48:33.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:16:38 899 8192000000 8930756704 8988614656 38043792 0.462 7 5 12 0 39242384 271648 926944
05:17:41 15129 8192000000 9709338768 10005192704 790222752 8.797 100 86 172 0 819143936 7535504 21385680
05:19:01 14911 8192000000 9738461776 10044493824 818595072 9.084 100 86 172 0 851585600 11652160 21338368
05:20:18 15727 8192000000 9787704304 10105126912 856846224 9.469 100 83 165 0 891505056 15569744 19089088
05:21:37 8995 8192000000 9844732768 10173116416 902456704 9.923 100 85 160 1 953788752 34201056 17130992
05:22:52 2270 8192000000 9911260576 10250600448 949417888 10.385 100 92 177 0 1025974816 57957856 18599072
05:23:56 2720 8192000000 9955683824 10303442944 981299712 10.697 100 89 172 1 1067762016 69172720 17289584
05:25:01 1480 8192000000 9967378272 10325905408 1008007696 10.956 100 86 166 0 1088887984 64151312 16728976
05:26:05 1025 8192000000 10016613664 10382802944 1024462800 11.115 100 91 182 0 1133295344 90569008 18263536
05:27:08 3410 8192000000 9990077232 10359373824 1037469552 11.240 100 87 162 2 1115934368 61691808 16773008
05:28:14 1025 8192000000 10022420576 10397949952 1056223040 11.420 100 97 189 2 1146499824 70765440 19511344
05:29:16 1058 8192000000 10047320512 10428891136 1068781408 11.540 100 96 184 1 1171449600 83928400 18739792
05:30:18 1403 8192000000 10056717488 10438885376 1069562864 11.548 100 93 180 1 1175025072 87263696 18198512
05:31:21 790 8192000000 10038537392 10426458112 1077755248 11.626 100 85 167 1 1165915728 70871408 17289072
05:32:23 1041 8192000000 10035449424 10416394240 1084859904 11.694 100 94 177 0 1157457872 52965312 19632656
05:33:26 6198 8192000000 10044254704 10438361088 1086632160 11.711 100 66 122 1 1159567440 60753904 12181376
05:34:33 1177 8192000000 10041519840 10427985920 1092659488 11.768 100 95 183 1 1166297264 55344304 18293472
05:35:36 1144 8192000000 10064037408 10453585920 1100268448 11.840 100 97 180 2 1187322864 69167488 17886928
05:36:39 1411 8192000000 10066683184 10460934144 1103832656 11.874 100 85 164 2 1188827328 68762928 16231744
05:37:41 1127 8192000000 10064890144 10455580672 1097530192 11.814 100 97 182 2 1187933168 71019136 19383840
05:38:44 1187 8192000000 10048694624 10440024064 1100223184 11.840 100 91 164 1 1172179264 54646304 17309776
05:39:46 1339 8192000000 10034159712 10421698560 1086467744 11.709 99 97 180 4 1162138640 57038000 18632896
05:40:47 1444 8192000000 10022842368 10405154816 1072856512 11.579 100 75 135 3 1149845376 62590208 14398656
05:41:50 1581 8192000000 10025780512 10401435648 1056776768 11.426 99 91 167 1 1148552784 74313232 17462784
05:42:52 1193 8192000000 9970438480 10337501184 1027312416 11.143 100 98 179 2 1093901600 48071440 18517744
05:43:54 7739 8192000000 9961077248 10338734080 1022993568 11.101 99 66 130 2 1092771024 56600432 13177024
05:45:03 1087 8192000000 9984713168 10355781632 1034882672 11.215 99 93 175 5 1107699696 55053232 17763792
05:46:07 1219 8192000000 9981085472 10358460416 1034424864 11.211 99 80 142 1 1103667776 55143808 14099104
05:47:18 33181 8192000000 9988864064 10373500928 1069319712 11.546 100 50 97 0 1104398992 26140976 8938304
05:48:56 2725 8192000000 10049872976 10428964864 1074071616 11.591 99 76 146 2 1166899968 78164624 14663728
05:50:02 3578 8192000000 10105310368 10497335296 1098515424 11.824 100 72 136 0 1225197840 112667904 14014512
05:51:09 1746 8192000000 10091769392 10480631808 1107582848 11.910 100 72 132 1 1209380976 89240736 12557392
05:52:14 6312 8192000000 10123944944 10519715840 1118729392 12.015 100 62 115 0 1234952896 104245024 11978480
05:53:23 12638 8192000000 10146158896 10546511872 1135668464 12.175 100 69 136 0 1262108176 112053744 14385968
05:54:43 952 8192000000 10204201216 10598297600 1150924896 12.318 100 87 160 2 1317962896 150609376 16428624
05:55:48 1298 8192000000 10232933520 10632384512 1153482080 12.342 100 88 161 0 1348201440 177110240 17609120
05:56:54 10391 8192000000 10229320992 10645209088 1157552176 12.380 100 67 129 0 1339556208 167772160 14231872
05:58:09 2659 8192000000 10260968416 10660442112 1150673392 12.316 99 74 142 1 1363745360 196124224 16947744
05:59:14 899 8192000000 10319691088 10721492992 1175100832 12.544 100 97 193 0 1430313088 233272800 21939456
06:00:20 2052 8192000000 10328009504 10746417152 1188027856 12.665 100 82 161 1 1447737600 240021680 19688064
06:01:29 1059 8192000000 10368534480 10781458432 1185235888 12.639 100 94 186 1 1476294816 268057504 23001424
06:02:33 1355 8192000000 10405280160 10814799872 1191864560 12.701 100 95 187 0 1514661840 300871760 21925520
06:03:39 1031 8192000000 10408618624 10839871488 1197417888 12.752 100 97 194 0 1516123952 296490976 22215088
06:04:45 1921 8192000000 10397933952 10838269952 1179528976 12.586 99 91 176 0 1506330768 304862320 21939472
06:05:49 903 8192000000 10433179936 10883223552 1195990960 12.739 100 97 188 0 1536712704 318322752 22398992
06:06:54 1210 8192000000 10427565216 10866765824 1204350288 12.817 100 68 134 1 1534968352 313663568 16954496
06:07:57 6417 8192000000 10420156992 10851008512 1205424048 12.827 100 48 92 1 1531408288 313836976 12147264
06:09:07 1065 8192000000 10429961696 10855251968 1190959632 12.692 99 75 144 0 1533932256 325888656 17083968
06:10:10 5799 8192000000 10476499872 10912780288 1190969168 12.692 100 50 98 1 1576678320 373123248 12585904
06:11:28 15438 8192000000 10452106928 10905436160 1167474144 12.473 99 36 72 0 1555529136 378231072 9823920
06:12:49 6658 8192000000 10422717552 10856648704 1146964992 12.281 99 56 109 0 1520184480 358697536 14521952
06:13:58 8320 8192000000 10322511376 10816745472 1148616848 12.297 99 46 85 0 1424916976 264721456 11578672
06:15:11 15778 8192000000 10301488176 10767302656 1152648144 12.334 100 33 64 0 1392754928 232387280 7719504
06:16:29 45331 8192000000 10227320416 10666610688 1146608624 12.278 99 26 50 0 1336266096 183112160 6545312
06:18:47 71531 8192000000 10058462720 10524991488 1085129536 11.696 95 11 22 0 1199733552 112253792 2350224
06:20:59 67755 8192000000 10068977072 10498363392 1081649808 11.663 95 44 80 0 1131099952 40353664 9096480
06:23:08 55087 8192000000 10082001968 10535718912 1134885632 12.167 100 31 56 1 1173626400 33009424 5731344
06:25:05 59422 8192000000 10126876544 10574643200 1136970528 12.187 99 17 34 0 1214331456 73233696 4127232
06:27:18 92142 8192000000 10089353184 10562854912 1118795216 12.016 98 22 44 0 1207760400 84131392 4833792
06:30:01 90808 8192000000 10038225296 10490380288 1041637232 11.280 92 15 30 0 1106164400 61153984 3373184
06:32:49 62041 8192000000 10034828144 10502168576 1104400416 11.879 100 17 35 1 1168903696 60441648 4061632
06:34:52 40623 8192000000 10052845280 10504916992 1097763296 11.816 99 23 46 0 1150332848 47674528 4895024
06:36:36 33943 8192000000 10086188224 10522427392 1123836928 12.063 100 36 65 1 1181877744 51156864 6883952
06:38:11 56586 8192000000 10041224688 10533666816 1092829680 11.770 97 6 13 0 1124860864 30897392 1133792
06:40:14 70721 8192000000 10069153456 10525958144 1121751856 12.044 99 16 30 0 1175639392 50328624 3558912
06:42:25 95694 8192000000 10060232608 10501267456 1074225584 11.592 95 8 13 0 1127370944 51934256 1211104
06:45:06 54122 8192000000 10057539200 10487902208 1097559920 11.814 98 21 42 1 1132194576 30628336 4006320
06:47:02 31101 8192000000 10081820384 10518626304 1132812368 12.148 100 32 64 0 1168671056 30061568 5797120
06:48:37 64688 8192000000 10130113952 10584387584 1134547488 12.164 99 31 61 0 1220200464 79663696 5989280
06:50:50 49896 8192000000 10092483648 10528706560 1115223104 11.982 97 31 62 0 1166673968 44988512 6462352
06:52:42 28681 8192000000 10139043152 10595991552 1134951584 12.168 99 46 90 1 1222527680 78613232 8962864
06:54:22 38624 8192000000 10094283280 10528923648 1129197184 12.114 99 34 67 1 1175045280 39276976 6571120
06:56:01 14167 8192000000 10173724224 10593353728 1145487616 12.267 100 77 146 2 1266768064 105323312 15957136
06:57:17 101105 8192000000 10064440320 10570407936 1093157280 11.773 96 15 30 1 1163251072 67226048 2867744
07:00:17 5366 8192000000 10063857264 10483511296 1120103392 12.028 100 52 97 1 1166795392 36130848 10561152
07:01:22 51851 8192000000 10062811696 10573324288 1127527216 12.098 100 9 18 0 1203340432 73461120 2352096
07:03:20 55546 8192000000 10055584592 10495623168 1099695264 11.835 98 21 40 1 1141020576 36823008 4502304
07:05:32 65932 8192000000 10057151664 10530504704 1092215472 11.764 97 13 26 1 1135334720 40091328 3027920
07:07:46 37850 8192000000 10112661216 10548879360 1129689968 12.118 100 22 42 0 1203579760 69291008 4598784
07:09:35 19002 8192000000 10141673056 10574299136 1130198576 12.123 100 37 74 0 1242082368 103647088 8236704
07:10:59 10608 8192000000 10202175952 10626916352 1146236864 12.274 100 50 98 0 1300735136 143194992 11303280
07:12:10 58896 8192000000 10231593536 10670256128 1103892272 11.875 96 37 74 0 1287211296 174916288 8402736
07:14:10 28522 8192000000 10270391072 10707378176 1129206000 12.114 99 44 88 0 1336348672 197164608 9978064
07:15:40 39580 8192000000 10172280512 10686173184 1116081648 11.990 98 23 46 0 1278245008 156974320 5189040
07:17:20 63092 8192000000 10063039392 10526892032 1050032944 11.361 93 19 37 0 1169798544 115857696 3907904
07:19:24 58200 8192000000 10065482576 10530127872 1094646240 11.787 98 28 56 1 1142747088 42398656 5702192
07:21:22 60415 8192000000 10077231520 10510934016 1101930128 11.856 99 19 35 0 1149999120 44568160 3500832
07:23:29 23109 8192000000 10129239328 10558533632 1122063552 12.046 99 56 114 0 1209045472 75086720 11895200
07:24:52 39989 8192000000 10180028512 10651267072 1123800192 12.063 99 44 88 0 1256503680 123987472 8716016
07:26:33 51977 8192000000 10085470768 10599079936 1082453344 11.671 96 20 40 0 1170429888 83651232 4325312
07:29:11 48449 8192000000 10078311504 10526543872 1123709024 12.062 100 20 40 0 1172899888 45155072 4035792
07:31:43 7429 8192000000 10110119680 10524176384 1130110976 12.122 100 68 131 4 1191258512 48672192 12475344
07:32:53 114459 8192000000 10133376304 10599759872 1137426720 12.191 100 27 53 0 1227146944 84069984 5650240
07:35:48 62946 8192000000 10104128288 10584072192 1144565344 12.258 100 19 36 0 1189942064 40788560 4588160
07:38:01 78490 8192000000 10092128784 10584072192 1132267712 12.143 99 23 45 0 1190993600 53898704 4827184
07:40:21 40949 8192000000 10082542992 10549313536 1148621488 12.297 100 11 22 0 1194580848 43822576 2136784
07:42:03 64038 8192000000 10124452576 10587578368 1158150224 12.386 100 19 38 0 1227231312 64827744 4253344
07:44:22 60694 8192000000 10077997600 10573623296 1108044432 11.914 97 11 20 0 1152283952 42048000 2191520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 06-01-2023 07:48:35 to 06-01-2023 07:48:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2023 07:48:53 to 06-01-2023 07:48:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          210002
MON$OLDEST_ACTIVE               212541
MON$OLDEST_SNAPSHOT             212541
MON$NEXT_TRANSACTION            212541
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2044384
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2886 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2886 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Jan  6 07:46:37 2023
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Jan  6 07:46:38 2023
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Jan  6 07:46:40 2023
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Jan  6 07:46:42 2023
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

06.01.23 07:48:56. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-06 07:48:58 No severe PSQL-related problems occured
06.01.23 07:49:00. End of report.