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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16187636 kB
MemAvailable 30932412 kB
Buffers 0 kB
Cached 15520448 kB
SwapCached 2512 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-08-09 01:38:21
DTS_END                         2022-08-09 01:38:21.6950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35464
TRN_ID                          243902
ATT_ID                          668
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: 8962.48 22081 1075497
customer order: creation 174.08 400 20889
customer order: refuse 15.03 320 1803
order to supplier: creation 47.62 1334 5714
order to supplier: removal 3.23 105873 388
invoice (draft): creation 45.61 3325 5473
invoice (draft): removal 49.16 831 5899
invoice accept: apply 41.93 17683 5031
invoice accept: cancel 44.28 848 5313
customer reserve: creation 3823.56 177 458827
customer reserve: removal 4421.25 61 530550
realization accept: apply 85.93 189 10312
realization accept: cancel 78.69 119 9443
payment from customer: creation 60.28 117 7234
payment from customer: removal 6.31 90 757
payment to supplier: creation 49.59 149 5951
payment to supplier: removal 3.40 87 408
service: total inventory turnovers 4.83 1017 580
service: total monetary turnovers 6.81 23 817
service: aggregate perf. data 0.87 20507 104
service: refresh index statistics 0.03 288478 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-08-2022 01:39:29 to 09-08-2022 01:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7750
TEST_TIME 2 6891
TEST_TIME 3 7293
TEST_TIME 4 7225
TEST_TIME 5 6728
TEST_TIME 6 6792
TEST_TIME 7 6632
TEST_TIME 8 6307
TEST_TIME 9 6413
TEST_TIME 10 6556
TEST_TIME 11 6375
TEST_TIME 12 6669
TEST_TIME 13 6593
TEST_TIME 14 6633
TEST_TIME 15 6758
TEST_TIME 16 6778
TEST_TIME 17 7003
TEST_TIME 18 7088
TEST_TIME 19 7374
TEST_TIME 20 7590
TEST_TIME 21 7988
TEST_TIME 22 8507
TEST_TIME 23 8432
TEST_TIME 24 8530
TEST_TIME 25 9567
TEST_TIME 26 9709
TEST_TIME 27 10519
TEST_TIME 28 10409
TEST_TIME 29 10170
TEST_TIME 30 10449
TEST_TIME 31 10183
TEST_TIME 32 10131
TEST_TIME 33 10073
TEST_TIME 34 9957
TEST_TIME 35 9806
TEST_TIME 36 9827
TEST_TIME 37 9836
TEST_TIME 38 9763
TEST_TIME 39 9622
TEST_TIME 40 9526
TEST_TIME 41 9470
TEST_TIME 42 9475
TEST_TIME 43 9527
TEST_TIME 44 9515
TEST_TIME 45 9436
TEST_TIME 46 9350
TEST_TIME 47 9264
TEST_TIME 48 9223
TEST_TIME 49 9119
TEST_TIME 50 9060
TEST_TIME 51 8987
TEST_TIME 52 8930
TEST_TIME 53 9026
TEST_TIME 54 9031
TEST_TIME 55 9015
TEST_TIME 56 9056
TEST_TIME 57 9099
TEST_TIME 58 9090
TEST_TIME 59 9122
TEST_TIME 60 9116
TEST_TIME 61 9197
TEST_TIME 62 9221
TEST_TIME 63 9298
TEST_TIME 64 9336
TEST_TIME 65 9346
TEST_TIME 66 9265
TEST_TIME 67 9199
TEST_TIME 68 9180
TEST_TIME 69 9103
TEST_TIME 70 9141
TEST_TIME 71 9078
TEST_TIME 72 9063
TEST_TIME 73 9051
TEST_TIME 74 8999
TEST_TIME 75 9025
TEST_TIME 76 9043
TEST_TIME 77 9068
TEST_TIME 78 9134
TEST_TIME 79 9127
TEST_TIME 80 9160
TEST_TIME 81 9225
TEST_TIME 82 9195
TEST_TIME 83 9281
TEST_TIME 84 9301
TEST_TIME 85 9280
TEST_TIME 86 9283
TEST_TIME 87 9230
TEST_TIME 88 9154
TEST_TIME 89 9126
TEST_TIME 90 9090
TEST_TIME 91 9051
TEST_TIME 92 9032
TEST_TIME 93 9025
TEST_TIME 94 9021
TEST_TIME 95 8990
TEST_TIME 96 8997
TEST_TIME 97 9002
TEST_TIME 98 9074
TEST_TIME 99 9083
TEST_TIME 100 9082
TEST_TIME 101 9073
TEST_TIME 102 9141
TEST_TIME 103 9128
TEST_TIME 104 9186
TEST_TIME 105 9209
TEST_TIME 106 9206
TEST_TIME 107 9229
TEST_TIME 108 9179
TEST_TIME 109 9112
TEST_TIME 110 9142
TEST_TIME 111 9111
TEST_TIME 112 9094
TEST_TIME 113 9063
TEST_TIME 114 9045
TEST_TIME 115 9014
TEST_TIME 116 9014
TEST_TIME 117 8994
TEST_TIME 118 8997
TEST_TIME 119 9002
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2022 01:39:33 to 09-08-2022 01:39:36.

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 1501537 1501537 0 0.00 0 19406 6 0 0
doc_list_biud 1501527 1501527 0 0.00 0 28339 27 0 0
sp_add_invoice_to_stock 5218 5031 187 3.58 39 217804 17683 0 187
sp_cancel_adding_invoice 5313 5313 0 0.00 4 66454 849 0 0
sp_cancel_client_order 1803 1803 0 0.00 4 8809 321 0 0
sp_cancel_customer_reserve 530550 530550 0 0.00 0 28340 62 0 0
sp_cancel_pay_from_customer 757 757 0 0.00 2 6975 91 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 7962 88 0 0
sp_cancel_supplier_invoice 5908 5899 9 0.15 2 15990 831 0 9
sp_cancel_supplier_order 388 388 0 0.00 7 998069 105873 0 0
sp_cancel_write_off 9443 9443 0 0.00 1 10534 119 0 0
sp_client_order 20889 20889 0 0.00 3 26900 400 0 0
sp_customer_reserve 458827 458827 0 0.00 0 48756 178 0 0
sp_fill_shopping_cart 32065 32065 0 0.00 1 19761 42 0 0
sp_fill_shopping_cart_clo_res 458812 458812 0 0.00 0 13888 16 0 0
sp_get_clo_for_invoice 5015 5015 0 0.00 0 17804 312 0 0
sp_kill_cost_storno 15921 15921 0 0.00 0 18984 60 0 0
sp_kill_qstorno_ret_qs2qd 1076830 1076830 0 0.00 0 28316 33 0 0
sp_kill_qty_storno 568722 568722 0 0.00 0 28338 72 0 0
sp_lock_selected_doc 21353 21353 0 0.00 0 7940 6 0 0
sp_make_cost_storno 28511 28511 0 0.00 0 14583 60 0 0
sp_multiply_rows_for_pdistr 28511 28511 0 0.00 0 6293 29 0 0
sp_multiply_rows_for_qdistr 20889 20889 0 0.00 0 26871 171 0 0
sp_pay_from_customer 7234 7234 0 0.00 4 14363 118 0 0
sp_pay_to_supplier 5951 5951 0 0.00 5 14301 150 0 0
sp_payment_common 13185 13185 0 0.00 1 14354 29 0 0
sp_qd_handle_on_cancel_clo 1803 1803 0 0.00 0 8801 294 0 0
sp_qd_handle_on_invoice_upd_sts 10327 10327 0 0.00 0 16442 312 0 0
sp_qd_handle_on_reserve_upd_sts 10312 10312 0 0.00 0 15392 65 0 0
sp_reserve_write_off 10312 10312 0 0.00 6 16983 190 0 0
sp_supplier_invoice 5473 5473 0 0.00 6 86099 3325 0 0
sp_supplier_order 5714 5714 0 0.00 5 35528 1335 0 0
srv_aggregate_perf_data 105 104 1 0.95 2674 449537 20507 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 860 107423 42840 0 0
srv_find_qd_qs_mism 1027962 1027962 0 0.00 0 18705 5 0 0
srv_make_invnt_saldo 580 580 0 0.00 26 54598 1018 0 0
srv_make_money_saldo 817 817 0 0.00 0 606 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 143042 383948 288479 0 0
t$perf-abend:sp_add_invoice_to_stock 187 187 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5031 5031 0 0.00 0 11753 158 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 7952 133 0 0
t$perf-norm:sp_cancel_client_order 1803 1803 0 0.00 0 3458 11 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 3736 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 757 757 0 0.00 0 1312 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 3024 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 1877 15 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 21799 1677 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 4584 18 0 0
t$perf-norm:sp_client_order 20889 20889 0 0.00 0 7354 15 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 4147 6 0 0
t$perf-norm:sp_pay_from_customer 7234 7234 0 0.00 0 5924 10 0 0
t$perf-norm:sp_pay_to_supplier 5951 5951 0 0.00 0 6188 11 0 0
t$perf-norm:sp_reserve_write_off 10312 10312 0 0.00 0 4767 11 0 0
t$perf-norm:sp_supplier_invoice 5473 5473 0 0.00 0 4472 14 0 0
t$perf-norm:sp_supplier_order 5714 5714 0 0.00 0 6054 13 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 284 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 148 3 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 2851 22 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 4123 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10402 10028 374 3.59 0 6259 24 0 374
v_all_customers 37932 37932 0 0.00 0 98 1 0 0
v_all_suppliers 30880 30880 0 0.00 0 29 1 0 0
v_all_wares 41778 41778 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2177 15 0 0
v_cancel_client_order 3606 3606 0 0.00 0 3509 8 0 0
v_cancel_customer_prepayment 1514 1514 0 0.00 0 6067 15 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 8830 20 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3661 8 0 0
v_cancel_supplier_invoice 898 880 18 2.00 0 4346 36 0 18
v_cancel_supplier_order 776 776 0 0.00 0 5520 23 0 0
v_cancel_write_off 2116 2116 0 0.00 0 5540 23 0 0
v_max_id_clo_ord 5710 5710 0 0.00 0 1726 2 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 3806 17 0 0
v_max_id_ord_sup 5465 5465 0 0.00 0 10725 9 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 3355 5 0 0
v_max_non_paid_realizn 3977 3977 0 0.00 0 1367 3 0 0
v_min_id_clo_ord 5710 5710 0 0.00 0 19579 16 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 7449 24 0 0
v_min_id_ord_sup 5465 5465 0 0.00 0 4166 7 0 0
v_min_non_paid_invoice 5951 5951 0 0.00 0 4996 17 0 0
v_min_non_paid_realizn 7234 7234 0 0.00 0 7752 24 0 0
v_our_firm 10360 10360 0 0.00 0 23 1 0 0
v_reserve_write_off 20624 20624 0 0.00 0 12574 10 0 0
x_lock_depdocs_on_canc_invoice 10772 10772 0 0.00 0 11159 119 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 11160 393 0 0
x_make_qstorno_1000_1200 5714 5714 0 0.00 1 35517 1254 0 0
x_make_qstorno_1200_2000 5473 5473 0 0.00 3 82706 3174 0 0
x_make_qstorno_2100_3300 458827 458827 0 0.00 0 41633 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2022 01:39:37 to 09-08-2022 01:39:41.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:25 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:26 23888 8192000000 9905155520 10217660416 986128432 10.744 100 91 182 0 1016493392 6488736 23876224
23:11:06 18889 8192000000 9942399040 10263228416 1022075296 11.092 100 86 170 0 1052733920 8663296 21995328
23:12:27 11905 8192000000 9966176960 10301800448 1055455424 11.413 100 77 154 0 1084275184 9516336 19303424
23:13:43 9691 8192000000 10024777072 10372644864 1112389808 11.955 100 83 165 0 1144299264 10896560 21012896
23:14:59 5547 8192000000 10074461008 10433142784 1160546176 12.408 100 84 164 0 1192798960 13343168 18909616
23:16:09 3805 8192000000 10117746576 10486587392 1204675552 12.820 100 70 137 4 1231827936 11667040 15485344
23:17:16 2066 8192000000 10152115104 10528268288 1240124816 13.147 100 84 166 0 1270779344 12167568 18486960
23:18:20 3727 8192000000 10179114128 10558324736 1269343936 13.416 100 86 167 3 1301850512 13886912 18619664
23:19:26 954 8192000000 10225405456 10609270784 1306034672 13.750 100 92 181 0 1337878064 11209200 20634192
23:20:28 25591 8192000000 10199816480 10614140928 1319153664 13.869 100 26 52 0 1331096784 5637040 6306080
23:21:54 2014 8192000000 10219934688 10629890048 1322777488 13.902 100 44 88 0 1340724496 7606688 10340320
23:22:57 13624 8192000000 10210806960 10635837440 1330013600 13.967 100 19 37 1 1340345328 5748976 4582752
23:24:12 6790 8192000000 10221627712 10642685952 1335092512 14.013 100 13 27 0 1343312960 5322640 2897808
23:25:20 51646 8192000000 10231097808 10653982720 1340227248 14.059 100 32 61 1 1351713616 4309520 7176848
23:27:13 3443 8192000000 10240080336 10656059392 1340229376 14.059 100 44 88 0 1358278672 8026016 10023280
23:28:17 4161 8192000000 10216606064 10628370432 1311186144 13.797 99 31 62 1 1324647136 6737744 6723248
23:29:24 8615 8192000000 10176124256 10585903104 1264701216 13.373 97 27 52 1 1276378768 5613632 6063920
23:30:35 21327 8192000000 10150894736 10565746688 1177627904 12.568 91 38 76 1 1196734256 10000400 9105952
23:31:59 13905 8192000000 10165902992 10572730368 1260891072 13.338 99 16 32 1 1270464688 6063136 3510480
23:33:14 9033 8192000000 10164459728 10569187328 1273053232 13.450 100 21 42 0 1283921888 5745024 5123632
23:34:24 14721 8192000000 10176761056 10584518656 1272316144 13.443 99 37 73 0 1290418448 10175376 7926928
23:35:39 20850 8192000000 10186946416 10597040128 1292651264 13.628 100 19 40 1 1301549632 4791648 4106720
23:37:00 8087 8192000000 10198689664 10613682176 1308375488 13.771 100 20 40 0 1318260864 5454496 4430880
23:38:09 10838 8192000000 10227994368 10641207296 1329544416 13.963 100 21 42 0 1343086400 9052560 4489424
23:39:30 61644 8192000000 10258045248 10680815616 1358181552 14.221 100 48 94 0 1378805840 9591456 11032832
23:41:36 14086 8192000000 10268407872 10690949120 1366365536 14.294 100 50 100 1 1386613040 8503568 11743936
23:43:06 19165 8192000000 10301851600 10724032512 1387577888 14.484 100 51 102 1 1410394096 11248432 11567776
23:44:28 12535 8192000000 10316571088 10741088256 1404443952 14.635 100 50 99 0 1430223376 14700464 11078960
23:45:54 9877 8192000000 10319638304 10750709760 1411463568 14.697 100 47 94 0 1437278176 15056592 10758016
23:47:11 23035 8192000000 10307791200 10741878784 1389087632 14.498 99 31 62 0 1410913616 14288304 7537680
23:48:47 13102 8192000000 10316617584 10748243968 1393514640 14.537 99 46 86 2 1417701936 14021568 10165728
23:50:04 5226 8192000000 10344000000 10776989696 1419322112 14.767 100 37 72 1 1452888768 24451584 9115072
23:51:16 6690 8192000000 10344114016 10770518016 1418574320 14.760 100 59 117 2 1457606864 24631552 14400992
23:52:29 3310 8192000000 10346058976 10764943360 1410887008 14.692 99 68 135 1 1456620448 28792528 16940912
23:53:41 5385 8192000000 10367897264 10789724160 1431076192 14.871 100 56 112 2 1477228064 31681152 14470720
23:55:02 858 8192000000 10373631696 10783551488 1420258640 14.775 100 78 157 0 1477219024 37793328 19167056
23:56:06 1491 8192000000 10365338928 10769530880 1404904000 14.639 100 91 180 1 1467485728 39627280 22954448
23:57:17 1884 8192000000 10345213456 10752995328 1380383856 14.420 99 79 157 0 1444297360 43355280 20558224
23:58:26 3403 8192000000 10347974848 10757615616 1383444576 14.447 100 68 134 0 1452190112 51804272 16941264
23:59:34 11201 8192000000 10354116416 10781700096 1395141312 14.552 100 43 87 1 1463650208 55735104 12773792
00:00:47 5332 8192000000 10333246224 10773614592 1398730032 14.584 100 32 62 0 1448446128 41249328 8466768
00:02:04 8604 8192000000 10336223888 10754834432 1379445648 14.412 100 57 111 2 1439451808 44227408 15778752
00:03:19 26162 8192000000 10274986960 10720763904 1346557280 14.116 99 21 42 1 1386383840 33401584 6424976
00:04:51 14884 8192000000 10300027920 10739838976 1355556192 14.197 100 17 34 0 1408558320 48399232 4602896
00:06:09 104138 8192000000 10269829456 10707259392 1345429008 14.106 99 23 47 0 1367713648 16515312 5769328
00:08:56 33568 8192000000 10236778208 10665910272 1295077968 13.650 96 12 25 0 1311203008 13372640 2752400
00:11:32 69735 8192000000 10270972320 10714570752 1368139152 14.310 100 17 35 0 1386259008 14561952 3557904
00:13:44 51607 8192000000 10267960816 10705215488 1347511056 14.125 98 30 60 0 1369346400 14462784 7372560
00:15:44 105583 8192000000 10278751184 10731737088 1353503360 14.179 98 14 28 0 1367864048 11280144 3080544
00:18:31 51596 8192000000 10294509040 10740596736 1386888640 14.478 100 41 82 1 1409407264 12249456 10269168
00:20:25 83597 8192000000 10303149664 10761134080 1393262624 14.535 100 12 24 0 1409563392 13455296 2845472
00:23:48 31520 8192000000 10288133616 10745466880 1394375056 14.545 100 12 24 1 1404819168 7878544 2565568
00:25:26 57833 8192000000 10290882832 10750283776 1393802352 14.540 100 12 24 1 1404091040 7753504 2535184
00:27:30 3980 8192000000 10295684576 10736967680 1378108768 14.400 99 61 117 1 1398091632 6437056 13545808
00:28:35 50414 8192000000 10310057168 10765766656 1402577056 14.618 100 17 34 0 1415060960 8297696 4186208
00:30:30 71594 8192000000 10304164496 10779185152 1393248592 14.535 99 8 16 0 1410232240 15131120 1852528
00:32:43 40638 8192000000 10305156448 10764849152 1394763520 14.548 99 17 34 0 1407813200 9274384 3775296
00:34:24 62874 8192000000 10287743408 10753433600 1356006144 14.201 97 22 44 1 1369302896 8658624 4638128
00:36:28 27438 8192000000 10287315392 10737696768 1340018400 14.058 96 28 57 2 1354106736 7812704 6275632
00:38:01 78948 8192000000 10296084208 10746023936 1360348656 14.240 98 33 66 0 1378542048 10648048 7545344
00:40:25 68622 8192000000 10288417712 10751746048 1349911536 14.147 97 21 42 0 1370430256 16129744 4388976
00:42:35 69949 8192000000 10257571728 10702204928 1281580144 13.527 93 18 36 0 1295851936 10328032 3943760
00:44:45 25979 8192000000 10277991024 10706526208 1352752240 14.172 99 64 128 2 1377782928 10193504 14837184
00:46:14 32899 8192000000 10251160784 10692071424 1328092576 13.950 98 58 114 1 1349708848 8450048 13166224
00:48:11 41504 8192000000 10241857904 10693947392 1335034000 14.013 98 27 55 0 1352774928 11504656 6236272
00:49:55 70308 8192000000 10267535280 10721636352 1355179616 14.194 99 7 14 0 1376889456 20071824 1638016
00:52:06 70577 8192000000 10247302384 10705960960 1336967648 14.030 99 21 42 0 1354371952 12507280 4897024
00:54:27 41985 8192000000 10260300096 10698813440 1328678704 13.955 98 28 56 0 1346524112 11058544 6786864
00:56:19 60915 8192000000 10274632368 10719567872 1333936800 14.003 97 19 38 1 1356418752 17684848 4797104
00:58:25 46158 8192000000 10272673872 10715664384 1343298960 14.087 98 23 47 0 1359305056 10436432 5569664
01:00:12 25552 8192000000 10274696768 10709319680 1344415216 14.097 98 40 77 1 1361601344 8439904 8746224
01:01:38 107423 8192000000 10278793936 10720903168 1328073376 13.950 97 15 29 2 1342447456 11171616 3202464
01:04:28 21313 8192000000 10291920320 10726887424 1364511328 14.278 99 36 72 0 1381289024 8719600 8058096
01:06:07 54378 8192000000 10289235296 10732453888 1381443296 14.429 100 46 92 0 1401339200 9098768 10797136
01:08:30 62742 8192000000 10282997616 10740170752 1381290144 14.428 99 14 28 0 1392746464 8060544 3395776
01:11:17 73784 8192000000 10290520208 10744393728 1325913040 13.930 95 11 22 0 1342608160 14369040 2326080
01:13:31 54097 8192000000 10306206480 10752192512 1376244336 14.383 99 23 46 0 1393489504 11536608 5708560
01:15:25 50275 8192000000 10296182592 10741993472 1381431488 14.429 99 18 36 0 1398124528 12426992 4266048
01:17:35 78893 8192000000 10283780464 10732892160 1344401040 14.097 97 23 46 0 1367667408 18080352 5186016
01:19:55 37298 8192000000 10298818560 10750652416 1368433360 14.313 99 19 38 0 1393867920 20778160 4656400
01:21:32 64804 8192000000 10295947024 10750431232 1369786048 14.325 99 30 57 1 1389098784 13087952 6224784
01:23:38 73710 8192000000 10266116864 10701635584 1312272192 13.807 95 29 58 1 1335163744 15244976 7646576
01:25:52 86295 8192000000 10278776432 10725998592 1347607680 14.126 98 20 40 0 1364401856 12530576 4263600
01:28:57 28523 8192000000 10278916208 10733166592 1358277888 14.222 99 20 40 0 1376704800 13699168 4727744
01:30:26 82279 8192000000 10263140880 10720677888 1347540480 14.125 98 12 24 0 1359090752 8891376 2658896
01:33:58 52473 8192000000 10246966960 10703036416 1321835312 13.893 97 7 14 0 1331169296 7963280 1370704
01:35:59 42416 8192000000 10250388912 10692034560 1308478192 13.772 96 18 36 0 1325119808 12590816 4050800
01:37:46 43640 8192000000 9057671920 9282158592 223889120 2.660 17 5 10 0 229451264 4346592 1215552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-08-2022 01:39:43 to 09-08-2022 01:39:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2022 01:39:59 to 09-08-2022 01:40:01.

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          245523
MON$OLDEST_ACTIVE               245524
MON$OLDEST_SNAPSHOT             245524
MON$NEXT_TRANSACTION            245524
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                       1999552
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.33615 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33615 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33615 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33615 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Aug  9 01:38:42 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

09.08.22 01:40:02. 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-08-09 01:40:04 No severe PSQL-related problems occured
09.08.22 01:40:06. End of report.