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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16381100 kB
MemAvailable 31464972 kB
Buffers 0 kB
Cached 15299688 kB
SwapCached 2688 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-18 16:38:17
DTS_END                         2022-04-18 16:38:17.2480
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(57:17)
IP                              ::1/57850
TRN_ID                          226242
ATT_ID                          675
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: 9121.04 24539 1094525
customer order: creation 157.53 394 18903
customer order: refuse 13.23 405 1588
order to supplier: creation 42.56 1351 5107
order to supplier: removal 3.46 128491 415
invoice (draft): creation 43.13 3384 5176
invoice (draft): removal 55.59 942 6671
invoice accept: apply 37.70 18141 4524
invoice accept: cancel 50.34 901 6041
customer reserve: creation 3565.43 171 427852
customer reserve: removal 4862.80 71 583536
realization accept: apply 77.39 220 9287
realization accept: cancel 92.80 126 11136
payment from customer: creation 53.98 136 6478
payment from customer: removal 5.30 115 636
payment to supplier: creation 45.34 160 5441
payment to supplier: removal 3.15 117 378
service: total inventory turnovers 4.77 1018 572
service: total monetary turnovers 5.76 29 691
service: aggregate perf. data 0.74 23303 89
service: refresh index statistics 0.03 311314 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-04-2022 16:39:34 to 18-04-2022 16:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3809
TEST_TIME 2 3796
TEST_TIME 3 3690
TEST_TIME 4 3766
TEST_TIME 5 3889
TEST_TIME 6 4664
TEST_TIME 7 4673
TEST_TIME 8 4969
TEST_TIME 9 5038
TEST_TIME 10 4980
TEST_TIME 11 5106
TEST_TIME 12 5353
TEST_TIME 13 5560
TEST_TIME 14 5714
TEST_TIME 15 6489
TEST_TIME 16 6730
TEST_TIME 17 6739
TEST_TIME 18 6678
TEST_TIME 19 6888
TEST_TIME 20 7102
TEST_TIME 21 7257
TEST_TIME 22 7210
TEST_TIME 23 7899
TEST_TIME 24 7883
TEST_TIME 25 7991
TEST_TIME 26 8120
TEST_TIME 27 8424
TEST_TIME 28 8990
TEST_TIME 29 9216
TEST_TIME 30 9209
TEST_TIME 31 11047
TEST_TIME 32 11041
TEST_TIME 33 11070
TEST_TIME 34 10799
TEST_TIME 35 10622
TEST_TIME 36 10606
TEST_TIME 37 10503
TEST_TIME 38 10353
TEST_TIME 39 10289
TEST_TIME 40 10124
TEST_TIME 41 10030
TEST_TIME 42 9895
TEST_TIME 43 9930
TEST_TIME 44 9907
TEST_TIME 45 9797
TEST_TIME 46 9807
TEST_TIME 47 9679
TEST_TIME 48 9639
TEST_TIME 49 9606
TEST_TIME 50 9510
TEST_TIME 51 9397
TEST_TIME 52 9312
TEST_TIME 53 9250
TEST_TIME 54 9445
TEST_TIME 55 9433
TEST_TIME 56 9336
TEST_TIME 57 9233
TEST_TIME 58 9240
TEST_TIME 59 9224
TEST_TIME 60 9218
TEST_TIME 61 9249
TEST_TIME 62 9244
TEST_TIME 63 9297
TEST_TIME 64 9257
TEST_TIME 65 9248
TEST_TIME 66 9318
TEST_TIME 67 9349
TEST_TIME 68 9437
TEST_TIME 69 9422
TEST_TIME 70 9461
TEST_TIME 71 9480
TEST_TIME 72 9429
TEST_TIME 73 9538
TEST_TIME 74 9469
TEST_TIME 75 9358
TEST_TIME 76 9414
TEST_TIME 77 9355
TEST_TIME 78 9343
TEST_TIME 79 9354
TEST_TIME 80 9296
TEST_TIME 81 9285
TEST_TIME 82 9248
TEST_TIME 83 9166
TEST_TIME 84 9188
TEST_TIME 85 9248
TEST_TIME 86 9233
TEST_TIME 87 9219
TEST_TIME 88 9307
TEST_TIME 89 9270
TEST_TIME 90 9267
TEST_TIME 91 9334
TEST_TIME 92 9390
TEST_TIME 93 9359
TEST_TIME 94 9398
TEST_TIME 95 9383
TEST_TIME 96 9345
TEST_TIME 97 9416
TEST_TIME 98 9544
TEST_TIME 99 9518
TEST_TIME 100 9490
TEST_TIME 101 9402
TEST_TIME 102 9407
TEST_TIME 103 9355
TEST_TIME 104 9388
TEST_TIME 105 9343
TEST_TIME 106 9311
TEST_TIME 107 9274
TEST_TIME 108 9283
TEST_TIME 109 9275
TEST_TIME 110 9236
TEST_TIME 111 9165
TEST_TIME 112 9194
TEST_TIME 113 9170
TEST_TIME 114 9130
TEST_TIME 115 9154
TEST_TIME 116 9155
TEST_TIME 117 9152
TEST_TIME 118 9131
TEST_TIME 119 9148
TEST_TIME 120 9123

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-04-2022 16:39:38 to 18-04-2022 16:39:41.

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 1492067 1492067 0 0.00 0 24576 6 0 0
doc_list_biud 1492066 1492066 0 0.00 0 72312 35 0 0
sp_add_invoice_to_stock 4591 4524 67 1.45 35 178742 18142 0 67
sp_cancel_adding_invoice 6041 6041 0 0.00 5 87476 902 0 0
sp_cancel_client_order 1588 1588 0 0.00 4 14530 406 0 0
sp_cancel_customer_reserve 583536 583536 0 0.00 1 72314 71 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 5071 115 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 14759 117 0 0
sp_cancel_supplier_invoice 6675 6671 4 0.05 1 16953 942 0 4
sp_cancel_supplier_order 415 415 0 0.00 5 1219147 128492 0 0
sp_cancel_write_off 11136 11136 0 0.00 1 25478 127 0 0
sp_client_order 18903 18903 0 0.00 4 54237 395 0 0
sp_customer_reserve 427852 427852 0 0.00 0 60377 172 0 0
sp_fill_shopping_cart 29180 29180 0 0.00 1 18450 45 0 0
sp_fill_shopping_cart_clo_res 427848 427848 0 0.00 0 21583 15 0 0
sp_get_clo_for_invoice 4520 4520 0 0.00 0 25208 381 0 0
sp_kill_cost_storno 18191 18191 0 0.00 0 20690 70 0 0
sp_kill_qstorno_ret_qs2qd 1185294 1185294 0 0.00 0 67705 38 0 0
sp_kill_qty_storno 623193 623193 0 0.00 0 72312 82 0 0
sp_lock_selected_doc 19160 19160 0 0.00 0 11837 5 0 0
sp_make_cost_storno 25726 25726 0 0.00 0 24569 73 0 0
sp_multiply_rows_for_pdistr 25726 25726 0 0.00 0 15259 36 0 0
sp_multiply_rows_for_qdistr 18903 18903 0 0.00 0 54200 177 0 0
sp_pay_from_customer 6478 6478 0 0.00 4 18460 137 0 0
sp_pay_to_supplier 5441 5441 0 0.00 4 20150 161 0 0
sp_payment_common 11919 11919 0 0.00 2 10254 35 0 0
sp_qd_handle_on_cancel_clo 1588 1588 0 0.00 0 14523 373 0 0
sp_qd_handle_on_invoice_upd_sts 10561 10561 0 0.00 0 24985 375 0 0
sp_qd_handle_on_reserve_upd_sts 9286 9286 0 0.00 0 17126 69 0 0
sp_reserve_write_off 9287 9287 0 0.00 6 18597 220 0 0
sp_supplier_invoice 5176 5176 0 0.00 5 66562 3384 0 0
sp_supplier_order 5107 5107 0 0.00 5 30318 1351 0 0
srv_aggregate_perf_data 90 89 1 1.11 2671 392309 23303 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 2506 142175 48560 0 0
srv_find_qd_qs_mism 1051169 1051169 0 0.00 0 17122 5 0 0
srv_make_invnt_saldo 572 572 0 0.00 27 41730 1018 0 0
srv_make_money_saldo 691 691 0 0.00 1 727 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 195825 478162 311315 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 4524 4524 0 0.00 0 10070 215 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 8515 154 0 0
t$perf-norm:sp_cancel_client_order 1588 1588 0 0.00 0 1418 12 0 0
t$perf-norm:sp_cancel_customer_reserve 599 599 0 0.00 0 2894 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 761 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 2009 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 782 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 24874 1881 0 0
t$perf-norm:sp_cancel_write_off 978 978 0 0.00 0 5771 25 0 0
t$perf-norm:sp_client_order 18903 18903 0 0.00 0 9060 23 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 2307 8 0 0
t$perf-norm:sp_pay_from_customer 6478 6478 0 0.00 0 10389 17 0 0
t$perf-norm:sp_pay_to_supplier 5441 5441 0 0.00 0 19812 18 0 0
t$perf-norm:sp_reserve_write_off 9287 9287 0 0.00 0 9936 12 0 0
t$perf-norm:sp_supplier_invoice 5176 5176 0 0.00 0 14229 37 0 0
t$perf-norm:sp_supplier_order 5107 5107 0 0.00 0 9617 22 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 688 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 34 1 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 1106 28 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 1171 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9172 9038 134 1.46 0 8102 22 0 134
v_all_customers 34788 34788 0 0.00 0 17 1 0 0
v_all_suppliers 28790 28790 0 0.00 0 17 1 0 0
v_all_wares 37806 37806 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1915 12 0 0
v_cancel_client_order 3176 3176 0 0.00 0 4847 10 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 4522 8 0 0
v_cancel_customer_reserve 1198 1198 0 0.00 0 5313 13 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 2425 8 0 0
v_cancel_supplier_invoice 728 720 8 1.09 0 848 13 0 8
v_cancel_supplier_order 830 830 0 0.00 0 1992 6 0 0
v_cancel_write_off 1956 1956 0 0.00 0 5182 18 0 0
v_max_id_clo_ord 5106 5106 0 0.00 0 388 2 0 0
v_max_id_clo_res 937 937 0 0.00 0 3244 13 0 0
v_max_id_ord_sup 5171 5171 0 0.00 0 4787 5 0 0
v_max_non_paid_invoice 1323 1323 0 0.00 0 3182 6 0 0
v_max_non_paid_realizn 3300 3300 0 0.00 0 6453 7 0 0
v_min_id_clo_ord 5106 5106 0 0.00 0 11965 17 0 0
v_min_id_clo_res 937 937 0 0.00 0 4463 17 0 0
v_min_id_ord_sup 5171 5171 0 0.00 0 8332 5 0 0
v_min_non_paid_invoice 5441 5441 0 0.00 0 7438 22 0 0
v_min_non_paid_realizn 6478 6478 0 0.00 0 10247 29 0 0
v_our_firm 9372 9372 0 0.00 0 14 1 0 0
v_reserve_write_off 18572 18572 0 0.00 0 5344 8 0 0
x_lock_depdocs_on_canc_invoice 12352 12352 0 0.00 0 22592 139 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 21219 502 0 0
x_make_qstorno_1000_1200 5107 5107 0 0.00 2 30309 1249 0 0
x_make_qstorno_1200_2000 5176 5176 0 0.00 3 64379 3233 0 0
x_make_qstorno_2100_3300 427852 427852 0 0.00 0 60341 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 16:39:42 to 18-04-2022 16:39:46.

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
14:08:21 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:21 26625 8192000000 9907486176 10217119744 990209376 10.783 100 91 182 0 1019525072 5466688 23849008
14:10:54 22982 8192000000 9943889408 10266378240 1031286160 11.181 100 85 170 0 1061061424 8007488 21767776
14:12:32 17634 8192000000 9987242800 10320744448 1073149552 11.582 100 88 177 0 1104590592 9802832 21638208
14:13:53 14904 8192000000 10048115376 10397609984 1138128592 12.198 100 76 152 0 1166247056 10355344 17763120
14:15:12 8197 8192000000 10099448304 10463399936 1188054352 12.665 100 78 153 2 1218538960 12273552 18211056
14:16:26 4557 8192000000 10156564624 10525622272 1235581600 13.106 100 77 154 0 1264644912 12170896 16892416
14:17:32 3677 8192000000 10169675488 10545815552 1259635072 13.327 100 84 167 1 1291856256 14158224 18062960
14:18:38 2595 8192000000 10199966672 10579316736 1286968368 13.577 100 83 164 0 1318199104 13020064 18210672
14:19:42 2886 8192000000 10216206736 10603491328 1301030928 13.705 100 90 180 2 1334152752 11224016 21897808
14:20:48 23811 8192000000 10210276864 10617901056 1312290400 13.807 100 70 141 1 1340620784 13080976 15249408
14:22:14 7179 8192000000 10206888720 10622537728 1322974192 13.904 100 25 50 0 1336394384 7610320 5809872
14:23:27 6261 8192000000 10221838560 10633588736 1327789040 13.947 100 31 63 1 1341043696 6341328 6913328
14:24:34 7685 8192000000 10217088496 10638290944 1333116992 13.995 100 26 52 0 1346525136 7493008 5915136
14:25:47 32082 8192000000 10215411344 10636193792 1318714208 13.865 99 30 59 1 1331605680 6020976 6870496
14:27:24 7214 8192000000 10233098896 10650882048 1337664128 14.036 100 32 63 0 1351655408 6260880 7730400
14:28:31 6666 8192000000 10205738864 10625302528 1296564416 13.664 98 20 40 0 1310648304 9271328 4812560
14:29:39 55583 8192000000 10100697136 10497576960 1150750400 12.317 89 31 60 1 1162933856 5051888 7131568
14:31:35 9744 8192000000 10148825648 10547658752 1225394112 13.012 97 25 49 1 1236403888 5427392 5582384
14:32:48 9050 8192000000 10157498160 10559062016 1253422608 13.270 99 34 66 1 1266523232 5441984 7658640
14:33:58 66250 8192000000 10164419216 10574352384 1260929968 13.339 98 34 67 0 1274859536 6192016 7737552
14:36:06 4646 8192000000 10190520992 10604732416 1300108176 13.696 100 26 52 3 1312884336 5845600 6930560
14:37:12 7438 8192000000 10202899936 10615754752 1306971504 13.759 100 15 30 1 1315489632 5043856 3474272
14:38:20 37499 8192000000 10223236256 10629296128 1317651008 13.855 100 29 58 0 1329753136 5020224 7081904
14:40:22 35615 8192000000 10246241648 10667692032 1344042304 14.094 100 35 66 2 1358905216 6915280 7947632
14:42:01 18372 8192000000 10263856208 10686849024 1357870896 14.218 100 37 74 2 1377373440 10771360 8731184
14:43:23 10525 8192000000 10281858480 10698371072 1365607040 14.288 100 55 110 1 1394797440 15997664 13192736
14:44:39 8979 8192000000 10291341216 10718298112 1380129968 14.418 100 40 81 0 1406652448 17266608 9255872
14:45:54 8612 8192000000 10314268416 10734841856 1390909248 14.514 100 52 102 2 1423296832 20350912 12036672
14:47:09 17363 8192000000 10323039904 10752032768 1404757440 14.637 100 51 100 4 1442544544 25826720 11960384
14:48:30 15062 8192000000 10342417120 10769477632 1414645840 14.725 100 43 86 0 1455028912 29128448 11254624
14:49:51 5063 8192000000 10354927088 10768822272 1407552752 14.662 100 72 144 4 1458982448 33806000 17623696
14:51:00 2555 8192000000 10367291088 10777444352 1412691536 14.708 100 87 174 1 1471541760 38088672 20761552
14:52:07 2782 8192000000 10378774672 10793050112 1413427216 14.714 100 82 165 0 1478615904 44934928 20253760
14:53:16 6868 8192000000 10354084992 10772017152 1395794688 14.558 100 67 133 2 1458762448 46512816 16454944
14:54:27 5174 8192000000 10343611392 10752921600 1386696928 14.476 100 79 155 1 1447468080 41860320 18910832
14:55:38 5183 8192000000 10336705712 10742632448 1374099952 14.364 100 68 136 0 1445648448 53745856 17802640
14:56:55 4456 8192000000 10346012848 10749149184 1369691040 14.324 100 78 156 0 1449280512 59037616 20551856
14:58:06 4897 8192000000 10379447104 10792534016 1400928176 14.603 100 69 138 0 1483342752 64302192 18112384
14:59:27 3402 8192000000 10372668352 10777497600 1393171632 14.534 99 78 156 1 1476843808 63497760 20174416
15:00:39 2513 8192000000 10411412832 10814717952 1409514800 14.680 100 94 189 1 1506919856 72174336 25230720
15:01:45 2506 8192000000 10417113696 10829254656 1420949568 14.781 100 66 132 0 1516182976 77196928 18036480
15:02:59 11380 8192000000 10391860288 10818400256 1417446912 14.750 100 40 80 1 1497480736 68561744 11472080
15:04:13 13185 8192000000 10394387552 10834219008 1415156912 14.730 100 40 80 1 1503349136 76051824 12140400
15:05:33 19578 8192000000 10385234624 10831249408 1406357632 14.652 100 32 64 0 1488296832 72579520 9359680
15:06:55 25155 8192000000 10357343472 10802434048 1385875456 14.469 100 23 46 0 1467851216 74928224 7047536
15:08:21 33477 8192000000 10351146272 10798862336 1347140368 14.122 98 27 52 1 1432478944 77308048 8030528
15:10:02 61651 8192000000 10299167184 10754605056 1376861968 14.388 100 11 22 0 1401210432 21919264 2429200
15:12:04 70025 8192000000 10294541872 10741444608 1371987472 14.345 99 23 46 0 1389266096 12012688 5265936
15:14:15 60801 8192000000 10264973888 10728857600 1357107648 14.211 99 6 12 0 1369587872 11264304 1215920
15:16:18 68838 8192000000 10287604096 10748071936 1375189152 14.374 99 11 22 0 1389425408 11702096 2534160
15:18:27 73211 8192000000 10295827600 10747244544 1362347472 14.258 98 20 40 3 1378776032 11895040 4533520
15:20:54 72649 8192000000 10285027152 10736705536 1350751520 14.154 97 26 53 1 1370690000 13588000 6350480
15:23:14 124319 8192000000 10291417680 10746605568 1358337968 14.222 98 14 28 1 1372434608 11126368 2970272
15:26:21 91291 8192000000 10312991680 10749206528 1345526352 14.107 97 38 76 1 1371356752 16109168 9721232
15:29:02 42194 8192000000 10314321120 10757681152 1397034176 14.569 100 50 100 1 1419674560 11624144 11016240
15:30:51 59826 8192000000 10317328656 10772881408 1412661968 14.708 100 39 78 1 1435674144 13759600 9252576
15:32:51 47448 8192000000 10297853216 10772688896 1403128480 14.623 100 11 23 0 1417279328 11484976 2665872
15:34:39 89841 8192000000 10303737632 10773479424 1399683168 14.592 99 9 18 0 1414328944 12169600 2476176
15:38:24 103289 8192000000 10312966304 10787385344 1402945168 14.621 100 14 28 0 1416614288 10766128 2902992
15:41:08 75955 8192000000 10315561328 10762911744 1399239040 14.588 100 18 36 0 1414405248 10344256 4821952
15:43:25 51879 8192000000 10284570192 10743750656 1342849680 14.083 96 22 44 2 1360404688 12362224 5192784
15:45:22 139376 8192000000 10282962512 10728177664 1292299600 13.625 93 10 20 0 1311588096 16964352 2324144
15:48:42 63788 8192000000 10215160144 10654240768 1191342176 12.696 87 14 28 0 1205317152 10480624 3494352
15:50:46 80490 8192000000 10253042976 10704011264 1332927168 13.994 99 20 40 0 1352660080 14648160 5084752
15:53:10 76061 8192000000 10254071504 10710753280 1331319440 13.979 98 22 44 0 1346402144 9613824 5468880
15:55:39 22163 8192000000 10255846624 10715549696 1353660736 14.180 100 6 12 1 1371826480 16156736 2009008
15:57:01 55407 8192000000 10265653120 10725855232 1364142880 14.275 100 10 20 0 1378157200 11861744 2152576
15:58:57 62737 8192000000 10253293776 10706399232 1302430928 13.717 95 20 40 0 1318905568 11724976 4749664
16:01:19 76328 8192000000 10269298720 10728579072 1367595088 14.305 100 27 53 2 1395994352 21873632 6525632
16:03:38 74409 8192000000 10269871280 10731835392 1334008896 14.003 97 20 40 0 1349621376 11007440 4605040
16:05:52 91709 8192000000 10267773392 10714902528 1310582352 13.791 96 13 26 0 1330825232 17088000 3154880
16:08:26 57965 8192000000 10276286528 10736254976 1369296912 14.321 100 26 52 1 1391968240 16362224 6309104
16:10:25 57003 8192000000 10291454848 10738561024 1365253184 14.284 99 20 40 0 1379468864 9853280 4362400
16:12:26 87967 8192000000 10282925248 10726703104 1286525200 13.573 93 14 28 0 1297950016 8637680 2787136
16:14:55 25287 8192000000 10316308176 10756079616 1387135856 14.480 100 42 82 1 1409478224 13147344 9195024
16:17:54 142175 8192000000 10309907504 10764668928 1395877168 14.558 100 18 36 0 1409461200 9401872 4182160
16:21:21 41960 8192000000 10305607664 10776137728 1396998176 14.568 99 17 34 0 1416001840 15492320 3511344
16:23:25 66406 8192000000 10312482512 10778963968 1409805792 14.682 100 11 22 0 1420092400 7944512 2342096
16:25:46 53244 8192000000 10314106272 10787475456 1417559952 14.751 100 21 42 2 1433412384 10555392 5297040
16:27:39 81485 8192000000 10308227808 10775101440 1377730512 14.396 97 14 28 1 1390365536 9605664 3029360
16:30:13 80412 8192000000 10291763088 10760949760 1373710896 14.360 97 15 30 1 1386334160 9435264 3188000
16:32:41 69068 8192000000 10299728704 10774077440 1369259088 14.320 97 19 38 0 1389311472 15475440 4576944
16:35:34 44650 8192000000 10310415424 10764738560 1381025152 14.426 98 15 30 1 1398215920 13517264 3673504
16:37:19 67171 8192000000 9296912176 9582911488 420407104 4.881 30 9 16 1 431532896 9176784 1949008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-04-2022 16:39:48 to 18-04-2022 16:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 67 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-04-2022 16:40:04 to 18-04-2022 16:40:06.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          230917
MON$OLDEST_ACTIVE               230918
MON$OLDEST_SNAPSHOT             230918
MON$NEXT_TRANSACTION            230918
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-03 16:41:55.3470
MON$PAGES                       2055136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

18.04.22 16:40:07. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-18 16:40:09 No severe PSQL-related problems occured
18.04.22 16:40:10. End of report.