Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2022 07: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 14609156 kB
MemAvailable 30048304 kB
Buffers 0 kB
Cached 17061316 kB
SwapCached 3256 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 2022-12-16 07:37:22
DTS_END                         2022-12-16 07:37:22.2950
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/34844
TRN_ID                          227155
ATT_ID                          664
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: 9110.01 23395 1093201
customer order: creation 179.19 456 21503
customer order: refuse 14.80 470 1776
order to supplier: creation 48.98 1249 5878
order to supplier: removal 3.36 161743 403
invoice (draft): creation 47.19 2876 5663
invoice (draft): removal 49.16 986 5899
invoice accept: apply 44.79 26178 5375
invoice accept: cancel 44.63 1321 5355
customer reserve: creation 4054.81 254 486577
customer reserve: removal 4315.35 98 517842
realization accept: apply 87.23 302 10467
realization accept: cancel 83.16 181 9979
payment from customer: creation 62.43 182 7491
payment from customer: removal 6.19 149 743
payment to supplier: creation 50.82 205 6098
payment to supplier: removal 3.97 170 476
service: total inventory turnovers 4.29 4074 515
service: total monetary turnovers 8.60 351 1032
service: aggregate perf. data 1.04 14389 125
service: refresh index statistics 0.03 252269 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-12-2022 07:38:31 to 16-12-2022 07:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7838
TEST_TIME 2 5876
TEST_TIME 3 6345
TEST_TIME 4 5952
TEST_TIME 5 5763
TEST_TIME 6 6067
TEST_TIME 7 6230
TEST_TIME 8 6443
TEST_TIME 9 6488
TEST_TIME 10 6435
TEST_TIME 11 6596
TEST_TIME 12 6846
TEST_TIME 13 7046
TEST_TIME 14 7233
TEST_TIME 15 7179
TEST_TIME 16 7240
TEST_TIME 17 7601
TEST_TIME 18 7522
TEST_TIME 19 7570
TEST_TIME 20 7521
TEST_TIME 21 7486
TEST_TIME 22 7596
TEST_TIME 23 7588
TEST_TIME 24 8097
TEST_TIME 25 8187
TEST_TIME 26 8310
TEST_TIME 27 8330
TEST_TIME 28 8362
TEST_TIME 29 8535
TEST_TIME 30 8724
TEST_TIME 31 8997
TEST_TIME 32 9331
TEST_TIME 33 9536
TEST_TIME 34 9572
TEST_TIME 35 9998
TEST_TIME 36 10078
TEST_TIME 37 10291
TEST_TIME 38 10543
TEST_TIME 39 10654
TEST_TIME 40 10491
TEST_TIME 41 10589
TEST_TIME 42 10466
TEST_TIME 43 10332
TEST_TIME 44 10206
TEST_TIME 45 10340
TEST_TIME 46 10369
TEST_TIME 47 10347
TEST_TIME 48 10140
TEST_TIME 49 10035
TEST_TIME 50 9974
TEST_TIME 51 9943
TEST_TIME 52 9834
TEST_TIME 53 9775
TEST_TIME 54 9812
TEST_TIME 55 9734
TEST_TIME 56 9707
TEST_TIME 57 9756
TEST_TIME 58 9767
TEST_TIME 59 9782
TEST_TIME 60 9727
TEST_TIME 61 9666
TEST_TIME 62 9530
TEST_TIME 63 9471
TEST_TIME 64 9440
TEST_TIME 65 9364
TEST_TIME 66 9346
TEST_TIME 67 9303
TEST_TIME 68 9274
TEST_TIME 69 9258
TEST_TIME 70 9269
TEST_TIME 71 9390
TEST_TIME 72 9344
TEST_TIME 73 9372
TEST_TIME 74 9341
TEST_TIME 75 9419
TEST_TIME 76 9458
TEST_TIME 77 9417
TEST_TIME 78 9375
TEST_TIME 79 9577
TEST_TIME 80 9598
TEST_TIME 81 9566
TEST_TIME 82 9598
TEST_TIME 83 9518
TEST_TIME 84 9471
TEST_TIME 85 9433
TEST_TIME 86 9406
TEST_TIME 87 9386
TEST_TIME 88 9455
TEST_TIME 89 9380
TEST_TIME 90 9348
TEST_TIME 91 9281
TEST_TIME 92 9242
TEST_TIME 93 9197
TEST_TIME 94 9211
TEST_TIME 95 9296
TEST_TIME 96 9287
TEST_TIME 97 9291
TEST_TIME 98 9273
TEST_TIME 99 9301
TEST_TIME 100 9337
TEST_TIME 101 9337
TEST_TIME 102 9393
TEST_TIME 103 9392
TEST_TIME 104 9448
TEST_TIME 105 9405
TEST_TIME 106 9385
TEST_TIME 107 9311
TEST_TIME 108 9337
TEST_TIME 109 9327
TEST_TIME 110 9288
TEST_TIME 111 9244
TEST_TIME 112 9212
TEST_TIME 113 9169
TEST_TIME 114 9126
TEST_TIME 115 9156
TEST_TIME 116 9133
TEST_TIME 117 9146
TEST_TIME 118 9117
TEST_TIME 119 9101
TEST_TIME 120 9137

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2022 07:38:35 to 16-12-2022 07:38:38.

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 1540312 1540312 0 0.00 0 21147 14 0 0
doc_list_biud 1540298 1540298 0 0.00 0 38236 39 0 0
sp_add_invoice_to_stock 5518 5375 143 2.59 44 222980 26179 0 143
sp_cancel_adding_invoice 5355 5355 0 0.00 7 81959 1321 0 0
sp_cancel_client_order 1776 1776 0 0.00 8 20314 470 0 0
sp_cancel_customer_reserve 517842 517842 0 0.00 1 24790 98 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 4 14364 150 0 0
sp_cancel_pay_to_supplier 476 476 0 0.00 4 11019 170 0 0
sp_cancel_supplier_invoice 5908 5899 9 0.15 3 12829 986 0 9
sp_cancel_supplier_order 403 403 0 0.00 6 1472695 161743 0 0
sp_cancel_write_off 9979 9979 0 0.00 2 6961 182 0 0
sp_client_order 21503 21503 0 0.00 5 26183 456 0 0
sp_customer_reserve 486577 486577 0 0.00 0 58851 255 0 0
sp_fill_shopping_cart 33032 33032 0 0.00 1 11201 82 0 0
sp_fill_shopping_cart_clo_res 486539 486539 0 0.00 0 21355 18 0 0
sp_get_clo_for_invoice 5326 5326 0 0.00 0 26737 256 0 0
sp_kill_cost_storno 16553 16553 0 0.00 0 14356 84 0 0
sp_kill_qstorno_ret_qs2qd 1051965 1051965 0 0.00 0 24759 42 0 0
sp_kill_qty_storno 557045 557045 0 0.00 0 38235 99 0 0
sp_lock_selected_doc 21845 21845 0 0.00 0 7147 8 0 0
sp_make_cost_storno 29380 29380 0 0.00 0 21121 77 0 0
sp_multiply_rows_for_pdistr 29379 29379 0 0.00 0 9631 39 0 0
sp_multiply_rows_for_qdistr 21503 21503 0 0.00 0 10840 178 0 0
sp_pay_from_customer 7491 7491 0 0.00 5 19478 182 0 0
sp_pay_to_supplier 6098 6098 0 0.00 6 18331 205 0 0
sp_payment_common 13587 13587 0 0.00 2 9393 48 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 1 17691 376 0 0
sp_qd_handle_on_invoice_upd_sts 10679 10679 0 0.00 0 38235 520 0 0
sp_qd_handle_on_reserve_upd_sts 10467 10467 0 0.00 0 32447 76 0 0
sp_reserve_write_off 10467 10467 0 0.00 8 32483 303 0 0
sp_supplier_invoice 5663 5663 0 0.00 8 82852 2876 0 0
sp_supplier_order 5878 5878 0 0.00 8 18188 1250 0 0
srv_aggregate_perf_data 126 125 1 0.79 2065 224880 14390 0 1
srv_fill_mon_cache_memory 76 76 0 0.00 824 128516 30574 0 0
srv_find_qd_qs_mism 1041290 1041290 0 0.00 0 12094 11 0 0
srv_make_invnt_saldo 515 515 0 0.00 43 70702 4074 0 0
srv_make_money_saldo 1032 1032 0 0.00 1 9768 352 0 0
srv_recalc_idx_stat 4 4 0 0.00 156413 440857 252269 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5381 5381 0 0.00 1 18218 995 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 7114 551 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 1157 30 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 2316 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 491 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 476 476 0 0.00 0 1281 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 613 29 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 100064 7665 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 1781 21 0 0
t$perf-norm:sp_client_order 21503 21503 0 0.00 0 8034 38 0 0
t$perf-norm:sp_customer_reserve 1050 1050 0 0.00 0 2343 17 0 0
t$perf-norm:sp_pay_from_customer 7491 7491 0 0.00 0 8929 20 0 0
t$perf-norm:sp_pay_to_supplier 6098 6098 0 0.00 0 3686 20 0 0
t$perf-norm:sp_reserve_write_off 10467 10467 0 0.00 0 4003 24 0 0
t$perf-norm:sp_supplier_invoice 5663 5663 0 0.00 0 4476 34 0 0
t$perf-norm:sp_supplier_order 5878 5878 0 0.00 0 4220 38 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 497 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 20 3 0 0
t$perf-norm:srv_make_invnt_saldo 515 515 0 0.00 0 2124 24 0 0
t$perf-norm:srv_make_money_saldo 1032 1032 0 0.00 0 673 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 198 60 0 0
v_add_invoice_to_stock 10928 10642 286 2.61 0 18540 29 0 286
v_all_customers 39524 39524 0 0.00 0 69 4 0 0
v_all_suppliers 31706 31706 0 0.00 0 92 5 0 0
v_all_wares 43002 43002 0 0.00 0 147 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 3744 22 0 0
v_cancel_client_order 3552 3552 0 0.00 0 19157 21 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 3569 15 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 1 8251 23 0 0
v_cancel_payment_to_supplier 952 952 0 0.00 0 3859 22 0 0
v_cancel_supplier_invoice 860 842 18 2.09 0 2497 27 0 18
v_cancel_supplier_order 806 806 0 0.00 0 10295 33 0 0
v_cancel_write_off 2108 2108 0 0.00 0 3837 27 0 0
v_max_id_clo_ord 5871 5871 0 0.00 0 1318 6 0 0
v_max_id_clo_res 1050 1050 0 0.00 0 3087 15 0 0
v_max_id_ord_sup 5658 5658 0 0.00 0 4955 6 0 0
v_max_non_paid_invoice 1773 1773 0 0.00 0 411 8 0 0
v_max_non_paid_realizn 3799 3799 0 0.00 0 9270 11 0 0
v_min_id_clo_ord 5871 5871 0 0.00 0 2205 10 0 0
v_min_id_clo_res 1050 1050 0 0.00 0 80 7 0 0
v_min_id_ord_sup 5658 5658 0 0.00 0 4211 8 0 0
v_min_non_paid_invoice 6097 6097 0 0.00 0 6057 18 0 0
v_min_non_paid_realizn 7490 7490 0 0.00 0 3779 16 0 0
v_our_firm 10860 10860 0 0.00 0 77 5 0 0
v_reserve_write_off 20930 20930 0 0.00 0 23488 16 0 0
x_lock_depdocs_on_canc_invoice 10833 10833 0 0.00 0 13518 129 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 13068 400 0 0
x_make_qstorno_1000_1200 5878 5878 0 0.00 3 18171 1066 0 0
x_make_qstorno_1200_2000 5663 5663 0 0.00 3 82825 2661 0 0
x_make_qstorno_2100_3300 486577 486577 0 0.00 0 58849 224 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-12-2022 07:38:39 to 16-12-2022 07:38:44.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:27 25357 8192000000 9704337408 10002354176 787222912 8.767 100 85 170 0 815699280 7466400 21009968
05:09:55 23746 8192000000 9752808608 10058956800 826144800 9.160 100 90 180 0 859879632 11743120 21991712
05:11:34 14270 8192000000 9797734080 10117656576 863557120 9.536 100 77 153 0 902495648 21198976 17739552
05:12:54 10235 8192000000 9855263104 10188660736 911694576 10.014 100 86 169 0 966113168 36036752 18381840
05:14:07 8792 8192000000 9923833472 10265473024 954488048 10.435 100 86 170 0 1035348016 62808064 18051904
05:15:20 2329 8192000000 9959533952 10311442432 991117760 10.792 100 88 167 0 1074079760 66130544 16831456
05:16:24 1732 8192000000 9982020352 10341421056 1019636768 11.069 100 93 176 0 1102613296 64992080 17984448
05:17:28 1073 8192000000 10035747328 10401906688 1034464960 11.211 100 92 176 1 1153327344 101552768 17309616
05:18:31 863 8192000000 10018576928 10392322048 1052196880 11.382 100 90 172 2 1135100272 66283472 16619920
05:19:34 1502 8192000000 10025373056 10401247232 1060317456 11.460 100 89 174 0 1143549232 66518416 16713360
05:20:39 1706 8192000000 10032121312 10409549824 1068675408 11.539 100 89 177 2 1152684816 66074528 17934880
05:21:43 1209 8192000000 10040238656 10421157888 1072536880 11.576 100 95 183 0 1159737168 68849888 18350400
05:22:46 1014 8192000000 10030413264 10411982848 1080374272 11.651 100 95 183 2 1151556912 53006096 18176544
05:23:49 1147 8192000000 10041515280 10425413632 1083248304 11.678 100 92 177 3 1163120752 62123536 17748912
05:24:52 1160 8192000000 10053505392 10437185536 1085347568 11.698 100 96 186 1 1174020272 69010032 19662672
05:25:54 3885 8192000000 10065553760 10446483456 1089910176 11.742 100 91 168 4 1178935792 72838080 16187536
05:26:59 1096 8192000000 10071770016 10459774976 1095584160 11.796 100 97 190 1 1192874080 77422352 19867568
05:28:04 1200 8192000000 10045197552 10447163392 1095718640 11.797 100 70 133 2 1175128032 65205056 14204336
05:29:06 3388 8192000000 10054982656 10450534400 1097382272 11.813 100 72 136 2 1167595680 56352048 13861360
05:30:12 8905 8192000000 10033550304 10425626624 1088367072 11.727 100 58 107 2 1151327856 52238288 10722496
05:31:21 1445 8192000000 10058465616 10451099648 1090846992 11.751 100 80 152 3 1180062816 73702400 15513424
05:32:25 1301 8192000000 10013602592 10394648576 1055698000 11.415 99 95 177 1 1136871744 64662752 16510992
05:33:27 1350 8192000000 10021129696 10396499968 1049204704 11.353 99 96 184 2 1142917568 75002768 18710096
05:34:29 952 8192000000 10006489024 10381778944 1034488096 11.212 100 89 171 1 1131395584 79047456 17860032
05:35:31 1276 8192000000 9994360816 10367791104 1029469456 11.163 100 91 178 1 1116014176 67822240 18722480
05:36:34 4365 8192000000 9969919984 10350292992 1014481632 11.019 98 72 138 2 1090061152 61807152 13772368
05:37:44 11633 8192000000 9981302224 10359361536 1038364992 11.249 100 67 130 0 1096572768 44997456 13210320
05:39:03 2418 8192000000 10023712800 10399891456 1059341520 11.450 100 80 148 2 1143919776 68706048 15872208
05:40:11 3671 8192000000 10034234400 10413019136 1073614608 11.587 100 84 152 0 1157124672 68302912 15207152
05:41:20 7567 8192000000 10076342832 10464677888 1089396240 11.737 100 76 151 1 1191694624 86327696 15970688
05:42:33 7289 8192000000 10118138512 10505351168 1098612016 11.824 100 74 146 0 1233273520 119214560 15446944
05:43:48 2840 8192000000 10151107152 10537435136 1109455824 11.927 100 87 159 1 1264362272 137275456 17630992
05:44:55 1018 8192000000 10171432784 10560077824 1107637888 11.910 100 89 162 0 1289426144 164907312 16880944
05:46:04 2080 8192000000 10167013472 10567487488 1121975248 12.046 100 84 159 0 1273919696 134480736 17463712
05:47:15 15817 8192000000 10171266688 10579709952 1125515280 12.079 100 57 113 1 1296542960 158570928 12456752
05:48:42 1962 8192000000 10219504464 10633781248 1154671472 12.353 100 74 141 0 1329171904 159652816 14847616
05:49:50 1624 8192000000 10268407552 10670542848 1173405040 12.529 100 93 188 0 1375244176 181210688 20628448
05:50:54 1532 8192000000 10287779328 10712330240 1178784848 12.579 100 98 190 0 1395210416 195182176 21243392
05:52:00 1229 8192000000 10295578976 10701197312 1182911968 12.617 100 87 167 1 1402370752 201048864 18409920
05:53:05 2266 8192000000 10335539872 10756558848 1183185472 12.620 100 85 156 0 1440785184 239968512 17631200
05:54:10 1557 8192000000 10337337536 10779537408 1194363424 12.724 100 90 176 0 1444321488 228806112 21151952
05:55:15 807 8192000000 10359207680 10765017088 1196121152 12.740 100 98 193 0 1467850560 249183536 22545872
05:56:18 2607 8192000000 10401521904 10821054464 1185457568 12.641 100 83 166 0 1508766112 304086704 19221840
05:57:25 1287 8192000000 10437896496 10848833536 1192416656 12.706 100 92 174 1 1543830528 331043600 20370272
05:58:31 2315 8192000000 10471072288 10881335296 1184504960 12.632 100 93 183 0 1577007008 370725200 21776848
05:59:38 1320 8192000000 10482779856 10924982272 1193560384 12.716 100 96 183 0 1590408720 374268640 22579696
06:00:42 1379 8192000000 10507634480 10978897920 1184832752 12.635 100 97 193 0 1607849216 399444144 23572320
06:01:47 3951 8192000000 10506509264 10939777024 1189779136 12.681 100 65 129 0 1615174144 409767872 15627136
06:02:55 5620 8192000000 10477372736 10946494464 1179367840 12.584 99 76 148 3 1589901168 391560928 18972400
06:04:06 2469 8192000000 10497065712 10926448640 1172595792 12.521 100 70 134 0 1603117296 414240208 16281296
06:05:13 993 8192000000 10527260800 10953072640 1179470192 12.585 100 87 173 0 1632265104 432466528 20328384
06:06:18 7965 8192000000 10510634432 10958766080 1173012448 12.525 100 49 98 0 1617463536 431611600 12839488
06:07:48 1761 8192000000 10466776208 10922012672 1164661024 12.447 100 66 130 1 1579289344 398891936 15736384
06:08:57 14586 8192000000 10366991936 10849120256 1138165040 12.198 98 36 72 0 1485231552 337190736 9875776
06:10:16 18633 8192000000 10347471072 10820313088 1149144064 12.301 100 25 46 0 1461510160 305951264 6414832
06:11:54 18655 8192000000 10282574944 10739159040 1145246320 12.265 100 31 62 0 1378838080 225521120 8070640
06:13:15 14196 8192000000 10182452544 10610315264 1129376576 12.115 99 45 84 1 1273945792 134334672 10234544
06:14:34 58893 8192000000 10162136416 10621059072 1130785184 12.129 99 19 35 0 1268012320 133114528 4112608
06:16:34 80744 8192000000 10096101808 10533163008 1101466416 11.852 96 22 44 0 1173231872 67575216 4190240
06:19:01 80007 8192000000 10092582544 10519834624 1058533504 11.442 93 27 52 1 1113166080 48780096 5852480
06:21:22 56108 8192000000 10114049552 10584555520 1108675328 11.920 98 21 42 0 1213754048 99707232 5371488
06:23:22 116150 8192000000 10088898688 10580361216 1121598624 12.042 99 10 20 0 1187773744 63552048 2623072
06:26:18 28820 8192000000 10072836992 10521980928 1137723808 12.194 100 32 61 0 1162808080 19573168 5511104
06:27:50 23183 8192000000 10102838864 10551144448 1120228352 12.029 98 47 87 0 1178788368 50246720 8313296
06:29:18 44146 8192000000 10041621360 10486484992 1094720160 11.788 97 15 30 0 1132793552 33795440 4277952
06:31:04 25722 8192000000 10103109856 10549276672 1143569968 12.249 100 32 64 0 1205052896 54713328 6769600
06:32:32 11936 8192000000 10096181648 10530004992 1119574112 12.023 100 36 67 0 1183129344 55936560 7618672
06:33:49 36206 8192000000 10129484912 10584006656 1126230048 12.086 100 40 74 1 1245310368 111497904 7582416
06:35:46 32134 8192000000 10079966256 10547310592 1123359712 12.059 100 16 30 0 1178490448 52080528 3050208
06:37:23 7385 8192000000 10060341616 10543824896 1123231712 12.058 100 12 24 0 1159394784 33881936 2281136
06:38:32 89676 8192000000 10058479200 10521026560 1099709200 11.835 98 16 32 0 1136997136 33393328 3894608
06:41:03 21962 8192000000 10068529328 10524626944 1110494400 11.937 99 32 59 2 1162797984 45982832 6320752
06:42:48 95386 8192000000 10076756960 10553151488 1102350768 11.860 98 22 38 0 1151315184 44793376 4171040
06:45:24 25607 8192000000 10065203616 10503630848 1115218496 11.982 99 34 63 0 1170788832 49030992 6539344
06:46:52 30373 8192000000 10118137440 10558140416 1079301184 11.641 96 35 68 0 1174787248 88491424 6994640
06:48:26 36071 8192000000 10043132176 10479579136 1110893808 11.941 99 19 36 0 1164984288 50174976 3915504
06:50:02 24986 8192000000 10109523664 10551037952 1125500336 12.079 100 48 96 2 1206750944 71424416 9826192
06:51:27 24247 8192000000 10134299744 10558238720 1143179648 12.245 100 44 84 1 1215858368 63880240 8798480
06:52:53 43368 8192000000 10108929472 10585022464 1139443600 12.210 100 20 40 0 1207778992 64159328 4176064
06:54:37 50181 8192000000 10212756032 10668277760 1140773808 12.223 99 33 65 0 1261189888 113834688 6581392
06:56:29 71591 8192000000 10089845728 10537320448 1096276912 11.802 96 18 36 0 1169564960 69731136 3556912
06:58:42 17264 8192000000 10120540624 10552590336 1136212112 12.180 100 50 96 0 1201248976 55588496 9448368
07:00:00 128515 8192000000 10102570992 10576072704 1109002480 11.923 98 29 58 1 1193292896 78049680 6240736
07:03:18 1906 8192000000 10067361056 10492534784 1134516416 12.164 100 53 100 0 1165157504 19768048 10873040
07:04:20 53024 8192000000 10104524080 10587877376 1138865552 12.205 100 26 50 1 1236594384 92692576 5036256
07:06:15 46178 8192000000 10071823504 10530623488 1100846624 11.846 97 14 29 0 1153678400 50080992 2750784
07:08:02 83896 8192000000 10125817104 10613919744 1125512288 12.079 99 17 33 0 1222957792 93848800 3596704
07:10:26 58129 8192000000 10143690928 10595409920 1112742128 11.958 98 19 36 0 1210487280 93879952 3865200
07:12:35 20787 8192000000 10081838336 10553393152 1136022080 12.178 100 26 50 1 1184546320 43593008 4931232
07:13:56 27684 8192000000 10112553888 10553896960 1127950576 12.102 98 33 62 0 1198025088 63253392 6821120
07:15:27 15988 8192000000 10105109136 10539601920 1126915536 12.092 100 48 93 0 1199156912 62292960 9948416
07:16:46 8647 8192000000 10121179936 10555342848 1135415616 12.172 100 56 112 0 1213848208 66153008 12279584
07:17:55 46336 8192000000 10146887088 10621382656 1144654672 12.259 100 35 68 1 1256181648 105032544 6494432
07:19:42 34839 8192000000 10094628432 10529525760 1105406064 11.889 97 38 74 0 1157264048 44575440 7282544
07:21:23 56985 8192000000 10091850224 10553597952 1120802720 12.035 98 35 62 2 1180134224 52891504 6440000
07:23:20 111977 8192000000 10123307168 10608107520 1137297920 12.190 100 21 42 1 1243125728 101433648 4394160
07:26:15 56159 8192000000 10059914208 10549469184 1140635200 12.222 100 30 57 0 1202724720 56364224 5725296
07:28:24 69443 8192000000 10085485872 10534821888 1114261136 11.973 98 17 33 0 1173811040 55240000 4309904
07:30:44 84551 8192000000 10135677296 10618724352 1136252192 12.180 100 13 25 0 1242942608 104095088 2595328
07:33:13 58177 8192000000 10090404624 10532655104 1126681792 12.090 99 25 48 1 1158062016 26202784 5177440
07:35:12 23042 8192000000 10125722144 10572333056 1129715952 12.119 99 31 62 1 1221246128 85015040 6515136
07:36:49 41830 8192000000 9366183184 9767387136 479339936 5.527 42 9 18 0 553587392 72116864 2130592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 16-12-2022 07:38:45 to 16-12-2022 07:39:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 143 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2022 07:39:03 to 16-12-2022 07: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          227154
MON$OLDEST_ACTIVE               227499
MON$OLDEST_SNAPSHOT             227499
MON$NEXT_TRANSACTION            227499
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                       2076832
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Fri Dec 16 07:37:32 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
:98: 
:99: mscs-test-161.budzdorov.ru    Fri Dec 16 07:37:39 2022
:100:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:101: 
--- end of diff output ---

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

16.12.22 07: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-12-16 07:39:09 No severe PSQL-related problems occured
16.12.22 07:39:10. End of report.