Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.03.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 16564232 kB
MemAvailable 31662176 kB
Buffers 0 kB
Cached 15172796 kB
SwapCached 2140 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-03-26 10:37:15
DTS_END                         2022-03-26 10:37:14.6660
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/40000
TRN_ID                          219950
ATT_ID                          636
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: 9056.72 31069 1086806
customer order: creation 163.88 456 19666
customer order: refuse 14.87 446 1784
order to supplier: creation 46.04 1364 5525
order to supplier: removal 3.34 187515 401
invoice (draft): creation 43.77 3134 5252
invoice (draft): removal 52.12 1034 6254
invoice accept: apply 40.79 27761 4895
invoice accept: cancel 46.67 1311 5600
customer reserve: creation 3825.20 260 459024
customer reserve: removal 4532.24 107 543869
realization accept: apply 81.01 297 9721
realization accept: cancel 81.22 172 9746
payment from customer: creation 57.56 189 6907
payment from customer: removal 5.58 139 670
payment to supplier: creation 46.63 206 5595
payment to supplier: removal 3.22 133 386
service: total inventory turnovers 3.84 4784 461
service: total monetary turnovers 7.81 387 937
service: aggregate perf. data 0.90 16138 108
service: refresh index statistics 0.04 375551 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-03-2022 10:38:37 to 26-03-2022 10:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6822
TEST_TIME 2 6059
TEST_TIME 3 6250
TEST_TIME 4 6111
TEST_TIME 5 6204
TEST_TIME 6 6113
TEST_TIME 7 6373
TEST_TIME 8 6492
TEST_TIME 9 6535
TEST_TIME 10 6501
TEST_TIME 11 6748
TEST_TIME 12 6790
TEST_TIME 13 6983
TEST_TIME 14 7033
TEST_TIME 15 7074
TEST_TIME 16 7266
TEST_TIME 17 7326
TEST_TIME 18 7208
TEST_TIME 19 7363
TEST_TIME 20 7466
TEST_TIME 21 7346
TEST_TIME 22 7345
TEST_TIME 23 7354
TEST_TIME 24 7528
TEST_TIME 25 7648
TEST_TIME 26 7671
TEST_TIME 27 7916
TEST_TIME 28 7948
TEST_TIME 29 8417
TEST_TIME 30 8922
TEST_TIME 31 8961
TEST_TIME 32 9481
TEST_TIME 33 10172
TEST_TIME 34 10547
TEST_TIME 35 10794
TEST_TIME 36 10634
TEST_TIME 37 10567
TEST_TIME 38 10565
TEST_TIME 39 10336
TEST_TIME 40 10160
TEST_TIME 41 10171
TEST_TIME 42 10151
TEST_TIME 43 10076
TEST_TIME 44 10205
TEST_TIME 45 10166
TEST_TIME 46 10067
TEST_TIME 47 10006
TEST_TIME 48 9915
TEST_TIME 49 9918
TEST_TIME 50 9882
TEST_TIME 51 9812
TEST_TIME 52 9824
TEST_TIME 53 9832
TEST_TIME 54 9735
TEST_TIME 55 9748
TEST_TIME 56 9690
TEST_TIME 57 9622
TEST_TIME 58 9570
TEST_TIME 59 9413
TEST_TIME 60 9315
TEST_TIME 61 9341
TEST_TIME 62 9324
TEST_TIME 63 9289
TEST_TIME 64 9262
TEST_TIME 65 9224
TEST_TIME 66 9177
TEST_TIME 67 9110
TEST_TIME 68 9094
TEST_TIME 69 9176
TEST_TIME 70 9119
TEST_TIME 71 9158
TEST_TIME 72 9464
TEST_TIME 73 9579
TEST_TIME 74 9618
TEST_TIME 75 9511
TEST_TIME 76 9502
TEST_TIME 77 9451
TEST_TIME 78 9496
TEST_TIME 79 9464
TEST_TIME 80 9458
TEST_TIME 81 9544
TEST_TIME 82 9525
TEST_TIME 83 9472
TEST_TIME 84 9463
TEST_TIME 85 9431
TEST_TIME 86 9486
TEST_TIME 87 9563
TEST_TIME 88 9524
TEST_TIME 89 9420
TEST_TIME 90 9385
TEST_TIME 91 9322
TEST_TIME 92 9318
TEST_TIME 93 9329
TEST_TIME 94 9273
TEST_TIME 95 9239
TEST_TIME 96 9284
TEST_TIME 97 9231
TEST_TIME 98 9219
TEST_TIME 99 9157
TEST_TIME 100 9141
TEST_TIME 101 9141
TEST_TIME 102 9133
TEST_TIME 103 9115
TEST_TIME 104 9111
TEST_TIME 105 9074
TEST_TIME 106 9128
TEST_TIME 107 9174
TEST_TIME 108 9153
TEST_TIME 109 9250
TEST_TIME 110 9206
TEST_TIME 111 9266
TEST_TIME 112 9287
TEST_TIME 113 9293
TEST_TIME 114 9267
TEST_TIME 115 9259
TEST_TIME 116 9307
TEST_TIME 117 9249
TEST_TIME 118 9212
TEST_TIME 119 9151
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-03-2022 10:38:41 to 26-03-2022 10:38:44.

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 1508641 1508641 0 0.00 0 33816 15 0 0
doc_list_biud 1508641 1508641 0 0.00 0 38984 45 0 0
sp_add_invoice_to_stock 4981 4895 86 1.72 69 204242 27762 0 86
sp_cancel_adding_invoice 5600 5600 0 0.00 6 94221 1311 0 0
sp_cancel_client_order 1784 1784 0 0.00 6 25695 447 0 0
sp_cancel_customer_reserve 543869 543869 0 0.00 1 38986 108 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 4 5907 140 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 3 4893 134 0 0
sp_cancel_supplier_invoice 6259 6254 5 0.07 3 15911 1035 0 5
sp_cancel_supplier_order 401 401 0 0.00 16 1744494 187515 0 0
sp_cancel_write_off 9746 9746 0 0.00 2 10972 172 0 0
sp_client_order 19666 19666 0 0.00 5 43905 456 0 0
sp_customer_reserve 459024 459024 0 0.00 1 188946 260 0 0
sp_fill_shopping_cart 30433 30433 0 0.00 1 14255 86 0 0
sp_fill_shopping_cart_clo_res 459013 459013 0 0.00 0 23161 19 0 0
sp_get_clo_for_invoice 4846 4846 0 0.00 0 28120 289 0 0
sp_kill_cost_storno 16402 16402 0 0.00 0 10632 78 0 0
sp_kill_qstorno_ret_qs2qd 1104139 1104139 0 0.00 0 38980 46 0 0
sp_kill_qty_storno 582220 582220 0 0.00 0 38984 107 0 0
sp_lock_selected_doc 20282 20282 0 0.00 0 12281 8 0 0
sp_make_cost_storno 27068 27068 0 0.00 0 33793 94 0 0
sp_multiply_rows_for_pdistr 27068 27068 0 0.00 0 9665 44 0 0
sp_multiply_rows_for_qdistr 19666 19666 0 0.00 0 9562 165 0 0
sp_pay_from_customer 6907 6907 0 0.00 6 9801 190 0 0
sp_pay_to_supplier 5595 5595 0 0.00 6 16592 207 0 0
sp_payment_common 12502 12502 0 0.00 2 8165 50 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 25657 359 0 0
sp_qd_handle_on_invoice_upd_sts 10445 10445 0 0.00 0 20098 587 0 0
sp_qd_handle_on_reserve_upd_sts 9721 9721 0 0.00 0 14570 61 0 0
sp_reserve_write_off 9721 9721 0 0.00 9 15368 298 0 0
sp_supplier_invoice 5252 5252 0 0.00 10 81828 3135 0 0
sp_supplier_order 5525 5525 0 0.00 7 92880 1364 0 0
srv_aggregate_perf_data 109 108 1 0.91 2372 128706 16139 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 784 125131 34391 0 0
srv_find_qd_qs_mism 1038662 1038662 0 0.00 0 11443 11 0 0
srv_make_invnt_saldo 461 461 0 0.00 106 133655 4785 0 0
srv_make_money_saldo 937 937 0 0.00 1 12917 388 0 0
srv_recalc_idx_stat 5 5 0 0.00 146042 470022 375552 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 4895 4895 0 0.00 1 10250 1033 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 1 6918 533 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 4471 33 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 6888 60 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1042 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 2380 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1048 43 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 110756 7639 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 1415 19 0 0
t$perf-norm:sp_client_order 19666 19666 0 0.00 0 5806 39 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 1923 17 0 0
t$perf-norm:sp_pay_from_customer 6907 6907 0 0.00 0 6653 24 0 0
t$perf-norm:sp_pay_to_supplier 5595 5595 0 0.00 0 6670 24 0 0
t$perf-norm:sp_reserve_write_off 9721 9721 0 0.00 0 6895 26 0 0
t$perf-norm:sp_supplier_invoice 5252 5252 0 0.00 0 10470 40 0 0
t$perf-norm:sp_supplier_order 5525 5525 0 0.00 0 10147 40 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 306 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 297 10 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 713 21 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 616 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 14 229 83 0 0
v_add_invoice_to_stock 9862 9690 172 1.74 0 5195 26 0 172
v_all_customers 36228 36228 0 0.00 0 67 4 0 0
v_all_suppliers 30202 30202 0 0.00 0 107 4 0 0
v_all_wares 39332 39332 0 0.00 0 88 4 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 911 16 0 0
v_cancel_client_order 3568 3568 0 0.00 0 4342 13 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 5896 19 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 590 11 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 453 10 0 0
v_cancel_supplier_invoice 824 814 10 1.21 0 3113 27 0 10
v_cancel_supplier_order 802 802 0 0.00 0 1818 13 0 0
v_cancel_write_off 1934 1934 0 0.00 0 2778 27 0 0
v_max_id_clo_ord 5525 5525 0 0.00 0 3362 6 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 1716 12 0 0
v_max_id_ord_sup 5242 5242 0 0.00 0 4216 7 0 0
v_max_non_paid_invoice 1261 1261 0 0.00 0 77 8 0 0
v_max_non_paid_realizn 3496 3496 0 0.00 0 3352 9 0 0
v_min_id_clo_ord 5525 5525 0 0.00 0 3159 10 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 5344 26 0 0
v_min_id_ord_sup 5242 5242 0 0.00 0 3877 9 0 0
v_min_non_paid_invoice 5595 5595 0 0.00 0 8158 19 0 0
v_min_non_paid_realizn 6907 6907 0 0.00 0 6871 20 0 0
v_our_firm 9926 9926 0 0.00 0 89 5 0 0
v_reserve_write_off 19442 19442 0 0.00 0 4022 11 0 0
x_lock_depdocs_on_canc_invoice 11447 11447 0 0.00 0 16937 136 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 12338 459 0 0
x_make_qstorno_1000_1200 5525 5525 0 0.00 2 92829 1166 0 0
x_make_qstorno_1200_2000 5252 5252 0 0.00 5 81804 2912 0 0
x_make_qstorno_2100_3300 459024 459024 0 0.00 0 188928 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-03-2022 10:38:45 to 26-03-2022 10:38:49.

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:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 51262 8192000000 9733199072 10032541696 803918400 8.936 100 92 184 0 834679168 8378896 22381872
08:10:14 21604 8192000000 9762681712 10069901312 834324656 9.243 100 90 180 0 870535936 14972736 21238544
08:11:47 16220 8192000000 9819437648 10143043584 876788192 9.668 100 80 159 2 924672704 30261808 17622704
08:13:06 12360 8192000000 9883784160 10223087616 935277056 10.247 100 86 170 1 997495936 44059040 18159840
08:14:24 6601 8192000000 9941823632 10289684480 969684608 10.584 100 88 174 1 1050269200 63201488 17383104
08:15:35 2710 8192000000 9967920592 10326032384 999334368 10.872 100 84 166 0 1087350144 70837792 17177984
08:16:41 1805 8192000000 9983629776 10346528768 1022330592 11.095 100 85 161 2 1101752608 63611600 15810416
08:17:48 951 8192000000 10019626752 10393718784 1053659920 11.396 100 94 177 0 1143068752 69913776 19495056
08:18:50 1244 8192000000 10025423120 10405314560 1060696720 11.463 100 94 185 2 1147944304 68776432 18471152
08:19:54 1092 8192000000 10046994560 10425176064 1066591104 11.520 100 95 184 2 1166626080 80450336 19584640
08:20:56 1108 8192000000 10045452624 10428272640 1078038224 11.629 100 92 175 0 1166980928 71338288 17604416
08:21:59 4153 8192000000 10039347968 10420756480 1078653600 11.635 100 93 173 1 1156226096 60613232 16959264
08:23:05 1472 8192000000 10068780512 10455113728 1083735152 11.683 100 91 172 5 1188644240 85766000 19143088
08:24:08 1052 8192000000 10058801440 10445058048 1086862016 11.713 100 93 181 1 1180266912 74431680 18973216
08:25:09 1663 8192000000 10062768944 10458107904 1086888752 11.713 100 72 130 2 1184178704 84765600 12524352
08:26:12 1201 8192000000 10049045776 10439716864 1084323456 11.689 100 93 178 1 1169057408 65227664 19506288
08:27:14 1160 8192000000 10069345136 10457657344 1095797104 11.798 100 96 184 1 1195124800 80213280 19114416
08:28:16 1014 8192000000 10067782528 10457804800 1093159104 11.773 100 92 184 0 1190563008 78407424 18996480
08:29:20 1256 8192000000 10034688496 10419535872 1075810368 11.608 100 96 180 0 1154055936 60053744 18191824
08:30:24 1181 8192000000 10006258848 10395607040 1069792480 11.550 100 77 146 1 1131979632 47311104 14876048
08:31:29 1221 8192000000 10016945136 10395000832 1048335392 11.345 100 95 178 0 1140372768 74049744 17987632
08:32:31 5791 8192000000 9992353008 10377580544 1045467584 11.317 98 60 117 1 1113661616 56759328 11434704
08:33:38 1080 8192000000 10009558896 10386051072 1041422256 11.278 100 91 174 0 1135946672 76864448 17659968
08:34:40 1044 8192000000 9987118928 10360115200 1031569216 11.184 99 94 184 0 1109411184 58276912 19565056
08:35:43 7220 8192000000 9998862048 10380226560 1032840976 11.196 100 65 131 1 1112761456 66950464 12970016
08:36:51 2644 8192000000 9985248368 10362232832 1047326384 11.335 100 75 138 0 1118215504 56666240 14222880
08:38:00 19120 8192000000 9972427728 10356023296 1029955600 11.168 98 55 106 2 1090744528 50271584 10517344
08:39:25 1327 8192000000 10017047440 10395299840 1053979760 11.399 99 88 164 0 1137160800 63879792 19301248
08:40:31 2598 8192000000 10050120752 10433122304 1079333952 11.641 100 87 159 0 1170153248 74363936 16455360
08:41:38 7085 8192000000 10090995072 10482905088 1096545472 11.805 100 75 146 0 1221452032 109658944 15247616
08:42:51 1789 8192000000 10106408768 10494361600 1101681040 11.854 100 83 152 1 1233102752 115793488 15628224
08:43:58 2252 8192000000 10147859712 10541379584 1104069456 11.876 100 79 151 2 1266018016 146430352 15518208
08:45:03 1013 8192000000 10178132464 10564513792 1114944080 11.979 100 89 165 4 1293181904 160686736 17551088
08:46:11 1473 8192000000 10216598992 10608074752 1138507056 12.201 100 95 181 2 1330415648 172309120 19599472
08:47:16 6127 8192000000 10224744608 10632212480 1143406816 12.248 100 71 135 2 1330378864 173184736 13787312
08:48:27 2472 8192000000 10245862176 10659749888 1160719824 12.410 100 81 158 0 1360295312 182425200 17150288
08:49:32 1377 8192000000 10292661824 10697924608 1174700896 12.541 100 85 158 2 1401409456 209898944 16809616
08:50:38 969 8192000000 10316735456 10714488832 1177232816 12.564 100 94 180 0 1426544288 228655664 20655808
08:51:43 773 8192000000 10326897328 10725634048 1178231808 12.574 100 96 188 2 1437145952 237544160 21369984
08:52:48 3075 8192000000 10387157136 10812190720 1195779408 12.737 100 80 156 0 1492776512 277956784 19040320
08:53:53 808 8192000000 10439656464 10849484800 1203040992 12.805 100 99 190 0 1548303120 322397200 22864928
08:54:58 1502 8192000000 10436249392 10845966336 1202666400 12.801 100 91 175 2 1541166064 318166576 20333088
08:56:04 1242 8192000000 10485960912 10899537920 1196460032 12.743 100 98 190 3 1591778672 372903904 22414736
08:57:09 1638 8192000000 10495114960 10904645632 1195278544 12.732 100 91 179 2 1602734704 386298960 21157200
08:58:13 1236 8192000000 10539529568 10949459968 1190838496 12.691 100 92 182 0 1647589392 435278944 21471952
08:59:18 1009 8192000000 10577583232 10992644096 1192744512 12.709 100 95 183 0 1682790496 467743536 22302448
09:00:23 1264 8192000000 10585373904 11032305664 1194585136 12.726 100 95 184 0 1691379872 473816320 22978416
09:01:28 1510 8192000000 10597674928 11043049472 1193111088 12.712 100 97 186 1 1705024848 489277248 22636512
09:02:35 801 8192000000 10619531792 11043418112 1185719664 12.644 100 99 197 1 1725836896 515939248 24177984
09:03:40 1401 8192000000 10617565616 11073769472 1192291712 12.705 100 94 189 0 1718340080 502619824 23428544
09:04:45 824 8192000000 10642198896 11066527744 1190647968 12.689 100 88 174 0 1741318048 528900304 21769776
09:05:47 2878 8192000000 10636812080 11116998656 1189284640 12.677 100 74 145 0 1737772144 529900656 18586848
09:06:56 20977 8192000000 10547801600 11087208448 1190494192 12.688 100 42 84 0 1694642384 493030016 11118176
09:08:18 13873 8192000000 10534780832 11008069632 1184625408 12.633 100 42 84 1 1639402896 444179216 10598272
09:10:13 26990 8192000000 10262826096 10810810368 1169806512 12.495 99 28 56 1 1437252304 260147568 7298224
09:12:18 36385 8192000000 10079841120 10549444608 1120330080 12.030 98 31 60 2 1181192032 54464512 6397440
09:14:13 60989 8192000000 10091902688 10552057856 1132023264 12.140 100 30 61 2 1201820064 62731328 7065472
09:16:19 102303 8192000000 10150429984 10590216192 1133275536 12.152 99 35 64 4 1214692640 74672192 6744912
09:19:18 121444 8192000000 10165315184 10664656896 1148702016 12.297 99 21 40 0 1275671152 122571088 4398048
09:22:20 51721 8192000000 10108256512 10608504832 1146893280 12.280 99 14 28 0 1208939744 59358928 2687536
09:24:16 6702 8192000000 10141603168 10573316096 1149786224 12.307 99 44 86 1 1232755040 73810432 9158384
09:25:23 2137 8192000000 10137247888 10569256960 1134987488 12.168 99 73 140 0 1221672640 70999792 15685360
09:26:33 41769 8192000000 10161477600 10636988416 1142282688 12.237 99 25 50 2 1262902640 114742768 5877184
09:29:03 102274 8192000000 10120846448 10612903936 1124240320 12.067 98 18 37 0 1220614128 92131456 4242352
09:31:45 47050 8192000000 10063309456 10550460416 1097215104 11.811 97 16 32 0 1127821120 26737776 3868240
09:33:33 43946 8192000000 10076881392 10534629376 1131970512 12.140 100 20 40 0 1185923792 48624704 5328576
09:35:22 105857 8192000000 10067251104 10531774464 1115112256 11.981 98 16 32 0 1159656464 40625344 3918864
09:38:27 47874 8192000000 10058540384 10590281728 1121686512 12.043 99 5 10 0 1174553760 51553888 1313360
09:40:16 45184 8192000000 10071812736 10514911232 1082968864 11.676 96 22 44 2 1130876864 43814848 4093152
09:42:04 33337 8192000000 10121970752 10556891136 1098903664 11.827 97 35 70 1 1200490544 94069360 7517520
09:43:42 19691 8192000000 10172655072 10610962432 1126468640 12.088 99 30 60 1 1247111920 113525440 7117840
09:45:05 18954 8192000000 10244920480 10683453440 1138903888 12.205 99 45 87 3 1342433248 192844000 10685360
09:46:27 49098 8192000000 10246649104 10685579264 1114710928 11.977 97 33 66 3 1295962544 173546976 7704640
09:48:18 43809 8192000000 10262940368 10703712256 1126598480 12.089 99 28 56 0 1373761296 240935744 6227072
09:50:25 98290 8192000000 10071047840 10546458624 1109180112 11.925 98 10 20 0 1192253904 81012464 2061328
09:53:08 94071 8192000000 10108956336 10603163648 1113427680 11.965 97 22 44 0 1184600848 65940656 5232512
09:55:42 48753 8192000000 10114817664 10573934592 1141729840 12.232 100 27 54 0 1205064080 57871808 5462432
09:57:37 64321 8192000000 10125564704 10592649216 1130258816 12.124 99 39 76 1 1235787856 97839648 7689392
09:59:48 63895 8192000000 10093813616 10534965248 1118739520 12.015 98 20 40 0 1163879632 41043440 4096672
10:01:53 11662 8192000000 10128469424 10567684096 1133814848 12.157 100 46 92 0 1214652592 71640688 9197056
10:03:10 125130 8192000000 10101400400 10604236800 1134491824 12.164 99 16 32 2 1204253792 66193680 3568288
10:06:21 108195 8192000000 10120829280 10584023040 1139011200 12.206 99 30 60 1 1182342928 37474752 5856976
10:09:20 32121 8192000000 10078388640 10530463744 1150269232 12.312 100 20 40 0 1211396224 56322032 4804960
10:10:55 67486 8192000000 10096048112 10562678784 1120433376 12.031 97 13 27 0 1165278112 42284064 2560672
10:13:45 74125 8192000000 10072183888 10528931840 1135529936 12.173 99 11 22 0 1190208416 51953312 2725168
10:16:07 66714 8192000000 10073356096 10532012032 1124268720 12.067 99 17 32 1 1179291920 51709152 3314048
10:18:14 96568 8192000000 10053581584 10509430784 1094173632 11.782 96 9 17 2 1132079952 36544960 1361360
10:21:00 24010 8192000000 10104033712 10538127360 1134771472 12.166 100 37 74 1 1187306672 45257424 7277776
10:22:27 14268 8192000000 10148918720 10585546752 1146536704 12.277 100 37 66 1 1239497424 85792560 7168160
10:23:44 29746 8192000000 10101751152 10554818560 1116090544 11.990 98 33 63 1 1199124336 74797024 8236768
10:25:15 32812 8192000000 10164751648 10601979904 1130905680 12.130 99 50 100 0 1242933952 101894544 10133728
10:26:56 36435 8192000000 10098317520 10537172992 1109087104 11.924 97 22 43 0 1173411120 59129568 5194448
10:28:33 43430 8192000000 10150100432 10653577216 1142799600 12.242 100 38 77 1 1236260176 85642432 7818144
10:30:25 48582 8192000000 10103589616 10572472320 1124764608 12.072 99 43 85 0 1185183584 51779344 8639632
10:32:16 8457 8192000000 10101226256 10515787776 1126978816 12.093 100 60 118 1 1188375680 49782480 11614384
10:33:26 77447 8192000000 10047004112 10510258176 1087570464 11.720 97 22 42 1 1141052736 49309488 4172784
10:36:15 65017 8192000000 9116495328 9385799680 252323728 2.988 23 15 26 2 266416080 11451728 2640624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-03-2022 10:38:51 to 26-03-2022 10:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 86 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

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

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          220564
MON$OLDEST_ACTIVE               220565
MON$OLDEST_SNAPSHOT             220565
MON$NEXT_TRANSACTION            220565
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                       2055200
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
--- end of diff output ---

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

26.03.22 10:39:11. 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-03-26 10:39:14 No severe PSQL-related problems occured
26.03.22 10:39:15. End of report.