Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.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 15125440 kB
MemAvailable 30531284 kB
Buffers 0 kB
Cached 16605400 kB
SwapCached 2184 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-10-15 07:37:21
DTS_END                         2022-10-15 07:37:21.3160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37398
TRN_ID                          221675
ATT_ID                          666
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: 9212.80 19905 1105536
customer order: creation 174.18 462 20902
customer order: refuse 14.23 398 1707
order to supplier: creation 45.36 1515 5443
order to supplier: removal 3.43 163141 412
invoice (draft): creation 45.13 3288 5415
invoice (draft): removal 50.39 989 6047
invoice accept: apply 41.86 26268 5023
invoice accept: cancel 46.03 1186 5524
customer reserve: creation 3973.99 245 476879
customer reserve: removal 4525.65 97 543078
realization accept: apply 83.37 279 10004
realization accept: cancel 78.52 177 9422
payment from customer: creation 59.84 172 7181
payment from customer: removal 5.64 136 677
payment to supplier: creation 48.32 194 5798
payment to supplier: removal 3.60 152 432
service: total inventory turnovers 4.33 3923 520
service: total monetary turnovers 7.87 351 944
service: aggregate perf. data 1.03 13243 123
service: refresh index statistics 0.04 181885 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-10-2022 07:39:18 to 15-10-2022 07:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7143
TEST_TIME 2 7337
TEST_TIME 3 7040
TEST_TIME 4 6840
TEST_TIME 5 7668
TEST_TIME 6 7323
TEST_TIME 7 7676
TEST_TIME 8 7400
TEST_TIME 9 7254
TEST_TIME 10 7379
TEST_TIME 11 7231
TEST_TIME 12 7321
TEST_TIME 13 7332
TEST_TIME 14 7448
TEST_TIME 15 7519
TEST_TIME 16 7757
TEST_TIME 17 7961
TEST_TIME 18 8072
TEST_TIME 19 8073
TEST_TIME 20 8010
TEST_TIME 21 8217
TEST_TIME 22 8069
TEST_TIME 23 7990
TEST_TIME 24 8277
TEST_TIME 25 8763
TEST_TIME 26 8939
TEST_TIME 27 9338
TEST_TIME 28 9685
TEST_TIME 29 9769
TEST_TIME 30 10037
TEST_TIME 31 10553
TEST_TIME 32 10439
TEST_TIME 33 10741
TEST_TIME 34 11062
TEST_TIME 35 11452
TEST_TIME 36 11414
TEST_TIME 37 11317
TEST_TIME 38 11294
TEST_TIME 39 11156
TEST_TIME 40 11188
TEST_TIME 41 10997
TEST_TIME 42 10881
TEST_TIME 43 10679
TEST_TIME 44 10604
TEST_TIME 45 10457
TEST_TIME 46 10379
TEST_TIME 47 10457
TEST_TIME 48 10384
TEST_TIME 49 10389
TEST_TIME 50 10444
TEST_TIME 51 10349
TEST_TIME 52 10273
TEST_TIME 53 10183
TEST_TIME 54 10100
TEST_TIME 55 10171
TEST_TIME 56 10109
TEST_TIME 57 10113
TEST_TIME 58 10095
TEST_TIME 59 10134
TEST_TIME 60 10138
TEST_TIME 61 10199
TEST_TIME 62 10155
TEST_TIME 63 10110
TEST_TIME 64 10161
TEST_TIME 65 10200
TEST_TIME 66 10161
TEST_TIME 67 10138
TEST_TIME 68 10193
TEST_TIME 69 10279
TEST_TIME 70 10184
TEST_TIME 71 10095
TEST_TIME 72 10066
TEST_TIME 73 9979
TEST_TIME 74 9895
TEST_TIME 75 9913
TEST_TIME 76 9830
TEST_TIME 77 9791
TEST_TIME 78 9696
TEST_TIME 79 9747
TEST_TIME 80 9733
TEST_TIME 81 9696
TEST_TIME 82 9670
TEST_TIME 83 9687
TEST_TIME 84 9646
TEST_TIME 85 9659
TEST_TIME 86 9703
TEST_TIME 87 9670
TEST_TIME 88 9785
TEST_TIME 89 9794
TEST_TIME 90 9796
TEST_TIME 91 9773
TEST_TIME 92 9725
TEST_TIME 93 9659
TEST_TIME 94 9626
TEST_TIME 95 9553
TEST_TIME 96 9548
TEST_TIME 97 9524
TEST_TIME 98 9494
TEST_TIME 99 9499
TEST_TIME 100 9489
TEST_TIME 101 9449
TEST_TIME 102 9438
TEST_TIME 103 9456
TEST_TIME 104 9451
TEST_TIME 105 9425
TEST_TIME 106 9409
TEST_TIME 107 9417
TEST_TIME 108 9490
TEST_TIME 109 9568
TEST_TIME 110 9589
TEST_TIME 111 9602
TEST_TIME 112 9559
TEST_TIME 113 9529
TEST_TIME 114 9478
TEST_TIME 115 9426
TEST_TIME 116 9376
TEST_TIME 117 9346
TEST_TIME 118 9322
TEST_TIME 119 9289
TEST_TIME 120 9231

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-10-2022 07:39:21 to 15-10-2022 07:39:25.

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 1542436 1542436 0 0.00 0 11402 13 0 0
doc_list_biud 1542424 1542424 0 0.00 0 37143 40 0 0
sp_add_invoice_to_stock 5164 5023 141 2.73 36 212456 26268 0 141
sp_cancel_adding_invoice 5524 5524 0 0.00 8 73916 1187 0 0
sp_cancel_client_order 1707 1707 0 0.00 5 15653 399 0 0
sp_cancel_customer_reserve 543078 543078 0 0.00 1 37182 98 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 2258 137 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 5062 153 0 0
sp_cancel_supplier_invoice 6062 6047 15 0.24 4 14639 990 0 15
sp_cancel_supplier_order 412 412 0 0.00 12 1469424 163141 0 0
sp_cancel_write_off 9422 9422 0 0.00 2 9830 177 0 0
sp_client_order 20902 20902 0 0.00 5 35710 462 0 0
sp_customer_reserve 476879 476879 0 0.00 1 104699 245 0 0
sp_fill_shopping_cart 31755 31755 0 0.00 2 18522 87 0 0
sp_fill_shopping_cart_clo_res 476857 476857 0 0.00 0 15258 18 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 19268 263 0 0
sp_kill_cost_storno 16055 16055 0 0.00 0 9466 69 0 0
sp_kill_qstorno_ret_qs2qd 1102037 1102037 0 0.00 0 37048 42 0 0
sp_kill_qty_storno 581180 581180 0 0.00 0 37139 97 0 0
sp_lock_selected_doc 20856 20856 0 0.00 0 17388 10 0 0
sp_make_cost_storno 27969 27969 0 0.00 0 11396 82 0 0
sp_multiply_rows_for_pdistr 27969 27969 0 0.00 0 6260 36 0 0
sp_multiply_rows_for_qdistr 20902 20902 0 0.00 0 35642 186 0 0
sp_pay_from_customer 7181 7181 0 0.00 6 9646 173 0 0
sp_pay_to_supplier 5798 5798 0 0.00 7 11408 195 0 0
sp_payment_common 12978 12978 0 0.00 2 8263 47 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 15610 316 0 0
sp_qd_handle_on_invoice_upd_sts 10510 10510 0 0.00 0 14124 497 0 0
sp_qd_handle_on_reserve_upd_sts 10004 10004 0 0.00 0 15126 60 0 0
sp_reserve_write_off 10004 10004 0 0.00 8 15174 279 0 0
sp_supplier_invoice 5415 5415 0 0.00 9 93513 3288 0 0
sp_supplier_order 5443 5443 0 0.00 7 43258 1515 0 0
srv_aggregate_perf_data 124 123 1 0.80 2213 175387 13243 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 748 120844 34357 0 0
srv_find_qd_qs_mism 1054770 1054770 0 0.00 0 11141 10 0 0
srv_make_invnt_saldo 520 520 0 0.00 65 104459 3923 0 0
srv_make_money_saldo 944 944 0 0.00 1 15166 351 0 0
srv_recalc_idx_stat 5 5 0 0.00 51278 371656 181885 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 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 5023 5023 0 0.00 0 11734 947 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 5780 386 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 1231 28 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 2413 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 2059 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 337 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2285 44 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 91417 6484 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 4778 30 0 0
t$perf-norm:sp_client_order 20902 20902 0 0.00 0 9948 38 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 6484 27 0 0
t$perf-norm:sp_pay_from_customer 7181 7181 0 0.00 0 6499 20 0 0
t$perf-norm:sp_pay_to_supplier 5798 5798 0 0.00 0 3648 20 0 0
t$perf-norm:sp_reserve_write_off 10004 10004 0 0.00 0 5523 25 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 5750 30 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 4377 32 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 155 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 266 10 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 4466 24 0 0
t$perf-norm:srv_make_money_saldo 944 944 0 0.00 0 1510 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 9 195 83 0 0
v_add_invoice_to_stock 10254 9972 282 2.75 0 5706 23 0 282
v_all_customers 38464 38464 0 0.00 0 73 4 0 0
v_all_suppliers 30204 30204 0 0.00 0 126 4 0 0
v_all_wares 41802 41802 0 0.00 0 356 4 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 3902 20 0 0
v_cancel_client_order 3414 3414 0 0.00 0 3289 13 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1521 12 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 3749 13 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 2471 18 0 0
v_cancel_supplier_invoice 886 856 30 3.38 0 1357 24 0 30
v_cancel_supplier_order 824 824 0 0.00 0 1945 12 0 0
v_cancel_write_off 2028 2028 0 0.00 0 5985 28 0 0
v_max_id_clo_ord 5443 5443 0 0.00 0 218 5 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 4743 21 0 0
v_max_id_ord_sup 5411 5411 0 0.00 0 7623 10 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 96 8 0 0
v_max_non_paid_realizn 3678 3678 0 0.00 0 4211 10 0 0
v_min_id_clo_ord 5443 5443 0 0.00 0 2126 8 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 3083 16 0 0
v_min_id_ord_sup 5411 5411 0 0.00 0 5541 8 0 0
v_min_non_paid_invoice 5798 5798 0 0.00 0 4441 16 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 6302 19 0 0
v_our_firm 10342 10342 0 0.00 0 70 5 0 0
v_reserve_write_off 20008 20008 0 0.00 0 3785 11 0 0
x_lock_depdocs_on_canc_invoice 11143 11143 0 0.00 0 29542 137 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 8360 353 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 2 43207 1347 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 5 93485 3038 0 0
x_make_qstorno_2100_3300 476879 476879 0 0.00 0 104561 215 0 0

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

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:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 28411 8192000000 9725425888 10023903232 800670656 8.903 100 89 178 0 830623424 7772080 22180688
05:09:58 21824 8192000000 9761338912 10069131264 836574432 9.265 100 92 182 0 871459200 12692144 22192624
05:11:25 18804 8192000000 9802629504 10122190848 866001760 9.560 100 82 160 0 910706368 26264880 18439728
05:12:52 8937 8192000000 9858016064 10188091392 906637440 9.964 100 82 162 0 971267392 47084704 17545248
05:14:06 2273 8192000000 9921152864 10261987328 954826896 10.438 100 84 166 0 1035404480 62996176 17581408
05:15:13 2164 8192000000 9962122528 10313555968 987823760 10.760 100 91 181 0 1080562160 73000928 19737472
05:16:17 1178 8192000000 10000456736 10358624256 1020373792 11.076 100 96 185 1 1111863312 73664624 17824896
05:17:20 967 8192000000 10013799472 10386055168 1039805744 11.263 100 93 177 2 1134327744 75633312 18888688
05:18:22 1002 8192000000 10028390816 10402422784 1057892848 11.436 100 94 179 0 1147308736 70513952 18901936
05:19:25 976 8192000000 10026101712 10400825344 1061500768 11.471 100 95 182 1 1145053536 65994816 17557952
05:20:27 909 8192000000 10035415040 10415517696 1065453856 11.509 100 92 176 3 1160637648 76102224 19081568
05:21:29 3416 8192000000 10044656128 10428891136 1072078688 11.572 100 71 140 1 1168001984 81393264 14530032
05:22:33 1728 8192000000 10025886544 10412748800 1080317024 11.650 100 79 149 0 1148174576 52144000 15713552
05:23:36 833 8192000000 10035600272 10420011008 1086815296 11.712 100 91 175 1 1161609680 56384144 18410240
05:24:37 3872 8192000000 10065249088 10458963968 1090288736 11.745 100 70 135 1 1183144752 79182144 13673872
05:25:42 2111 8192000000 10050154112 10447159296 1093779808 11.779 100 72 137 1 1184519664 76600048 14139808
05:26:45 1090 8192000000 10086165376 10475601920 1093487088 11.776 100 93 176 0 1208665648 98226512 16952048
05:27:47 1012 8192000000 10089226256 10480730112 1098102976 11.820 100 93 183 2 1212334512 95867344 18364192
05:28:48 1085 8192000000 10054646624 10442706944 1089568448 11.739 100 96 179 2 1172805920 65951904 17285568
05:29:50 1913 8192000000 10049194544 10451369984 1094447312 11.785 100 80 151 2 1173913536 64130880 15335344
05:30:53 897 8192000000 10010398768 10397593600 1062256128 11.478 100 76 145 1 1133370384 56444800 14669456
05:31:55 994 8192000000 10012378816 10392629248 1058543376 11.443 100 93 178 0 1136008208 59742240 17722592
05:32:57 1289 8192000000 9952406176 10320535552 1016934464 11.042 99 87 154 2 1078168320 45214736 16019120
05:34:00 1423 8192000000 9955917616 10332807168 1018094432 11.054 99 82 141 4 1076509264 44685616 13729216
05:35:02 1009 8192000000 9945904912 10311983104 1005173360 10.929 99 89 162 3 1065595664 42891824 17530480
05:36:04 1181 8192000000 9987864592 10357497856 1022008080 11.091 99 94 173 4 1106643616 66932992 17702544
05:37:06 1076 8192000000 9996690704 10374406144 1042143280 11.285 100 87 166 2 1120957312 61642432 17171600
05:38:16 1458 8192000000 9999891456 10380939264 1059059840 11.447 100 82 158 1 1120045280 44950256 16035184
05:39:22 20038 8192000000 10049504544 10443157504 1081399600 11.661 100 59 118 1 1166482224 72777248 12305376
05:40:45 8254 8192000000 10085735376 10475257856 1099386160 11.832 100 70 136 0 1202865376 89016560 14462656
05:41:58 1064 8192000000 10098931984 10492329984 1097791136 11.817 99 90 176 3 1219242768 101951872 19499760
05:43:02 3978 8192000000 10127605968 10526703616 1118995104 12.017 100 71 126 2 1243970736 111553296 13422336
05:44:11 874 8192000000 10156107584 10551234560 1127977040 12.102 100 94 180 1 1272363808 124391920 19994848
05:45:16 2641 8192000000 10150185728 10549563392 1140337136 12.219 100 87 159 0 1273457328 115447616 17672576
05:46:25 2173 8192000000 10178707488 10578583552 1154809968 12.355 100 78 144 2 1291474864 120999936 15664960
05:47:30 710 8192000000 10231386032 10625089536 1149931440 12.309 100 97 192 2 1341407792 170881664 20594688
05:48:35 2481 8192000000 10249158640 10651832320 1163412384 12.435 100 83 162 0 1357028352 174885408 18730560
05:49:43 2877 8192000000 10280400768 10697097216 1167038464 12.469 100 86 168 0 1391642336 204786320 19817552
05:50:52 2119 8192000000 10316373904 10719440896 1170792640 12.504 100 89 176 3 1431196928 239786016 20618272
05:51:58 1738 8192000000 10357807680 10764009472 1183156592 12.620 100 90 174 1 1468786304 263598992 22030720
05:53:03 3040 8192000000 10368331104 10778931200 1182871664 12.617 100 85 164 0 1484071680 282837712 18362304
05:54:09 2483 8192000000 10372182112 10800627712 1180958208 12.599 100 87 169 2 1476425712 276163552 19303952
05:55:15 885 8192000000 10427109472 10838540288 1181120448 12.601 100 98 194 2 1533736336 330104800 22511088
05:56:19 877 8192000000 10441089328 10847064064 1195024336 12.730 100 93 184 0 1548388592 332071552 21292704
05:57:23 1017 8192000000 10479513872 10883268608 1188368288 12.668 100 95 188 0 1581211584 371204240 21639056
05:58:27 1110 8192000000 10490287584 10907242496 1182857760 12.617 100 96 188 0 1596420720 390998448 22564512
05:59:32 2425 8192000000 10525934800 10937438208 1180979360 12.599 100 77 147 1 1631739744 433010016 17750368
06:00:37 928 8192000000 10566753152 11006767104 1187134928 12.657 100 91 178 1 1673185232 463762976 22287328
06:01:40 813 8192000000 10589699536 11027763200 1192876080 12.710 100 73 146 1 1698253264 486624912 18752272
06:02:45 7949 8192000000 10503848416 11003904000 1196336096 12.742 100 54 108 1 1625057152 415031792 13689264
06:03:57 14612 8192000000 10502096688 10966306816 1167740816 12.476 99 41 80 1 1604570512 425866864 10962832
06:05:29 37291 8192000000 10403924528 10897506304 1129978352 12.121 98 25 50 0 1518819328 381673472 7167504
06:07:17 15191 8192000000 10338139776 10800312320 1138233408 12.199 100 26 52 0 1456642336 311666480 6742448
06:08:36 43706 8192000000 10334091168 10762219520 1172662976 12.522 100 31 59 2 1417422080 237815760 6943344
06:10:34 40896 8192000000 10202090720 10690256896 1133320992 12.153 98 16 30 0 1336316096 199007616 3987488
06:12:25 75565 8192000000 10057737776 10507362304 1089621280 11.739 95 12 22 0 1162789024 71288560 1879184
06:14:44 21242 8192000000 10099017888 10529325056 1141334256 12.228 100 43 85 1 1199215984 49078400 8803328
06:16:14 32892 8192000000 10095025904 10511527936 1141288960 12.228 100 38 69 1 1174395296 26868576 6237760
06:17:47 49312 8192000000 10065514592 10487246848 1126201008 12.086 99 36 66 1 1150665504 17600944 6863552
06:19:42 39878 8192000000 10071773440 10524856320 1136706880 12.185 100 33 62 1 1170994288 26918288 7369120
06:21:28 115074 8192000000 10118666032 10551369728 1129802752 12.120 98 28 54 0 1188959648 53449152 5707744
06:24:24 20521 8192000000 10122502608 10599817216 1136853968 12.186 99 35 70 1 1236343728 91179760 8310000
06:25:47 18126 8192000000 10118662080 10557329408 1139545088 12.211 99 55 101 0 1219441264 67319008 12577168
06:27:06 38932 8192000000 10115967440 10582749184 1140983840 12.225 100 14 28 0 1211894256 67957712 2952704
06:28:54 42433 8192000000 10110713248 10578137088 1153254256 12.340 100 17 34 0 1214383552 57385296 3744000
06:31:32 77233 8192000000 10118660000 10660216832 1159723344 12.401 100 10 20 0 1250818256 89115232 1979680
06:34:10 33871 8192000000 10117826912 10583523328 1143817824 12.251 99 29 56 0 1222193968 72065504 6310640
06:35:47 46355 8192000000 10125287632 10588094464 1144452384 12.257 99 18 36 1 1193285536 45068640 3764512
06:37:41 16171 8192000000 10104638192 10542895104 1145520416 12.267 99 61 116 0 1196214000 38233904 12459680
06:38:59 39322 8192000000 10068287376 10506829824 1094774880 11.788 95 29 52 0 1141493232 41761200 4957152
06:40:41 54839 8192000000 10135364800 10583449600 1135206176 12.170 99 25 50 0 1228851392 87524544 6120672
06:42:44 19808 8192000000 10094642256 10535063552 1138173296 12.198 99 38 76 0 1165322592 19004944 8144352
06:44:15 28557 8192000000 10132979200 10594017280 1126004304 12.084 99 28 56 0 1228283968 96471376 5808288
06:45:45 110258 8192000000 10063295232 10526965760 1067821296 11.531 94 21 42 1 1100605056 27862800 4920960
06:48:45 87804 8192000000 10097328016 10570735616 1115404928 11.984 98 32 64 1 1164036464 41933232 6698304
06:51:24 22487 8192000000 10059983424 10489630720 1095329456 11.793 97 44 81 1 1158340704 54082944 8928304
06:52:48 44999 8192000000 10074843872 10526048256 1130425952 12.125 100 18 31 0 1189820896 55981968 3412976
06:54:36 72650 8192000000 10110408448 10568105984 1129211504 12.114 100 9 18 0 1222559440 91373104 1974832
06:56:49 56772 8192000000 10056919952 10507665408 1095654240 11.796 98 13 24 0 1130878784 32533328 2691216
06:58:46 34206 8192000000 10099897600 10541244416 1091284272 11.755 96 37 74 1 1175085312 75613440 8187600
07:00:26 31906 8192000000 10118578256 10574155776 1104782192 11.883 98 27 53 0 1199407984 88808368 5817424
07:02:26 28530 8192000000 10163841440 10617643008 1124206592 12.067 99 34 68 0 1239914336 108325392 7382352
07:04:07 45091 8192000000 10146999376 10585817088 1109599728 11.929 98 32 64 0 1235629312 119184800 6844784
07:05:56 77342 8192000000 10112268640 10557112320 1125975264 12.083 100 37 72 0 1177568032 44520592 7072176
07:08:14 11088 8192000000 10118267216 10545893376 1124027056 12.065 99 51 99 1 1192942352 59543584 9371712
07:09:27 83003 8192000000 10063024352 10496581632 1111177536 11.944 98 54 106 1 1185127232 63571456 10378240
07:11:51 48868 8192000000 10101807520 10563309568 1142749600 12.241 100 7 14 0 1209329952 65229168 1351184
07:13:40 46730 8192000000 10105165056 10606575616 1151516432 12.324 100 7 14 0 1217134096 64217200 1400464
07:15:27 116515 8192000000 10098342816 10596331520 1114411552 11.974 97 14 28 0 1165149392 47637680 3100160
07:18:24 38467 8192000000 10158524352 10614407168 1155505760 12.361 100 44 88 2 1233179632 68143456 9530416
07:20:07 42681 8192000000 10151026736 10617278464 1131169808 12.132 97 29 57 0 1215859072 78505488 6183776
07:21:58 38981 8192000000 10182102240 10640379904 1136156656 12.179 98 29 58 0 1267496416 124873728 6466032
07:23:41 35332 8192000000 10224133120 10668933120 1149347696 12.303 99 26 52 0 1301489120 145843584 6297840
07:25:17 83229 8192000000 10116827488 10660442112 1135566208 12.174 98 13 26 1 1281625520 142870448 3188864
07:27:55 21783 8192000000 10135603664 10578898944 1127363872 12.097 98 38 76 0 1185077744 50092976 7620896
07:29:17 92217 8192000000 10078462624 10559471616 1144367024 12.257 100 23 46 0 1200487840 51461008 4659808
07:31:52 65691 8192000000 10038544688 10497097728 1116411344 11.993 98 13 26 0 1150066528 31169552 2485632
07:34:06 120844 8192000000 10068080016 10557104128 1119066528 12.018 98 17 34 0 1186631024 63880768 3683728
07:37:07 22156 8192000000 9077387408 9336451072 245267408 2.906 22 6 12 0 256136624 9865136 1004080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-10-2022 07:39:32 to 15-10-2022 07:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-10-2022 07:39:49 to 15-10-2022 07:39:51.

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          222523
MON$OLDEST_ACTIVE               222524
MON$OLDEST_SNAPSHOT             222524
MON$NEXT_TRANSACTION            222524
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:34: 
:35: mscs-test-161.budzdorov.ru    Sat Oct 15 07:37:33 2022
:36:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:37: 
--- end of diff output ---

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

15.10.22 07:39:53. 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-10-15 07:39:55 No severe PSQL-related problems occured
15.10.22 07:39:56. End of report.