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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16201860 kB
MemAvailable 31406072 kB
Buffers 0 kB
Cached 15483812 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-20 16:38:16
DTS_END                         2022-05-20 16:38:16.3090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(270:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35242
TRN_ID                          255327
ATT_ID                          696
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: 9521.07 20719 1142528
customer order: creation 187.99 324 22559
customer order: refuse 15.73 328 1888
order to supplier: creation 48.91 1264 5869
order to supplier: removal 3.65 103145 438
invoice (draft): creation 48.36 3245 5803
invoice (draft): removal 53.83 919 6459
invoice accept: apply 45.11 16749 5413
invoice accept: cancel 48.26 847 5791
customer reserve: creation 4073.57 169 488828
customer reserve: removal 4675.72 61 561086
realization accept: apply 91.20 209 10944
realization accept: cancel 87.11 116 10453
payment from customer: creation 64.22 154 7707
payment from customer: removal 6.57 78 788
payment to supplier: creation 53.63 168 6436
payment to supplier: removal 3.78 91 454
service: total inventory turnovers 5.44 856 653
service: total monetary turnovers 7.11 41 853
service: aggregate perf. data 0.85 19204 102
service: refresh index statistics 0.03 266419 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-05-2022 16:38:49 to 20-05-2022 16:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1457
TEST_TIME 2 3720
TEST_TIME 3 5931
TEST_TIME 4 5845
TEST_TIME 5 5727
TEST_TIME 6 6275
TEST_TIME 7 6168
TEST_TIME 8 6847
TEST_TIME 9 6969
TEST_TIME 10 6940
TEST_TIME 11 6972
TEST_TIME 12 6977
TEST_TIME 13 7378
TEST_TIME 14 7235
TEST_TIME 15 7185
TEST_TIME 16 7213
TEST_TIME 17 7254
TEST_TIME 18 7142
TEST_TIME 19 7225
TEST_TIME 20 7479
TEST_TIME 21 7824
TEST_TIME 22 7709
TEST_TIME 23 8034
TEST_TIME 24 8112
TEST_TIME 25 8321
TEST_TIME 26 9348
TEST_TIME 27 10473
TEST_TIME 28 10631
TEST_TIME 29 10931
TEST_TIME 30 10727
TEST_TIME 31 10617
TEST_TIME 32 10727
TEST_TIME 33 10627
TEST_TIME 34 10492
TEST_TIME 35 10524
TEST_TIME 36 10432
TEST_TIME 37 10341
TEST_TIME 38 10608
TEST_TIME 39 10544
TEST_TIME 40 10470
TEST_TIME 41 10483
TEST_TIME 42 10474
TEST_TIME 43 10431
TEST_TIME 44 10260
TEST_TIME 45 10146
TEST_TIME 46 10147
TEST_TIME 47 10182
TEST_TIME 48 10060
TEST_TIME 49 10049
TEST_TIME 50 10029
TEST_TIME 51 9958
TEST_TIME 52 9967
TEST_TIME 53 9975
TEST_TIME 54 9948
TEST_TIME 55 9908
TEST_TIME 56 9830
TEST_TIME 57 9764
TEST_TIME 58 9703
TEST_TIME 59 9587
TEST_TIME 60 9645
TEST_TIME 61 9676
TEST_TIME 62 9654
TEST_TIME 63 9741
TEST_TIME 64 9711
TEST_TIME 65 9689
TEST_TIME 66 9784
TEST_TIME 67 9834
TEST_TIME 68 9828
TEST_TIME 69 9827
TEST_TIME 70 9856
TEST_TIME 71 9800
TEST_TIME 72 9839
TEST_TIME 73 9889
TEST_TIME 74 9867
TEST_TIME 75 9853
TEST_TIME 76 9737
TEST_TIME 77 9791
TEST_TIME 78 9708
TEST_TIME 79 9722
TEST_TIME 80 9667
TEST_TIME 81 9630
TEST_TIME 82 9612
TEST_TIME 83 9524
TEST_TIME 84 9522
TEST_TIME 85 9522
TEST_TIME 86 9532
TEST_TIME 87 9515
TEST_TIME 88 9592
TEST_TIME 89 9567
TEST_TIME 90 9605
TEST_TIME 91 9604
TEST_TIME 92 9656
TEST_TIME 93 9691
TEST_TIME 94 9677
TEST_TIME 95 9785
TEST_TIME 96 9773
TEST_TIME 97 9768
TEST_TIME 98 9767
TEST_TIME 99 9695
TEST_TIME 100 9683
TEST_TIME 101 9665
TEST_TIME 102 9656
TEST_TIME 103 9618
TEST_TIME 104 9588
TEST_TIME 105 9565
TEST_TIME 106 9513
TEST_TIME 107 9473
TEST_TIME 108 9472
TEST_TIME 109 9456
TEST_TIME 110 9435
TEST_TIME 111 9453
TEST_TIME 112 9435
TEST_TIME 113 9467
TEST_TIME 114 9453
TEST_TIME 115 9523
TEST_TIME 116 9543
TEST_TIME 117 9548
TEST_TIME 118 9570
TEST_TIME 119 9590
TEST_TIME 120 9521

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-05-2022 16:38:52 to 20-05-2022 16:38:56.

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 1595932 1595932 0 0.00 0 25981 7 0 0
doc_list_biud 1595912 1595912 0 0.00 0 35951 28 0 0
sp_add_invoice_to_stock 5546 5413 133 2.39 48 208756 16749 0 133
sp_cancel_adding_invoice 5791 5791 0 0.00 3 105696 847 0 0
sp_cancel_client_order 1888 1888 0 0.00 4 13065 329 0 0
sp_cancel_customer_reserve 561086 561086 0 0.00 1 18385 61 0 0
sp_cancel_pay_from_customer 788 788 0 0.00 3 11668 78 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 8316 91 0 0
sp_cancel_supplier_invoice 6473 6459 14 0.21 2 13426 919 0 14
sp_cancel_supplier_order 438 438 0 0.00 8 1274640 103146 0 0
sp_cancel_write_off 10453 10453 0 0.00 1 12246 117 0 0
sp_client_order 22559 22559 0 0.00 3 18628 324 0 0
sp_customer_reserve 488828 488828 0 0.00 0 99882 169 0 0
sp_fill_shopping_cart 34219 34219 0 0.00 1 16274 36 0 0
sp_fill_shopping_cart_clo_res 488825 488825 0 0.00 0 13709 13 0 0
sp_get_clo_for_invoice 5410 5410 0 0.00 0 27601 294 0 0
sp_kill_cost_storno 17486 17486 0 0.00 0 21999 65 0 0
sp_kill_qstorno_ret_qs2qd 1139522 1139522 0 0.00 0 18055 34 0 0
sp_kill_qty_storno 602469 602469 0 0.00 0 35950 73 0 0
sp_lock_selected_doc 22551 22551 0 0.00 0 10344 7 0 0
sp_make_cost_storno 30497 30497 0 0.00 0 25969 65 0 0
sp_multiply_rows_for_pdistr 30497 30497 0 0.00 0 25165 44 0 0
sp_multiply_rows_for_qdistr 22559 22559 0 0.00 0 18497 149 0 0
sp_pay_from_customer 7707 7707 0 0.00 4 26398 154 0 0
sp_pay_to_supplier 6436 6436 0 0.00 5 26372 168 0 0
sp_payment_common 14136 14136 0 0.00 1 25893 36 0 0
sp_qd_handle_on_cancel_clo 1888 1888 0 0.00 0 13057 299 0 0
sp_qd_handle_on_invoice_upd_sts 11201 11201 0 0.00 0 35949 361 0 0
sp_qd_handle_on_reserve_upd_sts 10944 10944 0 0.00 0 17312 59 0 0
sp_reserve_write_off 10944 10944 0 0.00 7 25198 209 0 0
sp_supplier_invoice 5803 5803 0 0.00 7 99196 3245 0 0
sp_supplier_order 5869 5869 0 0.00 5 30901 1264 0 0
srv_aggregate_perf_data 103 102 1 0.97 3112 181125 19204 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2716 124583 44859 0 0
srv_find_qd_qs_mism 1091509 1091509 0 0.00 0 11397 5 0 0
srv_make_invnt_saldo 653 653 0 0.00 15 28061 857 0 0
srv_make_money_saldo 853 853 0 0.00 1 13164 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 223922 324323 266419 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5413 5413 0 0.00 0 14312 154 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 3382 82 0 0
t$perf-norm:sp_cancel_client_order 1888 1888 0 0.00 0 7277 14 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1903 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 788 788 0 0.00 0 3265 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 1485 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 730 9 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 30542 1497 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 3695 9 0 0
t$perf-norm:sp_client_order 22559 22559 0 0.00 0 8837 16 0 0
t$perf-norm:sp_customer_reserve 1131 1131 0 0.00 0 434 3 0 0
t$perf-norm:sp_pay_from_customer 7707 7707 0 0.00 0 4453 7 0 0
t$perf-norm:sp_pay_to_supplier 6436 6436 0 0.00 0 14023 10 0 0
t$perf-norm:sp_reserve_write_off 10944 10944 0 0.00 0 8209 12 0 0
t$perf-norm:sp_supplier_invoice 5803 5803 0 0.00 0 10650 19 0 0
t$perf-norm:sp_supplier_order 5869 5869 0 0.00 0 13201 19 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 90 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 208 7 0 0
t$perf-norm:srv_make_invnt_saldo 653 653 0 0.00 0 5517 33 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 1379 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 11084 10818 266 2.39 0 8296 19 0 266
v_all_customers 40788 40788 0 0.00 0 17 1 0 0
v_all_suppliers 32566 32566 0 0.00 0 37 1 0 0
v_all_wares 45106 45106 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 2246 7 0 0
v_cancel_client_order 3776 3776 0 0.00 0 4235 7 0 0
v_cancel_customer_prepayment 1576 1576 0 0.00 0 3840 8 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 1369 4 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 1891 4 0 0
v_cancel_supplier_invoice 882 854 28 3.17 0 4585 20 0 28
v_cancel_supplier_order 876 876 0 0.00 0 1164 5 0 0
v_cancel_write_off 2164 2164 0 0.00 0 5590 15 0 0
v_max_id_clo_ord 5864 5864 0 0.00 0 544 1 0 0
v_max_id_clo_res 1131 1131 0 0.00 0 1211 7 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 4622 3 0 0
v_max_non_paid_invoice 1817 1817 0 0.00 0 811 3 0 0
v_max_non_paid_realizn 4249 4249 0 0.00 0 2650 5 0 0
v_min_id_clo_ord 5864 5864 0 0.00 0 16240 12 0 0
v_min_id_clo_res 1131 1131 0 0.00 0 4970 16 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 8296 9 0 0
v_min_non_paid_invoice 6434 6434 0 0.00 0 25883 29 0 0
v_min_non_paid_realizn 7702 7702 0 0.00 0 15692 25 0 0
v_our_firm 11224 11224 0 0.00 0 24 1 0 0
v_reserve_write_off 21888 21888 0 0.00 0 8300 8 0 0
x_lock_depdocs_on_canc_invoice 11823 11823 0 0.00 0 13434 112 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 4499 279 0 0
x_make_qstorno_1000_1200 5869 5869 0 0.00 2 30891 1184 0 0
x_make_qstorno_1200_2000 5803 5803 0 0.00 3 99065 3118 0 0
x_make_qstorno_2100_3300 488828 488828 0 0.00 0 99881 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-05-2022 16:38:57 to 20-05-2022 16:39:01.

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:20 30 8192000000 8815222240 8831934464 19570288 0.238 4 4 5 0 19797040 87456 139296
14:09:21 26106 8192000000 9897144496 10207928320 982174304 10.705 100 92 184 0 1013886416 6498432 25213680
14:10:59 28130 8192000000 9937473472 10257248256 1015982848 11.033 100 87 174 0 1047299136 8598192 22718096
14:12:29 18839 8192000000 9978137632 10313232384 1063488928 11.490 100 83 166 1 1094093760 9320912 21283920
14:13:51 9090 8192000000 10044217712 10393985024 1130128080 12.123 100 77 154 0 1158881264 10314816 18438368
14:15:05 12269 8192000000 10086059296 10448568320 1171505808 12.511 100 77 152 1 1201319744 11730176 18083760
14:16:20 6375 8192000000 10127167488 10498891776 1215016336 12.916 100 85 170 1 1247432736 13928256 18488144
14:17:27 5018 8192000000 10152091488 10534588416 1247591392 13.216 100 70 139 3 1274956208 11895808 15469008
14:18:34 2586 8192000000 10193087504 10578382848 1281643152 13.528 100 85 170 2 1312965776 11695552 19627072
14:19:38 10024 8192000000 10207145360 10597150720 1299585728 13.691 100 65 132 1 1326121136 12225232 14310176
14:20:48 10834 8192000000 10189667888 10597072896 1301426976 13.708 100 24 48 1 1313458208 6179088 5852144
14:21:59 9756 8192000000 10197818416 10612645888 1314220592 13.824 100 18 36 1 1324750608 6498256 4031760
14:23:11 11582 8192000000 10197568624 10620338176 1319138736 13.869 100 20 39 2 1330145360 6498368 4508256
14:24:27 37149 8192000000 10215427056 10638053376 1326528480 13.936 100 27 55 3 1337240176 4811504 5900192
14:26:05 5799 8192000000 10233413360 10654187520 1333405184 13.998 100 30 59 2 1350782144 10956032 6420928
14:27:13 11152 8192000000 10222752864 10641580032 1323056880 13.904 99 22 44 2 1334125120 6017024 5051216
14:28:27 64887 8192000000 10157880336 10555768832 1205287040 12.825 91 31 61 0 1217575472 5320128 6968304
14:30:36 7110 8192000000 10133428944 10531368960 1197607184 12.754 95 22 44 0 1208785408 6478656 4699568
14:31:44 7985 8192000000 10163891392 10561540096 1246853616 13.209 98 18 36 0 1256028720 5116144 4058960
14:32:53 11068 8192000000 10159849248 10559578112 1247771344 13.218 98 22 42 1 1258512400 4924048 5817008
14:34:06 32151 8192000000 10181427520 10591064064 1286583376 13.573 100 33 64 0 1300052816 6072240 7397200
14:35:39 7607 8192000000 10198183200 10605592576 1304287296 13.734 100 35 68 1 1318687088 5789840 8609952
14:36:48 9164 8192000000 10207860960 10623971328 1312094048 13.805 100 16 32 1 1325336272 9638768 3603456
14:37:58 15948 8192000000 10216092240 10633232384 1321927344 13.894 100 9 18 0 1328515168 4718976 1868848
14:39:21 71170 8192000000 10261681472 10674237440 1348125584 14.131 100 40 78 0 1368066016 10979152 8961280
14:41:36 11898 8192000000 10285725552 10701537280 1369911824 14.326 100 60 119 2 1396035072 11543456 14579792
14:42:51 15659 8192000000 10297010416 10711064576 1368361248 14.312 99 61 123 0 1395781360 13749888 13670224
14:44:16 18727 8192000000 10318689472 10739105792 1392030784 14.524 100 64 128 0 1428501360 21151424 15319152
14:45:37 7067 8192000000 10310537808 10725117952 1382761632 14.441 99 58 114 0 1417889248 21487232 13640384
14:46:48 2705 8192000000 10343425104 10762706944 1414543488 14.724 100 64 128 2 1452379248 22639968 15195792
14:47:56 15522 8192000000 10328357792 10741035008 1391782624 14.522 100 66 128 0 1432542224 24861808 15897792
14:49:14 5110 8192000000 10331032096 10747625472 1393679200 14.539 100 68 134 1 1443437680 32128336 17630144
14:50:23 9544 8192000000 10337082144 10748198912 1387566256 14.484 100 56 111 2 1436996656 35677440 13752960
14:51:38 4823 8192000000 10308005904 10718863360 1370097680 14.328 100 65 130 0 1415014640 29193648 15723312
14:52:47 5151 8192000000 10331208096 10742095872 1388710928 14.494 100 58 116 0 1437291584 34176832 14403824
14:53:58 4491 8192000000 10339746320 10746081280 1373973968 14.363 98 71 142 2 1425863728 33869472 18020288
14:55:07 5625 8192000000 10355448016 10759573504 1399634672 14.592 100 76 152 0 1456064976 37352592 19077712
14:56:19 9007 8192000000 10356306752 10767532032 1404066208 14.631 100 56 112 1 1461077232 42255728 14755296
14:57:37 6597 8192000000 10355516544 10756321280 1364286544 14.276 98 83 166 0 1434291168 48479888 21524736
14:58:50 3881 8192000000 10378677120 10776784896 1400070912 14.596 100 88 175 1 1478493136 55594384 22827840
14:59:57 7676 8192000000 10372817088 10783207424 1403619088 14.627 100 59 117 3 1475217088 55249520 16348480
15:01:14 12141 8192000000 10371839696 10797944832 1403855744 14.629 100 45 90 2 1481387328 64114528 13417056
15:02:37 5518 8192000000 10382061824 10807439360 1393799760 14.540 100 49 98 0 1480289408 71889456 14600192
15:03:47 9295 8192000000 10382279760 10807914496 1389952896 14.505 100 53 106 0 1487259680 81049344 16257440
15:04:59 14943 8192000000 10335234064 10780393472 1380967072 14.425 100 28 56 3 1446690784 57378064 8345648
15:06:28 20330 8192000000 10311674656 10752618496 1370275552 14.330 100 27 54 0 1411169376 33898352 6995472
15:07:55 40314 8192000000 10288240384 10728701952 1368748352 14.316 100 27 53 1 1382393984 8068272 5577360
15:09:37 62564 8192000000 10302368192 10755878912 1377413056 14.393 100 15 30 1 1402214320 21254208 3547056
15:11:53 70356 8192000000 10300367792 10742788096 1361926064 14.255 98 9 18 1 1381373504 17330592 2116848
15:14:04 48105 8192000000 10307999040 10750537728 1383708832 14.450 99 13 27 0 1395107360 8769216 2629312
15:15:53 75123 8192000000 10313109200 10784010240 1402006720 14.613 100 6 12 1 1422500272 19138272 1355280
15:18:10 60809 8192000000 10303344320 10752045056 1370321920 14.330 98 19 38 0 1383672112 8582880 4767312
15:20:11 72384 8192000000 10301880800 10742804480 1323130608 13.905 95 15 30 0 1338531216 11595088 3805520
15:23:06 46668 8192000000 10316340736 10761539584 1389079312 14.498 100 35 70 1 1408749504 11911744 7758448
15:24:55 97086 8192000000 10315847568 10787860480 1395832944 14.558 100 13 26 0 1418753872 19814304 3106624
15:27:42 52070 8192000000 10316788224 10767376384 1393620304 14.538 100 27 53 0 1410257936 10700528 5937104
15:29:40 124583 8192000000 10259064864 10725605376 1240938128 13.155 89 21 42 1 1266314416 20566784 4809504
15:32:49 94907 8192000000 10243368416 10703634432 1281791600 13.529 94 16 32 0 1292270944 6866576 3612768
15:35:27 5026 8192000000 10282493984 10712018944 1351359136 14.160 99 75 139 4 1372650400 4855456 16435808
15:36:34 62464 8192000000 10231179136 10684375040 1294435216 13.645 95 17 35 2 1308850608 10594432 3820960
15:38:45 79964 8192000000 10255377872 10721406976 1315004832 13.831 96 11 22 1 1329708784 12930832 1773120
15:41:05 73464 8192000000 10266580544 10719211520 1296763648 13.666 94 8 16 0 1306873360 8613552 1496160
15:43:24 51065 8192000000 10293971616 10736377856 1370181808 14.329 99 43 86 1 1392614416 12348192 10084416
15:45:15 82480 8192000000 10281888992 10732822528 1349963232 14.147 98 8 16 0 1363092560 11667792 1461536
15:47:39 60432 8192000000 10295334128 10748166144 1382804816 14.442 100 22 44 0 1398669056 10335600 5528640
15:49:40 48585 8192000000 10303448384 10755387392 1371993408 14.345 99 38 76 2 1394092320 13616672 8482240
15:51:30 52564 8192000000 10296081264 10748895232 1361149296 14.248 98 22 43 1 1375863088 9365968 5347824
15:53:24 48028 8192000000 10278253200 10738184192 1363993568 14.273 99 22 44 0 1384623520 15812768 4817184
15:55:49 68603 8192000000 10304786368 10761015296 1392529600 14.528 100 20 40 1 1408587440 11641168 4416672
15:57:57 55482 8192000000 10291616560 10764574720 1397078688 14.569 100 12 24 1 1411033424 10949824 3004912
15:59:53 65120 8192000000 10290956672 10757459968 1386636896 14.476 99 8 17 0 1400346560 12263376 1446288
16:02:01 57908 8192000000 10290673520 10759385088 1365880688 14.290 98 6 10 1 1379235552 11869888 1484976
16:04:07 52042 8192000000 10273492976 10734428160 1361518448 14.251 98 13 26 0 1378746192 14063056 3164688
16:06:03 57743 8192000000 10302344592 10758914048 1365376032 14.286 98 24 48 1 1386787232 16014944 5396256
16:08:01 88013 8192000000 10291459360 10751488000 1350495152 14.152 97 20 40 1 1364476928 9699952 4281824
16:10:30 55222 8192000000 10311014864 10776408064 1389207760 14.499 99 17 34 0 1414455184 22022336 3225088
16:12:26 58821 8192000000 10302287824 10760183808 1351390416 14.160 96 13 26 0 1365561872 10864912 3306544
16:15:15 38214 8192000000 10300331472 10727579648 1329817648 13.966 97 44 88 0 1355623392 16296224 9509520
16:16:55 67083 8192000000 10266952336 10709889024 1348127536 14.131 99 24 48 0 1360770800 7610704 5032560
16:19:08 55163 8192000000 10251113136 10707693568 1340627280 14.063 98 14 29 0 1360606112 16406896 3571936
16:21:04 56068 8192000000 10246066064 10697666560 1322110528 13.896 97 13 26 2 1335155968 10036864 3008576
16:23:00 31736 8192000000 10225215392 10670673920 1265843664 13.384 94 12 23 2 1274518304 5655808 3018832
16:24:35 84988 8192000000 10221343792 10677125120 1322035152 13.895 99 16 30 0 1338228864 11824032 4369680
16:27:14 61988 8192000000 10236316144 10693406720 1330986112 13.976 100 6 12 0 1340187856 8006352 1195392
16:29:17 39313 8192000000 10254876752 10706837504 1349153312 14.140 100 14 28 0 1366621264 14062112 3405840
16:30:57 46796 8192000000 10269417136 10707128320 1323483728 13.908 98 22 44 0 1342524416 13983456 5057232
16:32:46 83441 8192000000 10266205392 10732781568 1330094640 13.968 98 15 30 0 1352489248 18856704 3537904
16:35:09 71923 8192000000 10261117712 10719846400 1306842208 13.757 96 20 40 0 1322276848 10959520 4475120
16:37:35 44191 8192000000 8963780096 9144922112 128651920 1.546 10 3 6 0 134006000 5020560 333520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 133 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 20-05-2022 16:39:19 to 20-05-2022 16:39:20.

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          255752
MON$OLDEST_ACTIVE               255753
MON$OLDEST_SNAPSHOT             255753
MON$NEXT_TRANSACTION            255753
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                       2062176
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

20.05.22 16:39:21. 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-05-20 16:39:24 No severe PSQL-related problems occured
20.05.22 16:39:25. End of report.