Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.03.2023 16: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 15009312 kB
MemAvailable 30395216 kB
Buffers 0 kB
Cached 16667112 kB
SwapCached 3160 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 2023-03-17 16:38:45
DTS_END                         2023-03-17 16:38:45.3390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36364
TRN_ID                          240886
ATT_ID                          659
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: 9832.67 17388 1179921
customer order: creation 176.24 380 21149
customer order: refuse 15.12 373 1814
order to supplier: creation 45.86 1231 5503
order to supplier: removal 3.76 104446 451
invoice (draft): creation 46.67 3304 5600
invoice (draft): removal 59.90 800 7188
invoice accept: apply 41.23 16931 4947
invoice accept: cancel 54.13 776 6496
customer reserve: creation 3783.12 169 453974
customer reserve: removal 5293.23 58 635187
realization accept: apply 85.81 200 10297
realization accept: cancel 97.27 100 11672
payment from customer: creation 60.56 131 7267
payment from customer: removal 5.51 82 661
payment to supplier: creation 48.81 173 5857
payment to supplier: removal 3.57 76 428
service: total inventory turnovers 4.63 1272 555
service: total monetary turnovers 6.53 59 784
service: aggregate perf. data 0.73 25657 87
service: refresh index statistics 0.03 191538 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-03-2023 16:39:29 to 17-03-2023 16:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3507
TEST_TIME 2 4184
TEST_TIME 3 4206
TEST_TIME 4 4256
TEST_TIME 5 4363
TEST_TIME 6 4119
TEST_TIME 7 4434
TEST_TIME 8 4605
TEST_TIME 9 5178
TEST_TIME 10 5106
TEST_TIME 11 5069
TEST_TIME 12 5265
TEST_TIME 13 5545
TEST_TIME 14 5572
TEST_TIME 15 5686
TEST_TIME 16 5831
TEST_TIME 17 6230
TEST_TIME 18 6274
TEST_TIME 19 6647
TEST_TIME 20 7032
TEST_TIME 21 7777
TEST_TIME 22 7733
TEST_TIME 23 7914
TEST_TIME 24 7914
TEST_TIME 25 8038
TEST_TIME 26 7997
TEST_TIME 27 8179
TEST_TIME 28 8000
TEST_TIME 29 8566
TEST_TIME 30 9872
TEST_TIME 31 10700
TEST_TIME 32 10758
TEST_TIME 33 10722
TEST_TIME 34 10829
TEST_TIME 35 10788
TEST_TIME 36 10699
TEST_TIME 37 10505
TEST_TIME 38 10568
TEST_TIME 39 10381
TEST_TIME 40 10395
TEST_TIME 41 10168
TEST_TIME 42 10151
TEST_TIME 43 10059
TEST_TIME 44 10082
TEST_TIME 45 9958
TEST_TIME 46 10223
TEST_TIME 47 10297
TEST_TIME 48 10219
TEST_TIME 49 10096
TEST_TIME 50 10024
TEST_TIME 51 10020
TEST_TIME 52 10046
TEST_TIME 53 10021
TEST_TIME 54 9947
TEST_TIME 55 9934
TEST_TIME 56 9886
TEST_TIME 57 9802
TEST_TIME 58 9742
TEST_TIME 59 9737
TEST_TIME 60 9723
TEST_TIME 61 9818
TEST_TIME 62 9830
TEST_TIME 63 9932
TEST_TIME 64 10002
TEST_TIME 65 10033
TEST_TIME 66 10077
TEST_TIME 67 10088
TEST_TIME 68 10097
TEST_TIME 69 10138
TEST_TIME 70 10082
TEST_TIME 71 10187
TEST_TIME 72 10159
TEST_TIME 73 10227
TEST_TIME 74 10153
TEST_TIME 75 10088
TEST_TIME 76 10093
TEST_TIME 77 9999
TEST_TIME 78 9949
TEST_TIME 79 9936
TEST_TIME 80 9872
TEST_TIME 81 9964
TEST_TIME 82 9904
TEST_TIME 83 9827
TEST_TIME 84 9819
TEST_TIME 85 9839
TEST_TIME 86 9776
TEST_TIME 87 9812
TEST_TIME 88 9798
TEST_TIME 89 9881
TEST_TIME 90 9858
TEST_TIME 91 9852
TEST_TIME 92 9900
TEST_TIME 93 9979
TEST_TIME 94 10009
TEST_TIME 95 9958
TEST_TIME 96 9933
TEST_TIME 97 9986
TEST_TIME 98 10048
TEST_TIME 99 9995
TEST_TIME 100 9917
TEST_TIME 101 9915
TEST_TIME 102 9857
TEST_TIME 103 9816
TEST_TIME 104 9875
TEST_TIME 105 9829
TEST_TIME 106 9788
TEST_TIME 107 9774
TEST_TIME 108 9792
TEST_TIME 109 9788
TEST_TIME 110 9789
TEST_TIME 111 9791
TEST_TIME 112 9784
TEST_TIME 113 9831
TEST_TIME 114 9795
TEST_TIME 115 9846
TEST_TIME 116 9837
TEST_TIME 117 9843
TEST_TIME 118 9839
TEST_TIME 119 9881
TEST_TIME 120 9835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-03-2023 16:39:33 to 17-03-2023 16:39:37.

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 1603354 1603354 0 0.00 0 19459 6 0 0
doc_list_biud 1603354 1603354 0 0.00 0 24143 29 0 0
sp_add_invoice_to_stock 5095 4947 148 2.90 51 182702 16931 0 148
sp_cancel_adding_invoice 6496 6496 0 0.00 4 76031 776 0 0
sp_cancel_client_order 1814 1814 0 0.00 4 9496 374 0 0
sp_cancel_customer_reserve 635187 635187 0 0.00 0 24147 58 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 2 6592 82 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 4184 77 0 0
sp_cancel_supplier_invoice 7199 7188 11 0.15 1 22526 800 0 11
sp_cancel_supplier_order 451 451 0 0.00 6 1286776 104447 0 0
sp_cancel_write_off 11672 11672 0 0.00 1 19207 101 0 0
sp_client_order 21149 21149 0 0.00 3 43583 380 0 0
sp_customer_reserve 453974 453974 0 0.00 0 103748 169 0 0
sp_fill_shopping_cart 32252 32252 0 0.00 1 18430 35 0 0
sp_fill_shopping_cart_clo_res 453971 453971 0 0.00 0 15303 12 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 15912 266 0 0
sp_kill_cost_storno 19257 19257 0 0.00 0 6586 49 0 0
sp_kill_qstorno_ret_qs2qd 1289685 1289685 0 0.00 0 24139 32 0 0
sp_kill_qty_storno 678047 678047 0 0.00 0 24142 68 0 0
sp_lock_selected_doc 21167 21167 0 0.00 0 8376 5 0 0
sp_make_cost_storno 28363 28363 0 0.00 0 19445 61 0 0
sp_multiply_rows_for_pdistr 28363 28363 0 0.00 0 9302 28 0 0
sp_multiply_rows_for_qdistr 21149 21149 0 0.00 0 17174 168 0 0
sp_pay_from_customer 7267 7267 0 0.00 4 19430 132 0 0
sp_pay_to_supplier 5857 5857 0 0.00 4 19420 173 0 0
sp_payment_common 13124 13124 0 0.00 1 13645 48 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 0 9489 335 0 0
sp_qd_handle_on_invoice_upd_sts 11438 11438 0 0.00 0 18955 306 0 0
sp_qd_handle_on_reserve_upd_sts 10297 10297 0 0.00 0 13384 68 0 0
sp_reserve_write_off 10297 10297 0 0.00 6 18043 201 0 0
sp_supplier_invoice 5600 5600 0 0.00 5 94285 3305 0 0
sp_supplier_order 5503 5503 0 0.00 5 40218 1231 0 0
srv_aggregate_perf_data 88 87 1 1.13 2502 210901 25658 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1334 143040 46017 0 0
srv_find_qd_qs_mism 1133595 1133595 0 0.00 0 15638 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 39 115353 1273 0 0
srv_make_money_saldo 784 784 0 0.00 1 9782 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 76863 247067 191538 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4947 4947 0 0.00 0 12470 143 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 8245 129 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 2786 13 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 3273 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 2770 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 484 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2299 25 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 27863 1699 0 0
t$perf-norm:sp_cancel_write_off 1068 1068 0 0.00 0 13035 19 0 0
t$perf-norm:sp_client_order 21149 21149 0 0.00 0 16851 16 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 15074 25 0 0
t$perf-norm:sp_pay_from_customer 7267 7267 0 0.00 0 13472 13 0 0
t$perf-norm:sp_pay_to_supplier 5857 5857 0 0.00 0 3340 9 0 0
t$perf-norm:sp_reserve_write_off 10297 10297 0 0.00 0 16850 17 0 0
t$perf-norm:sp_supplier_invoice 5600 5600 0 0.00 0 21170 23 0 0
t$perf-norm:sp_supplier_order 5503 5503 0 0.00 0 28448 20 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 3104 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 643 22 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 3224 29 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 1519 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10180 9884 296 2.90 0 10862 22 0 296
v_all_customers 39382 39382 0 0.00 0 21 1 0 0
v_all_suppliers 30808 30808 0 0.00 0 54 1 0 0
v_all_wares 42298 42298 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 2178 11 0 0
v_cancel_client_order 3628 3628 0 0.00 0 4626 10 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 4957 11 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2396 9 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 4166 9 0 0
v_cancel_supplier_invoice 804 782 22 2.73 0 2176 17 0 22
v_cancel_supplier_order 902 902 0 0.00 0 1157 5 0 0
v_cancel_write_off 2136 2136 0 0.00 0 1880 14 0 0
v_max_id_clo_ord 5503 5503 0 0.00 0 1607 2 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1023 9 0 0
v_max_id_ord_sup 5600 5600 0 0.00 0 3461 2 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 156 2 0 0
v_max_non_paid_realizn 3481 3481 0 0.00 0 8401 6 0 0
v_min_id_clo_ord 5503 5503 0 0.00 0 6100 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 3155 8 0 0
v_min_id_ord_sup 5600 5600 0 0.00 0 5409 7 0 0
v_min_non_paid_invoice 5857 5857 0 0.00 0 13634 47 0 0
v_min_non_paid_realizn 7267 7267 0 0.00 0 13632 34 0 0
v_our_firm 10488 10488 0 0.00 0 19 1 0 0
v_reserve_write_off 20594 20594 0 0.00 0 6430 9 0 0
x_lock_depdocs_on_canc_invoice 13293 13293 0 0.00 0 22447 115 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 12032 364 0 0
x_make_qstorno_1000_1200 5503 5503 0 0.00 2 40204 1149 0 0
x_make_qstorno_1200_2000 5600 5600 0 0.00 2 91824 3182 0 0
x_make_qstorno_2100_3300 453974 453974 0 0.00 0 103746 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-03-2023 16:39:38 to 17-03-2023 16:39:42.

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:49 12 8192000000 8811091584 8826433536 15473664 0.188 4 4 5 0 15700400 87456 139280
14:09:50 25765 8192000000 9906143264 10215002112 988199696 10.764 100 91 182 0 1018525168 6088528 24236944
14:11:22 12327 8192000000 9952307120 10272862208 1035198000 11.218 100 91 181 1 1066176544 8105328 22873216
14:12:45 11846 8192000000 9980474304 10314891264 1064946224 11.504 100 83 166 0 1094930592 9553232 20431136
14:14:03 11655 8192000000 10030131920 10375929856 1113422192 11.965 100 80 159 0 1143784000 10953504 19408304
14:15:22 6116 8192000000 10066202192 10421805056 1152328816 12.331 100 77 152 0 1183035232 12831520 17874896
14:16:31 3666 8192000000 10103264192 10471383040 1200052032 12.777 100 76 152 0 1229163456 11666048 17445376
14:17:36 2560 8192000000 10144269216 10518163456 1232335616 13.076 100 83 166 0 1264419792 13783184 18300992
14:18:42 2052 8192000000 10172710800 10549051392 1261036144 13.340 100 89 178 0 1294497936 14321040 19140752
14:19:46 937 8192000000 10203527504 10586173440 1284956160 13.558 100 83 165 1 1320435104 17614960 17863984
14:20:49 24107 8192000000 10205894128 10594926592 1295928416 13.658 100 56 109 1 1317662992 9402048 12332528
14:22:16 3927 8192000000 10205143552 10612338688 1305864768 13.749 100 46 92 0 1325156464 9081216 10210480
14:23:22 3655 8192000000 10206289216 10617044992 1315222144 13.833 100 25 50 1 1326759184 6115616 5421424
14:24:29 17039 8192000000 10199336176 10620661760 1319733392 13.874 100 9 18 0 1326879232 5376496 1769344
14:25:49 50390 8192000000 10236440288 10644811776 1329471024 13.962 100 48 96 1 1345606432 5311936 10823472
14:27:40 7106 8192000000 10228590112 10648584192 1334799840 14.010 100 30 60 0 1348674704 6398576 7476288
14:28:49 12092 8192000000 10191504448 10608500736 1263452336 13.362 96 21 42 0 1274382336 6092976 4837024
14:30:01 13747 8192000000 10175476768 10581495808 1274163072 13.460 99 30 60 0 1287482336 5819456 7499808
14:31:18 16042 8192000000 10132411456 10531155968 1168968752 12.487 92 18 35 0 1178066096 4715888 4381456
14:32:35 6156 8192000000 10118565760 10515558400 1208216576 12.853 97 21 42 0 1219175008 5681120 5277312
14:33:42 5811 8192000000 10157389376 10555715584 1263268880 13.360 100 24 48 2 1274685408 5873168 5543360
14:34:53 7300 8192000000 10176724384 10585329664 1279663648 13.510 100 15 30 0 1297064560 14035136 3365776
14:36:05 4757 8192000000 10181379552 10589163520 1288151328 13.587 100 46 92 0 1307334368 8923328 10259712
14:37:10 8851 8192000000 10193811632 10604883968 1298467984 13.681 100 27 54 0 1310909792 6651504 5790304
14:38:20 8216 8192000000 10206757968 10624122880 1321389696 13.889 100 19 34 1 1330258816 4909232 3959888
14:39:33 14505 8192000000 10234153728 10647302144 1335403536 14.016 100 47 92 0 1354209056 7567072 11238448
14:41:09 53759 8192000000 10254750656 10676736000 1349268992 14.141 100 37 70 2 1363722848 6711216 7742640
14:43:07 17467 8192000000 10270202640 10694451200 1367844624 14.308 100 34 68 0 1388634640 13222992 7567024
14:44:30 23403 8192000000 10299083360 10720612352 1385270080 14.464 100 42 82 2 1412136720 17616720 9249920
14:46:03 17500 8192000000 10319006544 10737434624 1393459408 14.537 100 56 111 1 1429716880 22460992 13796480
14:47:39 5803 8192000000 10323727056 10750496768 1402871760 14.621 100 60 118 0 1442325760 24761792 14692208
14:48:49 7942 8192000000 10330239120 10760654848 1409272752 14.677 100 45 87 0 1444430832 24945296 10212784
14:50:07 1951 8192000000 10338155744 10767384576 1401766096 14.611 99 52 103 2 1444771232 30279712 12725424
14:51:12 6974 8192000000 10361272512 10790494208 1428369200 14.847 100 50 99 0 1475425008 34334208 12721600
14:52:33 5738 8192000000 10352946864 10767273984 1404040896 14.631 100 77 150 1 1457036720 34902384 18093440
14:53:55 1328 8192000000 10372905648 10789531648 1414876688 14.727 100 82 162 0 1477982256 42303648 20801920
14:55:03 1912 8192000000 10393845232 10810920960 1433500400 14.892 100 77 150 2 1502433488 50923856 18009232
14:56:10 1488 8192000000 10385462304 10801393664 1416932464 14.745 100 78 156 0 1491305776 53875904 20497408
14:57:18 3360 8192000000 10370235264 10789580800 1401606032 14.609 100 62 123 0 1482143552 63328880 17208640
14:58:31 2386 8192000000 10358351056 10776125440 1394488928 14.546 100 63 119 0 1463502960 53361344 15652688
14:59:39 7405 8192000000 10321472544 10746765312 1358504000 14.224 99 45 90 0 1426323952 55450864 12369088
15:00:50 7114 8192000000 10345048272 10764312576 1386769024 14.477 100 41 80 0 1449248880 51580704 10899152
15:01:59 10954 8192000000 10343250544 10764472320 1376475168 14.385 99 50 98 0 1448517280 58838496 13203616
15:03:18 7223 8192000000 10345212480 10775379968 1388519184 14.493 100 30 60 1 1451359216 54203024 8637008
15:04:30 4030 8192000000 10349378416 10777231360 1379148880 14.409 99 38 76 0 1447225264 57710704 10365680
15:05:49 24735 8192000000 10328046448 10760331264 1371788016 14.343 100 29 58 1 1444582416 64159824 8634576
15:07:18 38160 8192000000 10343000256 10782756864 1337115232 14.031 99 35 70 0 1441621360 93038704 11467424
15:09:03 58024 8192000000 10273711216 10715983872 1340170032 14.059 98 19 35 2 1386448656 41024016 5254608
15:11:07 69942 8192000000 10302729808 10740543488 1369940208 14.327 100 15 28 1 1391196704 17395632 3860864
15:13:23 64274 8192000000 10274728240 10720534528 1357683856 14.217 99 24 46 1 1371878528 8410272 5784400
15:15:31 116644 8192000000 10291901408 10739564544 1369113904 14.319 99 26 53 0 1391349552 15751568 6484080
15:18:28 41888 8192000000 10294446992 10750754816 1381682112 14.432 100 40 80 0 1406520448 15810672 9027664
15:20:16 9070 8192000000 10300384512 10749198336 1386435968 14.474 100 33 66 2 1408284128 14362864 7485296
15:21:26 29635 8192000000 10315497520 10767212544 1387511200 14.484 99 17 34 0 1411644320 20188992 3944128
15:22:57 20141 8192000000 10342315152 10777096192 1402962512 14.621 100 58 115 2 1434272928 17272112 14038304
15:24:19 56230 8192000000 10308035904 10785955840 1396470576 14.564 100 10 20 0 1423901264 25323536 2107152
15:26:16 143040 8192000000 10315088288 10781892608 1401653168 14.610 100 14 28 0 1417347264 12407296 3286800
15:30:25 39227 8192000000 10305114224 10765209600 1394843584 14.549 99 24 48 0 1406602896 6356720 5402592
15:32:04 46812 8192000000 10298926208 10765320192 1401883392 14.612 100 22 44 0 1414353488 7715936 4754160
15:33:52 68740 8192000000 10300687792 10771091456 1411035760 14.693 100 13 26 0 1425868832 12015280 2817792
15:36:01 100648 8192000000 10291174384 10773127168 1375700880 14.378 98 17 34 0 1389526960 9196000 4630080
15:38:42 43773 8192000000 10302478896 10765746176 1350796928 14.155 96 20 40 0 1371166112 15530912 4838272
15:40:35 68409 8192000000 10267893968 10730688512 1304426160 13.735 94 11 22 0 1319745280 12605792 2713328
15:42:46 47670 8192000000 10268688464 10730823680 1318547648 13.864 96 13 26 0 1336008016 14591792 2868576
15:44:36 48291 8192000000 10249849296 10708291584 1297896656 13.676 95 25 50 0 1317620816 14012304 5711856
15:46:24 93699 8192000000 10228378960 10677231616 1199252320 12.769 89 16 31 0 1214405520 11435872 3717328
15:49:03 24218 8192000000 10249482976 10688757760 1324194368 13.915 99 24 48 0 1342750176 12999456 5556352
15:50:29 41803 8192000000 10265660128 10705379328 1318475008 13.863 98 27 54 1 1334804896 10305552 6024336
15:52:21 107396 8192000000 10244332096 10699563008 1314225232 13.824 97 21 42 0 1327692864 8410464 5057168
15:55:10 37909 8192000000 10238012944 10690764800 1316549072 13.845 98 17 33 0 1330604096 10695376 3359648
15:56:51 56336 8192000000 10263506768 10714710016 1331154624 13.978 98 42 84 0 1353554736 12861072 9539040
15:58:49 64542 8192000000 10258408656 10726879232 1358624256 14.225 100 17 34 0 1377155792 14252976 4278560
16:01:00 91976 8192000000 10257222656 10712858624 1339731696 14.055 98 11 22 0 1350302384 7922352 2648336
16:03:33 70032 8192000000 10274698608 10728476672 1349276672 14.141 98 14 29 0 1364736112 12361840 3097600
16:05:51 50352 8192000000 10280695648 10744320000 1361857856 14.254 99 22 44 0 1380561264 13828496 4874912
16:07:42 118629 8192000000 10297147328 10750545920 1370572288 14.332 99 14 28 0 1391454896 17945776 2936832
16:10:44 130937 8192000000 10303845536 10773147648 1366453360 14.295 98 9 18 0 1388457440 20629216 1374864
16:13:55 10721 8192000000 10323427376 10766213120 1398872384 14.585 100 50 98 0 1418489776 9033024 10584368
16:15:08 53270 8192000000 10313828864 10773225472 1389223776 14.499 99 35 70 1 1414942432 18253520 7465136
16:17:32 84130 8192000000 10317619360 10768080896 1390412016 14.510 99 19 38 1 1406684672 12010032 4262624
16:20:09 59687 8192000000 10311599168 10771456000 1393545552 14.537 99 15 30 0 1414688736 18094288 3048896
16:22:09 81640 8192000000 10311856880 10780803072 1392645408 14.529 99 19 38 0 1410800256 13724912 4429936
16:24:32 41979 8192000000 10317435104 10782883840 1411841520 14.700 100 29 56 1 1434159264 15634976 6682768
16:26:38 74304 8192000000 10301122432 10763530240 1376841264 14.388 98 18 36 0 1391679904 10578944 4259696
16:28:53 52847 8192000000 10275868032 10735603712 1349318992 14.141 98 16 32 0 1364948720 11978832 3650896
16:30:46 101060 8192000000 10274765312 10727690240 1304565424 13.737 95 18 36 1 1327102592 18377792 4159376
16:33:32 52302 8192000000 10279009600 10718769152 1314342800 13.825 96 19 38 1 1327026672 8522256 4161616
16:35:31 39245 8192000000 10286211056 10732851200 1338687680 14.046 98 26 52 0 1360363456 15970816 5704960
16:37:13 68396 8192000000 10285790688 10719014912 1308358240 13.771 96 32 64 0 1325987072 10885456 6743376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-03-2023 16:39:44 to 17-03-2023 16:39:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-03-2023 16:40:00 to 17-03-2023 16:40:02.

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          240823
MON$OLDEST_ACTIVE               243407
MON$OLDEST_SNAPSHOT             243407
MON$NEXT_TRANSACTION            243407
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                       2055072
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:370: 
:371: mscs-test-161.budzdorov.ru    Fri Mar 17 16:38:55 2023
:372:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:373: 
:374: 
:375: mscs-test-161.budzdorov.ru    Fri Mar 17 16:38:55 2023
:376:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:377: 
:378: 
:379: mscs-test-161.budzdorov.ru    Fri Mar 17 16:38:55 2023
:380:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:381: 
:382: 
:383: mscs-test-161.budzdorov.ru    Fri Mar 17 16:38:55 2023
:384:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:385: 
--- end of diff output ---

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

17.03.23 16:40:03. 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
2023-03-17 16:40:06 No severe PSQL-related problems occured
17.03.23 16:40:07. End of report.