Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.04.2022 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 16408420 kB
MemAvailable 31614292 kB
Buffers 0 kB
Cached 15330000 kB
SwapCached 2224 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.2
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 2022-04-04 07:46:56
DTS_END                         2022-04-04 07:46:56.6030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34766
TRN_ID                          211704
ATT_ID                          531
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: 8760.98 25373 1051318
customer order: creation 165.19 499 19823
customer order: refuse 13.46 421 1615
order to supplier: creation 42.48 1547 5097
order to supplier: removal 3.42 165453 410
invoice (draft): creation 43.11 3698 5173
invoice (draft): removal 50.65 1080 6078
invoice accept: apply 39.51 27735 4741
invoice accept: cancel 45.53 1271 5464
customer reserve: creation 3645.63 264 437476
customer reserve: removal 4433.67 99 532040
realization accept: apply 78.59 295 9431
realization accept: cancel 77.98 167 9358
payment from customer: creation 56.68 180 6801
payment from customer: removal 5.31 142 637
payment to supplier: creation 44.63 202 5355
payment to supplier: removal 3.33 127 400
service: total inventory turnovers 3.75 4279 450
service: total monetary turnovers 7.18 398 862
service: aggregate perf. data 0.85 16129 102
service: refresh index statistics 0.04 283474 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-04-2022 07:48:45 to 04-04-2022 07:48:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7647
TEST_TIME 2 6324
TEST_TIME 3 5523
TEST_TIME 4 5620
TEST_TIME 5 5603
TEST_TIME 6 5555
TEST_TIME 7 5528
TEST_TIME 8 5393
TEST_TIME 9 5600
TEST_TIME 10 5501
TEST_TIME 11 5641
TEST_TIME 12 5661
TEST_TIME 13 5977
TEST_TIME 14 6028
TEST_TIME 15 6138
TEST_TIME 16 6302
TEST_TIME 17 6452
TEST_TIME 18 7131
TEST_TIME 19 7271
TEST_TIME 20 7359
TEST_TIME 21 7374
TEST_TIME 22 7718
TEST_TIME 23 7906
TEST_TIME 24 7784
TEST_TIME 25 7792
TEST_TIME 26 8022
TEST_TIME 27 7992
TEST_TIME 28 8223
TEST_TIME 29 8365
TEST_TIME 30 8832
TEST_TIME 31 9240
TEST_TIME 32 9202
TEST_TIME 33 9531
TEST_TIME 34 9699
TEST_TIME 35 9797
TEST_TIME 36 10419
TEST_TIME 37 10529
TEST_TIME 38 10584
TEST_TIME 39 10414
TEST_TIME 40 10220
TEST_TIME 41 10062
TEST_TIME 42 9874
TEST_TIME 43 9840
TEST_TIME 44 9753
TEST_TIME 45 9717
TEST_TIME 46 9708
TEST_TIME 47 9701
TEST_TIME 48 9636
TEST_TIME 49 9521
TEST_TIME 50 9473
TEST_TIME 51 9579
TEST_TIME 52 9411
TEST_TIME 53 9405
TEST_TIME 54 9368
TEST_TIME 55 9273
TEST_TIME 56 9182
TEST_TIME 57 9142
TEST_TIME 58 9050
TEST_TIME 59 8991
TEST_TIME 60 8989
TEST_TIME 61 9004
TEST_TIME 62 8993
TEST_TIME 63 8930
TEST_TIME 64 8878
TEST_TIME 65 8877
TEST_TIME 66 8958
TEST_TIME 67 9148
TEST_TIME 68 9172
TEST_TIME 69 9164
TEST_TIME 70 9193
TEST_TIME 71 9237
TEST_TIME 72 9206
TEST_TIME 73 9190
TEST_TIME 74 9156
TEST_TIME 75 9113
TEST_TIME 76 9055
TEST_TIME 77 8974
TEST_TIME 78 8990
TEST_TIME 79 8994
TEST_TIME 80 8938
TEST_TIME 81 8901
TEST_TIME 82 8894
TEST_TIME 83 8878
TEST_TIME 84 8852
TEST_TIME 85 8820
TEST_TIME 86 8803
TEST_TIME 87 8848
TEST_TIME 88 8898
TEST_TIME 89 9031
TEST_TIME 90 9001
TEST_TIME 91 9010
TEST_TIME 92 9029
TEST_TIME 93 8949
TEST_TIME 94 8935
TEST_TIME 95 8881
TEST_TIME 96 8810
TEST_TIME 97 8801
TEST_TIME 98 8812
TEST_TIME 99 8911
TEST_TIME 100 8884
TEST_TIME 101 8876
TEST_TIME 102 8870
TEST_TIME 103 8857
TEST_TIME 104 8844
TEST_TIME 105 8871
TEST_TIME 106 8896
TEST_TIME 107 8880
TEST_TIME 108 8961
TEST_TIME 109 8974
TEST_TIME 110 8955
TEST_TIME 111 8952
TEST_TIME 112 8905
TEST_TIME 113 8875
TEST_TIME 114 8850
TEST_TIME 115 8815
TEST_TIME 116 8781
TEST_TIME 117 8749
TEST_TIME 118 8760
TEST_TIME 119 8815
TEST_TIME 120 8793

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-04-2022 07:48:48 to 04-04-2022 07:48:52.

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 1455538 1455538 0 0.00 0 23082 14 0 0
doc_list_biud 1455526 1455526 0 0.00 0 49469 43 0 0
sp_add_invoice_to_stock 4836 4741 95 1.96 67 238000 27736 0 95
sp_cancel_adding_invoice 5464 5464 0 0.00 4 73624 1272 0 0
sp_cancel_client_order 1615 1615 0 0.00 6 8508 421 0 0
sp_cancel_customer_reserve 532040 532040 0 0.00 1 42031 100 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 4 7022 142 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 3917 127 0 0
sp_cancel_supplier_invoice 6083 6078 5 0.08 2 21308 1081 0 5
sp_cancel_supplier_order 410 410 0 0.00 10 1523530 165453 0 0
sp_cancel_write_off 9358 9358 0 0.00 2 8677 167 0 0
sp_client_order 19823 19823 0 0.00 6 29928 500 0 0
sp_customer_reserve 437476 437476 0 0.00 1 53050 265 0 0
sp_fill_shopping_cart 30078 30078 0 0.00 1 19211 90 0 0
sp_fill_shopping_cart_clo_res 437445 437445 0 0.00 0 13114 20 0 0
sp_get_clo_for_invoice 4696 4696 0 0.00 0 45597 322 0 0
sp_kill_cost_storno 15859 15859 0 0.00 0 23072 76 0 0
sp_kill_qstorno_ret_qs2qd 1079926 1079926 0 0.00 0 42026 44 0 0
sp_kill_qty_storno 569087 569087 0 0.00 0 49467 102 0 0
sp_lock_selected_doc 19536 19536 0 0.00 0 9977 7 0 0
sp_make_cost_storno 26281 26281 0 0.00 0 20707 85 0 0
sp_multiply_rows_for_pdistr 26280 26280 0 0.00 0 9948 40 0 0
sp_multiply_rows_for_qdistr 19823 19823 0 0.00 0 21059 181 0 0
sp_pay_from_customer 6801 6801 0 0.00 6 13983 180 0 0
sp_pay_to_supplier 5355 5355 0 0.00 6 8481 203 0 0
sp_payment_common 12155 12155 0 0.00 2 6125 46 0 0
sp_qd_handle_on_cancel_clo 1615 1615 0 0.00 0 8485 344 0 0
sp_qd_handle_on_invoice_upd_sts 10155 10155 0 0.00 0 49465 555 0 0
sp_qd_handle_on_reserve_upd_sts 9431 9431 0 0.00 0 10327 66 0 0
sp_reserve_write_off 9431 9431 0 0.00 9 20747 296 0 0
sp_supplier_invoice 5173 5173 0 0.00 10 114167 3698 0 0
sp_supplier_order 5097 5097 0 0.00 9 41696 1548 0 0
srv_aggregate_perf_data 102 102 0 0.00 2549 197468 16129 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 743 140644 37284 0 0
srv_find_qd_qs_mism 1005727 1005727 0 0.00 0 17339 11 0 0
srv_make_invnt_saldo 450 450 0 0.00 70 50232 4280 0 0
srv_make_money_saldo 862 862 0 0.00 1 20105 398 0 0
srv_recalc_idx_stat 5 5 0 0.00 59252 431418 283475 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4747 4747 0 0.00 0 16414 1031 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 6967 529 0 0
t$perf-norm:sp_cancel_client_order 1615 1615 0 0.00 0 7737 37 0 0
t$perf-norm:sp_cancel_customer_reserve 600 600 0 0.00 0 1591 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 593 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 995 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1095 36 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 96856 7213 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 2063 21 0 0
t$perf-norm:sp_client_order 19823 19823 0 0.00 0 13701 43 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 724 15 0 0
t$perf-norm:sp_pay_from_customer 6801 6801 0 0.00 0 8469 24 0 0
t$perf-norm:sp_pay_to_supplier 5355 5355 0 0.00 0 6229 26 0 0
t$perf-norm:sp_reserve_write_off 9431 9431 0 0.00 0 11577 31 0 0
t$perf-norm:sp_supplier_invoice 5173 5173 0 0.00 0 10613 40 0 0
t$perf-norm:sp_supplier_order 5098 5098 0 0.00 0 4256 36 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 409 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 531 23 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 2327 29 0 0
t$perf-norm:srv_make_money_saldo 862 862 0 0.00 0 1133 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 189 50 0 0
v_add_invoice_to_stock 9568 9378 190 1.98 0 8788 27 0 190
v_all_customers 36342 36342 0 0.00 0 103 4 0 0
v_all_suppliers 28364 28364 0 0.00 0 101 4 0 0
v_all_wares 39636 39636 0 0.00 0 133 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 372 16 0 0
v_cancel_client_order 3230 3230 0 0.00 0 3965 11 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 6985 21 0 0
v_cancel_customer_reserve 1200 1200 0 0.00 0 2899 11 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 389 10 0 0
v_cancel_supplier_invoice 846 836 10 1.18 0 2837 29 0 10
v_cancel_supplier_order 820 820 0 0.00 0 2088 13 0 0
v_cancel_write_off 1912 1912 0 0.00 0 3355 27 0 0
v_max_id_clo_ord 5094 5094 0 0.00 0 5292 8 0 0
v_max_id_clo_res 954 954 0 0.00 0 3131 21 0 0
v_max_id_ord_sup 5164 5164 0 0.00 0 4682 8 0 0
v_max_non_paid_invoice 1430 1430 0 0.00 0 924 9 0 0
v_max_non_paid_realizn 3391 3391 0 0.00 0 529 7 0 0
v_min_id_clo_ord 5094 5094 0 0.00 0 3925 11 0 0
v_min_id_clo_res 954 954 0 0.00 0 6434 20 0 0
v_min_id_ord_sup 5164 5164 0 0.00 0 7015 12 0 0
v_min_non_paid_invoice 5354 5354 0 0.00 0 6121 16 0 0
v_min_non_paid_realizn 6800 6800 0 0.00 0 3950 17 0 0
v_our_firm 10110 10110 0 0.00 0 224 5 0 0
v_reserve_write_off 18861 18861 0 0.00 0 5638 11 0 0
x_lock_depdocs_on_canc_invoice 11124 11124 0 0.00 0 19169 144 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 5984 368 0 0
x_make_qstorno_1000_1200 5096 5096 0 0.00 2 41682 1367 0 0
x_make_qstorno_1200_2000 5173 5173 0 0.00 5 113908 3445 0 0
x_make_qstorno_2100_3300 437475 437475 0 0.00 0 49392 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-04-2022 07:48:53 to 04-04-2022 07:48:57.

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:17:00 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:01 36201 8192000000 9712258704 10005884928 788396608 8.779 100 94 187 0 820225120 7946704 23881808
05:19:42 19143 8192000000 9754416224 10063036416 832194128 9.221 100 85 170 0 865165920 12156224 20815568
05:21:05 15069 8192000000 9794576688 10116313088 864727536 9.547 100 80 160 0 906847728 23919296 18200896
05:22:24 11452 8192000000 9870375904 10209239040 921915968 10.115 100 71 142 1 982894144 45965376 15012800
05:23:39 4604 8192000000 9929200352 10276438016 973734896 10.623 100 83 163 1 1043710640 53037616 16938128
05:24:49 2882 8192000000 9991189376 10349297664 1003965264 10.917 100 92 181 0 1104281296 81547776 18768256
05:25:54 1581 8192000000 10012954512 10376036352 1032689088 11.194 100 97 189 0 1130271376 78614688 18967600
05:26:58 922 8192000000 10038678896 10408620032 1050101264 11.362 100 95 189 1 1157360336 88477392 18781680
05:28:00 1572 8192000000 10035470560 10412974080 1059426656 11.451 100 95 189 1 1151259344 72318848 19513840
05:29:03 10198 8192000000 10024231248 10405736448 1073886320 11.589 100 74 138 2 1145942304 59336992 12718992
05:30:14 1067 8192000000 10048714960 10430386176 1075847792 11.608 100 92 180 0 1169892000 76432480 17611728
05:31:16 1172 8192000000 10051568256 10436341760 1085745344 11.702 100 94 182 2 1170579440 66623296 18210800
05:32:18 1212 8192000000 10061247232 10451562496 1087631696 11.720 100 83 166 1 1180360080 77577472 15150912
05:33:20 1895 8192000000 10044880656 10440519680 1089508768 11.738 100 76 143 0 1165331296 61723888 14098640
05:34:22 1123 8192000000 10057562512 10451546112 1095176544 11.792 100 87 162 0 1176911520 65045872 16689104
05:35:24 1189 8192000000 10045580688 10436923392 1100185712 11.839 100 86 162 2 1169725520 52360832 17178976
05:36:26 1207 8192000000 10067803264 10459688960 1098375904 11.822 100 97 184 0 1188700224 72561360 17762960
05:37:28 1046 8192000000 10063976640 10455900160 1098318816 11.822 100 93 182 1 1187550416 69611552 19620048
05:38:30 1051 8192000000 10078483152 10476441600 1092011472 11.762 100 84 163 1 1200211392 90678608 17521312
05:39:32 1187 8192000000 10067024288 10453397504 1094247264 11.783 100 96 183 1 1186584016 73387296 18949456
05:40:36 3392 8192000000 10023385408 10415460352 1062223808 11.478 100 70 139 0 1145230112 69190304 13816000
05:41:41 4583 8192000000 9993961136 10373468160 1032953712 11.197 98 58 107 1 1113339152 69632656 10752784
05:42:47 1372 8192000000 9976189600 10354147328 1041552736 11.280 100 78 144 1 1101557376 45694576 14310064
05:43:48 4946 8192000000 9977249104 10362621952 1032636304 11.194 98 57 103 1 1094114432 50492656 10985472
05:44:54 1469 8192000000 9985192336 10355294208 1027034576 11.140 99 74 141 0 1103593104 60433008 16125520
05:45:56 5343 8192000000 10003396288 10374643712 1044227360 11.305 100 83 150 3 1115680912 57747264 13706288
05:47:03 1525 8192000000 10017995760 10397163520 1046475440 11.327 100 91 164 3 1137315488 73898128 16941920
05:48:13 1771 8192000000 10018819472 10399604736 1070715296 11.559 100 94 176 1 1139630624 51596000 17319328
05:49:21 4112 8192000000 10041915904 10428284928 1082529824 11.672 100 74 146 2 1158516144 59973456 16012864
05:50:31 2259 8192000000 10054366336 10433372160 1093771776 11.779 100 88 163 3 1174057776 62270400 18015600
05:51:37 13757 8192000000 10083279408 10478419968 1093131008 11.772 100 63 125 0 1202795536 96221936 13442592
05:53:00 10209 8192000000 10114295136 10511728640 1105275360 11.888 100 59 109 1 1229846752 113235616 11335776
05:54:20 14235 8192000000 10146310048 10547621888 1117962384 12.008 100 67 129 1 1255927776 124238944 13726448
05:55:38 792 8192000000 10180897872 10585227264 1133359008 12.153 100 82 151 5 1297594992 148471168 15764816
05:56:45 3961 8192000000 10213888048 10611953664 1143484752 12.248 100 84 165 0 1326240208 164819600 17935856
05:57:55 12024 8192000000 10251550608 10649063424 1145481536 12.267 100 77 152 1 1358895584 196548992 16865056
05:59:20 1416 8192000000 10319793056 10719973376 1166071312 12.460 100 93 182 0 1429323168 243336768 19915088
06:00:26 2223 8192000000 10313588736 10721873920 1167148832 12.470 100 91 177 0 1428987808 240517072 21321904
06:01:31 920 8192000000 10332726256 10741071872 1168014368 12.478 100 92 183 0 1442128192 251557424 22556400
06:02:36 3381 8192000000 10378777200 10783166464 1163993760 12.441 100 88 171 1 1485964864 300615264 21355840
06:03:47 2497 8192000000 10367549664 10814283776 1175248768 12.546 100 93 184 1 1475570608 279358400 20963440
06:04:53 1706 8192000000 10355440544 10793549824 1184800912 12.635 100 87 170 0 1467534448 263564480 19169056
06:05:57 739 8192000000 10360946400 10776158208 1175154400 12.545 100 96 186 1 1467052400 270776176 21121824
06:07:00 1099 8192000000 10403098960 10821935104 1181709680 12.606 100 95 188 1 1508898048 304134368 23054000
06:08:04 1972 8192000000 10426964352 10871263232 1191183888 12.694 100 97 194 0 1536895088 323088688 22622512
06:09:10 4776 8192000000 10435332560 10866360320 1194832832 12.728 100 75 150 1 1541588304 329362608 17392864
06:10:23 893 8192000000 10453867104 10867408896 1192546256 12.707 100 87 168 1 1560425600 347809760 20069584
06:11:28 1329 8192000000 10502423840 10904997888 1171875216 12.514 100 88 172 0 1605827600 414125696 19826688
06:12:34 919 8192000000 10495090576 10925887488 1171027968 12.506 100 74 146 1 1603124976 413251184 18845824
06:13:42 8864 8192000000 10510980528 10964488192 1160567552 12.409 100 57 109 0 1614409520 439791216 14050752
06:14:57 1985 8192000000 10496918320 10931359744 1161554272 12.418 100 74 146 0 1598572624 418055024 18963328
06:16:09 6290 8192000000 10456150704 10944512000 1169432688 12.492 100 59 113 2 1564159616 379255808 15471120
06:17:17 23911 8192000000 10365458208 10823049216 1165177152 12.452 99 40 77 3 1477844272 302390528 10276592
06:18:45 9435 8192000000 10376920528 10808643584 1163924800 12.440 100 42 84 0 1474758576 300555648 10278128
06:20:10 32372 8192000000 10319435584 10812563456 1163287680 12.434 100 24 48 1 1454669152 285465440 5916032
06:22:07 56056 8192000000 10156949280 10659094528 1145809840 12.270 99 18 36 3 1265440752 115560448 4070464
06:24:05 140209 8192000000 10074562112 10536914944 1114027856 11.971 97 16 30 2 1157733184 40540480 3164848
06:27:50 10195 8192000000 10086546832 10519687168 1132354752 12.144 99 55 108 4 1167904432 24316112 11233568
06:29:20 23531 8192000000 10147407424 10592514048 1147052400 12.282 100 58 112 1 1230130224 71944992 11132832
06:30:50 29683 8192000000 10139550944 10595323904 1146538928 12.277 99 55 111 1 1225491456 66790800 12161728
06:32:30 53716 8192000000 10137840160 10623844352 1158880080 12.393 100 31 62 1 1245734656 80278288 6576288
06:34:25 75357 8192000000 10130968848 10599825408 1115139936 11.981 96 15 29 1 1190485664 71913568 3432160
06:36:40 29110 8192000000 10109053488 10589868032 1133949008 12.159 100 26 52 2 1228047392 88025152 6073232
06:38:19 83113 8192000000 10099135696 10585382912 1145804256 12.270 100 27 54 1 1207365584 55601056 5960272
06:41:44 59779 8192000000 10070942976 10546393088 1136538400 12.183 100 19 38 0 1177500608 37148320 3813888
06:43:50 76834 8192000000 10051127936 10546667520 1116027840 11.989 99 12 24 0 1147754064 29355360 2370864
06:46:14 57157 8192000000 10067294064 10534707200 1128982368 12.112 100 20 40 2 1179838464 46729104 4126992
06:48:13 24532 8192000000 10117561728 10558615552 1137184912 12.189 100 39 76 1 1215668368 70535904 7947552
06:49:49 22267 8192000000 10167824224 10605076480 1147991312 12.291 100 47 94 1 1262514800 104760880 9762608
06:51:15 17817 8192000000 10225101968 10667708416 1144964592 12.262 100 46 90 3 1331046944 176603104 9479248
06:52:45 57288 8192000000 10108148624 10631168000 1122557728 12.051 99 25 49 0 1280147792 151775072 5814992
06:54:45 71596 8192000000 10126196816 10591203328 1113163824 11.962 97 17 34 2 1198784960 82320304 3300832
06:56:57 23083 8192000000 10095884576 10560987136 1093719152 11.778 96 35 68 1 1182058944 81372464 6967328
06:58:26 124539 8192000000 10098950656 10551488512 1130213616 12.123 99 35 70 0 1218076512 80789536 7073360
07:01:41 43237 8192000000 10073932736 10568478720 1135672848 12.175 100 6 12 0 1194891712 57820144 1398720
07:03:27 61879 8192000000 10076982384 10576510976 1128609600 12.108 99 13 25 2 1190544464 59523312 2411552
07:06:11 41760 8192000000 10076952400 10520367104 1107659856 11.910 97 18 35 2 1134994720 23994240 3340624
07:07:57 37559 8192000000 10095872672 10553200640 1117673632 12.005 98 28 55 2 1199258320 75842512 5742176
07:09:44 27990 8192000000 10136530080 10588790784 1119463824 12.022 98 34 68 2 1220794832 94339776 6991232
07:11:13 26763 8192000000 10180107232 10615394304 1138808656 12.204 100 44 87 1 1253112048 105422928 8880464
07:12:45 46794 8192000000 10237322560 10690052096 1153230656 12.340 100 31 58 2 1331687136 171762736 6693744
07:14:35 42043 8192000000 10143828064 10627670016 1120372256 12.031 98 25 50 1 1262473824 136625792 5475776
07:16:18 18001 8192000000 10099482864 10520039424 1100915280 11.846 97 36 72 1 1158209648 50097936 7196432
07:17:43 115087 8192000000 10082957008 10580115456 1120895456 12.035 99 32 64 0 1196045632 68000128 7150048
07:20:50 38098 8192000000 10072807872 10533068800 1123078560 12.056 99 10 20 2 1177796160 53221344 1496256
07:22:36 104472 8192000000 10131075248 10643488768 1137797984 12.195 100 21 42 3 1244950816 102032320 5120512
07:25:58 137193 8192000000 10067846512 10516054016 1097452192 11.813 96 17 30 1 1116070304 16169280 2448832
07:29:16 51456 8192000000 10106241792 10543075328 1146848688 12.280 100 23 44 1 1195088704 43387840 4852176
07:31:07 114019 8192000000 10064005440 10504990720 1052724096 11.387 92 35 65 1 1110482896 50941168 6817632
07:34:02 140644 8192000000 10094880496 10595958784 1106959216 11.904 97 22 44 1 1183988256 72530016 4499024
07:37:23 14122 8192000000 10080288896 10518937600 1127808864 12.101 98 35 69 0 1159541984 24898720 6834400
07:38:37 101354 8192000000 10058978864 10521845760 1085749888 11.702 95 31 62 1 1158971328 66593504 6627936
07:41:24 62537 8192000000 10125626688 10579808256 1113033584 11.961 98 31 59 5 1179572432 60520816 6018032
07:43:26 85382 8192000000 10127418656 10632302592 1087123440 11.715 97 12 24 1 1225017456 134682368 3211648
07:45:52 54732 8192000000 10053652208 10548383744 1105885360 11.893 99 15 30 0 1150290816 41609264 2796192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-04-2022 07:48:59 to 04-04-2022 07:49:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-04-2022 07:49:16 to 04-04-2022 07:49:18.

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          209960
MON$OLDEST_ACTIVE               211892
MON$OLDEST_SNAPSHOT             211892
MON$NEXT_TRANSACTION            211892
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                       2072288
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2746 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2746 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2746 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2746 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    Mon Apr  4 07:47:01 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Mon Apr  4 07:47:12 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Mon Apr  4 07:47:13 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Mon Apr  4 07:47:13 2022
: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'

04.04.22 07:49:19. 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
2022-04-04 07:49:21 No severe PSQL-related problems occured
04.04.22 07:49:22. End of report.