Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.07.2022 16:43
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 15727748 kB
MemAvailable 31060760 kB
Buffers 0 kB
Cached 15952420 kB
SwapCached 2480 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.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-16 16:42:36
DTS_END                         2022-07-16 16:42:36.7570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58930
TRN_ID                          258548
ATT_ID                          751
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: 9486.79 23916 1138415
customer order: creation 189.02 324 22682
customer order: refuse 16.61 347 1993
order to supplier: creation 50.09 1230 6011
order to supplier: removal 3.48 101323 417
invoice (draft): creation 50.56 3343 6067
invoice (draft): removal 50.92 837 6110
invoice accept: apply 45.65 17359 5478
invoice accept: cancel 46.05 848 5526
customer reserve: creation 4163.61 173 499633
customer reserve: removal 4547.73 60 545728
realization accept: apply 90.40 163 10848
realization accept: cancel 89.58 111 10750
payment from customer: creation 64.78 118 7773
payment from customer: removal 6.28 52 754
payment to supplier: creation 54.84 141 6581
payment to supplier: removal 3.86 118 463
service: total inventory turnovers 5.13 970 616
service: total monetary turnovers 7.34 31 881
service: aggregate perf. data 0.83 24947 100
service: refresh index statistics 0.03 325830 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-07-2022 16:43:40 to 16-07-2022 16:43:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2874
TEST_TIME 2 5644
TEST_TIME 3 6981
TEST_TIME 4 7073
TEST_TIME 5 6796
TEST_TIME 6 7049
TEST_TIME 7 7110
TEST_TIME 8 7337
TEST_TIME 9 7259
TEST_TIME 10 7364
TEST_TIME 11 7639
TEST_TIME 12 7728
TEST_TIME 13 7716
TEST_TIME 14 7994
TEST_TIME 15 8100
TEST_TIME 16 8273
TEST_TIME 17 8124
TEST_TIME 18 7909
TEST_TIME 19 7796
TEST_TIME 20 8073
TEST_TIME 21 8287
TEST_TIME 22 8247
TEST_TIME 23 8804
TEST_TIME 24 8905
TEST_TIME 25 8954
TEST_TIME 26 9040
TEST_TIME 27 9092
TEST_TIME 28 9171
TEST_TIME 29 9532
TEST_TIME 30 9910
TEST_TIME 31 10013
TEST_TIME 32 10012
TEST_TIME 33 10167
TEST_TIME 34 10545
TEST_TIME 35 10665
TEST_TIME 36 10501
TEST_TIME 37 10510
TEST_TIME 38 10344
TEST_TIME 39 10261
TEST_TIME 40 10346
TEST_TIME 41 10258
TEST_TIME 42 10171
TEST_TIME 43 10058
TEST_TIME 44 10112
TEST_TIME 45 10114
TEST_TIME 46 10055
TEST_TIME 47 10068
TEST_TIME 48 10105
TEST_TIME 49 9987
TEST_TIME 50 9813
TEST_TIME 51 9785
TEST_TIME 52 9662
TEST_TIME 53 9717
TEST_TIME 54 9650
TEST_TIME 55 9670
TEST_TIME 56 9721
TEST_TIME 57 9675
TEST_TIME 58 9657
TEST_TIME 59 9738
TEST_TIME 60 9676
TEST_TIME 61 9756
TEST_TIME 62 9704
TEST_TIME 63 9578
TEST_TIME 64 9557
TEST_TIME 65 9654
TEST_TIME 66 9624
TEST_TIME 67 9669
TEST_TIME 68 9631
TEST_TIME 69 9670
TEST_TIME 70 9677
TEST_TIME 71 9587
TEST_TIME 72 9730
TEST_TIME 73 9779
TEST_TIME 74 9810
TEST_TIME 75 9828
TEST_TIME 76 9842
TEST_TIME 77 9879
TEST_TIME 78 9887
TEST_TIME 79 9830
TEST_TIME 80 9739
TEST_TIME 81 9720
TEST_TIME 82 9731
TEST_TIME 83 9696
TEST_TIME 84 9696
TEST_TIME 85 9654
TEST_TIME 86 9614
TEST_TIME 87 9606
TEST_TIME 88 9578
TEST_TIME 89 9526
TEST_TIME 90 9535
TEST_TIME 91 9464
TEST_TIME 92 9452
TEST_TIME 93 9516
TEST_TIME 94 9499
TEST_TIME 95 9504
TEST_TIME 96 9486
TEST_TIME 97 9514
TEST_TIME 98 9481
TEST_TIME 99 9542
TEST_TIME 100 9554
TEST_TIME 101 9545
TEST_TIME 102 9529
TEST_TIME 103 9585
TEST_TIME 104 9577
TEST_TIME 105 9577
TEST_TIME 106 9615
TEST_TIME 107 9617
TEST_TIME 108 9656
TEST_TIME 109 9588
TEST_TIME 110 9546
TEST_TIME 111 9506
TEST_TIME 112 9528
TEST_TIME 113 9485
TEST_TIME 114 9461
TEST_TIME 115 9428
TEST_TIME 116 9418
TEST_TIME 117 9434
TEST_TIME 118 9496
TEST_TIME 119 9513
TEST_TIME 120 9488

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-07-2022 16:43:43 to 16-07-2022 16:43:47.

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 1600591 1600591 0 0.00 0 19705 6 0 0
doc_list_biud 1600583 1600583 0 0.00 0 47604 26 0 0
sp_add_invoice_to_stock 5628 5478 150 2.66 37 166375 17360 0 150
sp_cancel_adding_invoice 5526 5526 0 0.00 3 59179 849 0 0
sp_cancel_client_order 1993 1993 0 0.00 3 11343 347 0 0
sp_cancel_customer_reserve 545728 545728 0 0.00 0 47605 61 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 2 2023 53 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 12744 118 0 0
sp_cancel_supplier_invoice 6124 6110 14 0.22 2 43903 837 0 14
sp_cancel_supplier_order 417 417 0 0.00 9 1096484 101323 0 0
sp_cancel_write_off 10750 10750 0 0.00 1 11751 111 0 0
sp_client_order 22682 22682 0 0.00 3 54655 325 0 0
sp_customer_reserve 499633 499633 0 0.00 0 64197 173 0 0
sp_fill_shopping_cart 34748 34748 0 0.00 1 15864 38 0 0
sp_fill_shopping_cart_clo_res 499633 499633 0 0.00 0 17923 13 0 0
sp_get_clo_for_invoice 5477 5477 0 0.00 0 25542 287 0 0
sp_kill_cost_storno 17493 17493 0 0.00 0 8554 57 0 0
sp_kill_qstorno_ret_qs2qd 1108733 1108733 0 0.00 0 47602 33 0 0
sp_kill_qty_storno 586839 586839 0 0.00 0 47603 71 0 0
sp_lock_selected_doc 22692 22692 0 0.00 0 8883 7 0 0
sp_make_cost_storno 30676 30676 0 0.00 0 19693 53 0 0
sp_multiply_rows_for_pdistr 30669 30669 0 0.00 0 9521 33 0 0
sp_multiply_rows_for_qdistr 22682 22682 0 0.00 0 45398 127 0 0
sp_pay_from_customer 7773 7773 0 0.00 4 11814 118 0 0
sp_pay_to_supplier 6581 6581 0 0.00 4 19710 142 0 0
sp_payment_common 14354 14354 0 0.00 1 11501 28 0 0
sp_qd_handle_on_cancel_clo 1993 1993 0 0.00 0 11314 322 0 0
sp_qd_handle_on_invoice_upd_sts 10999 10999 0 0.00 0 19773 337 0 0
sp_qd_handle_on_reserve_upd_sts 10842 10842 0 0.00 0 7984 48 0 0
sp_reserve_write_off 10848 10848 0 0.00 6 12846 163 0 0
sp_supplier_invoice 6067 6067 0 0.00 7 84066 3344 0 0
sp_supplier_order 6011 6011 0 0.00 5 23173 1231 0 0
srv_aggregate_perf_data 100 100 0 0.00 2373 709900 24948 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1619 165931 40790 0 0
srv_find_qd_qs_mism 1085930 1085930 0 0.00 0 11120 5 0 0
srv_make_invnt_saldo 616 616 0 0.00 50 66563 971 0 0
srv_make_money_saldo 881 881 0 0.00 0 1486 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 135471 460297 325831 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5478 5478 0 0.00 0 12307 188 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 4301 111 0 0
t$perf-norm:sp_cancel_client_order 1993 1993 0 0.00 0 13660 21 0 0
t$perf-norm:sp_cancel_customer_reserve 785 785 0 0.00 0 917 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 5381 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 5893 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 754 13 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 21280 1678 0 0
t$perf-norm:sp_cancel_write_off 1076 1076 0 0.00 0 4540 10 0 0
t$perf-norm:sp_client_order 22682 22682 0 0.00 0 18185 21 0 0
t$perf-norm:sp_customer_reserve 1133 1133 0 0.00 0 1125 6 0 0
t$perf-norm:sp_pay_from_customer 7773 7773 0 0.00 0 5880 10 0 0
t$perf-norm:sp_pay_to_supplier 6581 6581 0 0.00 0 5884 12 0 0
t$perf-norm:sp_reserve_write_off 10848 10848 0 0.00 0 8128 13 0 0
t$perf-norm:sp_supplier_invoice 6067 6067 0 0.00 0 16137 21 0 0
t$perf-norm:sp_supplier_order 6011 6011 0 0.00 0 6348 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 673 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1306 26 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 4240 26 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 10715 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 29 9 0 0
v_add_invoice_to_stock 11246 10946 300 2.66 0 7280 21 0 300
v_all_customers 41266 41266 0 0.00 0 149 1 0 0
v_all_suppliers 33272 33272 0 0.00 0 25 1 0 0
v_all_wares 45362 45362 0 0.00 0 91 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 7459 30 0 0
v_cancel_client_order 3986 3986 0 0.00 0 1845 4 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 1958 5 0 0
v_cancel_customer_reserve 1570 1570 0 0.00 0 5450 8 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 2530 10 0 0
v_cancel_supplier_invoice 912 884 28 3.07 0 3612 23 0 28
v_cancel_supplier_order 834 834 0 0.00 0 1894 8 0 0
v_cancel_write_off 2152 2152 0 0.00 0 7602 18 0 0
v_max_id_clo_ord 6011 6011 0 0.00 0 918 1 0 0
v_max_id_clo_res 1133 1133 0 0.00 0 1906 17 0 0
v_max_id_ord_sup 6056 6056 0 0.00 0 6565 7 0 0
v_max_non_paid_invoice 2012 2012 0 0.00 0 581 3 0 0
v_max_non_paid_realizn 4062 4062 0 0.00 0 7093 7 0 0
v_min_id_clo_ord 6011 6011 0 0.00 0 2516 5 0 0
v_min_id_clo_res 1133 1133 0 0.00 0 4709 7 0 0
v_min_id_ord_sup 6056 6056 0 0.00 0 6812 7 0 0
v_min_non_paid_invoice 6581 6581 0 0.00 0 9416 22 0 0
v_min_non_paid_realizn 7773 7773 0 0.00 0 11497 21 0 0
v_our_firm 11518 11518 0 0.00 0 23 1 0 0
v_reserve_write_off 21684 21684 0 0.00 0 7304 6 0 0
x_lock_depdocs_on_canc_invoice 11194 11194 0 0.00 0 15411 127 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 10982 413 0 0
x_make_qstorno_1000_1200 6011 6011 0 0.00 1 23162 1152 0 0
x_make_qstorno_1200_2000 6067 6067 0 0.00 3 84057 3206 0 0
x_make_qstorno_2100_3300 499633 499633 0 0.00 0 64197 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 16:43:48 to 16-07-2022 16:43:52.

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:12:40 9 8192000000 8805243456 8818757632 9749840 0.118 4 2 3 0 9893584 68800 74944
14:13:41 29149 8192000000 9918733744 10233819136 997137264 10.851 100 91 182 1 1027382528 6354960 23890304
14:15:14 10413 8192000000 9949253808 10273824768 1033919968 11.206 100 92 184 0 1066549824 8621664 24008192
14:16:35 17849 8192000000 9987880688 10322972672 1067380624 11.527 100 82 164 0 1097436256 9451184 20604448
14:17:56 8414 8192000000 10041799360 10388049920 1119599888 12.023 100 85 170 0 1151388624 10997376 20791360
14:19:08 5021 8192000000 10073639232 10432454656 1164148096 12.442 100 82 161 0 1195308944 12779984 18380864
14:20:18 3268 8192000000 10118252256 10483179520 1204305792 12.816 100 87 174 0 1237696048 13199152 20191104
14:21:24 2256 8192000000 10161265280 10535059456 1244629536 13.189 100 88 173 0 1276416208 12926688 18859984
14:22:28 1962 8192000000 10172193088 10558042112 1269989488 13.422 100 76 143 3 1296908576 11281344 15637744
14:23:31 1440 8192000000 10213550432 10600861696 1304600160 13.737 100 88 175 1 1338499136 14496208 19402768
14:24:33 11416 8192000000 10211047008 10614951936 1307881600 13.767 100 59 118 0 1331213760 10059184 13272976
14:25:46 6597 8192000000 10203410368 10618785792 1314947984 13.831 100 25 50 0 1327386096 6915504 5522608
14:26:55 12566 8192000000 10211824912 10629156864 1323686784 13.910 100 8 17 1 1330412080 4890624 1834672
14:28:09 9970 8192000000 10221812368 10643251200 1335544336 14.017 100 10 20 0 1343433408 5768688 2120384
14:29:21 33708 8192000000 10242937056 10655535104 1341461024 14.071 100 53 102 1 1359005296 5842400 11701872
14:30:55 5311 8192000000 10240493904 10656694272 1337269440 14.033 100 27 52 1 1348830256 5803024 5757792
14:32:03 7163 8192000000 10204940864 10620350464 1291298272 13.616 97 22 44 0 1302460688 5840944 5321472
14:33:13 67350 8192000000 10160808384 10569719808 1193327056 12.714 92 23 45 0 1203172864 4699232 5146576
14:35:22 4074 8192000000 10115762240 10514911232 1201392672 12.789 97 26 52 1 1213493408 5365536 6735200
14:36:28 11570 8192000000 10146235504 10550009856 1254441424 13.279 100 20 40 0 1265216288 5769312 5005552
14:37:42 66032 8192000000 10165112768 10583777280 1281179392 13.524 100 39 78 0 1295152064 4886240 9086432
14:39:50 5542 8192000000 10187616256 10600804352 1294135120 13.642 100 38 76 1 1310573120 8406624 8031376
14:40:56 8719 8192000000 10193939728 10611896320 1304032256 13.732 100 21 40 0 1314666832 5416608 5217968
14:42:08 6150 8192000000 10212270912 10629169152 1322305808 13.898 100 20 39 0 1331789184 5175872 4307504
14:43:17 27947 8192000000 10242439104 10658820096 1338091648 14.040 100 44 88 1 1354319328 6270688 9956992
14:44:49 9823 8192000000 10267503392 10680549376 1353796384 14.182 100 60 117 2 1377593120 10744736 13052000
14:46:03 4913 8192000000 10261829952 10680430592 1348334048 14.132 99 41 82 0 1367221856 8990592 9897216
14:47:16 5295 8192000000 10269658160 10690191360 1362484336 14.260 100 48 94 2 1382850096 9302032 11063728
14:48:24 10151 8192000000 10278138256 10703491072 1371685808 14.342 100 44 88 0 1394067376 12184080 10197488
14:49:36 12791 8192000000 10284992672 10706874368 1371006528 14.336 100 49 96 1 1394537920 11962800 11568592
14:50:53 6024 8192000000 10288232880 10712477696 1364719440 14.280 99 44 86 1 1390276560 14869808 10687312
14:52:02 2508 8192000000 10299799712 10714587136 1375914256 14.380 100 65 128 0 1409655744 18381616 15359872
14:53:08 4736 8192000000 10297797696 10710020096 1369375120 14.321 100 64 129 0 1408336432 22702992 16258320
14:54:16 4597 8192000000 10259032112 10672783360 1330394576 13.971 99 52 102 0 1363823168 20639648 12788944
14:55:29 1976 8192000000 10278030400 10682015744 1338275024 14.042 99 68 134 1 1377136016 22889472 15971520
14:56:33 1619 8192000000 10278699344 10682724352 1337077472 14.031 100 73 144 0 1380674544 26560368 17036704
14:57:39 3124 8192000000 10290825264 10691960832 1342597360 14.081 100 78 155 2 1393762576 32626160 18539056
14:58:49 6192 8192000000 10290343440 10704236544 1355612832 14.198 100 57 111 1 1402788960 33189680 13986448
15:00:18 6544 8192000000 10310878240 10715791360 1362935296 14.264 100 64 128 0 1415494544 37219168 15340080
15:01:37 5766 8192000000 10318955616 10727776256 1369740192 14.325 100 59 114 0 1425929712 41571952 14617568
15:02:46 3949 8192000000 10327221424 10732269568 1373088064 14.355 100 70 140 1 1428485568 37639216 17758288
15:03:55 2439 8192000000 10310003456 10712842240 1358240416 14.222 100 83 164 1 1417657392 38542304 20874672
15:05:07 2666 8192000000 10350220640 10757107712 1384089712 14.453 100 75 148 0 1451254640 48120128 19044800
15:06:17 3161 8192000000 10324258944 10737131520 1373082448 14.355 100 60 119 0 1424768032 36383616 15301968
15:07:31 7396 8192000000 10319893088 10742308864 1375108896 14.373 100 47 92 1 1429653344 41520832 13023616
15:08:43 13350 8192000000 10315324720 10744803328 1365366976 14.286 99 33 66 0 1416217024 41307408 9542640
15:10:00 23461 8192000000 10307231136 10734346240 1355829184 14.200 99 31 62 0 1416574160 51405408 9339568
15:11:27 18529 8192000000 10321973584 10755682304 1378354656 14.402 100 32 62 1 1428474416 41183984 8935776
15:12:56 45420 8192000000 10329692320 10767929344 1403380944 14.625 100 28 56 0 1439176528 28133120 7662464
15:15:01 14804 8192000000 10315955040 10760429568 1396912480 14.567 100 22 44 0 1431705392 28932480 5860432
15:16:56 28951 8192000000 10298584000 10764546048 1389480528 14.501 100 9 16 0 1407302800 16044672 1777600
15:18:25 44314 8192000000 10311044448 10761003008 1399903408 14.594 100 7 14 0 1413252976 11871920 1477648
15:20:38 25519 8192000000 10323731696 10770550784 1407858592 14.665 100 26 55 0 1426233008 12297296 6077120
15:22:14 138000 8192000000 10318868240 10782511104 1354954064 14.192 96 10 20 0 1371278640 14469408 1855168
15:25:33 35917 8192000000 10306146352 10760916992 1388808256 14.495 99 15 30 0 1406210880 14051504 3351120
15:27:12 114748 8192000000 10293799728 10738036736 1319071040 13.868 94 18 34 0 1331895888 9356304 3468544
15:30:08 83985 8192000000 10301729184 10756509696 1375993664 14.381 99 14 29 1 1388423968 9455232 2975072
15:32:33 92666 8192000000 10301737264 10758709248 1354800432 14.191 97 32 63 0 1368928256 6537632 7590192
15:35:43 61481 8192000000 10302445920 10759081984 1373602400 14.359 98 22 41 0 1390898944 12658304 4638240
15:37:46 33293 8192000000 10311426528 10760208384 1356463824 14.206 97 25 48 1 1382146720 20112224 5570672
15:39:28 36576 8192000000 10305482528 10759278592 1369560896 14.323 98 37 70 0 1396774128 18737120 8476112
15:41:20 88267 8192000000 10296553424 10754441216 1375569104 14.377 99 18 36 0 1399125936 19605168 3951664
15:43:51 32184 8192000000 10269600592 10727665664 1368456976 14.313 99 14 28 0 1383648576 11932672 3258928
15:45:36 50541 8192000000 10282058368 10736533504 1315691328 13.838 95 18 34 0 1330543328 11299328 3552672
15:47:30 44984 8192000000 10266805392 10723106816 1323104896 13.905 97 4 8 0 1336483200 12683968 694336
15:49:16 46521 8192000000 10286242640 10725871616 1357125680 14.212 99 23 46 0 1368883232 7173744 4583808
15:51:03 25890 8192000000 10290447104 10734583808 1357417712 14.214 99 27 54 0 1378469056 15104064 5947280
15:52:32 59186 8192000000 10301484400 10739322880 1363825840 14.272 99 30 60 0 1397628000 27242160 6560000
15:54:31 72916 8192000000 10286964304 10733756416 1334037536 14.004 97 16 31 0 1358628016 21421408 3169072
15:56:45 90569 8192000000 10308681824 10759581696 1342407488 14.079 97 21 42 0 1358851168 11661376 4782304
16:00:14 165931 8192000000 10303797648 10747146240 1334951232 14.012 96 27 54 0 1354286768 13105808 6229728
16:04:05 66473 8192000000 10292620736 10747641856 1346262496 14.114 97 30 60 0 1367527440 14350160 6914784
16:06:17 58188 8192000000 10285265280 10747572224 1376930832 14.389 99 14 29 1 1386147568 6514880 2701856
16:08:16 67118 8192000000 10294985344 10753388544 1383855344 14.451 99 11 22 0 1393354512 7138496 2360672
16:10:29 47905 8192000000 10292099328 10757214208 1383308464 14.446 99 9 19 0 1396274320 10656368 2309488
16:12:36 67941 8192000000 10314131712 10779013120 1408160432 14.668 100 26 52 1 1432013312 17513248 6339632
16:14:45 71012 8192000000 10310541216 10795327488 1397550560 14.573 99 13 26 1 1416127312 15136928 3439824
16:16:56 52241 8192000000 10290571600 10753388544 1377273088 14.392 98 12 24 0 1387908928 8011504 2624336
16:18:51 39460 8192000000 10306281312 10770726912 1385219072 14.463 99 31 61 0 1401615808 9941808 6454928
16:20:44 52931 8192000000 10297343072 10758807552 1359469776 14.233 97 13 26 0 1376541488 14123264 2948448
16:22:41 45582 8192000000 10282808400 10733662208 1353770208 14.181 98 31 63 0 1371317856 10847648 6700000
16:24:30 39096 8192000000 10283660528 10741174272 1358188240 14.221 98 23 46 0 1375103616 11980272 4935104
16:26:34 30601 8192000000 10289823200 10737618944 1353687424 14.181 98 40 80 0 1370375408 7520352 9167632
16:28:08 72050 8192000000 10289607520 10749796352 1329252816 13.960 96 10 18 0 1341841536 10991376 1597344
16:30:20 69740 8192000000 10249659040 10708926464 1312306064 13.807 96 18 36 0 1325796000 8718416 4771520
16:32:35 72014 8192000000 10255447248 10700066816 1274737680 13.465 93 35 70 1 1291242112 8534192 7970240
16:35:27 49584 8192000000 10241194272 10700443648 1319987472 13.877 98 9 18 0 1334804192 12796608 2020112
16:37:24 43563 8192000000 10263044080 10711560192 1330026352 13.967 98 18 37 1 1348213968 14204480 3983136
16:39:31 63805 8192000000 10245283408 10699288576 1299437712 13.690 96 9 18 0 1314828192 13271328 2119152
16:41:39 61134 8192000000 9626101184 9984106496 726655504 8.147 53 6 12 0 736768928 8824928 1288496

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-07-2022 16:43:54 to 16-07-2022 16:44:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-07-2022 16:44:10 to 16-07-2022 16:44:11.

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          258874
MON$OLDEST_ACTIVE               258875
MON$OLDEST_SNAPSHOT             258875
MON$NEXT_TRANSACTION            258875
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                       2067936
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

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