Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.04.2022 07:39
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 16389480 kB
MemAvailable 31519536 kB
Buffers 0 kB
Cached 15355944 kB
SwapCached 2712 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-20 07:37:15
DTS_END                         2022-04-20 07:37:15.1950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40878
TRN_ID                          219535
ATT_ID                          580
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: 8735.85 24176 1048302
customer order: creation 165.48 499 19858
customer order: refuse 14.75 421 1770
order to supplier: creation 45.46 1453 5455
order to supplier: removal 3.22 164448 386
invoice (draft): creation 44.43 3478 5332
invoice (draft): removal 48.37 1068 5804
invoice accept: apply 41.22 24812 4946
invoice accept: cancel 43.16 1207 5179
customer reserve: creation 3788.92 237 454670
customer reserve: removal 4253.78 97 510454
realization accept: apply 80.62 277 9674
realization accept: cancel 80.01 152 9601
payment from customer: creation 57.55 176 6906
payment from customer: removal 5.93 133 711
payment to supplier: creation 47.23 203 5668
payment to supplier: removal 3.53 122 423
service: total inventory turnovers 3.68 3818 442
service: total monetary turnovers 7.58 360 910
service: aggregate perf. data 0.90 16597 108
service: refresh index statistics 0.04 263958 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-04-2022 07:39:55 to 20-04-2022 07:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9131
TEST_TIME 2 7423
TEST_TIME 3 7969
TEST_TIME 4 7236
TEST_TIME 5 7386
TEST_TIME 6 7279
TEST_TIME 7 7381
TEST_TIME 8 7085
TEST_TIME 9 7451
TEST_TIME 10 7488
TEST_TIME 11 7557
TEST_TIME 12 7508
TEST_TIME 13 7576
TEST_TIME 14 7463
TEST_TIME 15 7532
TEST_TIME 16 7627
TEST_TIME 17 7872
TEST_TIME 18 8255
TEST_TIME 19 8274
TEST_TIME 20 8245
TEST_TIME 21 8294
TEST_TIME 22 8241
TEST_TIME 23 8361
TEST_TIME 24 8749
TEST_TIME 25 8945
TEST_TIME 26 9201
TEST_TIME 27 9328
TEST_TIME 28 9965
TEST_TIME 29 10146
TEST_TIME 30 10130
TEST_TIME 31 10600
TEST_TIME 32 10829
TEST_TIME 33 10977
TEST_TIME 34 10813
TEST_TIME 35 11169
TEST_TIME 36 10864
TEST_TIME 37 10721
TEST_TIME 38 10646
TEST_TIME 39 10510
TEST_TIME 40 10346
TEST_TIME 41 10225
TEST_TIME 42 10070
TEST_TIME 43 9942
TEST_TIME 44 9823
TEST_TIME 45 9775
TEST_TIME 46 9753
TEST_TIME 47 9722
TEST_TIME 48 9563
TEST_TIME 49 9526
TEST_TIME 50 9458
TEST_TIME 51 9411
TEST_TIME 52 9335
TEST_TIME 53 9310
TEST_TIME 54 9222
TEST_TIME 55 9133
TEST_TIME 56 9056
TEST_TIME 57 8964
TEST_TIME 58 8962
TEST_TIME 59 8979
TEST_TIME 60 9036
TEST_TIME 61 8997
TEST_TIME 62 9036
TEST_TIME 63 9055
TEST_TIME 64 9015
TEST_TIME 65 9170
TEST_TIME 66 9102
TEST_TIME 67 9121
TEST_TIME 68 9132
TEST_TIME 69 9104
TEST_TIME 70 9142
TEST_TIME 71 9173
TEST_TIME 72 9186
TEST_TIME 73 9143
TEST_TIME 74 9086
TEST_TIME 75 8999
TEST_TIME 76 9085
TEST_TIME 77 9029
TEST_TIME 78 8931
TEST_TIME 79 8898
TEST_TIME 80 8890
TEST_TIME 81 8890
TEST_TIME 82 8874
TEST_TIME 83 8843
TEST_TIME 84 8857
TEST_TIME 85 8845
TEST_TIME 86 8816
TEST_TIME 87 8910
TEST_TIME 88 8955
TEST_TIME 89 9009
TEST_TIME 90 9045
TEST_TIME 91 9036
TEST_TIME 92 9009
TEST_TIME 93 8919
TEST_TIME 94 8889
TEST_TIME 95 8901
TEST_TIME 96 8862
TEST_TIME 97 8827
TEST_TIME 98 8796
TEST_TIME 99 8788
TEST_TIME 100 8764
TEST_TIME 101 8736
TEST_TIME 102 8731
TEST_TIME 103 8793
TEST_TIME 104 8803
TEST_TIME 105 8786
TEST_TIME 106 8806
TEST_TIME 107 8947
TEST_TIME 108 8973
TEST_TIME 109 8971
TEST_TIME 110 9008
TEST_TIME 111 9000
TEST_TIME 112 8951
TEST_TIME 113 8902
TEST_TIME 114 8872
TEST_TIME 115 8904
TEST_TIME 116 8898
TEST_TIME 117 8874
TEST_TIME 118 8852
TEST_TIME 119 8818
TEST_TIME 120 8754

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-04-2022 07:39:58 to 20-04-2022 07:40:02.

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 1465883 1465883 0 0.00 0 32111 13 0 0
doc_list_biud 1465871 1465871 0 0.00 0 50620 40 0 0
sp_add_invoice_to_stock 5071 4946 125 2.46 36 224364 24813 0 125
sp_cancel_adding_invoice 5179 5179 0 0.00 6 72360 1208 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 14502 422 0 0
sp_cancel_customer_reserve 510454 510454 0 0.00 1 41674 97 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 4 10205 134 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 3768 123 0 0
sp_cancel_supplier_invoice 5815 5804 11 0.18 2 50687 1068 0 11
sp_cancel_supplier_order 386 386 0 0.00 16 1290171 164448 0 0
sp_cancel_write_off 9601 9601 0 0.00 2 41238 153 0 0
sp_client_order 19858 19858 0 0.00 6 53294 500 0 0
sp_customer_reserve 454670 454670 0 0.00 0 58317 238 0 0
sp_fill_shopping_cart 30636 30636 0 0.00 1 28146 88 0 0
sp_fill_shopping_cart_clo_res 454641 454641 0 0.00 0 16796 20 0 0
sp_get_clo_for_invoice 4916 4916 0 0.00 0 35848 372 0 0
sp_kill_cost_storno 15914 15914 0 0.00 0 32101 74 0 0
sp_kill_qstorno_ret_qs2qd 1036699 1036699 0 0.00 0 50611 43 0 0
sp_kill_qty_storno 547780 547780 0 0.00 0 50617 99 0 0
sp_lock_selected_doc 20268 20268 0 0.00 0 5742 7 0 0
sp_make_cost_storno 27163 27163 0 0.00 0 25939 82 0 0
sp_multiply_rows_for_pdistr 27160 27160 0 0.00 0 10474 40 0 0
sp_multiply_rows_for_qdistr 19857 19857 0 0.00 0 19595 187 0 0
sp_pay_from_customer 6906 6906 0 0.00 5 11814 176 0 0
sp_pay_to_supplier 5668 5668 0 0.00 7 18922 203 0 0
sp_payment_common 12574 12574 0 0.00 2 5547 42 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 14489 322 0 0
sp_qd_handle_on_invoice_upd_sts 10091 10091 0 0.00 0 20965 507 0 0
sp_qd_handle_on_reserve_upd_sts 9674 9674 0 0.00 0 19521 59 0 0
sp_reserve_write_off 9674 9674 0 0.00 8 28848 277 0 0
sp_supplier_invoice 5332 5332 0 0.00 10 94078 3479 0 0
sp_supplier_order 5455 5455 0 0.00 7 56602 1453 0 0
srv_aggregate_perf_data 108 108 0 0.00 2579 235379 16597 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 938 108588 39805 0 0
srv_find_qd_qs_mism 999992 999992 0 0.00 0 17129 9 0 0
srv_make_invnt_saldo 442 442 0 0.00 57 57959 3818 0 0
srv_make_money_saldo 910 910 0 0.00 1 11357 360 0 0
srv_recalc_idx_stat 5 5 0 0.00 64129 461960 263959 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 1 25568 871 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 5504 393 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 14660 39 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 803 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 920 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 489 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 6397 54 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 94736 7112 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 1173 16 0 0
t$perf-norm:sp_client_order 19858 19858 0 0.00 0 5546 35 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 2610 18 0 0
t$perf-norm:sp_pay_from_customer 6906 6906 0 0.00 0 35686 27 0 0
t$perf-norm:sp_pay_to_supplier 5668 5668 0 0.00 0 4467 20 0 0
t$perf-norm:sp_reserve_write_off 9674 9674 0 0.00 0 23342 25 0 0
t$perf-norm:sp_supplier_invoice 5332 5332 0 0.00 0 3326 28 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 5037 30 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1897 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 89 5 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 1117 21 0 0
t$perf-norm:srv_make_money_saldo 910 910 0 0.00 0 1553 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 206 70 0 0
v_add_invoice_to_stock 10072 9822 250 2.48 0 12010 30 0 250
v_all_customers 36276 36276 0 0.00 0 102 4 0 0
v_all_suppliers 29644 29644 0 0.00 0 61 4 0 0
v_all_wares 39712 39712 0 0.00 0 74 4 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 735 14 0 0
v_cancel_client_order 3540 3540 0 0.00 0 10546 19 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 10084 22 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 1 4622 16 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 3738 17 0 0
v_cancel_supplier_invoice 806 784 22 2.72 0 1266 22 0 22
v_cancel_supplier_order 772 772 0 0.00 0 6983 23 0 0
v_cancel_write_off 1918 1918 0 0.00 0 6194 35 0 0
v_max_id_clo_ord 5452 5452 0 0.00 0 382 5 0 0
v_max_id_clo_res 981 981 0 0.00 0 5462 24 0 0
v_max_id_ord_sup 5328 5328 0 0.00 0 5637 7 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 1902 8 0 0
v_max_non_paid_realizn 3721 3721 0 0.00 0 531 7 0 0
v_min_id_clo_ord 5452 5452 0 0.00 0 3679 8 0 0
v_min_id_clo_res 981 981 0 0.00 0 6965 22 0 0
v_min_id_ord_sup 5328 5328 0 0.00 0 4234 9 0 0
v_min_non_paid_invoice 5668 5668 0 0.00 0 5069 16 0 0
v_min_non_paid_realizn 6906 6906 0 0.00 0 5542 13 0 0
v_our_firm 9804 9804 0 0.00 0 77 4 0 0
v_reserve_write_off 19346 19346 0 0.00 0 10449 14 0 0
x_lock_depdocs_on_canc_invoice 10591 10591 0 0.00 0 15510 129 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 20422 388 0 0
x_make_qstorno_1000_1200 5455 5455 0 0.00 3 56215 1289 0 0
x_make_qstorno_1200_2000 5332 5332 0 0.00 5 93956 3230 0 0
x_make_qstorno_2100_3300 454670 454670 0 0.00 0 57828 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-04-2022 07:40:03 to 20-04-2022 07:40:07.

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:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:19 28367 8192000000 9710783120 10008211456 784390848 8.738 100 89 178 0 814885040 7739600 22754592
05:09:51 20344 8192000000 9761692608 10071547904 841540624 9.315 100 87 174 0 874436912 11965888 20930400
05:11:13 16657 8192000000 9799417392 10118651904 870840528 9.608 100 85 168 1 907263440 16964752 19458160
05:12:34 12579 8192000000 9857007680 10190348288 904183872 9.940 100 82 163 0 965450048 42728704 18537472
05:13:49 6625 8192000000 9910601312 10252374016 952253920 10.413 100 81 160 2 1023755632 54803920 16697792
05:14:59 2624 8192000000 9954266128 10307739648 995225792 10.832 100 87 171 0 1070094992 57696576 17172624
05:16:05 1260 8192000000 9991076688 10353668096 1022623312 11.097 100 94 179 1 1115005440 74910352 17471776
05:17:08 1461 8192000000 10020552880 10389262336 1045281392 11.315 100 94 181 1 1140208304 76352256 18574656
05:18:10 847 8192000000 10045658752 10421477376 1063468112 11.490 100 96 187 0 1167638304 85697504 18472688
05:19:12 1535 8192000000 10051219456 10429931520 1069024816 11.543 100 96 186 0 1173535088 84745488 19764784
05:20:16 975 8192000000 10080751312 10460512256 1076899680 11.618 100 96 189 0 1204612288 108400720 19311888
05:21:18 1514 8192000000 10070634256 10457755648 1078780512 11.636 100 86 168 1 1187581280 92111968 16688800
05:22:20 1078 8192000000 10041185440 10424786944 1082044064 11.667 100 88 166 0 1161772256 62795216 16932976
05:23:24 1351 8192000000 10044709968 10432126976 1089234352 11.735 100 82 160 0 1166845248 61425600 16185296
05:24:26 2079 8192000000 10046541008 10431676416 1092940848 11.771 100 78 151 0 1165583152 57225680 15416624
05:25:29 2383 8192000000 10058057520 10449731584 1098236128 11.821 100 75 143 3 1185953840 73412832 14304880
05:26:32 3645 8192000000 10069535184 10462363648 1099177072 11.830 100 72 143 1 1193059184 79437216 14444896
05:27:38 1281 8192000000 10056347280 10440413184 1093930576 11.780 100 99 196 3 1177374768 64296528 19147664
05:28:40 10173 8192000000 10046944832 10451656704 1090030224 11.743 100 61 120 0 1176857344 74841440 11985680
05:29:53 2968 8192000000 10024005232 10407702528 1085791296 11.703 100 77 148 0 1146257040 44788768 15676976
05:30:58 1135 8192000000 10046620928 10434916352 1081333504 11.660 100 90 174 3 1165827520 66594048 17899968
05:32:00 923 8192000000 10045176592 10428301312 1069603632 11.548 100 85 162 3 1166359984 80420320 16336032
05:33:01 1648 8192000000 9986487648 10362679296 1037632272 11.242 99 73 134 4 1103353936 52067104 13654560
05:34:04 4680 8192000000 10004456880 10382045184 1047110832 11.333 100 82 148 3 1125687568 63158240 15418496
05:35:10 1191 8192000000 9993756176 10368413696 1037101072 11.237 100 95 175 1 1116480176 60506448 18872656
05:36:11 4494 8192000000 9997827072 10377441280 1040037696 11.265 100 80 144 3 1119179744 63707808 15434240
05:37:18 2546 8192000000 9995156464 10373423104 1056623360 11.424 100 72 130 2 1114690496 45776896 12290240
05:38:26 21976 8192000000 10028330768 10422063104 1084110496 11.687 100 60 108 3 1153740432 58845456 10784480
05:39:54 1223 8192000000 10081428512 10470420480 1104635344 11.882 100 95 186 1 1200102864 76755312 18712208
05:41:00 4897 8192000000 10071130816 10462330880 1110875824 11.941 100 84 160 0 1193508128 66715408 15916896
05:42:10 1303 8192000000 10133196720 10524647424 1124144592 12.066 100 81 157 0 1248134832 107814544 16175696
05:43:16 11332 8192000000 10157094368 10559909888 1135251296 12.171 100 66 126 1 1270816832 121490400 14075136
05:44:31 3120 8192000000 10151370624 10551042048 1128372752 12.106 99 76 142 0 1264370128 120605856 15391520
05:45:38 4604 8192000000 10185788656 10589011968 1156546960 12.371 100 69 136 1 1302324496 131054912 14722624
05:46:45 931 8192000000 10237617280 10634940416 1166096032 12.460 100 98 194 2 1352027584 164007472 21924080
05:47:51 1070 8192000000 10261637296 10666172416 1179331552 12.584 100 96 188 1 1374901888 173514640 22055696
05:48:55 2881 8192000000 10305914192 10713432064 1193382400 12.715 100 87 168 1 1416610896 204559376 18669120
05:50:04 1818 8192000000 10341094464 10754772992 1201345152 12.789 100 83 166 3 1455479520 235071824 19062544
05:51:08 1149 8192000000 10369238400 10775597056 1205513088 12.828 100 91 178 1 1480867664 254755504 20599072
05:52:14 5502 8192000000 10394082128 10823090176 1200806752 12.784 100 66 129 0 1505759264 289491680 15460832
05:53:23 1257 8192000000 10418066000 10853707776 1205921200 12.831 100 92 174 2 1527489152 301391888 20176064
05:54:28 1035 8192000000 10401232640 10856271872 1197897360 12.757 100 98 190 1 1508915312 288934496 22083456
05:55:33 1278 8192000000 10434995152 10839777280 1198697824 12.764 100 90 180 1 1538313888 318294080 21321984
05:56:36 1005 8192000000 10445067920 10859077632 1200450592 12.781 100 94 183 5 1550739680 328576096 21712992
05:57:43 9683 8192000000 10441248784 10883751936 1199032896 12.767 100 52 101 1 1548716976 336541952 13142128
05:58:55 4222 8192000000 10468872032 10905718784 1193131728 12.712 100 61 116 3 1579363312 371812016 14419568
06:00:08 2102 8192000000 10536417424 10976481280 1195498080 12.735 100 81 159 0 1636755152 422159328 19097744
06:01:12 3383 8192000000 10496300000 10935033856 1158367280 12.388 98 70 138 2 1600041200 423628016 18045904
06:02:24 14371 8192000000 10458852560 10930679808 1171375424 12.510 100 50 98 0 1579623648 395526416 12721808
06:04:40 3921 8192000000 10400789520 10885697536 1157302240 12.378 100 50 98 1 1508101824 337661344 13138240
06:05:47 9911 8192000000 10285020240 10751082496 1139142576 12.207 100 35 71 1 1391814688 243473040 9199072
06:07:02 31202 8192000000 10264583168 10714279936 1131101520 12.132 99 29 58 1 1358632880 219897280 7634080
06:08:45 43857 8192000000 10216131504 10664960000 1131967504 12.140 99 21 42 0 1323616448 186392976 5255968
06:10:30 57021 8192000000 10130383472 10555006976 1129905488 12.120 99 41 63 2 1245917568 107927376 8084704
06:12:44 106611 8192000000 10106644432 10547228672 1122631920 12.052 98 22 42 2 1192518304 65025168 4861216
06:15:45 58170 8192000000 10077678768 10545950720 1131366112 12.134 100 31 63 1 1173440976 35795648 6279216
06:17:49 63309 8192000000 10081507680 10548416512 1128975248 12.112 99 13 24 2 1172233776 40093136 3165392
06:20:03 58077 8192000000 10119121392 10573471744 1145797776 12.270 100 32 64 2 1210875376 58281168 6796432
06:22:10 46637 8192000000 10122964160 10567794688 1114879040 11.979 98 19 34 2 1190552320 72129712 3543568
06:24:02 75186 8192000000 10102098000 10562387968 1117806544 12.006 99 20 38 1 1208751872 86660112 4285216
06:26:21 108587 8192000000 10065009920 10508283904 1121471840 12.041 99 25 46 3 1183532784 57510688 4550256
06:29:11 84252 8192000000 10050378192 10532704256 1100456096 11.842 97 20 40 0 1145825888 40974832 4394960
06:32:17 64072 8192000000 10094417280 10543566848 1116536272 11.994 98 23 47 0 1182094592 60275104 5283216
06:35:08 58917 8192000000 10121521856 10597306368 1114199360 11.972 98 22 44 0 1187433344 68572912 4661072
06:37:08 52881 8192000000 10067884880 10507657216 1129814976 12.120 99 35 70 0 1210489616 72489152 8185488
06:39:01 52952 8192000000 10092568064 10573352960 1148992928 12.300 100 18 36 1 1233090400 80563648 3533824
06:41:18 50603 8192000000 10124293888 10595082240 1135660608 12.175 99 24 48 1 1221609232 80480816 5467808
06:43:08 39093 8192000000 10122258816 10562433024 1148012400 12.291 100 28 56 0 1195399920 41279648 6107872
06:44:48 40254 8192000000 10087226544 10548789248 1093699008 11.778 95 29 58 0 1164041088 64712688 5629392
06:46:29 16081 8192000000 10109249280 10532782080 1117696064 12.005 99 53 106 1 1171495584 43270432 10529088
06:47:47 42578 8192000000 10091556848 10565677056 1109828976 11.931 98 30 60 0 1190282672 74315760 6137936
06:49:33 44068 8192000000 10122188144 10574524416 1131688368 12.137 100 44 88 0 1206606560 66053376 8864816
06:51:22 79948 8192000000 10108473792 10546479104 1123699088 12.062 99 38 72 1 1213123472 81406128 8018256
06:53:43 107100 8192000000 10078111808 10587181056 1119348496 12.021 99 5 10 0 1188112080 67668976 1094608
06:56:30 31141 8192000000 10061500736 10525769728 1129803760 12.120 100 7 13 1 1170056672 39093248 1159664
06:58:01 77562 8192000000 10065672352 10510454784 1056963456 11.427 93 21 43 0 1090969920 29246288 4760176
07:00:25 27134 8192000000 10113960176 10552397824 1114490544 11.975 99 42 85 0 1185146736 61586944 9069248
07:01:54 25370 8192000000 10088436656 10537910272 1098495760 11.823 98 19 38 1 1187288080 84063264 4729056
07:03:20 48055 8192000000 10128084816 10572259328 1093689408 11.778 98 37 72 1 1193149136 91427776 8031952
07:05:08 95045 8192000000 10084598928 10578063360 1108323808 11.917 99 9 18 1 1162282512 52210880 1747824
07:07:44 78758 8192000000 10101016448 10553532416 1095428720 11.794 97 30 57 1 1149293296 47144640 6719936
07:10:03 102887 8192000000 10065374880 10559201280 1121150688 12.038 99 37 72 1 1205587728 76608144 7828896
07:12:54 79353 8192000000 10068634576 10531332096 1125899488 12.083 99 13 26 0 1148775552 20472400 2403664
07:15:14 75353 8192000000 10110084416 10561966080 1122820544 12.054 98 16 32 3 1178941472 52844240 3276688
07:17:34 29648 8192000000 10136579888 10582048768 1138304480 12.200 100 26 50 2 1235125056 91144064 5676512
07:19:04 26911 8192000000 10200312592 10640576512 1126152944 12.085 97 41 83 0 1266519216 131040848 9325424
07:20:31 52027 8192000000 10144255088 10628788224 1150608464 12.315 100 28 56 2 1307799632 150647648 6543520
07:22:30 39169 8192000000 10194606592 10635010048 1135631200 12.174 99 41 82 0 1277700064 133049712 9019152
07:24:13 56380 8192000000 10110744320 10597748736 1118147072 12.009 98 19 38 0 1176370112 53851056 4371984
07:26:12 83423 8192000000 10124969504 10563555328 1136418032 12.182 99 21 42 1 1195827920 54466784 4943104
07:28:36 44384 8192000000 10117336576 10551504896 1146985072 12.281 99 44 88 0 1229240912 72478000 9777840
07:30:25 105528 8192000000 10131000400 10588602368 1132108848 12.141 98 33 66 0 1244201136 104575040 7517248
07:34:05 53323 8192000000 10068201296 10525130752 1139467568 12.211 99 10 19 1 1176020656 34479232 2073856
07:36:02 77125 8192000000 10003897424 10449477632 1032923536 11.197 89 16 32 0 1096581968 60108976 3549456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-04-2022 07:40:09 to 20-04-2022 07:40:24.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-04-2022 07:40:25 to 20-04-2022 07:40:27.

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          217535
MON$OLDEST_ACTIVE               219812
MON$OLDEST_SNAPSHOT             219812
MON$NEXT_TRANSACTION            219812
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                       2058720
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:386: 
:387: mscs-test-161.budzdorov.ru    Wed Apr 20 07:37:40 2022
:388:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:389: 
--- end of diff output ---

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

20.04.22 07:40:29. 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-20 07:40:31 No severe PSQL-related problems occured
20.04.22 07:40:32. End of report.