Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2023 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 15193576 kB
MemAvailable 30402392 kB
Buffers 0 kB
Cached 16486732 kB
SwapCached 3196 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-03-14 10:37:27
DTS_END                         2023-03-14 10:37:27.7770
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/57418
TRN_ID                          212875
ATT_ID                          583
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: 8643.73 31168 1037247
customer order: creation 159.36 515 19123
customer order: refuse 13.84 455 1661
order to supplier: creation 43.35 1527 5202
order to supplier: removal 3.08 184243 370
invoice (draft): creation 43.37 3580 5204
invoice (draft): removal 48.03 1131 5763
invoice accept: apply 39.95 29353 4794
invoice accept: cancel 42.68 1432 5121
customer reserve: creation 3783.86 274 454063
customer reserve: removal 4190.76 105 502891
realization accept: apply 78.58 284 9429
realization accept: cancel 74.75 179 8970
payment from customer: creation 56.56 199 6787
payment from customer: removal 5.53 142 663
payment to supplier: creation 45.07 212 5408
payment to supplier: removal 3.28 160 394
service: total inventory turnovers 3.58 4341 430
service: total monetary turnovers 7.16 449 859
service: aggregate perf. data 0.92 17905 110
service: refresh index statistics 0.04 376872 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-03-2023 10:38:44 to 14-03-2023 10:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8231
TEST_TIME 2 5476
TEST_TIME 3 6029
TEST_TIME 4 5703
TEST_TIME 5 5767
TEST_TIME 6 5828
TEST_TIME 7 5607
TEST_TIME 8 5939
TEST_TIME 9 5857
TEST_TIME 10 6230
TEST_TIME 11 6242
TEST_TIME 12 6109
TEST_TIME 13 6481
TEST_TIME 14 6766
TEST_TIME 15 6748
TEST_TIME 16 6858
TEST_TIME 17 7193
TEST_TIME 18 7224
TEST_TIME 19 7355
TEST_TIME 20 7456
TEST_TIME 21 7745
TEST_TIME 22 8020
TEST_TIME 23 8140
TEST_TIME 24 8212
TEST_TIME 25 8225
TEST_TIME 26 8231
TEST_TIME 27 8338
TEST_TIME 28 8297
TEST_TIME 29 8520
TEST_TIME 30 8907
TEST_TIME 31 9522
TEST_TIME 32 9514
TEST_TIME 33 9861
TEST_TIME 34 10120
TEST_TIME 35 10337
TEST_TIME 36 10456
TEST_TIME 37 10217
TEST_TIME 38 9994
TEST_TIME 39 9888
TEST_TIME 40 9874
TEST_TIME 41 9855
TEST_TIME 42 9818
TEST_TIME 43 9699
TEST_TIME 44 9558
TEST_TIME 45 9466
TEST_TIME 46 9316
TEST_TIME 47 9228
TEST_TIME 48 9295
TEST_TIME 49 9238
TEST_TIME 50 9202
TEST_TIME 51 9070
TEST_TIME 52 9009
TEST_TIME 53 8995
TEST_TIME 54 9037
TEST_TIME 55 8953
TEST_TIME 56 8980
TEST_TIME 57 8903
TEST_TIME 58 8894
TEST_TIME 59 8971
TEST_TIME 60 8928
TEST_TIME 61 9009
TEST_TIME 62 8989
TEST_TIME 63 9209
TEST_TIME 64 9223
TEST_TIME 65 9260
TEST_TIME 66 9211
TEST_TIME 67 9091
TEST_TIME 68 9049
TEST_TIME 69 9058
TEST_TIME 70 9032
TEST_TIME 71 8957
TEST_TIME 72 8935
TEST_TIME 73 8927
TEST_TIME 74 8866
TEST_TIME 75 8858
TEST_TIME 76 8829
TEST_TIME 77 8808
TEST_TIME 78 8786
TEST_TIME 79 8770
TEST_TIME 80 8743
TEST_TIME 81 8740
TEST_TIME 82 8713
TEST_TIME 83 8757
TEST_TIME 84 8761
TEST_TIME 85 8844
TEST_TIME 86 8864
TEST_TIME 87 8869
TEST_TIME 88 8956
TEST_TIME 89 8927
TEST_TIME 90 8953
TEST_TIME 91 8965
TEST_TIME 92 8903
TEST_TIME 93 8838
TEST_TIME 94 8859
TEST_TIME 95 8831
TEST_TIME 96 8812
TEST_TIME 97 8765
TEST_TIME 98 8723
TEST_TIME 99 8726
TEST_TIME 100 8719
TEST_TIME 101 8684
TEST_TIME 102 8696
TEST_TIME 103 8709
TEST_TIME 104 8729
TEST_TIME 105 8740
TEST_TIME 106 8755
TEST_TIME 107 8758
TEST_TIME 108 8794
TEST_TIME 109 8817
TEST_TIME 110 8812
TEST_TIME 111 8893
TEST_TIME 112 8817
TEST_TIME 113 8767
TEST_TIME 114 8732
TEST_TIME 115 8712
TEST_TIME 116 8740
TEST_TIME 117 8742
TEST_TIME 118 8715
TEST_TIME 119 8729
TEST_TIME 120 8674

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-03-2023 10:38:48 to 14-03-2023 10:38:51.

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 1453637 1453637 0 0.00 0 30297 14 0 0
doc_list_biud 1453622 1453622 0 0.00 0 77043 43 0 0
sp_add_invoice_to_stock 4890 4794 96 1.96 61 230989 29353 0 96
sp_cancel_adding_invoice 5121 5121 0 0.00 6 93894 1432 0 0
sp_cancel_client_order 1661 1661 0 0.00 5 8331 456 0 0
sp_cancel_customer_reserve 502891 502891 0 0.00 1 33037 106 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 3 4281 142 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 10743 160 0 0
sp_cancel_supplier_invoice 5767 5763 4 0.06 3 77050 1132 0 4
sp_cancel_supplier_order 370 370 0 0.00 8 1426597 184244 0 0
sp_cancel_write_off 8970 8970 0 0.00 2 10007 180 0 0
sp_client_order 19123 19123 0 0.00 5 35685 516 0 0
sp_customer_reserve 454063 454063 0 0.00 0 200665 274 0 0
sp_fill_shopping_cart 29524 29524 0 0.00 1 12396 91 0 0
sp_fill_shopping_cart_clo_res 454022 454022 0 0.00 0 17481 21 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 31578 308 0 0
sp_kill_cost_storno 15148 15148 0 0.00 0 28342 87 0 0
sp_kill_qstorno_ret_qs2qd 1020885 1020885 0 0.00 0 77041 47 0 0
sp_kill_qty_storno 538953 538953 0 0.00 0 77042 108 0 0
sp_lock_selected_doc 19691 19691 0 0.00 0 8158 7 0 0
sp_make_cost_storno 26372 26372 0 0.00 0 30289 81 0 0
sp_multiply_rows_for_pdistr 26372 26372 0 0.00 0 17732 45 0 0
sp_multiply_rows_for_qdistr 19123 19123 0 0.00 0 13091 194 0 0
sp_pay_from_customer 6787 6787 0 0.00 5 24970 200 0 0
sp_pay_to_supplier 5408 5408 0 0.00 6 23425 212 0 0
sp_payment_common 12195 12195 0 0.00 2 24938 54 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 6241 370 0 0
sp_qd_handle_on_invoice_upd_sts 9869 9869 0 0.00 0 40815 578 0 0
sp_qd_handle_on_reserve_upd_sts 9429 9429 0 0.00 0 29138 62 0 0
sp_reserve_write_off 9429 9429 0 0.00 8 29420 284 0 0
sp_supplier_invoice 5204 5204 0 0.00 8 126863 3581 0 0
sp_supplier_order 5202 5202 0 0.00 6 73168 1528 0 0
srv_aggregate_perf_data 111 110 1 0.90 2092 134310 17905 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 705 285206 35877 0 0
srv_find_qd_qs_mism 989679 989679 0 0.00 0 14530 11 0 0
srv_make_invnt_saldo 430 430 0 0.00 70 59971 4341 0 0
srv_make_money_saldo 859 859 0 0.00 1 27411 450 0 0
srv_recalc_idx_stat 5 5 0 0.00 215263 463505 376872 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4799 4799 0 0.00 1 15075 1033 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 6670 508 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 5528 42 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 3655 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 669 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 303 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1037 37 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 1 87712 8123 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 4202 21 0 0
t$perf-norm:sp_client_order 19123 19123 0 0.00 0 7245 41 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 1979 14 0 0
t$perf-norm:sp_pay_from_customer 6787 6787 0 0.00 0 12288 23 0 0
t$perf-norm:sp_pay_to_supplier 5408 5408 0 0.00 0 7299 23 0 0
t$perf-norm:sp_reserve_write_off 9429 9429 0 0.00 0 5511 23 0 0
t$perf-norm:sp_supplier_invoice 5205 5205 0 0.00 0 2472 32 0 0
t$perf-norm:sp_supplier_order 5202 5202 0 0.00 0 7400 34 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 132 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 197 7 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 603 20 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 910 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 419 136 0 0
v_add_invoice_to_stock 9684 9492 192 1.98 0 4949 26 0 192
v_all_customers 35270 35270 0 0.00 0 79 4 0 0
v_all_suppliers 28940 28940 0 0.00 0 99 5 0 0
v_all_wares 38246 38246 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1447 17 0 0
v_cancel_client_order 3322 3322 0 0.00 0 3913 12 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 413 10 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 1743 13 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 10618 24 0 0
v_cancel_supplier_invoice 834 826 8 0.95 0 2091 28 0 8
v_cancel_supplier_order 740 740 0 0.00 0 732 12 0 0
v_cancel_write_off 1920 1920 0 0.00 0 2898 31 0 0
v_max_id_clo_ord 5201 5201 0 0.00 0 3260 6 0 0
v_max_id_clo_res 954 954 0 0.00 0 3559 24 0 0
v_max_id_ord_sup 5200 5200 0 0.00 0 5655 9 0 0
v_max_non_paid_invoice 1338 1338 0 0.00 0 200 9 0 0
v_max_non_paid_realizn 3523 3523 0 0.00 0 2901 10 0 0
v_min_id_clo_ord 5201 5201 0 0.00 0 3906 11 0 0
v_min_id_clo_res 954 954 0 0.00 0 4196 17 0 0
v_min_id_ord_sup 5200 5200 0 0.00 0 5071 8 0 0
v_min_non_paid_invoice 5408 5408 0 0.00 0 9571 18 0 0
v_min_non_paid_realizn 6787 6787 0 0.00 0 24935 28 0 0
v_our_firm 9502 9502 0 0.00 0 117 5 0 0
v_reserve_write_off 18858 18858 0 0.00 0 7925 11 0 0
x_lock_depdocs_on_canc_invoice 10471 10471 0 0.00 0 16432 155 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 8395 427 0 0
x_make_qstorno_1000_1200 5202 5202 0 0.00 2 72990 1338 0 0
x_make_qstorno_1200_2000 5204 5204 0 0.00 3 126830 3330 0 0
x_make_qstorno_2100_3300 454063 454063 0 0.00 0 200663 240 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 10:38:53 to 14-03-2023 10:38: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
08:07:31 1053 8192000000 8951073968 9012461568 51694128 0.627 11 7 13 0 53584144 379472 1510544
08:08:35 30555 8192000000 9721018848 10021703680 798580320 8.882 100 88 176 0 827689488 7394544 21714624
08:10:12 15716 8192000000 9757483840 10065375232 835305088 9.253 100 90 180 1 868580752 11405808 21869856
08:11:37 13995 8192000000 9816553152 10135293952 878444016 9.684 100 87 174 0 918111136 19892880 19774240
08:12:58 6747 8192000000 9852601200 10189393920 916632784 10.063 100 78 152 0 967051872 33700320 16718768
08:14:08 3629 8192000000 9921915024 10262867968 953673104 10.427 100 86 164 0 1033613984 62437216 17503664
08:15:16 2728 8192000000 9971160864 10321420288 986396288 10.746 100 90 172 0 1089766576 84773984 18596304
08:16:21 1943 8192000000 9991590048 10348462080 1009362208 10.969 100 92 180 0 1111881936 84570448 17949280
08:17:26 1691 8192000000 10001238992 10368737280 1025362352 11.124 100 91 174 1 1120615552 78006512 17246688
08:18:30 1441 8192000000 10008840272 10380402688 1043712816 11.300 100 90 173 1 1127868432 65757728 18397888
08:19:38 918 8192000000 10030971520 10404122624 1063182560 11.487 100 95 182 2 1153288464 71300704 18805200
08:20:41 1713 8192000000 10044579856 10423447552 1069573168 11.548 100 87 170 1 1161185152 75168752 16443232
08:21:43 989 8192000000 10047484928 10429128704 1072640160 11.577 100 95 183 1 1168167952 77639360 17888432
08:22:46 1210 8192000000 10048448496 10429894656 1077227632 11.621 100 92 177 1 1171594592 75667056 18699904
08:23:48 3417 8192000000 10059335488 10451283968 1079837584 11.646 100 66 130 0 1177257424 84879552 12540288
08:24:54 1164 8192000000 10045921568 10429599744 1086040400 11.705 100 98 193 1 1168258544 63434848 18783296
08:25:55 4761 8192000000 10053069664 10439688192 1088119472 11.725 100 83 152 2 1183078896 79379520 15579904
08:27:01 865 8192000000 10034419904 10423468032 1084520544 11.691 100 86 162 1 1157207440 55880064 16806832
08:28:03 1039 8192000000 10045061456 10434019328 1088209280 11.726 100 81 153 4 1171080272 66982816 15888176
08:29:05 936 8192000000 10035302080 10430025728 1099334832 11.831 100 83 148 2 1160581568 45841632 15405104
08:30:07 1388 8192000000 10038245072 10425892864 1080788832 11.655 99 84 157 3 1158477712 62724928 14963952
08:31:09 1046 8192000000 10034774752 10417610752 1079004144 11.638 100 96 179 1 1156002208 58641616 18356448
08:32:12 1050 8192000000 10012751856 10392686592 1058711184 11.444 100 95 173 1 1135827696 58039712 19076800
08:33:14 1101 8192000000 10028742016 10407067648 1056718896 11.425 100 91 180 3 1149353152 74074368 18559888
08:34:16 986 8192000000 9955546912 10329669632 1033033504 11.198 99 79 148 5 1080178944 30565440 16580000
08:35:17 1425 8192000000 9961914640 10337820672 1034426368 11.211 100 74 125 2 1080072112 33474432 12171312
08:36:20 986 8192000000 9978383872 10349830144 1023294816 11.104 100 85 161 1 1096385600 57575632 15515152
08:37:21 952 8192000000 10020768960 10400079872 1048296928 11.344 100 97 179 3 1146473456 78376272 19800256
08:38:29 6620 8192000000 9999507824 10383323136 1060353616 11.460 100 67 120 1 1120121504 48158624 11609264
08:39:45 11837 8192000000 10027145504 10408398848 1066520688 11.519 100 79 144 2 1151282320 68434656 16326976
08:41:00 936 8192000000 10057816704 10436603904 1076650880 11.616 100 90 169 2 1175901696 81675520 17575296
08:42:05 1566 8192000000 10100241744 10481795072 1087532784 11.719 100 81 154 6 1207244016 103149888 16561344
08:43:11 4675 8192000000 10131348496 10521071616 1099233600 11.830 100 81 160 0 1248759648 131851376 17674672
08:44:19 1543 8192000000 10178049424 10571870208 1110157120 11.934 100 75 146 0 1288615488 162880944 15577424
08:45:54 1894 8192000000 10198278992 10592849920 1130658992 12.128 100 86 158 0 1312657088 163827680 18170416
08:47:01 931 8192000000 10240860368 10641989632 1135813344 12.176 100 90 174 0 1351434112 196054032 19566736
08:48:06 1140 8192000000 10263671968 10655158272 1152105200 12.329 100 94 174 0 1370846256 200125984 18615072
08:49:10 1038 8192000000 10318994352 10714759168 1170863792 12.505 100 98 193 1 1433678256 240858128 21956336
08:50:16 1148 8192000000 10341748560 10755997696 1169932400 12.496 100 98 190 0 1450377520 259133968 21311152
08:51:23 1212 8192000000 10333600592 10751967232 1181390528 12.603 100 93 182 0 1442418800 240639632 20388640
08:52:28 768 8192000000 10373185616 10773700608 1175940240 12.552 100 95 184 1 1482825840 286558048 20327552
08:53:34 1246 8192000000 10381927072 10819563520 1186390464 12.650 100 96 185 2 1489085088 281853696 20840928
08:54:39 823 8192000000 10417233872 10822553600 1184644928 12.633 100 97 190 1 1523559888 317114016 21800944
08:55:46 952 8192000000 10431701264 10847416320 1196668656 12.745 100 96 191 2 1535807696 316883040 22256000
08:56:51 955 8192000000 10452298256 10880929792 1204058480 12.814 100 98 192 2 1561662000 335265424 22338096
08:57:55 774 8192000000 10466248016 10905591808 1195118016 12.731 100 93 182 0 1573280848 356131824 22031008
08:58:59 698 8192000000 10437049104 10891694080 1200243008 12.779 100 95 189 3 1541515664 318713584 22559072
09:00:04 8499 8192000000 10450931392 10895200256 1198302912 12.761 99 70 135 2 1546207296 330525584 17378800
09:01:24 2171 8192000000 10415318464 10838638592 1189427904 12.678 100 79 151 1 1518137296 311490480 17218912
09:02:32 853 8192000000 10449594272 10867818496 1199376528 12.771 100 69 132 1 1563837376 349211328 15249520
09:03:41 8085 8192000000 10491363376 10928095232 1197493568 12.753 100 67 132 1 1600256832 386135152 16628112
09:04:52 3111 8192000000 10515449392 10945572864 1188057808 12.665 100 64 124 0 1624394832 419668000 16669024
09:06:06 1109 8192000000 10485602896 10942300160 1166615168 12.465 100 93 179 0 1587928064 400886880 20426016
09:07:09 1479 8192000000 10489429664 10959335424 1171895488 12.515 100 70 134 1 1587782240 399548928 16337824
09:08:14 23611 8192000000 10366843248 10869215232 1168895312 12.487 100 43 86 0 1494156672 314442784 10818576
09:09:41 12333 8192000000 10336893200 10805579776 1162687840 12.428 100 26 52 0 1442996864 273288960 7020064
09:11:02 27588 8192000000 10225737616 10707505152 1132303776 12.143 99 35 63 1 1339318640 199515408 7499456
09:12:35 37074 8192000000 10070813168 10496139264 1089005056 11.733 97 25 48 1 1207096208 112009840 6081312
09:14:14 104008 8192000000 10091973040 10531004416 1118607808 12.014 100 55 99 2 1199697712 69549184 11540720
09:17:04 27811 8192000000 10119454112 10554986496 1119460304 12.022 99 44 87 1 1218958672 90230832 9267536
09:18:33 285205 8192000000 10091051408 10539560960 1064531104 11.500 94 16 31 0 1116784224 48802672 3450448
09:24:18 60319 8192000000 10136354560 10648969216 1133088144 12.150 100 29 58 0 1272206752 131857344 7261264
09:28:00 50125 8192000000 10045962896 10479071232 1107022768 11.904 98 31 60 0 1133848080 20235520 6589792
09:29:56 48820 8192000000 10096071344 10563334144 1122829328 12.054 99 30 60 0 1201909408 72449120 6630960
09:31:45 39129 8192000000 10087697024 10553479168 1105822080 11.893 97 18 36 0 1152266800 43036880 3407840
09:33:25 89425 8192000000 10173954816 10608631808 1111741952 11.949 97 38 76 0 1205605280 85786768 8076560
09:36:01 31723 8192000000 10199221280 10647748608 1149441936 12.304 100 35 70 0 1293930192 136747408 7740848
09:37:47 63138 8192000000 10182685312 10640945152 1143421408 12.248 99 22 42 0 1282751072 134404976 4924688
09:39:50 44242 8192000000 10114769776 10613985280 1137308016 12.190 99 24 47 1 1252029504 109075408 5646080
09:41:37 39448 8192000000 10101376080 10541838336 1138375008 12.200 99 31 62 1 1189664928 45060896 6229024
09:43:17 127105 8192000000 10152762000 10593910784 1157470416 12.380 100 40 77 0 1244154928 78865120 7819392
09:46:30 42810 8192000000 10085450976 10593943552 1148600944 12.296 100 14 28 0 1199829936 48796720 2432272
09:48:14 60141 8192000000 10115676560 10582249472 1113848624 11.969 97 21 42 1 1190192480 72118240 4225616
09:51:16 54075 8192000000 10095381872 10563858432 1134906720 12.168 100 15 30 0 1202341376 63982160 3452496
09:53:18 72659 8192000000 10082080592 10548797440 1143749520 12.251 100 12 24 1 1189611424 43019488 2842416
09:55:54 38602 8192000000 10061126192 10511065088 1123284624 12.058 99 28 54 1 1157281088 28612528 5383936
09:57:38 56336 8192000000 10109806032 10573819904 1111965760 11.951 97 17 34 0 1175098208 59952272 3180176
09:59:38 47590 8192000000 10145076656 10604879872 1107863184 11.912 98 31 62 0 1236671712 122081312 6727216
10:01:27 6879 8192000000 10139025680 10552348672 1112934112 11.960 99 67 126 4 1226540608 101040448 12566048
10:02:36 42089 8192000000 10152325088 10627305472 1130275520 12.124 100 47 94 0 1263590144 123397984 9916640
10:04:23 54352 8192000000 10091835904 10556231680 1078849072 11.637 96 32 64 0 1193618272 107334144 7435056
10:06:27 13222 8192000000 10082838688 10507751424 1131453616 12.135 100 62 121 0 1180028800 36329696 12245488
10:07:45 20240 8192000000 10144688704 10597801984 1132383712 12.144 100 42 84 0 1256431824 115542864 8505248
10:09:42 89659 8192000000 10112933120 10578235392 1129464976 12.116 100 40 80 0 1226299760 88494464 8340320
10:12:12 38404 8192000000 10050074704 10515701760 1113325920 11.964 99 15 26 1 1149895856 33772768 2797168
10:14:13 31519 8192000000 10035274944 10517610496 1104569808 11.881 99 4 8 0 1135404800 30278272 556720
10:15:45 41619 8192000000 10055173312 10525519872 1128389712 12.106 100 11 22 0 1158345392 28326320 1629360
10:17:27 44749 8192000000 10041514384 10476912640 1048507312 11.346 93 32 64 0 1079195968 24286928 6401728
10:19:14 90598 8192000000 10090982384 10572832768 1083664912 11.682 97 19 38 0 1172902704 84927840 4309952
10:22:02 18671 8192000000 10067291824 10503561216 1094122592 11.782 98 45 89 1 1159233968 56176112 8935264
10:23:51 49793 8192000000 10093146480 10551382016 1101892752 11.856 99 18 36 0 1191754912 85617648 4244512
10:25:51 51283 8192000000 10108371968 10562228224 1131843360 12.139 100 33 66 0 1201179536 62851744 6484432
10:27:45 68779 8192000000 10070555584 10567655424 1139734192 12.213 100 15 30 0 1230598160 87951024 2912944
10:29:55 47523 8192000000 10070459760 10518683648 1147256960 12.284 100 43 77 2 1170526832 15361056 7908816
10:31:43 74926 8192000000 10151400384 10631766016 1157306384 12.378 100 17 34 0 1255550160 94453760 3790016
10:34:00 125372 8192000000 10114226816 10573189120 1133573744 12.155 98 15 31 0 1196263584 59456704 3233136
10:37:17 16149 8192000000 9365376496 9698045952 490840688 5.652 43 7 14 0 517761744 25568896 1352160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-03-2023 10:38:59 to 14-03-2023 10:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 96 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2023 10:39:16 to 14-03-2023 10:39: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          213436
MON$OLDEST_ACTIVE               213437
MON$OLDEST_SNAPSHOT             213437
MON$NEXT_TRANSACTION            213437
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                       2045216
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:151: 
:152: mscs-test-161.budzdorov.ru    Tue Mar 14 10:37:36 2023
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:154: 
:155: 
:156: mscs-test-161.budzdorov.ru    Tue Mar 14 10:37:41 2023
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:158: 
--- end of diff output ---

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

14.03.23 10:39: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
2023-03-14 10:39:22 No severe PSQL-related problems occured
14.03.23 10:39:23. End of report.