Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2021 16:40
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 16519428 kB
MemAvailable 31464696 kB
Buffers 0 kB
Cached 15171212 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-14 16:38:00
DTS_END                         2021-12-14 16:38:00.5340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48318
TRN_ID                          243240
ATT_ID                          680
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: 9348.47 20695 1121816
customer order: creation 179.76 369 21571
customer order: refuse 15.33 328 1839
order to supplier: creation 46.55 1218 5586
order to supplier: removal 3.28 94370 394
invoice (draft): creation 47.03 3644 5644
invoice (draft): removal 53.17 697 6380
invoice accept: apply 42.95 18929 5154
invoice accept: cancel 48.47 773 5816
customer reserve: creation 3909.43 189 469132
customer reserve: removal 4689.68 52 562761
realization accept: apply 86.00 172 10320
realization accept: cancel 93.02 102 11162
payment from customer: creation 60.50 99 7260
payment from customer: removal 6.11 69 733
payment to supplier: creation 50.90 131 6108
payment to supplier: removal 3.76 72 451
service: total inventory turnovers 4.82 968 578
service: total monetary turnovers 6.77 28 812
service: aggregate perf. data 0.93 19166 111
service: refresh index statistics 0.03 272517 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2021 16:40:22 to 14-12-2021 16:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6325
TEST_TIME 2 6126
TEST_TIME 3 5440
TEST_TIME 4 6589
TEST_TIME 5 6953
TEST_TIME 6 7190
TEST_TIME 7 7590
TEST_TIME 8 7252
TEST_TIME 9 7165
TEST_TIME 10 7110
TEST_TIME 11 7230
TEST_TIME 12 7110
TEST_TIME 13 7148
TEST_TIME 14 7407
TEST_TIME 15 7674
TEST_TIME 16 7579
TEST_TIME 17 7959
TEST_TIME 18 8128
TEST_TIME 19 9068
TEST_TIME 20 9311
TEST_TIME 21 9036
TEST_TIME 22 9038
TEST_TIME 23 9050
TEST_TIME 24 9381
TEST_TIME 25 9315
TEST_TIME 26 9778
TEST_TIME 27 10048
TEST_TIME 28 11102
TEST_TIME 29 10880
TEST_TIME 30 10638
TEST_TIME 31 10614
TEST_TIME 32 10469
TEST_TIME 33 10474
TEST_TIME 34 10395
TEST_TIME 35 10444
TEST_TIME 36 10410
TEST_TIME 37 10198
TEST_TIME 38 10176
TEST_TIME 39 10158
TEST_TIME 40 10101
TEST_TIME 41 10056
TEST_TIME 42 10026
TEST_TIME 43 9879
TEST_TIME 44 9977
TEST_TIME 45 9928
TEST_TIME 46 9794
TEST_TIME 47 9803
TEST_TIME 48 9801
TEST_TIME 49 9874
TEST_TIME 50 9757
TEST_TIME 51 9912
TEST_TIME 52 9898
TEST_TIME 53 9821
TEST_TIME 54 9814
TEST_TIME 55 9887
TEST_TIME 56 9848
TEST_TIME 57 10012
TEST_TIME 58 10048
TEST_TIME 59 10033
TEST_TIME 60 9968
TEST_TIME 61 9828
TEST_TIME 62 9873
TEST_TIME 63 9781
TEST_TIME 64 9779
TEST_TIME 65 9781
TEST_TIME 66 9739
TEST_TIME 67 9682
TEST_TIME 68 9668
TEST_TIME 69 9635
TEST_TIME 70 9593
TEST_TIME 71 9555
TEST_TIME 72 9442
TEST_TIME 73 9412
TEST_TIME 74 9448
TEST_TIME 75 9397
TEST_TIME 76 9502
TEST_TIME 77 9460
TEST_TIME 78 9513
TEST_TIME 79 9518
TEST_TIME 80 9495
TEST_TIME 81 9552
TEST_TIME 82 9611
TEST_TIME 83 9622
TEST_TIME 84 9611
TEST_TIME 85 9657
TEST_TIME 86 9679
TEST_TIME 87 9687
TEST_TIME 88 9604
TEST_TIME 89 9581
TEST_TIME 90 9554
TEST_TIME 91 9551
TEST_TIME 92 9478
TEST_TIME 93 9449
TEST_TIME 94 9474
TEST_TIME 95 9479
TEST_TIME 96 9520
TEST_TIME 97 9511
TEST_TIME 98 9480
TEST_TIME 99 9474
TEST_TIME 100 9547
TEST_TIME 101 9561
TEST_TIME 102 9584
TEST_TIME 103 9574
TEST_TIME 104 9571
TEST_TIME 105 9623
TEST_TIME 106 9644
TEST_TIME 107 9610
TEST_TIME 108 9699
TEST_TIME 109 9669
TEST_TIME 110 9597
TEST_TIME 111 9583
TEST_TIME 112 9536
TEST_TIME 113 9576
TEST_TIME 114 9537
TEST_TIME 115 9494
TEST_TIME 116 9484
TEST_TIME 117 9453
TEST_TIME 118 9455
TEST_TIME 119 9405
TEST_TIME 120 9351

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2021 16:40:26 to 14-12-2021 16:40:29.

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 1556665 1556665 0 0.00 0 14876 7 0 0
doc_list_biud 1556665 1556665 0 0.00 0 55910 24 0 0
sp_add_invoice_to_stock 5291 5154 137 2.58 42 247529 18930 0 137
sp_cancel_adding_invoice 5816 5816 0 0.00 4 84594 774 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 9182 329 0 0
sp_cancel_customer_reserve 562761 562761 0 0.00 1 55911 52 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 7046 70 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 6248 72 0 0
sp_cancel_supplier_invoice 6391 6380 11 0.17 2 18507 698 0 11
sp_cancel_supplier_order 394 394 0 0.00 6 1151635 94370 0 0
sp_cancel_write_off 11162 11162 0 0.00 1 13058 102 0 0
sp_client_order 21571 21571 0 0.00 3 39515 369 0 0
sp_customer_reserve 469132 469132 0 0.00 0 78583 190 0 0
sp_fill_shopping_cart 32792 32792 0 0.00 1 35843 38 0 0
sp_fill_shopping_cart_clo_res 469123 469123 0 0.00 0 18878 12 0 0
sp_get_clo_for_invoice 5145 5145 0 0.00 0 44515 317 0 0
sp_kill_cost_storno 18162 18162 0 0.00 0 13051 51 0 0
sp_kill_qstorno_ret_qs2qd 1143458 1143458 0 0.00 0 55908 28 0 0
sp_kill_qty_storno 603817 603817 0 0.00 0 55910 61 0 0
sp_lock_selected_doc 21430 21430 0 0.00 0 8747 6 0 0
sp_make_cost_storno 28833 28833 0 0.00 0 14873 54 0 0
sp_multiply_rows_for_pdistr 28833 28833 0 0.00 0 11698 30 0 0
sp_multiply_rows_for_qdistr 21571 21571 0 0.00 0 39501 159 0 0
sp_pay_from_customer 7260 7260 0 0.00 4 13626 100 0 0
sp_pay_to_supplier 6108 6108 0 0.00 5 19209 132 0 0
sp_payment_common 13368 13368 0 0.00 1 10611 24 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 6649 295 0 0
sp_qd_handle_on_invoice_upd_sts 10961 10961 0 0.00 0 44653 307 0 0
sp_qd_handle_on_reserve_upd_sts 10320 10320 0 0.00 0 12030 51 0 0
sp_reserve_write_off 10320 10320 0 0.00 6 15120 172 0 0
sp_supplier_invoice 5644 5644 0 0.00 5 90693 3645 0 0
sp_supplier_order 5586 5586 0 0.00 6 28236 1218 0 0
srv_aggregate_perf_data 112 111 1 0.89 2541 413093 19166 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 738 163627 46683 0 0
srv_find_qd_qs_mism 1072968 1072968 0 0.00 0 10734 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 54 39206 969 0 0
srv_make_money_saldo 812 812 0 0.00 0 1029 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 167475 369102 272517 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 5154 5154 0 0.00 0 14720 156 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3012 93 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 1000 9 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 5692 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 1691 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 2609 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 2816 21 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 19786 1487 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 1468 5 0 0
t$perf-norm:sp_client_order 21571 21571 0 0.00 0 15221 15 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 3415 8 0 0
t$perf-norm:sp_pay_from_customer 7260 7260 0 0.00 0 7537 11 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 5566 9 0 0
t$perf-norm:sp_reserve_write_off 10320 10320 0 0.00 0 8107 9 0 0
t$perf-norm:sp_supplier_invoice 5644 5644 0 0.00 0 6190 15 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 10903 14 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 162 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 333 8 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1413 21 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 377 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10562 10288 274 2.59 0 3790 17 0 274
v_all_customers 39070 39070 0 0.00 0 15 1 0 0
v_all_suppliers 30924 30924 0 0.00 0 26 1 0 0
v_all_wares 43142 43142 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 824 7 0 0
v_cancel_client_order 3678 3678 0 0.00 0 1975 4 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1364 4 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2770 6 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 874 5 0 0
v_cancel_supplier_invoice 826 804 22 2.66 0 3927 19 0 22
v_cancel_supplier_order 788 788 0 0.00 0 2121 7 0 0
v_cancel_write_off 2124 2124 0 0.00 0 5918 16 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 500 1 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 2545 13 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 3432 5 0 0
v_max_non_paid_invoice 1867 1867 0 0.00 0 2528 5 0 0
v_max_non_paid_realizn 3915 3915 0 0.00 0 1397 4 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 4916 6 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 1786 5 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 22749 15 0 0
v_min_non_paid_invoice 6108 6108 0 0.00 0 10601 14 0 0
v_min_non_paid_realizn 7260 7260 0 0.00 0 9830 18 0 0
v_our_firm 10762 10762 0 0.00 0 16 1 0 0
v_reserve_write_off 20640 20640 0 0.00 0 4465 6 0 0
x_lock_depdocs_on_canc_invoice 11794 11794 0 0.00 0 29760 113 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 11694 373 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 1 28227 1140 0 0
x_make_qstorno_1200_2000 5644 5644 0 0.00 1 90225 3506 0 0
x_make_qstorno_2100_3300 469132 469132 0 0.00 0 78580 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2021 16:40:30 to 14-12-2021 16:40:34.

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:04 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:04 39447 8192000000 9899537040 10210525184 985862512 10.741 100 91 182 1 1016157456 6266656 24028288
14:10:48 12554 8192000000 9940178256 10257862656 1018631424 11.059 100 88 176 0 1050068896 8674160 22763312
14:12:13 24428 8192000000 9989131072 10328551424 1065361424 11.508 100 77 154 2 1094155024 9900576 18893024
14:13:39 12774 8192000000 10047810336 10395914240 1129932000 12.121 100 82 164 2 1161246320 11933584 19380736
14:14:54 7337 8192000000 10093970096 10456702976 1173902144 12.533 100 78 154 3 1204648384 12928464 17817776
14:16:05 4384 8192000000 10141127168 10508234752 1222062768 12.981 100 90 181 1 1256287648 14669152 19555728
14:17:11 8711 8192000000 10169622336 10549293056 1259351424 13.324 100 83 166 0 1291206880 13904160 17951296
14:18:21 1380 8192000000 10213222080 10595692544 1295479088 13.654 100 91 186 1 1328411552 11827840 21104624
14:19:24 26326 8192000000 10209217008 10607161344 1301385312 13.708 100 45 90 2 1322615904 9559872 11670720
14:20:51 8074 8192000000 10204519504 10616258560 1311863760 13.803 100 24 49 1 1323550512 6656400 5030352
14:22:02 11975 8192000000 10200993456 10618941440 1320391584 13.880 100 12 25 1 1328531360 5559760 2580016
14:23:16 13014 8192000000 10233917440 10642886656 1328389120 13.953 100 31 62 1 1341577904 5367072 7821712
14:24:29 1589 8192000000 10246518528 10651037696 1333615584 14.000 100 85 173 2 1362610608 8037184 20957840
14:25:32 6358 8192000000 10232797424 10654801920 1333981472 14.003 100 25 50 1 1354098736 15033568 5083696
14:26:41 4062 8192000000 10213090032 10632347648 1317097664 13.850 99 20 40 1 1327375088 5769952 4507472
14:27:48 54358 8192000000 10206499088 10619297792 1287974816 13.586 98 34 68 2 1301810144 6005584 7829744
14:29:47 6157 8192000000 10174568688 10575126528 1264215568 13.369 99 31 62 0 1278535856 7730848 6589440
14:30:56 6442 8192000000 10160364160 10559361024 1243438016 13.178 98 34 68 1 1257714496 5718400 8558080
14:32:06 6142 8192000000 10149913328 10554421248 1247814960 13.218 98 19 38 0 1258474880 6239760 4420160
14:33:15 30266 8192000000 10162603200 10571767808 1262675776 13.355 99 39 78 1 1277178496 5507008 8995712
14:34:47 5476 8192000000 10181191824 10590773248 1287774064 13.584 100 31 62 0 1301461536 6893040 6794432
14:35:53 11137 8192000000 10199591232 10614951936 1304925392 13.740 100 29 56 2 1317883632 6528880 6429360
14:37:05 12984 8192000000 10200427552 10620383232 1311360208 13.798 100 8 16 0 1318365728 5311200 1694320
14:38:19 45635 8192000000 10227513568 10642141184 1330776944 13.974 100 44 87 2 1347148960 6524368 9847648
14:40:10 14632 8192000000 10257092864 10671161344 1353470112 14.179 100 43 86 3 1371164496 8906944 8787440
14:41:30 16768 8192000000 10284066864 10695704576 1366587504 14.296 100 63 127 1 1393244208 12094720 14561984
14:42:50 20962 8192000000 10284472240 10701479936 1362259088 14.258 99 70 139 0 1389905184 11507888 16138208
14:44:20 3076 8192000000 10303590256 10723782656 1386544096 14.475 100 57 112 1 1417179312 17191792 13443424
14:45:28 7793 8192000000 10326246192 10739691520 1398791968 14.584 100 73 143 1 1434845776 19733904 16319904
14:46:40 11287 8192000000 10318099152 10736214016 1374890960 14.371 98 61 120 0 1408361504 20364896 13105648
14:47:57 2995 8192000000 10335974352 10743279616 1397035680 14.569 100 84 170 3 1441994528 24768400 20190448
14:49:04 3881 8192000000 10334146624 10754596864 1397224208 14.570 99 69 138 2 1442583376 28068880 17290288
14:50:13 7290 8192000000 10326422816 10745716736 1390469328 14.510 99 54 108 1 1434381584 30174160 13738096
14:51:25 4279 8192000000 10336524336 10750095360 1387540016 14.484 99 61 122 3 1441737136 38808544 15388576
14:52:35 3202 8192000000 10356787648 10762276864 1394610512 14.547 100 87 169 1 1457455664 41380640 21464512
14:53:41 737 8192000000 10344751040 10743939072 1384696064 14.459 100 88 175 1 1449723248 44013312 21013872
14:54:45 1949 8192000000 10348410080 10746998784 1361404960 14.250 99 95 188 2 1439562352 54275872 23881520
14:55:52 4161 8192000000 10322402000 10734972928 1359588528 14.234 100 63 126 0 1433905344 57263424 17053392
14:57:00 3865 8192000000 10339073392 10752270336 1371826048 14.343 100 65 129 3 1445939920 57231296 16882576
14:58:06 3303 8192000000 10333237136 10740326400 1372105424 14.346 100 68 133 3 1432098400 43617072 16375904
14:59:13 15548 8192000000 10327580960 10755080192 1386704784 14.476 100 36 72 0 1435610704 39421920 9484000
15:00:35 7531 8192000000 10356037632 10774585344 1391217728 14.517 100 71 143 1 1456368656 47791056 17359872
15:01:51 15756 8192000000 10344869344 10783141888 1395815952 14.558 100 31 62 4 1453314304 47724240 9774112
15:03:08 21429 8192000000 10308662720 10756235264 1329282688 13.961 96 20 40 3 1380415424 44792640 6340096
15:04:36 22335 8192000000 10306898752 10749022208 1374694192 14.369 100 15 30 0 1417505584 38472800 4338592
15:05:59 37920 8192000000 10291245824 10737283072 1350722608 14.154 99 22 44 0 1394659744 38091056 5846080
15:07:45 42445 8192000000 10294257280 10721153024 1341099056 14.067 98 39 78 0 1361445696 11343280 9003360
15:09:31 111863 8192000000 10292842528 10737201152 1368722816 14.316 99 39 77 1 1390297184 12557632 9016736
15:12:26 66728 8192000000 10272888416 10728820736 1344242736 14.096 97 12 24 1 1361876032 15407920 2225376
15:14:33 84728 8192000000 10297868128 10729324544 1337910784 14.039 97 33 65 2 1359474480 14301456 7262240
15:17:03 20956 8192000000 10289969696 10734411776 1382788000 14.441 100 22 41 2 1396247968 7820896 5639072
15:18:26 37184 8192000000 10317995648 10764357632 1396864304 14.567 100 38 75 1 1422748016 16917408 8966304
15:20:04 33297 8192000000 10305313088 10774511616 1403911072 14.630 100 16 32 2 1423986416 16255392 3819952
15:21:38 54952 8192000000 10294785264 10748846080 1349495456 14.143 96 11 22 0 1372200720 20023424 2681840
15:23:37 57738 8192000000 10291395184 10759888896 1391337664 14.518 100 29 58 0 1411476720 13272864 6866192
15:25:38 89861 8192000000 10316861856 10784104448 1407906192 14.665 100 9 18 0 1429502256 19142032 2454032
15:28:11 73282 8192000000 10318898592 10787921920 1409606992 14.680 100 14 28 0 1429304960 16488480 3209488
15:30:26 55927 8192000000 10292930704 10746732544 1345923008 14.111 96 12 24 1 1357601984 9236688 2442288
15:32:29 53994 8192000000 10296608000 10758828032 1380898400 14.425 99 23 46 0 1405347456 19000352 5448704
15:34:25 90585 8192000000 10272121792 10743078912 1313534672 13.818 95 26 52 0 1342645280 22910336 6200272
15:37:01 96367 8192000000 10278601680 10705629184 1235119488 13.101 90 16 32 0 1247626272 9301984 3204800
15:39:40 82409 8192000000 10255082928 10703069184 1343119392 14.086 99 35 70 0 1362761872 11970624 7671856
15:42:15 75029 8192000000 10250138016 10710298624 1318847712 13.866 97 20 40 1 1342716384 19156160 4712512
15:44:30 71463 8192000000 10250302608 10715324416 1338271184 14.042 99 12 24 0 1357982560 16593424 3117952
15:47:46 45354 8192000000 10247882448 10692743168 1337079568 14.031 99 26 53 0 1350272576 7350080 5842928
15:49:32 62896 8192000000 10257599504 10711588864 1350960400 14.156 99 14 29 1 1362144160 7798208 3385552
15:51:39 74691 8192000000 10265100016 10729660416 1354074848 14.184 99 11 22 1 1374497040 17843760 2578432
15:53:54 74621 8192000000 10274695376 10734026752 1351779392 14.163 99 11 22 1 1367549296 13121216 2648688
15:56:10 68374 8192000000 10281348032 10735865856 1351095008 14.157 98 22 44 0 1366039312 10375136 4569168
15:58:19 17661 8192000000 10300741056 10740846592 1384916672 14.460 100 42 84 1 1404390752 9532928 9941152
15:59:38 51696 8192000000 10280142160 10728570880 1330267264 13.970 96 20 39 1 1351666976 16778272 4621440
16:01:30 43232 8192000000 10278877632 10732892160 1345471408 14.107 98 17 34 0 1359575120 9537184 4566528
16:03:14 70544 8192000000 10294414208 10753392640 1375650640 14.378 99 12 24 0 1392044752 13906512 2487600
16:05:25 112036 8192000000 10298890960 10746687488 1373210000 14.356 99 19 37 1 1386289696 8048048 5031648
16:08:24 45549 8192000000 10311051088 10781147136 1401681648 14.610 100 21 42 1 1427790112 21273568 4834896
16:10:26 67523 8192000000 10303289072 10764914688 1402568272 14.618 100 10 20 0 1419255712 14184560 2502880
16:12:35 76422 8192000000 10307154800 10766909440 1407412192 14.661 100 16 32 1 1423834800 12806560 3616048
16:14:52 49333 8192000000 10313950896 10764374016 1369233888 14.320 97 14 28 0 1383838000 11329616 3274496
16:16:42 46307 8192000000 10316112800 10770124800 1385690304 14.467 98 30 58 0 1406802432 14436032 6676096
16:18:42 67670 8192000000 10308819280 10762903552 1359915056 14.237 97 20 40 1 1378572656 14271152 4386448
16:20:49 71058 8192000000 10296870640 10739830784 1325199424 13.924 95 28 56 0 1346168096 14559472 6409200
16:23:03 78850 8192000000 10281009776 10749190144 1334154880 14.005 98 23 46 0 1366541504 27408880 4977744
16:25:28 163627 8192000000 10250381072 10696552448 1240730512 13.153 91 9 18 0 1259472672 16554336 2187824
16:29:12 67988 8192000000 10250948496 10701246464 1324641088 13.919 98 16 32 0 1335780000 7462624 3676288
16:31:23 38025 8192000000 10253085280 10714222592 1355040032 14.193 100 16 32 1 1372925632 13669872 4215728
16:33:02 41715 8192000000 10252406192 10706243584 1345418384 14.106 99 15 31 0 1359081008 10268464 3394160
16:35:00 65156 8192000000 10258561040 10711949312 1343599008 14.090 99 16 32 1 1363203168 15668192 3935968
16:37:15 58832 8192000000 9438269520 9761165312 574935920 6.558 43 11 22 0 592122896 14634720 2552256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2021 16:40:36 to 14-12-2021 16:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 137 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2021 16:40:52 to 14-12-2021 16:40:54.

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          243177
MON$OLDEST_ACTIVE               244133
MON$OLDEST_SNAPSHOT             244133
MON$NEXT_TRANSACTION            244133
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                       2046112
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.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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'

14.12.21 16:40:55. 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-14 16:40:58 No severe PSQL-related problems occured
14.12.21 16:40:59. End of report.