Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16033268 kB
MemAvailable 30776276 kB
Buffers 0 kB
Cached 15657912 kB
SwapCached 1628 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.8
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 2021-09-24 19:37:50
DTS_END                         2021-09-24 19:37:50.7580
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/41976
TRN_ID                          238160
ATT_ID                          694
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: 9162.23 23349 1099468
customer order: creation 173.28 364 20794
customer order: refuse 14.38 352 1726
order to supplier: creation 44.94 1241 5393
order to supplier: removal 3.68 94448 441
invoice (draft): creation 46.05 3373 5526
invoice (draft): removal 53.62 808 6434
invoice accept: apply 40.84 17367 4901
invoice accept: cancel 48.32 784 5798
customer reserve: creation 3728.15 173 447378
customer reserve: removal 4710.08 57 565210
realization accept: apply 83.28 174 9993
realization accept: cancel 86.33 105 10360
payment from customer: creation 59.20 105 7104
payment from customer: removal 5.57 83 668
payment to supplier: creation 48.40 127 5808
payment to supplier: removal 3.48 108 418
service: total inventory turnovers 4.85 1087 582
service: total monetary turnovers 6.87 24 824
service: aggregate perf. data 0.88 19402 106
service: refresh index statistics 0.03 326796 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-09-2021 19:39:18 to 24-09-2021 19:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2300
TEST_TIME 2 2813
TEST_TIME 3 3875
TEST_TIME 4 4525
TEST_TIME 5 5021
TEST_TIME 6 4928
TEST_TIME 7 5747
TEST_TIME 8 5688
TEST_TIME 9 5703
TEST_TIME 10 5922
TEST_TIME 11 6005
TEST_TIME 12 6372
TEST_TIME 13 6620
TEST_TIME 14 6701
TEST_TIME 15 6838
TEST_TIME 16 7388
TEST_TIME 17 7767
TEST_TIME 18 7853
TEST_TIME 19 8075
TEST_TIME 20 8353
TEST_TIME 21 8745
TEST_TIME 22 8854
TEST_TIME 23 9073
TEST_TIME 24 9181
TEST_TIME 25 9584
TEST_TIME 26 9699
TEST_TIME 27 11142
TEST_TIME 28 11290
TEST_TIME 29 10918
TEST_TIME 30 11008
TEST_TIME 31 10881
TEST_TIME 32 10646
TEST_TIME 33 10430
TEST_TIME 34 10413
TEST_TIME 35 10701
TEST_TIME 36 10550
TEST_TIME 37 10396
TEST_TIME 38 10345
TEST_TIME 39 10190
TEST_TIME 40 10000
TEST_TIME 41 9961
TEST_TIME 42 9856
TEST_TIME 43 9705
TEST_TIME 44 9582
TEST_TIME 45 9702
TEST_TIME 46 9628
TEST_TIME 47 9462
TEST_TIME 48 9515
TEST_TIME 49 9404
TEST_TIME 50 9269
TEST_TIME 51 9255
TEST_TIME 52 9333
TEST_TIME 53 9277
TEST_TIME 54 9326
TEST_TIME 55 9315
TEST_TIME 56 9369
TEST_TIME 57 9357
TEST_TIME 58 9448
TEST_TIME 59 9423
TEST_TIME 60 9488
TEST_TIME 61 9471
TEST_TIME 62 9468
TEST_TIME 63 9486
TEST_TIME 64 9518
TEST_TIME 65 9547
TEST_TIME 66 9440
TEST_TIME 67 9421
TEST_TIME 68 9386
TEST_TIME 69 9330
TEST_TIME 70 9285
TEST_TIME 71 9265
TEST_TIME 72 9184
TEST_TIME 73 9197
TEST_TIME 74 9164
TEST_TIME 75 9239
TEST_TIME 76 9275
TEST_TIME 77 9265
TEST_TIME 78 9304
TEST_TIME 79 9299
TEST_TIME 80 9358
TEST_TIME 81 9316
TEST_TIME 82 9389
TEST_TIME 83 9359
TEST_TIME 84 9363
TEST_TIME 85 9469
TEST_TIME 86 9429
TEST_TIME 87 9426
TEST_TIME 88 9341
TEST_TIME 89 9315
TEST_TIME 90 9266
TEST_TIME 91 9273
TEST_TIME 92 9249
TEST_TIME 93 9179
TEST_TIME 94 9162
TEST_TIME 95 9138
TEST_TIME 96 9148
TEST_TIME 97 9148
TEST_TIME 98 9211
TEST_TIME 99 9229
TEST_TIME 100 9227
TEST_TIME 101 9276
TEST_TIME 102 9252
TEST_TIME 103 9252
TEST_TIME 104 9300
TEST_TIME 105 9293
TEST_TIME 106 9301
TEST_TIME 107 9353
TEST_TIME 108 9389
TEST_TIME 109 9420
TEST_TIME 110 9369
TEST_TIME 111 9325
TEST_TIME 112 9316
TEST_TIME 113 9279
TEST_TIME 114 9247
TEST_TIME 115 9210
TEST_TIME 116 9158
TEST_TIME 117 9143
TEST_TIME 118 9148
TEST_TIME 119 9178
TEST_TIME 120 9166

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-09-2021 19:39:21 to 24-09-2021 19: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 1514247 1514247 0 0.00 0 22207 6 0 0
doc_list_biud 1514247 1514247 0 0.00 0 23613 27 0 0
sp_add_invoice_to_stock 5005 4901 104 2.07 23 186179 17368 0 104
sp_cancel_adding_invoice 5798 5798 0 0.00 5 79654 784 0 0
sp_cancel_client_order 1726 1726 0 0.00 3 14264 353 0 0
sp_cancel_customer_reserve 565210 565210 0 0.00 1 20663 57 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 5757 84 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 2 18311 108 0 0
sp_cancel_supplier_invoice 6438 6434 4 0.06 2 18729 809 0 4
sp_cancel_supplier_order 441 441 0 0.00 6 1161158 94449 0 0
sp_cancel_write_off 10360 10360 0 0.00 1 20155 106 0 0
sp_client_order 20794 20794 0 0.00 4 18374 364 0 0
sp_customer_reserve 447378 447378 0 0.00 0 43836 173 0 0
sp_fill_shopping_cart 31713 31713 0 0.00 1 15570 41 0 0
sp_fill_shopping_cart_clo_res 447370 447370 0 0.00 0 13048 12 0 0
sp_get_clo_for_invoice 4893 4893 0 0.00 0 15822 313 0 0
sp_kill_cost_storno 17244 17244 0 0.00 0 6957 53 0 0
sp_kill_qstorno_ret_qs2qd 1147655 1147655 0 0.00 0 20658 31 0 0
sp_kill_qty_storno 604855 604855 0 0.00 0 23609 68 0 0
sp_lock_selected_doc 20665 20665 0 0.00 0 7793 5 0 0
sp_make_cost_storno 27798 27798 0 0.00 0 16061 49 0 0
sp_multiply_rows_for_pdistr 27798 27798 0 0.00 0 6974 27 0 0
sp_multiply_rows_for_qdistr 20794 20794 0 0.00 0 12891 167 0 0
sp_pay_from_customer 7104 7104 0 0.00 4 10990 106 0 0
sp_pay_to_supplier 5808 5808 0 0.00 5 18392 128 0 0
sp_payment_common 12912 12912 0 0.00 2 15549 25 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 6625 317 0 0
sp_qd_handle_on_invoice_upd_sts 10691 10691 0 0.00 0 23601 335 0 0
sp_qd_handle_on_reserve_upd_sts 9993 9993 0 0.00 0 14617 56 0 0
sp_reserve_write_off 9993 9993 0 0.00 6 20459 175 0 0
sp_supplier_invoice 5526 5526 0 0.00 5 79066 3373 0 0
sp_supplier_order 5393 5393 0 0.00 5 30548 1241 0 0
srv_aggregate_perf_data 107 106 1 0.93 2842 307359 19402 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1165 116454 46163 0 0
srv_find_qd_qs_mism 1052553 1052553 0 0.00 0 22182 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 34 37732 1088 0 0
srv_make_money_saldo 824 824 0 0.00 1 260 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 165701 533123 326797 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 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 4901 4901 0 0.00 0 13253 188 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 1 2428 73 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 4342 16 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 8635 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 5809 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 4440 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 501 15 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 17179 1384 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 9164 16 0 0
t$perf-norm:sp_client_order 20794 20794 0 0.00 0 9091 21 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 654 5 0 0
t$perf-norm:sp_pay_from_customer 7104 7104 0 0.00 0 6896 11 0 0
t$perf-norm:sp_pay_to_supplier 5808 5808 0 0.00 0 6065 13 0 0
t$perf-norm:sp_reserve_write_off 9993 9993 0 0.00 0 11186 14 0 0
t$perf-norm:sp_supplier_invoice 5526 5526 0 0.00 0 4755 19 0 0
t$perf-norm:sp_supplier_order 5393 5393 0 0.00 0 5362 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 2568 37 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 476 8 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 749 23 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 5473 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9992 9784 208 2.08 0 18517 22 0 208
v_all_customers 37900 37900 0 0.00 0 29 1 0 0
v_all_suppliers 30238 30238 0 0.00 0 17 1 0 0
v_all_wares 41588 41588 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 6476 15 0 0
v_cancel_client_order 3452 3452 0 0.00 0 14147 11 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 5707 7 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 13885 26 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 18274 27 0 0
v_cancel_supplier_invoice 832 824 8 0.96 0 1602 17 0 8
v_cancel_supplier_order 882 882 0 0.00 0 237 3 0 0
v_cancel_write_off 1914 1914 0 0.00 0 20042 34 0 0
v_max_id_clo_ord 5393 5393 0 0.00 0 645 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4388 12 0 0
v_max_id_ord_sup 5526 5526 0 0.00 0 3379 4 0 0
v_max_non_paid_invoice 1608 1608 0 0.00 0 905 3 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 2932 4 0 0
v_min_id_clo_ord 5393 5393 0 0.00 0 2497 7 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 2489 8 0 0
v_min_id_ord_sup 5526 5526 0 0.00 0 4230 7 0 0
v_min_non_paid_invoice 5808 5808 0 0.00 0 15545 19 0 0
v_min_non_paid_realizn 7104 7104 0 0.00 0 5206 15 0 0
v_our_firm 10254 10254 0 0.00 0 12 1 0 0
v_reserve_write_off 19986 19986 0 0.00 0 20004 12 0 0
x_lock_depdocs_on_canc_invoice 11820 11820 0 0.00 0 30957 131 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 6321 315 0 0
x_make_qstorno_1000_1200 5393 5393 0 0.00 1 27881 1153 0 0
x_make_qstorno_1200_2000 5526 5526 0 0.00 2 75008 3230 0 0
x_make_qstorno_2100_3300 447378 447378 0 0.00 0 43835 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2021 19:39:26 to 24-09-2021 19: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
17:07:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:55 36825 8192000000 10383561776 10703171584 1428322016 14.846 100 89 177 1 1463989456 6997552 28669888
17:10:40 24765 8192000000 10458006400 10787844096 1499141760 15.469 100 90 180 0 1537863120 9571376 29149984
17:12:10 15944 8192000000 10503679536 10852028416 1559183792 15.989 100 79 158 1 1593946992 10759648 24003552
17:13:34 8875 8192000000 10578784640 10936463360 1626731440 16.567 100 75 150 0 1660645344 11147248 22766656
17:14:45 7216 8192000000 10648288912 11016577024 1706694752 17.241 100 83 166 1 1743633552 12857296 24081504
17:15:56 5871 8192000000 10704927440 11088633856 1772536144 17.788 100 76 152 0 1805897360 12472512 20888704
17:17:03 2494 8192000000 10776964768 11159883776 1834356000 18.295 100 92 182 1 1874318032 13856784 26105248
17:18:07 2326 8192000000 10815227776 11203538944 1870899056 18.592 100 94 189 0 1912135888 14068560 27168272
17:19:11 14717 8192000000 10817127408 11217797120 1883531184 18.694 100 66 132 0 1911568672 8314432 19723056
17:20:27 6141 8192000000 10804315408 11228139520 1889494736 18.742 100 33 66 2 1905437840 6806080 9137024
17:21:34 9020 8192000000 10798155456 11230347264 1888426208 18.733 100 12 24 0 1897669456 5575504 3667744
17:22:48 7083 8192000000 10812643248 11237236736 1900917168 18.834 100 34 65 2 1915997712 4238704 10841840
17:23:55 23916 8192000000 10860242000 11260194816 1913629904 18.936 100 64 131 2 1939594080 5416144 20548032
17:25:19 4501 8192000000 10844799904 11265822720 1915403776 18.950 100 45 89 1 1935139712 7568656 12167280
17:26:25 6837 8192000000 10816674448 11257344000 1893780272 18.776 99 21 41 0 1909948800 10230016 5938512
17:27:32 47167 8192000000 10804162272 11243798528 1877668832 18.646 98 27 54 0 1895200208 9855392 7675984
17:29:20 5146 8192000000 10745245456 11164393472 1790568336 17.936 97 33 66 1 1806297520 5703984 10025200
17:30:29 3402 8192000000 10720279648 11136671744 1804074432 18.047 100 28 54 3 1819233376 6880176 8278768
17:31:32 52228 8192000000 10744463984 11149692928 1808186672 18.081 100 22 44 0 1821010896 6685584 6138640
17:33:25 9194 8192000000 10740589552 11154481152 1808619328 18.085 99 32 64 1 1828006336 10394992 8992016
17:34:34 8810 8192000000 10755848784 11187789824 1847360960 18.401 100 21 41 1 1858901568 5837744 5702864
17:35:43 11832 8192000000 10775599856 11203571712 1862982928 18.527 100 23 46 1 1876000688 5818288 7199472
17:37:00 41131 8192000000 10793351392 11229073408 1884873648 18.704 100 42 85 0 1902771904 5637568 12260688
17:38:46 39015 8192000000 10840366592 11271340032 1913345296 18.933 100 35 70 1 1932940800 8926240 10669264
17:40:28 8507 8192000000 10879246368 11309043712 1946830880 19.201 100 52 104 2 1978555248 16699632 15024736
17:41:40 23135 8192000000 10890309344 11328479232 1954769168 19.264 100 25 50 1 1978758992 16014432 7975392
17:43:07 12399 8192000000 10898397568 11332923392 1959574816 19.303 100 32 62 0 1985844688 17254240 9015632
17:44:38 8336 8192000000 10921419360 11353186304 1974583248 19.422 100 54 106 1 2012491296 22480976 15427072
17:45:49 13118 8192000000 10936586320 11368493056 1986423536 19.516 100 63 125 0 2031681136 27012528 18245072
17:47:08 9909 8192000000 10955480816 11383492608 1980779120 19.471 99 67 132 0 2032674688 32718656 19176912
17:48:22 2924 8192000000 10980328256 11404488704 2006384960 19.673 100 72 143 0 2062706368 35176368 21145040
17:49:28 2956 8192000000 10989376896 11404603392 2005438864 19.666 100 87 172 1 2069487744 38956928 25091952
17:50:36 2286 8192000000 10996805120 11410726912 2006509504 19.674 100 88 179 1 2074046704 40289824 27247376
17:51:45 3064 8192000000 10961002656 11375054848 1972233504 19.403 99 76 153 1 2036718336 41574992 22909840
17:52:52 1393 8192000000 10960132352 11370205184 1961465152 19.318 100 85 168 4 2038272752 51449088 25358512
17:53:58 1801 8192000000 10962045520 11365134336 1958972768 19.298 100 88 171 1 2035022864 51191520 24858576
17:55:04 3415 8192000000 10947098272 11366100992 1942755472 19.169 99 72 145 0 2016810384 51117904 22937008
17:56:12 1165 8192000000 10973163056 11378774016 1966181312 19.355 100 90 181 0 2050806960 57389456 27236192
17:57:20 2873 8192000000 10963031056 11382476800 1958242048 19.292 100 58 115 0 2040591552 63663792 18685712
17:58:26 4095 8192000000 10953313664 11376488448 1948263488 19.213 99 57 113 1 2021029040 54246992 18518560
17:59:36 8778 8192000000 10948050816 11391279104 1974100592 19.418 100 34 67 1 2035504720 49334576 12069552
18:00:49 14205 8192000000 10940721472 11377893376 1960700944 19.312 100 35 68 2 2019613344 47682448 11229952
18:02:05 25789 8192000000 10925351120 11379310592 1955617216 19.271 100 25 50 0 2016243728 51606368 9020144
18:03:34 33203 8192000000 10934224432 11389116416 1940389072 19.150 99 29 58 1 2005858816 54793312 10676432
18:05:13 64466 8192000000 10847415936 11297882112 1886919888 18.721 97 17 34 0 1907170320 15392032 4858400
18:07:36 75935 8192000000 10881864176 11350097920 1927073152 19.043 99 20 41 1 1959469360 27358752 5037456
18:10:17 51433 8192000000 10911180128 11363860480 1966609232 19.359 100 27 54 1 1991263616 17137024 7517360
18:12:21 79584 8192000000 10884736304 11370008576 1945390944 19.190 99 11 22 0 1970218064 21671712 3155408
18:14:41 34339 8192000000 10884285904 11353538560 1969318224 19.380 100 12 24 0 1980599216 7864944 3416048
18:16:21 22854 8192000000 10884068336 11351998464 1953620064 19.255 98 19 38 1 1966733440 6910928 6202448
18:17:50 91464 8192000000 10897423280 11382784000 1949504016 19.223 98 9 18 0 1960263872 8175344 2584512
18:20:22 20964 8192000000 10922683904 11380703232 1988350672 19.531 100 32 58 1 2010343008 13078208 8914128
18:21:43 20581 8192000000 10890993712 11358343168 1953610176 19.255 99 35 69 1 1979625072 15924464 10090432
18:23:24 72095 8192000000 10900479888 11374563328 1982567728 19.485 100 12 24 0 1993234368 7188032 3478608
18:25:40 61447 8192000000 10916102720 11389124608 1993226592 19.569 100 8 16 1 2005363552 10027936 2109024
18:27:48 83306 8192000000 10906876752 11398717440 1962581312 19.327 98 11 22 1 1977447040 11680656 3185072
18:30:12 46268 8192000000 10919150960 11386507264 1960602512 19.311 98 17 34 0 1973435152 8092896 4739744
18:31:59 40633 8192000000 10904834032 11380850688 1979556096 19.461 100 24 48 1 2002178496 16185504 6436896
18:33:39 24422 8192000000 10928800976 11387387904 1994418592 19.579 100 35 70 0 2017008336 12429680 10160064
18:35:09 84154 8192000000 10908072928 11383721984 1897999600 18.810 95 20 40 2 1913426336 10220512 5206224
18:37:36 83867 8192000000 10874265408 11340242944 1866174816 18.553 95 14 28 1 1884491296 14476992 3839488
18:40:00 39011 8192000000 10896151104 11353743360 1951544560 19.239 100 22 44 1 1970630784 13028560 6057664
18:41:40 103418 8192000000 10848300976 11319382016 1786686720 17.905 91 15 30 1 1803811680 13214208 3910752
18:44:23 93227 8192000000 10887974464 11343118336 1867506848 18.564 96 26 52 1 1882574992 8237104 6831040
18:47:05 37092 8192000000 10856629312 11331383296 1919539728 18.983 98 13 26 0 1931863696 8963088 3360880
18:48:43 76113 8192000000 10890408800 11362148352 1940565824 19.151 99 11 22 1 1958461520 14496064 3399632
18:50:59 101644 8192000000 10865772992 11363303424 1900462800 18.830 97 10 20 1 1922890768 19070608 3357360
18:54:07 51674 8192000000 10868403472 11328962560 1875721408 18.631 97 10 21 0 1887638768 9219472 2697888
18:56:14 51905 8192000000 10903669504 11355516928 1934632288 19.104 99 22 43 0 1955434512 14226592 6575632
18:58:06 96128 8192000000 10884117424 11366346752 1907650592 18.888 97 8 16 0 1929139904 19441168 2048144
19:00:43 28780 8192000000 10912563216 11356012544 1957952048 19.290 100 38 76 1 1981492240 12460960 11079232
19:02:16 76282 8192000000 10922677872 11383365632 1972997472 19.409 100 8 16 1 1985229888 10250144 1982272
19:04:34 108640 8192000000 10897143776 11374895104 1969207104 19.379 100 26 52 0 1991428224 14496704 7724416
19:07:26 62191 8192000000 10910961904 11388317696 1988719888 19.534 100 27 54 0 2006170112 10097232 7352992
19:09:42 40525 8192000000 10876403024 11352547328 1957367936 19.285 99 9 18 2 1965093792 5326560 2399296
19:11:24 68909 8192000000 10885464576 11372015616 1969907136 19.385 100 8 16 0 1983000304 10751568 2341600
19:13:33 46801 8192000000 10905927584 11383521280 1947870896 19.210 98 23 46 2 1967754288 13083376 6800016
19:15:21 116454 8192000000 10916257872 11404496896 1974513904 19.421 99 15 30 0 1994432896 15174800 4744192
19:18:23 62212 8192000000 10894776672 11365687296 1908849520 18.897 96 14 28 0 1921747920 9327424 3570976
19:20:52 41231 8192000000 10879869968 11348414464 1869033968 18.576 95 31 62 1 1889643952 12286960 8323024
19:22:42 52783 8192000000 10888128960 11359670272 1918312704 18.973 98 25 50 0 1939877168 14787104 6777360
19:24:40 83522 8192000000 10891070736 11369422848 1924209632 19.021 98 11 22 0 1944429408 16810048 3409728
19:27:56 97021 8192000000 10883050080 11342381056 1913289872 18.933 97 34 68 0 1931130848 7773600 10067376
19:30:34 6513 8192000000 10896623008 11370983424 1966309232 19.356 100 24 48 0 1984596400 11667312 6619856
19:31:41 86237 8192000000 10892881872 11378196480 1950644560 19.232 99 12 24 1 1967946576 13825520 3476496
19:34:38 76270 8192000000 10886536080 11377389568 1944318848 19.181 99 13 26 1 1968480928 20383920 3778160
19:36:56 69617 8192000000 8971679024 9354186752 64141920 0.776 4 1 2 0 66859744 2655696 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2021 19:39:31 to 24-09-2021 19:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 104 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 24-09-2021 19:39:47 to 24-09-2021 19:39:49.

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          239865
MON$OLDEST_ACTIVE               239866
MON$OLDEST_SNAPSHOT             239866
MON$NEXT_TRANSACTION            239866
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                       2031904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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'

24.09.21 19:39:50. 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
2021-09-24 19:39:52 No severe PSQL-related problems occured
24.09.21 19:39:54. End of report.