Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2021 01: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 16712704 kB
MemAvailable 31445136 kB
Buffers 0 kB
Cached 14974488 kB
SwapCached 0 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.9
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-12-16 01:38:00
DTS_END                         2021-12-16 01:38:00.8400
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41886
TRN_ID                          242516
ATT_ID                          689
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: 9115.08 19718 1093810
customer order: creation 176.33 325 21160
customer order: refuse 15.10 368 1812
order to supplier: creation 47.04 1212 5645
order to supplier: removal 3.58 86627 429
invoice (draft): creation 46.27 3326 5552
invoice (draft): removal 51.97 751 6236
invoice accept: apply 41.66 18571 4999
invoice accept: cancel 46.68 813 5601
customer reserve: creation 3807.87 186 456944
customer reserve: removal 4578.52 53 549423
realization accept: apply 86.38 182 10366
realization accept: cancel 81.72 116 9806
payment from customer: creation 59.26 118 7111
payment from customer: removal 5.78 81 694
payment to supplier: creation 51.23 125 6148
payment to supplier: removal 3.35 82 402
service: total inventory turnovers 4.74 1068 569
service: total monetary turnovers 6.67 24 800
service: aggregate perf. data 0.91 16355 109
service: refresh index statistics 0.03 263985 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 01:39:27 to 16-12-2021 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7622
TEST_TIME 2 6606
TEST_TIME 3 7227
TEST_TIME 4 6380
TEST_TIME 5 6049
TEST_TIME 6 5680
TEST_TIME 7 6364
TEST_TIME 8 6071
TEST_TIME 9 6704
TEST_TIME 10 6771
TEST_TIME 11 6738
TEST_TIME 12 7188
TEST_TIME 13 7420
TEST_TIME 14 7660
TEST_TIME 15 7823
TEST_TIME 16 8056
TEST_TIME 17 8445
TEST_TIME 18 8519
TEST_TIME 19 8762
TEST_TIME 20 8932
TEST_TIME 21 8989
TEST_TIME 22 9175
TEST_TIME 23 9137
TEST_TIME 24 9324
TEST_TIME 25 10320
TEST_TIME 26 10463
TEST_TIME 27 10300
TEST_TIME 28 10222
TEST_TIME 29 10573
TEST_TIME 30 10454
TEST_TIME 31 10334
TEST_TIME 32 10088
TEST_TIME 33 10052
TEST_TIME 34 9851
TEST_TIME 35 9845
TEST_TIME 36 9658
TEST_TIME 37 9702
TEST_TIME 38 9745
TEST_TIME 39 9667
TEST_TIME 40 9785
TEST_TIME 41 9652
TEST_TIME 42 9570
TEST_TIME 43 9522
TEST_TIME 44 9370
TEST_TIME 45 9390
TEST_TIME 46 9322
TEST_TIME 47 9199
TEST_TIME 48 9192
TEST_TIME 49 9114
TEST_TIME 50 9232
TEST_TIME 51 9164
TEST_TIME 52 9254
TEST_TIME 53 9202
TEST_TIME 54 9343
TEST_TIME 55 9471
TEST_TIME 56 9474
TEST_TIME 57 9508
TEST_TIME 58 9537
TEST_TIME 59 9581
TEST_TIME 60 9530
TEST_TIME 61 9587
TEST_TIME 62 9639
TEST_TIME 63 9531
TEST_TIME 64 9511
TEST_TIME 65 9496
TEST_TIME 66 9420
TEST_TIME 67 9425
TEST_TIME 68 9398
TEST_TIME 69 9366
TEST_TIME 70 9289
TEST_TIME 71 9272
TEST_TIME 72 9242
TEST_TIME 73 9286
TEST_TIME 74 9245
TEST_TIME 75 9309
TEST_TIME 76 9380
TEST_TIME 77 9359
TEST_TIME 78 9422
TEST_TIME 79 9419
TEST_TIME 80 9378
TEST_TIME 81 9409
TEST_TIME 82 9566
TEST_TIME 83 9591
TEST_TIME 84 9591
TEST_TIME 85 9490
TEST_TIME 86 9420
TEST_TIME 87 9385
TEST_TIME 88 9353
TEST_TIME 89 9311
TEST_TIME 90 9282
TEST_TIME 91 9259
TEST_TIME 92 9230
TEST_TIME 93 9292
TEST_TIME 94 9266
TEST_TIME 95 9271
TEST_TIME 96 9329
TEST_TIME 97 9281
TEST_TIME 98 9252
TEST_TIME 99 9330
TEST_TIME 100 9307
TEST_TIME 101 9292
TEST_TIME 102 9375
TEST_TIME 103 9342
TEST_TIME 104 9347
TEST_TIME 105 9477
TEST_TIME 106 9452
TEST_TIME 107 9457
TEST_TIME 108 9392
TEST_TIME 109 9363
TEST_TIME 110 9317
TEST_TIME 111 9298
TEST_TIME 112 9323
TEST_TIME 113 9274
TEST_TIME 114 9267
TEST_TIME 115 9222
TEST_TIME 116 9194
TEST_TIME 117 9207
TEST_TIME 118 9209
TEST_TIME 119 9198
TEST_TIME 120 9122

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 01:39:31 to 16-12-2021 01:39:34.

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 1516913 1516913 0 0.00 0 16257 6 0 0
doc_list_biud 1516903 1516903 0 0.00 0 43558 25 0 0
sp_add_invoice_to_stock 5115 4999 116 2.26 40 240107 18572 0 116
sp_cancel_adding_invoice 5601 5601 0 0.00 4 66236 813 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 19312 369 0 0
sp_cancel_customer_reserve 549423 549423 0 0.00 1 43559 54 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 2 6016 81 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 3940 82 0 0
sp_cancel_supplier_invoice 6245 6236 9 0.14 2 23835 752 0 9
sp_cancel_supplier_order 429 429 0 0.00 6 814611 86627 0 0
sp_cancel_write_off 9806 9806 0 0.00 1 14404 116 0 0
sp_client_order 21160 21160 0 0.00 3 43113 325 0 0
sp_customer_reserve 456944 456944 0 0.00 0 40233 187 0 0
sp_fill_shopping_cart 32352 32352 0 0.00 1 14719 40 0 0
sp_fill_shopping_cart_clo_res 456929 456929 0 0.00 0 14643 13 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 37998 329 0 0
sp_kill_cost_storno 16503 16503 0 0.00 0 8029 55 0 0
sp_kill_qstorno_ret_qs2qd 1115317 1115317 0 0.00 0 43554 29 0 0
sp_kill_qty_storno 588656 588656 0 0.00 0 43556 64 0 0
sp_lock_selected_doc 21219 21219 0 0.00 0 9660 6 0 0
sp_make_cost_storno 28608 28608 0 0.00 0 14007 50 0 0
sp_multiply_rows_for_pdistr 28608 28608 0 0.00 0 11119 29 0 0
sp_multiply_rows_for_qdistr 21160 21160 0 0.00 0 43103 152 0 0
sp_pay_from_customer 7111 7111 0 0.00 4 13571 119 0 0
sp_pay_to_supplier 6148 6148 0 0.00 4 14554 125 0 0
sp_payment_common 13259 13259 0 0.00 1 10454 30 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 19306 346 0 0
sp_qd_handle_on_invoice_upd_sts 10584 10584 0 0.00 0 22822 305 0 0
sp_qd_handle_on_reserve_upd_sts 10366 10366 0 0.00 0 16673 70 0 0
sp_reserve_write_off 10366 10366 0 0.00 6 16698 182 0 0
sp_supplier_invoice 5552 5552 0 0.00 5 85697 3326 0 0
sp_supplier_order 5645 5645 0 0.00 4 24447 1212 0 0
srv_aggregate_perf_data 110 109 1 0.90 3049 112054 16355 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2335 155968 44041 0 0
srv_find_qd_qs_mism 1045611 1045611 0 0.00 0 14505 6 0 0
srv_make_invnt_saldo 569 569 0 0.00 33 41030 1068 0 0
srv_make_money_saldo 800 800 0 0.00 0 1208 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 176603 385350 263985 0 0
t$perf-abend:sp_add_invoice_to_stock 116 116 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5000 5000 0 0.00 0 11561 149 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 6898 105 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 6660 14 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 661 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3553 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 3908 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 3932 28 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 21978 1519 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 10765 23 0 0
t$perf-norm:sp_client_order 21160 21160 0 0.00 0 7610 16 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 5359 14 0 0
t$perf-norm:sp_pay_from_customer 7111 7111 0 0.00 0 4371 7 0 0
t$perf-norm:sp_pay_to_supplier 6148 6148 0 0.00 0 8703 12 0 0
t$perf-norm:sp_reserve_write_off 10366 10366 0 0.00 0 8278 12 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 5805 20 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 7162 20 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 4034 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 512 16 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 3898 25 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 2941 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10196 9964 232 2.27 0 5529 17 0 232
v_all_customers 37998 37998 0 0.00 0 16 0 0 0
v_all_suppliers 31322 31322 0 0.00 0 19 1 0 0
v_all_wares 42320 42320 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1617 10 0 0
v_cancel_client_order 3624 3624 0 0.00 0 2402 4 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 4168 9 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1016 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 3888 10 0 0
v_cancel_supplier_invoice 856 838 18 2.10 0 3737 22 0 18
v_cancel_supplier_order 858 858 0 0.00 0 132 2 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2235 14 0 0
v_max_id_clo_ord 5643 5643 0 0.00 0 360 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1180 7 0 0
v_max_id_ord_sup 5549 5549 0 0.00 0 6401 7 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 3762 5 0 0
v_max_non_paid_realizn 3943 3943 0 0.00 0 1379 4 0 0
v_min_id_clo_ord 5643 5643 0 0.00 0 5613 6 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 1872 6 0 0
v_min_id_ord_sup 5549 5549 0 0.00 0 8210 15 0 0
v_min_non_paid_invoice 6148 6148 0 0.00 0 10429 24 0 0
v_min_non_paid_realizn 7111 7111 0 0.00 0 10445 22 0 0
v_our_firm 10658 10658 0 0.00 0 26 1 0 0
v_reserve_write_off 20732 20732 0 0.00 0 3188 5 0 0
x_lock_depdocs_on_canc_invoice 11418 11418 0 0.00 0 14978 106 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 5154 283 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 1 24437 1133 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 85681 3175 0 0
x_make_qstorno_2100_3300 456944 456944 0 0.00 0 38846 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 01:39:35 to 16-12-2021 01:39:39.

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
23:08:04 4 8192000000 8799415776 8810889216 5805408 0.070 2 1 2 0 5905040 56848 42784
23:09:06 36017 8192000000 9919392720 10236538880 1002096464 10.899 100 91 182 1 1032275008 6366128 23812416
23:10:45 22082 8192000000 9947048672 10269773824 1026388144 11.134 100 88 176 0 1057946160 8672880 22885136
23:12:27 19296 8192000000 9982073136 10318409728 1063964672 11.494 100 75 150 0 1092553744 9654432 18934640
23:13:59 10389 8192000000 10053242784 10406010880 1132889504 12.149 100 79 158 2 1163664064 11852672 18921888
23:15:14 7802 8192000000 10099622400 10464808960 1192979600 12.711 100 77 153 0 1222554304 12670800 16903904
23:16:25 3935 8192000000 10143727696 10516361216 1227494352 13.031 100 76 153 1 1258405872 13502496 17409024
23:17:31 2656 8192000000 10185735776 10560012288 1262402640 13.352 100 90 175 4 1295490848 13976208 19112000
23:18:36 1648 8192000000 10210044208 10591809536 1293538160 13.636 100 95 191 1 1328784912 13811248 21435504
23:19:38 4138 8192000000 10218747136 10612875264 1307090256 13.760 100 74 145 3 1333241360 8644896 17506208
23:20:43 4785 8192000000 10208374992 10620358656 1314977472 13.831 100 34 68 1 1330592976 8405008 7210496
23:21:49 13243 8192000000 10203020944 10623586304 1321604784 13.891 100 13 26 0 1330615728 6005328 3005616
23:23:05 35376 8192000000 10245518112 10652790784 1331773392 13.983 100 59 116 2 1351809488 5708352 14327744
23:24:41 7110 8192000000 10225594592 10648784896 1337239600 14.033 100 27 52 2 1348738640 5788016 5711024
23:25:50 8845 8192000000 10235589696 10659110912 1344171520 14.095 100 23 45 0 1356032288 6928816 4931952
23:27:05 50886 8192000000 10225451744 10644598784 1294836144 13.648 96 26 50 1 1306132416 6142000 5154272
23:28:58 4795 8192000000 10209057680 10619711488 1292037200 13.623 98 25 50 0 1303614096 5765648 5811248
23:30:06 8435 8192000000 10144769472 10548469760 1207119856 12.842 95 18 36 1 1216230992 4946256 4164880
23:31:15 59913 8192000000 10169512576 10554712064 1240844576 13.154 98 58 117 2 1261456192 5559568 15052048
23:33:15 7896 8192000000 10152303904 10559459328 1255726128 13.291 99 22 44 1 1266626976 5545200 5355648
23:34:27 10562 8192000000 10170228192 10582413312 1272834720 13.448 99 19 37 1 1282905536 5692944 4377872
23:35:40 59342 8192000000 10184308128 10603397120 1300997952 13.704 100 25 50 0 1312273824 5347376 5928496
23:37:43 4646 8192000000 10211622048 10621390848 1309653792 13.783 100 42 82 1 1325098928 5979184 9465952
23:38:52 4445 8192000000 10250228272 10656595968 1341603376 14.072 100 63 128 1 1364572400 8348704 14620320
23:40:06 22369 8192000000 10271971504 10688372736 1358547840 14.224 100 52 104 0 1385150272 15517024 11085408
23:41:32 75248 8192000000 10280035968 10707161088 1376097280 14.382 100 32 64 0 1397715072 12735824 8881968
23:43:57 15977 8192000000 10298074448 10718203904 1387178240 14.481 100 40 80 2 1408880784 11868224 9834320
23:45:17 26237 8192000000 10302284976 10737164288 1393766096 14.539 100 35 70 0 1420376112 17944144 8665872
23:46:47 12954 8192000000 10326180496 10754781184 1411066512 14.693 100 53 106 2 1444714800 20661600 12986688
23:48:10 2418 8192000000 10332530304 10756136960 1406213008 14.650 100 66 132 2 1442496272 20767456 15515808
23:49:17 5681 8192000000 10333664240 10765631488 1414043152 14.720 100 49 98 0 1447128448 21488288 11597008
23:50:27 3267 8192000000 10346530112 10767994880 1407302688 14.660 100 75 150 0 1454500336 28949456 18248192
23:51:33 2449 8192000000 10345732496 10766872576 1404965008 14.639 100 75 150 0 1453175296 29269120 18941168
23:52:40 3688 8192000000 10333334608 10742964224 1385661312 14.467 100 74 147 1 1436442896 31931680 18849904
23:53:48 2334 8192000000 10337076592 10750308352 1387521248 14.484 100 79 157 0 1439018736 31772432 19725056
23:54:58 2957 8192000000 10329309584 10743787520 1372548896 14.350 99 85 170 2 1433147168 38799216 21799056
23:56:05 3997 8192000000 10337072240 10755448832 1385715264 14.468 100 65 128 1 1440308656 37180256 17413136
23:57:14 10492 8192000000 10334395968 10768879616 1395991312 14.559 100 35 70 3 1445132080 39949824 9190944
23:58:45 6234 8192000000 10332814816 10758647808 1386589712 14.475 100 50 100 0 1439136032 40022736 12523584
00:00:00 6948 8192000000 10336051856 10757292032 1390477040 14.510 100 38 77 1 1437061152 35535968 11048144
00:01:11 18121 8192000000 10309103568 10738520064 1368754448 14.316 99 21 42 1 1409141840 34104064 6283328
00:02:44 50494 8192000000 10284798704 10718769152 1327230688 13.942 98 15 30 1 1368772272 37438592 4102992
00:04:58 48847 8192000000 10257810176 10688516096 1310797856 13.793 98 14 28 0 1331933472 17628432 3507184
00:06:52 85582 8192000000 10265288208 10714136576 1315108368 13.832 97 9 18 0 1337153440 20134944 1910128
00:09:18 8347 8192000000 10278908416 10691506176 1349725600 14.145 100 65 129 1 1374127296 9120992 15280704
00:10:28 71287 8192000000 10261871104 10712244224 1357677952 14.217 100 19 38 0 1369668304 8022384 3967968
00:12:41 20705 8192000000 10286998416 10726313984 1379255392 14.410 100 24 47 0 1390688752 6117104 5316256
00:14:03 31628 8192000000 10307779024 10747498496 1390758512 14.513 100 28 55 2 1409183936 11748272 6677152
00:15:38 59935 8192000000 10302381888 10754514944 1390564848 14.511 100 24 47 1 1411757056 15407712 5784496
00:17:43 16318 8192000000 10302900800 10759434240 1397149072 14.570 100 9 18 1 1412393984 13018880 2226032
00:19:37 39197 8192000000 10307610032 10765299712 1410718192 14.690 100 10 20 0 1421481856 8498976 2264688
00:21:37 51911 8192000000 10316916368 10765340672 1404189056 14.632 100 28 56 0 1416932864 6501072 6242736
00:23:33 27187 8192000000 10307370032 10765000704 1404586496 14.636 100 19 38 0 1422118576 13582880 3949200
00:25:02 75343 8192000000 10275891408 10733375488 1345493184 14.107 96 12 24 0 1360374656 11752176 3129296
00:27:27 64994 8192000000 10282782304 10751840256 1368368304 14.312 99 8 16 0 1389939984 19612432 1959248
00:29:37 18449 8192000000 10314496576 10766249984 1395249968 14.553 99 45 90 2 1414281104 8403680 10627456
00:31:11 58730 8192000000 10291717040 10745675776 1361810032 14.254 98 18 36 0 1385635200 19687904 4137264
00:33:10 72142 8192000000 10260219008 10714157056 1287087248 13.578 93 9 18 0 1297998752 8794352 2117152
00:35:23 126549 8192000000 10270146272 10730471424 1292101632 13.623 94 16 32 0 1314304624 18453920 3749072
00:38:33 41469 8192000000 10315916288 10757439488 1367199136 14.302 98 44 86 2 1391158224 14958464 9000624
00:40:29 96539 8192000000 10278870864 10721935360 1340527088 14.062 97 21 42 0 1357690000 12440896 4722016
00:43:19 28066 8192000000 10272809008 10726617088 1355340048 14.195 99 17 34 0 1374316528 14904144 4072336
00:45:09 70466 8192000000 10259156544 10725191680 1331933664 13.985 97 11 22 1 1344528752 10029760 2565328
00:47:27 75323 8192000000 10265262176 10728030208 1380174288 14.418 100 14 28 0 1392132544 8652832 3305424
00:49:55 60144 8192000000 10276822624 10728906752 1328576416 13.954 96 16 32 2 1347937744 15513056 3848272
00:51:58 68675 8192000000 10296854416 10750820352 1371527680 14.341 99 9 18 0 1389166752 15472944 2166128
00:54:31 34782 8192000000 10277495984 10732445696 1375105072 14.373 100 7 14 0 1386231296 9900080 1226144
00:56:06 53579 8192000000 10277186272 10734788608 1342480208 14.080 97 17 34 1 1357386480 11428064 3478208
00:58:04 11411 8192000000 10274843376 10712641536 1357202064 14.212 100 33 67 1 1373412080 9467072 6742944
00:59:24 27968 8192000000 10295065392 10744938496 1362808384 14.263 99 40 80 1 1390684880 18536336 9340160
01:00:55 84003 8192000000 10286528528 10745671680 1341657456 14.072 97 12 24 0 1354113728 9907760 2548512
01:03:20 129668 8192000000 10277030960 10724323328 1353383088 14.178 98 19 38 1 1369645920 11785328 4477504
01:06:32 65268 8192000000 10285259616 10746191872 1375957456 14.380 99 8 16 0 1385907360 8111232 1838672
01:08:37 57133 8192000000 10295701136 10760036352 1392158512 14.525 100 12 24 0 1407499152 12618480 2722160
01:10:38 64128 8192000000 10295835232 10771615744 1397083712 14.569 100 17 35 1 1417929024 16975792 3869520
01:12:50 43731 8192000000 10295134656 10759233536 1384488224 14.457 99 14 28 0 1395797168 8266720 3042224
01:14:34 50224 8192000000 10285592400 10739957760 1339502864 14.053 96 16 32 1 1354938288 12245952 3189472
01:16:27 56733 8192000000 10262875472 10723270656 1286336880 13.571 93 21 42 0 1306827248 15301376 5188992
01:18:24 39123 8192000000 10293167360 10742345728 1369515744 14.323 99 25 50 1 1386848512 11501568 5831200
01:20:03 52212 8192000000 10287879568 10756943872 1375179376 14.373 99 32 64 3 1396334400 13958608 7196416
01:22:01 38953 8192000000 10322125392 10762473472 1390127568 14.507 100 44 88 1 1417273888 17169648 9976672
01:23:44 56862 8192000000 10281357344 10746843136 1346757776 14.118 97 5 10 0 1363721552 16055984 907792
01:25:49 155968 8192000000 10287713504 10734460928 1327354240 13.943 96 32 64 1 1345429216 10575936 7499040
01:29:26 10784 8192000000 10299356112 10747990016 1369048832 14.319 99 47 94 1 1396963824 17479600 10435392
01:30:56 41027 8192000000 10264648464 10739896320 1355090544 14.193 99 7 14 0 1372541216 15844880 1605792
01:32:38 70267 8192000000 10263477264 10716225536 1330421136 13.971 97 8 16 0 1339926016 7621728 1883152
01:34:48 63900 8192000000 10261105024 10726027264 1341785232 14.074 98 16 32 0 1356657328 11069200 3802896
01:37:02 60706 8192000000 9899943136 10291666944 1014171136 11.016 75 19 38 0 1027666224 9251632 4243456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2021 01:39:41 to 16-12-2021 01:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 116 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 01:39:57 to 16-12-2021 01:39:59.

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          242950
MON$OLDEST_ACTIVE               242951
MON$OLDEST_SNAPSHOT             242951
MON$NEXT_TRANSACTION            242951
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                       2022336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33544 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33544 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33544 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33544 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Thu Dec 16 01:38:11 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Thu Dec 16 01:38:21 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

16.12.21 01:40:00. 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-12-16 01:40:02 No severe PSQL-related problems occured
16.12.21 01:40:03. End of report.