Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15233136 kB
MemAvailable 30618004 kB
Buffers 0 kB
Cached 16501892 kB
SwapCached 2132 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-09-26 10:37:18
DTS_END                         2022-09-26 10:37:18.2970
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/57296
TRN_ID                          216979
ATT_ID                          641
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: 8702.87 33739 1044344
customer order: creation 164.31 503 19717
customer order: refuse 14.47 476 1736
order to supplier: creation 44.61 1356 5353
order to supplier: removal 3.33 181543 399
invoice (draft): creation 45.44 3492 5453
invoice (draft): removal 45.80 1128 5496
invoice accept: apply 41.40 29732 4968
invoice accept: cancel 41.41 1570 4969
customer reserve: creation 3932.75 275 471930
customer reserve: removal 4090.62 116 490874
realization accept: apply 78.47 314 9417
realization accept: cancel 75.21 191 9025
payment from customer: creation 57.20 193 6864
payment from customer: removal 5.43 146 651
payment to supplier: creation 46.56 220 5587
payment to supplier: removal 3.41 196 409
service: total inventory turnovers 4.01 4230 481
service: total monetary turnovers 7.44 415 893
service: aggregate perf. data 0.98 16440 118
service: refresh index statistics 0.03 432241 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-09-2022 10:39:07 to 26-09-2022 10:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9323
TEST_TIME 2 5993
TEST_TIME 3 6200
TEST_TIME 4 6136
TEST_TIME 5 5792
TEST_TIME 6 5538
TEST_TIME 7 6056
TEST_TIME 8 5954
TEST_TIME 9 5797
TEST_TIME 10 6297
TEST_TIME 11 6315
TEST_TIME 12 6250
TEST_TIME 13 6197
TEST_TIME 14 6472
TEST_TIME 15 6588
TEST_TIME 16 6704
TEST_TIME 17 7049
TEST_TIME 18 7076
TEST_TIME 19 7351
TEST_TIME 20 7391
TEST_TIME 21 7389
TEST_TIME 22 7402
TEST_TIME 23 7538
TEST_TIME 24 7818
TEST_TIME 25 8174
TEST_TIME 26 8171
TEST_TIME 27 8167
TEST_TIME 28 8367
TEST_TIME 29 8612
TEST_TIME 30 8667
TEST_TIME 31 8907
TEST_TIME 32 9243
TEST_TIME 33 9371
TEST_TIME 34 9558
TEST_TIME 35 9623
TEST_TIME 36 9819
TEST_TIME 37 10039
TEST_TIME 38 10398
TEST_TIME 39 10523
TEST_TIME 40 10406
TEST_TIME 41 10370
TEST_TIME 42 10143
TEST_TIME 43 10020
TEST_TIME 44 10229
TEST_TIME 45 10061
TEST_TIME 46 9943
TEST_TIME 47 9846
TEST_TIME 48 9759
TEST_TIME 49 9656
TEST_TIME 50 9663
TEST_TIME 51 9646
TEST_TIME 52 9592
TEST_TIME 53 9536
TEST_TIME 54 9610
TEST_TIME 55 9451
TEST_TIME 56 9383
TEST_TIME 57 9343
TEST_TIME 58 9288
TEST_TIME 59 9227
TEST_TIME 60 9177
TEST_TIME 61 9284
TEST_TIME 62 9240
TEST_TIME 63 9301
TEST_TIME 64 9219
TEST_TIME 65 9240
TEST_TIME 66 9278
TEST_TIME 67 9267
TEST_TIME 68 9259
TEST_TIME 69 9253
TEST_TIME 70 9306
TEST_TIME 71 9271
TEST_TIME 72 9174
TEST_TIME 73 9115
TEST_TIME 74 9071
TEST_TIME 75 9063
TEST_TIME 76 9015
TEST_TIME 77 8976
TEST_TIME 78 8967
TEST_TIME 79 8994
TEST_TIME 80 8948
TEST_TIME 81 8948
TEST_TIME 82 8968
TEST_TIME 83 8968
TEST_TIME 84 8988
TEST_TIME 85 9076
TEST_TIME 86 9069
TEST_TIME 87 9075
TEST_TIME 88 9081
TEST_TIME 89 9096
TEST_TIME 90 9131
TEST_TIME 91 9053
TEST_TIME 92 9010
TEST_TIME 93 9038
TEST_TIME 94 9016
TEST_TIME 95 9001
TEST_TIME 96 8954
TEST_TIME 97 8913
TEST_TIME 98 8858
TEST_TIME 99 8848
TEST_TIME 100 8864
TEST_TIME 101 8845
TEST_TIME 102 8839
TEST_TIME 103 8823
TEST_TIME 104 8830
TEST_TIME 105 8798
TEST_TIME 106 8842
TEST_TIME 107 8861
TEST_TIME 108 8846
TEST_TIME 109 8912
TEST_TIME 110 9011
TEST_TIME 111 8977
TEST_TIME 112 9012
TEST_TIME 113 8992
TEST_TIME 114 8966
TEST_TIME 115 8921
TEST_TIME 116 8899
TEST_TIME 117 8870
TEST_TIME 118 8820
TEST_TIME 119 8780
TEST_TIME 120 8731

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-09-2022 10:39:11 to 26-09-2022 10:39:14.

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 1476461 1476461 0 0.00 0 19641 16 0 0
doc_list_biud 1476438 1476438 0 0.00 0 42385 45 0 0
sp_add_invoice_to_stock 5051 4968 83 1.64 55 200049 29732 0 83
sp_cancel_adding_invoice 4969 4969 0 0.00 6 95100 1571 0 0
sp_cancel_client_order 1736 1736 0 0.00 6 15000 476 0 0
sp_cancel_customer_reserve 490874 490874 0 0.00 1 26652 117 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 3 5728 147 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 11905 196 0 0
sp_cancel_supplier_invoice 5502 5496 6 0.10 3 19120 1129 0 6
sp_cancel_supplier_order 399 399 0 0.00 12 1485381 181544 0 0
sp_cancel_write_off 9025 9025 0 0.00 2 8594 191 0 0
sp_client_order 19717 19717 0 0.00 5 25813 504 0 0
sp_customer_reserve 471930 471930 0 0.00 1 95513 275 0 0
sp_fill_shopping_cart 30520 30520 0 0.00 1 14400 88 0 0
sp_fill_shopping_cart_clo_res 471885 471885 0 0.00 0 17165 19 0 0
sp_get_clo_for_invoice 4918 4918 0 0.00 0 32186 320 0 0
sp_kill_cost_storno 15054 15054 0 0.00 0 12026 89 0 0
sp_kill_qstorno_ret_qs2qd 996668 996668 0 0.00 0 26565 50 0 0
sp_kill_qty_storno 526833 526833 0 0.00 0 42384 116 0 0
sp_lock_selected_doc 19958 19958 0 0.00 0 6796 8 0 0
sp_make_cost_storno 26786 26786 0 0.00 0 11711 89 0 0
sp_multiply_rows_for_pdistr 26786 26786 0 0.00 0 11574 44 0 0
sp_multiply_rows_for_qdistr 19717 19717 0 0.00 0 11800 185 0 0
sp_pay_from_customer 6864 6864 0 0.00 5 14227 193 0 0
sp_pay_to_supplier 5587 5587 0 0.00 7 18121 221 0 0
sp_payment_common 12451 12451 0 0.00 2 18109 54 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 1 14992 388 0 0
sp_qd_handle_on_invoice_upd_sts 9886 9886 0 0.00 0 42383 627 0 0
sp_qd_handle_on_reserve_upd_sts 9417 9417 0 0.00 0 15871 72 0 0
sp_reserve_write_off 9417 9417 0 0.00 8 15967 315 0 0
sp_supplier_invoice 5453 5453 0 0.00 11 99857 3492 0 0
sp_supplier_order 5353 5353 0 0.00 8 31682 1357 0 0
srv_aggregate_perf_data 119 118 1 0.84 2259 161635 16441 0 1
srv_fill_mon_cache_memory 74 74 0 0.00 663 138471 30629 0 0
srv_find_qd_qs_mism 994907 994907 0 0.00 0 19639 12 0 0
srv_make_invnt_saldo 481 481 0 0.00 61 90660 4231 0 0
srv_make_money_saldo 893 893 0 0.00 1 12037 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 181814 569392 432241 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4972 4972 0 0.00 1 29007 1153 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 9510 692 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 2089 32 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1371 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 587 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 5045 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 10083 88 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 100696 8695 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 6026 27 0 0
t$perf-norm:sp_client_order 19717 19717 0 0.00 0 9147 43 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 721 16 0 0
t$perf-norm:sp_pay_from_customer 6864 6864 0 0.00 0 16599 27 0 0
t$perf-norm:sp_pay_to_supplier 5587 5587 0 0.00 0 13647 25 0 0
t$perf-norm:sp_reserve_write_off 9417 9417 0 0.00 0 10447 25 0 0
t$perf-norm:sp_supplier_invoice 5454 5454 0 0.00 0 2485 35 0 0
t$perf-norm:sp_supplier_order 5353 5353 0 0.00 0 7713 38 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 750 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 254 13 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 4403 32 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 610 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 206 65 0 0
v_add_invoice_to_stock 9996 9830 166 1.66 0 11836 29 0 166
v_all_customers 36442 36442 0 0.00 0 183 4 0 0
v_all_suppliers 29829 29829 0 0.00 0 79 5 0 0
v_all_wares 39432 39432 0 0.00 0 81 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 11821 36 0 0
v_cancel_client_order 3472 3472 0 0.00 0 11707 17 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 5698 19 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 1 9972 19 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 11819 44 0 0
v_cancel_supplier_invoice 768 756 12 1.56 0 936 26 0 12
v_cancel_supplier_order 798 798 0 0.00 0 11710 32 0 0
v_cancel_write_off 1994 1994 0 0.00 0 5534 28 0 0
v_max_id_clo_ord 5353 5353 0 0.00 0 8452 7 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 860 9 0 0
v_max_id_ord_sup 5451 5451 0 0.00 0 4711 8 0 0
v_max_non_paid_invoice 1476 1476 0 0.00 0 4038 11 0 0
v_max_non_paid_realizn 3491 3491 0 0.00 0 2977 9 0 0
v_min_id_clo_ord 5353 5353 0 0.00 0 3468 10 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 9340 32 0 0
v_min_id_ord_sup 5451 5451 0 0.00 0 5588 10 0 0
v_min_non_paid_invoice 5587 5587 0 0.00 0 18089 19 0 0
v_min_non_paid_realizn 6863 6863 0 0.00 0 7880 24 0 0
v_our_firm 9727 9727 0 0.00 0 72 5 0 0
v_reserve_write_off 18833 18833 0 0.00 0 11708 14 0 0
x_lock_depdocs_on_canc_invoice 10087 10087 0 0.00 0 14924 137 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 4649 392 0 0
x_make_qstorno_1000_1200 5353 5353 0 0.00 2 31673 1173 0 0
x_make_qstorno_1200_2000 5453 5453 0 0.00 5 98016 3252 0 0
x_make_qstorno_2100_3300 471927 471927 0 0.00 0 95510 241 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 10:39:15 to 26-09-2022 10:39:19.

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:22 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:23 29710 8192000000 9726353088 10028179456 803508752 8.932 100 84 168 0 831174480 7958560 19707168
08:09:57 10937 8192000000 9766896448 10078208000 844363600 9.344 100 83 166 0 876704784 12752512 19588672
08:11:12 12550 8192000000 9792150224 10106040320 860662096 9.507 100 88 176 0 901082112 20092256 20327760
08:12:29 7160 8192000000 9846536016 10172170240 889888640 9.798 100 85 167 0 951599632 43673168 18037824
08:13:45 4844 8192000000 9902077680 10238865408 933150576 10.226 100 82 161 0 1007823904 58196784 16476544
08:14:53 3320 8192000000 9940057568 10290556928 973074096 10.617 100 85 165 0 1052013760 61889568 17050096
08:15:59 3012 8192000000 9957471904 10314444800 1004635664 10.923 100 84 159 0 1076235424 56325344 15274416
08:17:04 2087 8192000000 9981690960 10345836544 1022108816 11.092 100 85 165 0 1109127376 70816832 16201728
08:18:08 1168 8192000000 10024897392 10395455488 1052234896 11.382 100 91 174 2 1145759056 76437920 17086240
08:19:11 1950 8192000000 10032967280 10407714816 1061909872 11.475 100 81 151 2 1153559120 76422800 15226448
08:20:14 1486 8192000000 10026787344 10411597824 1067982352 11.533 100 87 167 1 1150128688 64683312 17463024
08:21:18 1264 8192000000 10059030880 10440114176 1076332608 11.613 100 93 181 0 1180914288 87351104 17230576
08:22:21 1162 8192000000 10054234160 10437279744 1081798944 11.665 100 98 189 0 1178051104 78271856 17980304
08:23:23 4017 8192000000 10036498176 10422800384 1084028768 11.686 100 70 125 2 1156630432 60010256 12591408
08:24:28 1768 8192000000 10051620784 10438135808 1085137744 11.696 100 79 153 0 1168272240 66967600 16166896
08:25:33 1230 8192000000 10076281952 10465349632 1088984768 11.733 100 98 185 1 1199487584 90819232 19683584
08:26:36 1212 8192000000 10058312192 10444480512 1083039728 11.676 100 89 166 0 1180129984 80178816 16911440
08:27:38 1274 8192000000 10055556432 10446884864 1089238896 11.735 100 76 146 1 1177116976 71645648 16232432
08:28:40 943 8192000000 10051442112 10439766016 1092992880 11.771 100 89 169 4 1172589792 62249056 17347856
08:29:42 1259 8192000000 10038834208 10422730752 1079578768 11.643 100 97 186 2 1159231536 60765536 18887232
08:30:44 1200 8192000000 10028589632 10411311104 1079183552 11.640 100 94 172 3 1148498800 53105488 16209760
08:31:47 1583 8192000000 10025279168 10406690816 1073002880 11.581 100 80 152 3 1146708160 58380400 15324880
08:32:49 2875 8192000000 10009278480 10387562496 1029507472 11.164 98 75 145 1 1127876816 83060288 15309056
08:33:53 8237 8192000000 9981235968 10357305344 1039562800 11.260 99 72 117 0 1100969632 48990976 12415856
08:35:03 2279 8192000000 9967722912 10342752256 1012419136 10.999 99 79 150 5 1092656576 64976832 15260608
08:36:06 1011 8192000000 9986418304 10360934400 1036461952 11.231 100 77 145 0 1105540192 54439408 14638832
08:37:08 2454 8192000000 9986970960 10364293120 1041527680 11.279 100 73 139 2 1110876384 56042304 13306400
08:38:19 6687 8192000000 10008676848 10390319104 1059029264 11.447 100 83 166 1 1137897808 62691344 16177200
08:39:31 2934 8192000000 10034941632 10412376064 1065794352 11.512 100 76 142 2 1155274608 75774288 13705968
08:40:50 1602 8192000000 10069300288 10449346560 1087048576 11.715 100 90 170 3 1184018064 79770768 17198720
08:41:57 12529 8192000000 10060380176 10449100800 1080927824 11.656 99 70 139 0 1185371568 89926176 14517568
08:43:24 21974 8192000000 10138117696 10531442688 1096968352 11.809 100 67 126 1 1244023840 133427248 13628240
08:44:50 4798 8192000000 10126068512 10518126592 1108839552 11.921 100 77 140 1 1241116000 118817504 13458944
08:46:05 2660 8192000000 10157061360 10557861888 1125958896 12.083 100 72 143 1 1271420688 129968768 15493024
08:47:13 1038 8192000000 10181326128 10593886208 1138401536 12.200 100 94 173 3 1298623664 140961136 19260992
08:48:19 1558 8192000000 10231114160 10625417216 1147818992 12.289 100 86 162 1 1338903904 174088640 16996272
08:49:30 1304 8192000000 10263472112 10656919552 1166391424 12.463 100 96 184 0 1374519024 186881440 21246160
08:50:36 965 8192000000 10299904736 10697211904 1172212928 12.518 100 89 176 0 1410469888 218568128 19688832
08:51:40 1224 8192000000 10335270512 10749829120 1175497408 12.548 100 90 170 2 1443754960 249084160 19173392
08:52:45 1653 8192000000 10347358160 10754007040 1176903680 12.561 100 91 182 1 1458047648 260209136 20934832
08:53:52 1408 8192000000 10331914288 10771927040 1175902208 12.552 100 91 175 1 1442991056 245998688 21090160
08:54:57 652 8192000000 10374969648 10774626304 1192383248 12.706 100 98 195 0 1483016048 267703952 22928848
08:56:01 1098 8192000000 10392687904 10822488064 1195967776 12.739 100 95 182 1 1498081392 281167152 20946464
08:57:07 1228 8192000000 10411137616 10821881856 1191608832 12.698 100 95 184 1 1517379840 304140864 21630144
08:58:14 880 8192000000 10451751024 10865434624 1194243952 12.723 100 93 181 0 1557425264 340927216 22254096
08:59:18 1187 8192000000 10498115712 10911092736 1189098048 12.675 100 99 193 0 1601294592 388951888 23244656
09:00:23 3012 8192000000 10446084096 10908733440 1182099936 12.610 100 87 170 0 1551895232 349396336 20398960
09:01:31 747 8192000000 10399303792 10851631104 1171660880 12.512 99 92 179 1 1500801840 306881584 22259376
09:02:34 931 8192000000 10430292208 10839195648 1179014608 12.581 100 95 189 1 1532437296 331328096 22094592
09:03:41 1467 8192000000 10441570288 10872987648 1172713184 12.522 100 70 134 1 1547397648 358308560 16375904
09:04:47 4320 8192000000 10451280528 10916212736 1172996448 12.525 100 64 128 0 1564778096 375569632 16212016
09:05:55 1206 8192000000 10427335488 10887671808 1164413152 12.445 100 85 167 0 1528097328 343194176 20490000
09:06:59 1234 8192000000 10496422352 10930638848 1188512320 12.670 100 89 177 1 1600779168 391163904 21102944
09:08:02 1809 8192000000 10438306656 10925559808 1176666912 12.559 100 75 146 1 1541329072 347328768 17333392
09:09:06 3972 8192000000 10430051984 10897969152 1173476976 12.529 100 63 126 0 1542680544 353452576 15750992
09:10:16 41632 8192000000 10425266752 10901532672 1178639232 12.578 100 33 66 0 1530503840 343301184 8563424
09:12:01 27424 8192000000 10351921392 10830430208 1151470304 12.323 99 23 46 0 1454747152 296868368 6408480
09:13:30 15257 8192000000 10281284432 10813534208 1155826624 12.364 100 22 44 0 1422153968 260928032 5399312
09:14:50 62912 8192000000 10151970736 10664067072 1125275280 12.077 98 17 34 0 1288419936 158897664 4246992
09:17:12 3143 8192000000 10072211136 10483621888 1113669744 11.967 99 80 151 0 1161299888 33283040 14347104
09:18:23 83800 8192000000 10097346896 10522333184 1097554336 11.814 98 38 65 2 1167464256 62310544 7599376
09:21:12 45368 8192000000 10054266288 10510172160 1129219360 12.114 100 12 22 1 1207929840 76391504 2318976
09:22:58 86565 8192000000 10098608704 10532667392 1105883296 11.893 97 37 72 0 1149236832 36170736 7182800
09:25:26 53603 8192000000 10172261152 10624004096 1148256464 12.293 100 57 109 0 1264964928 104627952 12080512
09:27:24 71607 8192000000 10103465696 10601963520 1090563488 11.748 96 13 26 0 1199511104 106032480 2915136
09:29:37 75065 8192000000 10099031712 10545500160 1114067472 11.971 99 30 60 0 1217501168 96593808 6839888
09:33:44 30817 8192000000 10058744768 10534604800 1126117360 12.085 100 37 70 0 1161569072 27622272 7829440
09:35:17 70567 8192000000 10048583856 10502934528 1097104816 11.810 97 10 18 1 1136671712 37720496 1846400
09:37:28 15850 8192000000 10128965392 10565914624 1131846704 12.139 100 53 106 0 1210168944 67327472 10994768
09:38:44 53663 8192000000 10070691232 10520481792 1105740064 11.892 98 13 27 0 1171218352 62896560 2581728
09:40:39 41046 8192000000 10063208992 10512560128 1122637456 12.052 99 20 38 0 1157557968 31110976 3809536
09:42:20 32036 8192000000 10090989792 10544353280 1136593088 12.183 100 28 50 2 1177485664 34984288 5908288
09:43:53 28884 8192000000 10081256560 10532003840 1118464480 12.012 98 15 30 0 1170583760 49085984 3033296
09:45:36 21082 8192000000 10103090608 10552827904 1117187808 12.000 98 61 111 0 1173463040 45634672 10640560
09:47:00 52406 8192000000 10089978528 10565894144 1120604976 12.033 99 29 58 0 1213227952 87089248 5533728
09:48:54 104395 8192000000 10069924320 10532356096 1076826624 11.617 95 26 52 0 1134130544 52211264 5092656
09:51:41 33774 8192000000 10084012944 10512543744 1098998848 11.828 98 49 94 1 1150499696 41544176 9956672
09:53:15 99667 8192000000 10071906912 10543890432 1084107488 11.687 96 9 18 0 1151592128 65901664 1582976
09:55:56 47467 8192000000 10075098880 10555289600 1096219888 11.802 97 19 38 0 1153345344 52756912 4368544
09:57:45 43447 8192000000 10103661552 10561564672 1109543520 11.928 98 28 56 0 1202311536 86646800 6121216
09:59:37 47137 8192000000 10121825488 10561646592 1109943376 11.932 98 25 50 2 1199856000 84116816 5795808
10:01:25 55156 8192000000 10151742032 10604343296 1137381072 12.191 99 40 78 1 1234873520 89715536 7776912
10:03:32 28545 8192000000 10111170640 10543448064 1124020912 12.065 99 40 78 0 1202296448 69888720 8386816
10:05:05 21860 8192000000 10123314816 10565484544 1113465136 11.965 99 62 124 0 1209897536 84030592 12401808
10:06:31 17647 8192000000 10108543504 10536292352 1122837088 12.054 100 48 93 0 1206819504 74331840 9650576
10:07:51 138471 8192000000 10074986464 10549571584 1107128512 11.905 98 26 50 0 1161772720 49361024 5283184
10:11:37 71920 8192000000 10049888048 10542170112 1126750704 12.091 100 11 22 0 1188811120 59435408 2625008
10:13:54 60637 8192000000 10064302336 10523680768 1107830176 11.912 98 22 44 0 1165010592 52463472 4716944
10:16:12 66192 8192000000 10069218640 10563391488 1127542256 12.098 100 7 14 0 1182739536 53579488 1617792
10:18:20 24138 8192000000 10027399088 10463412224 1086996336 11.714 97 21 38 1 1130399888 39751296 3652256
10:19:44 17834 8192000000 10115414624 10549792768 1106116320 11.896 98 39 78 0 1192511424 78257616 8137488
10:21:02 27030 8192000000 10140931344 10584084480 1103244384 11.868 98 45 89 0 1233023424 120392064 9386976
10:22:35 21979 8192000000 10202199440 10630807552 1139945040 12.215 100 38 74 0 1284015040 135688016 8381984
10:24:00 51548 8192000000 10168776912 10627063808 1129717888 12.119 99 29 58 0 1277458848 141424352 6316608
10:26:00 26981 8192000000 10161985824 10637365248 1102046304 11.857 97 28 56 0 1242593808 134578032 5969472
10:27:29 42454 8192000000 10063871008 10499440640 1093461184 11.776 98 20 41 0 1149527680 51991520 4074976
10:29:16 31708 8192000000 10097263792 10558869504 1107078448 11.905 98 52 104 2 1222142032 104696480 10367104
10:30:51 50143 8192000000 10108044032 10566258688 1121286208 12.039 100 62 120 2 1200150272 67117808 11746256
10:33:07 34722 8192000000 10060990000 10524180480 1125583856 12.080 100 31 58 1 1174709040 42831376 6293808
10:34:46 60969 8192000000 10057285776 10523734016 1131008688 12.131 100 5 10 0 1168620608 36865856 746064
10:36:47 41611 8192000000 9104000944 9399369728 242260288 2.872 22 5 10 0 279809440 36553216 995936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-09-2022 10:39:21 to 26-09-2022 10:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 83 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 10:39:39 to 26-09-2022 10:39:41.

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          216978
MON$OLDEST_ACTIVE               217362
MON$OLDEST_SNAPSHOT             217362
MON$NEXT_TRANSACTION            217362
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                       2077728
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.3.2849 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2849 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2849 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 ---
:139: 
:140: mscs-test-161.budzdorov.ru    Mon Sep 26 10:37:49 2022
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
--- end of diff output ---

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

26.09.22 10:39:42. 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-09-26 10:39:45 No severe PSQL-related problems occured
26.09.22 10:39:46. End of report.