Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2022 10:38
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 16017408 kB
MemAvailable 30902976 kB
Buffers 0 kB
Cached 15675340 kB
SwapCached 2508 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-08-11 10:37:17
DTS_END                         2022-08-11 10:37:17.9520
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58140
TRN_ID                          218019
ATT_ID                          594
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: 8459.38 23376 1015125
customer order: creation 163.93 495 19671
customer order: refuse 14.07 375 1688
order to supplier: creation 43.38 1453 5205
order to supplier: removal 3.27 150840 392
invoice (draft): creation 43.37 3570 5204
invoice (draft): removal 46.42 1049 5570
invoice accept: apply 40.34 27879 4841
invoice accept: cancel 41.29 1316 4955
customer reserve: creation 3771.66 267 452599
customer reserve: removal 4008.41 98 481009
realization accept: apply 80.13 282 9615
realization accept: cancel 80.01 157 9601
payment from customer: creation 55.64 179 6677
payment from customer: removal 6.19 112 743
payment to supplier: creation 46.03 197 5523
payment to supplier: removal 3.10 108 372
service: total inventory turnovers 4.10 3522 492
service: total monetary turnovers 7.13 319 855
service: aggregate perf. data 0.91 14009 109
service: refresh index statistics 0.03 261299 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2022 10:38:33 to 11-08-2022 10:38:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6126
TEST_TIME 2 4921
TEST_TIME 3 5341
TEST_TIME 4 5560
TEST_TIME 5 5314
TEST_TIME 6 5320
TEST_TIME 7 5640
TEST_TIME 8 5794
TEST_TIME 9 5778
TEST_TIME 10 5936
TEST_TIME 11 5942
TEST_TIME 12 6326
TEST_TIME 13 6498
TEST_TIME 14 6720
TEST_TIME 15 7287
TEST_TIME 16 7400
TEST_TIME 17 7453
TEST_TIME 18 7570
TEST_TIME 19 7751
TEST_TIME 20 7911
TEST_TIME 21 8159
TEST_TIME 22 9067
TEST_TIME 23 9015
TEST_TIME 24 9434
TEST_TIME 25 9593
TEST_TIME 26 9897
TEST_TIME 27 10347
TEST_TIME 28 10366
TEST_TIME 29 10606
TEST_TIME 30 10354
TEST_TIME 31 10021
TEST_TIME 32 10006
TEST_TIME 33 9864
TEST_TIME 34 10086
TEST_TIME 35 9957
TEST_TIME 36 9750
TEST_TIME 37 9745
TEST_TIME 38 9635
TEST_TIME 39 9566
TEST_TIME 40 9661
TEST_TIME 41 9586
TEST_TIME 42 9522
TEST_TIME 43 9412
TEST_TIME 44 9364
TEST_TIME 45 9325
TEST_TIME 46 9198
TEST_TIME 47 9167
TEST_TIME 48 9046
TEST_TIME 49 8908
TEST_TIME 50 8975
TEST_TIME 51 9056
TEST_TIME 52 9098
TEST_TIME 53 9002
TEST_TIME 54 8867
TEST_TIME 55 8910
TEST_TIME 56 8776
TEST_TIME 57 8784
TEST_TIME 58 8729
TEST_TIME 59 8807
TEST_TIME 60 8771
TEST_TIME 61 8835
TEST_TIME 62 8786
TEST_TIME 63 8754
TEST_TIME 64 8871
TEST_TIME 65 8862
TEST_TIME 66 8837
TEST_TIME 67 9001
TEST_TIME 68 8953
TEST_TIME 69 9050
TEST_TIME 70 9015
TEST_TIME 71 9021
TEST_TIME 72 9050
TEST_TIME 73 8932
TEST_TIME 74 8943
TEST_TIME 75 8883
TEST_TIME 76 8871
TEST_TIME 77 8836
TEST_TIME 78 8810
TEST_TIME 79 8818
TEST_TIME 80 8749
TEST_TIME 81 8739
TEST_TIME 82 8715
TEST_TIME 83 8729
TEST_TIME 84 8740
TEST_TIME 85 8715
TEST_TIME 86 8684
TEST_TIME 87 8698
TEST_TIME 88 8757
TEST_TIME 89 8783
TEST_TIME 90 8774
TEST_TIME 91 8838
TEST_TIME 92 8911
TEST_TIME 93 8894
TEST_TIME 94 8871
TEST_TIME 95 8801
TEST_TIME 96 8802
TEST_TIME 97 8762
TEST_TIME 98 8711
TEST_TIME 99 8673
TEST_TIME 100 8616
TEST_TIME 101 8653
TEST_TIME 102 8619
TEST_TIME 103 8623
TEST_TIME 104 8595
TEST_TIME 105 8591
TEST_TIME 106 8581
TEST_TIME 107 8659
TEST_TIME 108 8646
TEST_TIME 109 8609
TEST_TIME 110 8660
TEST_TIME 111 8670
TEST_TIME 112 8645
TEST_TIME 113 8640
TEST_TIME 114 8593
TEST_TIME 115 8573
TEST_TIME 116 8541
TEST_TIME 117 8496
TEST_TIME 118 8526
TEST_TIME 119 8529
TEST_TIME 120 8473

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-08-2022 10:38:36 to 11-08-2022 10:38:40.

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 1430750 1430750 0 0.00 0 20949 14 0 0
doc_list_biud 1430739 1430739 0 0.00 0 61627 39 0 0
sp_add_invoice_to_stock 4940 4841 99 2.00 27 221470 27880 0 99
sp_cancel_adding_invoice 4955 4955 0 0.00 6 86800 1316 0 0
sp_cancel_client_order 1688 1688 0 0.00 5 9256 375 0 0
sp_cancel_customer_reserve 481009 481009 0 0.00 1 33771 98 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 4 2368 112 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 4 2744 109 0 0
sp_cancel_supplier_invoice 5585 5570 15 0.26 3 61662 1049 0 15
sp_cancel_supplier_order 392 392 0 0.00 8 1206427 150841 0 0
sp_cancel_write_off 9601 9601 0 0.00 2 8784 157 0 0
sp_client_order 19671 19671 0 0.00 5 33561 495 0 0
sp_customer_reserve 452599 452599 0 0.00 0 74732 268 0 0
sp_fill_shopping_cart 30062 30062 0 0.00 1 17414 82 0 0
sp_fill_shopping_cart_clo_res 452577 452577 0 0.00 0 18526 21 0 0
sp_get_clo_for_invoice 4817 4817 0 0.00 0 18889 325 0 0
sp_kill_cost_storno 15671 15671 0 0.00 0 13251 67 0 0
sp_kill_qstorno_ret_qs2qd 977581 977581 0 0.00 0 61622 45 0 0
sp_kill_qty_storno 517642 517642 0 0.00 0 61626 101 0 0
sp_lock_selected_doc 20012 20012 0 0.00 0 12500 7 0 0
sp_make_cost_storno 26632 26632 0 0.00 0 18688 85 0 0
sp_multiply_rows_for_pdistr 26629 26629 0 0.00 0 19169 36 0 0
sp_multiply_rows_for_qdistr 19671 19671 0 0.00 0 18590 190 0 0
sp_pay_from_customer 6677 6677 0 0.00 5 19465 180 0 0
sp_pay_to_supplier 5523 5523 0 0.00 6 10037 197 0 0
sp_payment_common 12200 12200 0 0.00 2 15582 49 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 9246 309 0 0
sp_qd_handle_on_invoice_upd_sts 9769 9769 0 0.00 0 10019 479 0 0
sp_qd_handle_on_reserve_upd_sts 9613 9613 0 0.00 0 13777 72 0 0
sp_reserve_write_off 9615 9615 0 0.00 8 20841 283 0 0
sp_supplier_invoice 5204 5204 0 0.00 9 104077 3571 0 0
sp_supplier_order 5205 5205 0 0.00 7 46860 1453 0 0
srv_aggregate_perf_data 109 109 0 0.00 2256 97446 14009 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 778 123597 36679 0 0
srv_find_qd_qs_mism 967551 967551 0 0.00 0 18087 11 0 0
srv_make_invnt_saldo 492 492 0 0.00 58 79802 3522 0 0
srv_make_money_saldo 855 855 0 0.00 1 8545 319 0 0
srv_recalc_idx_stat 4 4 0 0.00 101606 510242 261300 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4843 4843 0 0.00 1 17825 867 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 5869 460 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 1874 25 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 3907 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 2266 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 185 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 643 24 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 82040 6161 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 1361 16 0 0
t$perf-norm:sp_client_order 19671 19671 0 0.00 0 11406 39 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 1466 14 0 0
t$perf-norm:sp_pay_from_customer 6677 6677 0 0.00 0 4826 19 0 0
t$perf-norm:sp_pay_to_supplier 5523 5523 0 0.00 0 7709 27 0 0
t$perf-norm:sp_reserve_write_off 9615 9615 0 0.00 0 27892 26 0 0
t$perf-norm:sp_supplier_invoice 5204 5204 0 0.00 0 3286 28 0 0
t$perf-norm:sp_supplier_order 5205 5205 0 0.00 0 14536 39 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 194 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 188 8 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 3892 20 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 4601 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 12 194 59 0 0
v_add_invoice_to_stock 9822 9624 198 2.01 0 2839 22 0 198
v_all_customers 35550 35550 0 0.00 0 65 3 0 0
v_all_suppliers 29020 29020 0 0.00 0 143 4 0 0
v_all_wares 39336 39336 0 0.00 0 122 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 627 14 0 0
v_cancel_client_order 3376 3376 0 0.00 0 1596 9 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 2230 12 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 1 535 10 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 2623 14 0 0
v_cancel_supplier_invoice 822 792 30 3.64 0 1551 22 0 30
v_cancel_supplier_order 784 784 0 0.00 0 943 11 0 0
v_cancel_write_off 1874 1874 0 0.00 0 4084 26 0 0
v_max_id_clo_ord 5203 5203 0 0.00 0 161 4 0 0
v_max_id_clo_res 971 971 0 0.00 0 3636 25 0 0
v_max_id_ord_sup 5191 5191 0 0.00 0 5169 6 0 0
v_max_non_paid_invoice 1407 1407 0 0.00 0 174 7 0 0
v_max_non_paid_realizn 3602 3602 0 0.00 0 7508 9 0 0
v_min_id_clo_ord 5203 5203 0 0.00 0 17358 12 0 0
v_min_id_clo_res 971 971 0 0.00 0 834 8 0 0
v_min_id_ord_sup 5191 5191 0 0.00 0 7709 13 0 0
v_min_non_paid_invoice 5523 5523 0 0.00 0 8754 19 0 0
v_min_non_paid_realizn 6677 6677 0 0.00 0 15577 23 0 0
v_our_firm 9936 9936 0 0.00 0 62 4 0 0
v_reserve_write_off 19226 19226 0 0.00 0 3229 10 0 0
x_lock_depdocs_on_canc_invoice 10129 10129 0 0.00 0 21625 127 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 14218 385 0 0
x_make_qstorno_1000_1200 5205 5205 0 0.00 2 46844 1297 0 0
x_make_qstorno_1200_2000 5204 5204 0 0.00 3 103177 3325 0 0
x_make_qstorno_2100_3300 452599 452599 0 0.00 0 74731 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2022 10:38:41 to 11-08-2022 10:38:45.

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
08:07:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 25925 8192000000 9715770544 10011729920 794373824 8.839 100 84 168 0 822650928 7539648 20737456
08:09:55 15357 8192000000 9758187552 10066460672 836765616 9.267 100 84 166 0 867678096 11229104 19683376
08:11:16 13418 8192000000 9802113232 10121494528 873459632 9.635 100 84 168 0 911221104 18071664 19689808
08:12:34 7778 8192000000 9871638192 10206212096 919065840 10.087 100 85 169 0 983502064 46266160 18170064
08:13:45 2990 8192000000 9941313216 10287280128 966348464 10.551 100 85 169 0 1056594032 72375488 17870080
08:14:51 1924 8192000000 9967373504 10323628032 998027520 10.859 100 93 185 0 1082501120 65708928 18764672
08:15:56 1642 8192000000 9996464656 10358075392 1027134800 11.141 100 89 175 0 1117486544 72997424 17354320
08:16:59 1140 8192000000 10020387152 10392563712 1047586192 11.338 100 98 189 1 1137337360 69851696 19899472
08:18:01 906 8192000000 10030966416 10406625280 1066267968 11.516 100 99 188 2 1150402144 64821440 19312736
08:19:04 9356 8192000000 10037028096 10421239808 1071256832 11.564 100 71 132 0 1168977216 85926560 11793824
08:20:15 1116 8192000000 10037507216 10420711424 1071981440 11.571 100 98 181 3 1163504704 73753680 17769584
08:21:17 8730 8192000000 10034742848 10433519616 1080177216 11.649 100 60 116 1 1164987376 73015904 11794256
08:22:28 7173 8192000000 10030269136 10420310016 1086330560 11.708 100 68 129 2 1149597072 50497744 12768768
08:23:38 973 8192000000 10035187328 10430500864 1090579664 11.748 100 73 136 2 1159107968 53394864 15133440
08:24:40 1023 8192000000 10052593504 10441265152 1098983472 11.828 100 88 170 1 1173886192 58516960 16385760
08:25:42 1062 8192000000 10061103824 10451337216 1098463232 11.823 100 96 180 1 1182781968 65252064 19066672
08:26:43 1304 8192000000 10066679232 10457677824 1099196832 11.830 100 93 179 2 1187588192 70384752 18006608
08:27:46 1037 8192000000 10073265280 10464514048 1098451888 11.823 100 89 174 2 1195581296 79699632 17429776
08:28:48 1185 8192000000 10038255952 10422001664 1068021728 11.533 99 95 174 3 1160131520 73569296 18540496
08:29:50 6692 8192000000 9975779856 10353389568 1044638448 11.309 98 67 126 3 1094717056 36732576 13346032
08:30:57 6838 8192000000 10013980768 10415783936 1048030688 11.342 98 51 98 1 1130658080 72823952 9803440
08:32:05 2528 8192000000 9953620016 10330628096 1030103552 11.169 100 62 121 0 1069690032 27762240 11824240
08:33:08 11100 8192000000 10007091536 10390970368 1039685792 11.262 100 66 125 1 1119189760 66822384 12681584
08:34:21 1203 8192000000 9980707168 10356396032 1037177456 11.238 100 79 157 0 1100602064 47305360 16119248
08:35:23 7301 8192000000 9980068752 10356649984 1020849824 11.080 99 52 102 1 1095542896 63076656 11616416
08:36:31 7589 8192000000 9995040944 10378158080 1044672160 11.310 100 60 114 1 1118006656 62058288 11276208
08:37:43 7890 8192000000 9988118752 10362585088 1057074432 11.428 100 84 146 3 1099505424 30217744 12213248
08:38:55 1506 8192000000 10042277600 10430906368 1084734384 11.693 100 74 142 2 1159950496 60969520 14246592
08:40:01 1757 8192000000 10061148720 10445529088 1094509104 11.786 100 82 153 2 1177638400 67154160 15975136
08:41:19 8731 8192000000 10097058416 10490978304 1114551360 11.975 100 63 125 0 1216791552 89066512 13173680
08:42:31 4375 8192000000 10125164448 10520334336 1130685568 12.128 100 75 146 1 1238786320 91878768 16221984
08:43:43 9228 8192000000 10168976304 10571911168 1141473120 12.229 100 59 117 0 1289988928 135768032 12747776
08:44:56 1025 8192000000 10213478192 10609270784 1153750304 12.345 100 91 173 1 1331894704 159189920 18954480
08:46:00 7925 8192000000 10258918000 10661224448 1160043440 12.404 100 66 132 0 1366983264 191998560 14941264
08:47:12 1027 8192000000 10289048784 10688147456 1173652976 12.531 100 95 188 1 1404621376 209221936 21746464
08:48:15 1160 8192000000 10324164272 10725486592 1174812400 12.542 100 92 179 0 1435321552 239719840 20789312
08:49:20 1248 8192000000 10335214800 10750570496 1173741168 12.532 100 90 168 1 1444031872 252033920 18256784
08:50:29 1523 8192000000 10347549344 10756202496 1177635888 12.568 100 88 169 1 1463214176 264898016 20680272
08:51:34 1856 8192000000 10354974160 10780868608 1166761536 12.467 99 80 157 0 1464002400 278624864 18616000
08:52:38 925 8192000000 10357904656 10791772160 1185484464 12.641 100 99 195 3 1469411296 262234736 21692096
08:53:42 951 8192000000 10403466960 10809032704 1185391680 12.640 100 88 169 1 1506641792 301820160 19429952
08:54:46 1348 8192000000 10416952512 10833850368 1182026960 12.609 100 91 174 2 1526369440 323645440 20697040
08:55:50 1852 8192000000 10447427728 10875330560 1176069344 12.554 100 80 160 0 1555479808 359771552 19638912
08:56:58 1221 8192000000 10451996928 10873237504 1164255232 12.443 100 90 178 0 1556283680 370367984 21660464
08:58:02 772 8192000000 10443949312 10894602240 1167630624 12.475 100 97 192 0 1548246016 358301664 22313728
08:59:06 2586 8192000000 10381088704 10896134144 1169089136 12.488 100 74 144 0 1487681312 300205680 18386496
09:00:15 16952 8192000000 10333219264 10762715136 1146510640 12.277 98 46 91 0 1411142192 253154880 11476672
09:01:32 16265 8192000000 10314525568 10780151808 1156645008 12.372 100 38 74 1 1432023968 265611872 9767088
09:02:50 33750 8192000000 10256182928 10749292544 1145277008 12.265 99 21 42 0 1371880560 220851472 5752080
09:04:27 44599 8192000000 10157012368 10603560960 1111252048 11.944 97 24 44 0 1269551856 152894272 5405536
09:06:25 8484 8192000000 10130155072 10560925696 1141305904 12.228 100 38 76 0 1229672048 80482320 7883824
09:07:40 89547 8192000000 10178466064 10610425856 1132641408 12.146 99 54 102 2 1276662384 133423264 10597712
09:10:14 102591 8192000000 10100116672 10608300032 1083284640 11.679 95 29 59 0 1214800256 125247488 6268128
09:12:57 60122 8192000000 10137467456 10586144768 1143264960 12.246 100 38 69 3 1229774736 79643184 6866592
09:14:58 112483 8192000000 10148364352 10632994816 1128378944 12.106 98 25 50 0 1236511600 102615664 5516992
09:18:50 2899 8192000000 10070563552 10500599808 1145605968 12.268 100 86 163 0 1174916576 10940256 18370352
09:19:55 34409 8192000000 10081474544 10521292800 1119323488 12.021 98 39 78 0 1161096400 33577440 8195472
09:21:38 55098 8192000000 10088380512 10564157440 1108012304 11.914 97 17 34 0 1179342080 67805984 3523792
09:23:35 29496 8192000000 10121185776 10570985472 1143987744 12.253 100 22 42 1 1221572560 73167696 4417120
09:25:10 87126 8192000000 10180310528 10634833920 1128591632 12.108 98 34 65 0 1255199680 119730912 6877136
09:27:39 62103 8192000000 10123688576 10627842048 1129014960 12.112 99 31 62 0 1232812336 96869872 6927504
09:30:24 47950 8192000000 10046686880 10499149824 1133542576 12.155 100 16 28 0 1164365328 27885968 2936784
09:32:14 123574 8192000000 10078292144 10572394496 1133576560 12.155 99 6 12 0 1192135248 57337760 1220928
09:35:18 26659 8192000000 10091669360 10535854080 1151429936 12.323 100 37 69 0 1194318400 35694192 7194272
09:36:46 33183 8192000000 10090384496 10539307008 1147102592 12.282 99 27 54 1 1190122064 36622064 6397408
09:38:53 43224 8192000000 10096869120 10542145536 1082242608 11.669 94 27 52 0 1137929072 50421200 5265264
09:40:41 38540 8192000000 10140772112 10608373760 1141678240 12.231 100 33 64 0 1248232400 99865952 6688208
09:42:25 14283 8192000000 10104638544 10557874176 1147099600 12.282 100 39 76 0 1220358864 64935152 8324112
09:43:40 35489 8192000000 10171587952 10612498432 1123013248 12.055 98 38 76 0 1249388544 118707088 7668208
09:45:21 42980 8192000000 10132230832 10567811072 1139464896 12.210 100 24 46 0 1208981088 64736960 4779232
09:47:09 25226 8192000000 10118886064 10561921024 1142776032 12.242 100 50 98 1 1208603056 55808512 10018512
09:48:36 24180 8192000000 10144151872 10592239616 1145140480 12.264 100 31 63 0 1232613168 81295168 6177520
09:50:02 110463 8192000000 10076518304 10550415360 1127491968 12.098 98 30 58 2 1204051168 70150688 6408512
09:53:00 24599 8192000000 10088693808 10531840000 1123815536 12.063 98 41 82 0 1185810016 53704224 8290256
09:54:26 32725 8192000000 10087383680 10533281792 1116819392 11.997 98 26 45 0 1169151280 47970480 4361408
09:55:59 49836 8192000000 10097813120 10563936256 1112750560 11.958 98 10 20 0 1188074352 73033296 2290496
09:57:50 53953 8192000000 10068913312 10556534784 1134988592 12.168 100 8 16 0 1168672640 32072800 1611248
09:59:44 51195 8192000000 10085049792 10554019840 1115292256 11.982 98 20 38 0 1188782416 69664224 3825936
10:01:35 25076 8192000000 10103472976 10540437504 1114598320 11.976 98 34 68 0 1188258176 66595552 7064304
10:03:01 17095 8192000000 10145214592 10573324288 1132513168 12.145 100 39 78 0 1230622720 89549728 8559824
10:04:32 22373 8192000000 10179980928 10610143232 1126779600 12.091 99 37 74 0 1275988992 141389088 7820304
10:06:00 49861 8192000000 10148899232 10619367424 1124145024 12.066 99 31 62 0 1271908032 140832544 6930464
10:07:58 24796 8192000000 10154348048 10590433280 1094011840 11.781 97 35 70 0 1236122832 134935712 7175280
10:09:23 67808 8192000000 10098922688 10520940544 1081233216 11.659 96 43 83 2 1140186912 49614544 9339152
10:11:34 59402 8192000000 10103957488 10528948224 1132742224 12.147 100 71 141 0 1211151584 64412160 13997200
10:13:51 57067 8192000000 10078526416 10549903360 1136297792 12.181 100 11 22 0 1184511312 45823040 2390480
10:15:50 59923 8192000000 10090767920 10551902208 1130102528 12.122 99 13 27 0 1206296672 72855568 3338576
10:18:01 80141 8192000000 10073620848 10539630592 1105921616 11.894 97 12 24 0 1170285504 61204368 3159520
10:20:30 94920 8192000000 10132690192 10585489408 1144868688 12.261 100 25 50 0 1208990240 58768528 5353024
10:23:14 84826 8192000000 10123113280 10616070144 1114460512 11.975 97 18 35 1 1201205040 82726400 4018128
10:26:09 47807 8192000000 10098219872 10530717696 1080681120 11.654 95 40 80 0 1158571168 69052512 8837536
10:28:01 44248 8192000000 10090299328 10547650560 1100605856 11.843 98 29 58 0 1159985488 53846640 5532992
10:29:45 3082 8192000000 10110504448 10520977408 1119476416 12.022 100 78 154 0 1197616400 63145056 14994928
10:30:49 72120 8192000000 10080609328 10528075776 1098818768 11.826 98 39 75 0 1177737008 71452752 7465488
10:33:01 77994 8192000000 10085042880 10540404736 1104542608 11.881 98 13 26 0 1174947568 67843888 2561072
10:35:21 85799 8192000000 10048305216 10520125440 1104291664 11.878 98 13 26 0 1184441280 77794976 2354640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-08-2022 10:38:46 to 11-08-2022 10:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2022 10:39:03 to 11-08-2022 10:39:05.

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          217194
MON$OLDEST_ACTIVE               218150
MON$OLDEST_SNAPSHOT             218150
MON$NEXT_TRANSACTION            218150
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                       2021888
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Thu Aug 11 10:37:21 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Thu Aug 11 10:37:22 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Thu Aug 11 10:37:22 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
--- end of diff output ---

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

11.08.22 10:39:06. 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-08-11 10:39:09 No severe PSQL-related problems occured
11.08.22 10:39:10. End of report.