Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2022 19: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 14972252 kB
MemAvailable 30060480 kB
Buffers 0 kB
Cached 16709728 kB
SwapCached 3188 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-12-13 19:38:34
DTS_END                         2022-12-13 19:38:34.1120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37748
TRN_ID                          242645
ATT_ID                          644
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: 9667.03 20758 1160044
customer order: creation 178.67 358 21440
customer order: refuse 14.76 345 1771
order to supplier: creation 47.03 1227 5643
order to supplier: removal 3.58 83722 430
invoice (draft): creation 47.52 2810 5702
invoice (draft): removal 57.72 626 6926
invoice accept: apply 42.63 18312 5116
invoice accept: cancel 51.61 687 6193
customer reserve: creation 3921.01 183 470521
customer reserve: removal 4979.80 47 597576
realization accept: apply 87.57 167 10508
realization accept: cancel 99.36 90 11923
payment from customer: creation 61.89 126 7427
payment from customer: removal 6.27 86 752
payment to supplier: creation 51.03 129 6123
payment to supplier: removal 3.57 97 428
service: total inventory turnovers 5.35 957 642
service: total monetary turnovers 6.78 24 814
service: aggregate perf. data 0.88 16080 105
service: refresh index statistics 0.03 289086 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2022 19:39:16 to 13-12-2022 19:39:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5668
TEST_TIME 2 6369
TEST_TIME 3 6554
TEST_TIME 4 6183
TEST_TIME 5 6741
TEST_TIME 6 6532
TEST_TIME 7 6209
TEST_TIME 8 6033
TEST_TIME 9 6216
TEST_TIME 10 6252
TEST_TIME 11 6572
TEST_TIME 12 6897
TEST_TIME 13 6849
TEST_TIME 14 6777
TEST_TIME 15 6921
TEST_TIME 16 7123
TEST_TIME 17 7458
TEST_TIME 18 7609
TEST_TIME 19 7731
TEST_TIME 20 8021
TEST_TIME 21 8222
TEST_TIME 22 8231
TEST_TIME 23 8145
TEST_TIME 24 8616
TEST_TIME 25 9403
TEST_TIME 26 9205
TEST_TIME 27 9312
TEST_TIME 28 9476
TEST_TIME 29 9480
TEST_TIME 30 10159
TEST_TIME 31 10361
TEST_TIME 32 10222
TEST_TIME 33 10292
TEST_TIME 34 10329
TEST_TIME 35 10537
TEST_TIME 36 10711
TEST_TIME 37 10581
TEST_TIME 38 10427
TEST_TIME 39 10293
TEST_TIME 40 10146
TEST_TIME 41 9993
TEST_TIME 42 10121
TEST_TIME 43 9951
TEST_TIME 44 10014
TEST_TIME 45 9944
TEST_TIME 46 9979
TEST_TIME 47 10039
TEST_TIME 48 10013
TEST_TIME 49 9895
TEST_TIME 50 9851
TEST_TIME 51 9709
TEST_TIME 52 9749
TEST_TIME 53 9766
TEST_TIME 54 9726
TEST_TIME 55 9657
TEST_TIME 56 9597
TEST_TIME 57 9629
TEST_TIME 58 9592
TEST_TIME 59 9545
TEST_TIME 60 9535
TEST_TIME 61 9566
TEST_TIME 62 9768
TEST_TIME 63 9813
TEST_TIME 64 9842
TEST_TIME 65 9951
TEST_TIME 66 10044
TEST_TIME 67 10074
TEST_TIME 68 10109
TEST_TIME 69 10137
TEST_TIME 70 10134
TEST_TIME 71 10062
TEST_TIME 72 10009
TEST_TIME 73 9958
TEST_TIME 74 9947
TEST_TIME 75 9882
TEST_TIME 76 9902
TEST_TIME 77 9850
TEST_TIME 78 9768
TEST_TIME 79 9792
TEST_TIME 80 9748
TEST_TIME 81 9681
TEST_TIME 82 9623
TEST_TIME 83 9654
TEST_TIME 84 9622
TEST_TIME 85 9652
TEST_TIME 86 9613
TEST_TIME 87 9731
TEST_TIME 88 9722
TEST_TIME 89 9748
TEST_TIME 90 9715
TEST_TIME 91 9751
TEST_TIME 92 9793
TEST_TIME 93 9860
TEST_TIME 94 9822
TEST_TIME 95 9799
TEST_TIME 96 9871
TEST_TIME 97 9895
TEST_TIME 98 9899
TEST_TIME 99 9876
TEST_TIME 100 9802
TEST_TIME 101 9752
TEST_TIME 102 9729
TEST_TIME 103 9691
TEST_TIME 104 9646
TEST_TIME 105 9646
TEST_TIME 106 9641
TEST_TIME 107 9590
TEST_TIME 108 9539
TEST_TIME 109 9541
TEST_TIME 110 9531
TEST_TIME 111 9515
TEST_TIME 112 9504
TEST_TIME 113 9483
TEST_TIME 114 9662
TEST_TIME 115 9673
TEST_TIME 116 9653
TEST_TIME 117 9647
TEST_TIME 118 9634
TEST_TIME 119 9707
TEST_TIME 120 9674

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2022 19:39:19 to 13-12-2022 19:39:23.

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 1596563 1596563 0 0.00 0 22322 6 0 0
doc_list_biud 1596561 1596561 0 0.00 0 64160 23 0 0
sp_add_invoice_to_stock 5205 5116 89 1.70 32 183637 18312 0 89
sp_cancel_adding_invoice 6193 6193 0 0.00 4 88602 687 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 8547 346 0 0
sp_cancel_customer_reserve 597576 597576 0 0.00 0 64161 47 0 0
sp_cancel_pay_from_customer 752 752 0 0.00 3 4610 86 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 5701 98 0 0
sp_cancel_supplier_invoice 6936 6926 10 0.14 2 12960 626 0 10
sp_cancel_supplier_order 430 430 0 0.00 6 1018551 83722 0 0
sp_cancel_write_off 11923 11923 0 0.00 1 7861 91 0 0
sp_client_order 21440 21440 0 0.00 3 77099 358 0 0
sp_customer_reserve 470521 470521 0 0.00 0 30379 183 0 0
sp_fill_shopping_cart 32782 32782 0 0.00 1 7473 33 0 0
sp_fill_shopping_cart_clo_res 470508 470508 0 0.00 0 13676 13 0 0
sp_get_clo_for_invoice 5101 5101 0 0.00 0 23494 265 0 0
sp_kill_cost_storno 19296 19296 0 0.00 0 9282 46 0 0
sp_kill_qstorno_ret_qs2qd 1214431 1214431 0 0.00 0 64152 26 0 0
sp_kill_qty_storno 640427 640427 0 0.00 0 64159 56 0 0
sp_lock_selected_doc 21593 21593 0 0.00 0 7929 5 0 0
sp_make_cost_storno 29158 29158 0 0.00 0 22318 53 0 0
sp_multiply_rows_for_pdistr 29158 29158 0 0.00 0 6124 27 0 0
sp_multiply_rows_for_qdistr 21440 21440 0 0.00 0 77089 176 0 0
sp_pay_from_customer 7427 7427 0 0.00 4 17202 127 0 0
sp_pay_to_supplier 6123 6123 0 0.00 4 22328 130 0 0
sp_payment_common 13550 13550 0 0.00 1 11495 26 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 8540 318 0 0
sp_qd_handle_on_invoice_upd_sts 11293 11293 0 0.00 0 17816 289 0 0
sp_qd_handle_on_reserve_upd_sts 10508 10508 0 0.00 0 18483 53 0 0
sp_reserve_write_off 10508 10508 0 0.00 6 18504 168 0 0
sp_supplier_invoice 5702 5702 0 0.00 5 63384 2811 0 0
sp_supplier_order 5643 5643 0 0.00 5 25154 1227 0 0
srv_aggregate_perf_data 105 105 0 0.00 2274 151910 16081 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1202 116804 43906 0 0
srv_find_qd_qs_mism 1111136 1111136 0 0.00 0 11143 5 0 0
srv_make_invnt_saldo 642 642 0 0.00 29 38726 958 0 0
srv_make_money_saldo 814 814 0 0.00 1 1315 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 95754 529844 289087 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5116 5116 0 0.00 0 13065 165 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 18757 158 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 5815 16 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1091 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 752 752 0 0.00 0 673 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 843 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 463 463 0 0.00 0 970 11 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 22050 1429 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 5035 18 0 0
t$perf-norm:sp_client_order 21440 21440 0 0.00 0 7580 18 0 0
t$perf-norm:sp_customer_reserve 1017 1017 0 0.00 0 5791 14 0 0
t$perf-norm:sp_pay_from_customer 7427 7427 0 0.00 0 6958 11 0 0
t$perf-norm:sp_pay_to_supplier 6123 6123 0 0.00 0 5448 12 0 0
t$perf-norm:sp_reserve_write_off 10508 10508 0 0.00 0 10649 13 0 0
t$perf-norm:sp_supplier_invoice 5702 5702 0 0.00 0 6889 19 0 0
t$perf-norm:sp_supplier_order 5643 5643 0 0.00 0 9241 21 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 868 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 534 9 0 0
t$perf-norm:srv_make_invnt_saldo 642 642 0 0.00 0 5941 34 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 3937 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10378 10200 178 1.71 0 5404 17 0 178
v_all_customers 38896 38896 0 0.00 0 17 1 0 0
v_all_suppliers 31622 31622 0 0.00 0 26 1 0 0
v_all_wares 42880 42880 0 0.00 0 178 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 2269 13 0 0
v_cancel_client_order 3542 3542 0 0.00 0 2464 7 0 0
v_cancel_customer_prepayment 1504 1504 0 0.00 0 1991 6 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 768 4 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 3761 17 0 0
v_cancel_supplier_invoice 946 926 20 2.11 0 1993 21 0 20
v_cancel_supplier_order 860 860 0 0.00 0 1278 5 0 0
v_cancel_write_off 2036 2036 0 0.00 0 5295 17 0 0
v_max_id_clo_ord 5642 5642 0 0.00 0 393 1 0 0
v_max_id_clo_res 1017 1017 0 0.00 0 1731 14 0 0
v_max_id_ord_sup 5700 5700 0 0.00 0 5479 5 0 0
v_max_non_paid_invoice 1654 1654 0 0.00 0 229 2 0 0
v_max_non_paid_realizn 4007 4007 0 0.00 0 1078 4 0 0
v_min_id_clo_ord 5642 5642 0 0.00 0 3451 6 0 0
v_min_id_clo_res 1017 1017 0 0.00 0 9453 16 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 5433 7 0 0
v_min_non_paid_invoice 6123 6123 0 0.00 0 4172 15 0 0
v_min_non_paid_realizn 7427 7427 0 0.00 0 4319 20 0 0
v_our_firm 10824 10824 0 0.00 0 24 1 0 0
v_reserve_write_off 21016 21016 0 0.00 0 12298 7 0 0
x_lock_depdocs_on_canc_invoice 12656 12656 0 0.00 0 20956 110 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 6315 294 0 0
x_make_qstorno_1000_1200 5643 5643 0 0.00 1 25140 1157 0 0
x_make_qstorno_1200_2000 5702 5702 0 0.00 2 63376 2691 0 0
x_make_qstorno_2100_3300 470521 470521 0 0.00 0 28906 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2022 19:39:24 to 13-12-2022 19:39:28.

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
17:08:38 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:38 10048 8192000000 9878138608 10182365184 966076032 10.548 100 89 178 0 995000320 5544992 23379296
17:10:56 25769 8192000000 9929534096 10248548352 1014754304 11.021 100 91 182 0 1046302480 7723856 23824320
17:12:29 12333 8192000000 9969789088 10293018624 1049262032 11.354 100 90 180 0 1081503696 9200064 23041600
17:13:47 10848 8192000000 10015254400 10357293056 1099268320 11.831 100 73 146 0 1127210528 10208656 17733552
17:15:04 6720 8192000000 10070500080 10426707968 1162121328 12.423 100 75 148 1 1190646784 11719680 16805776
17:16:14 4054 8192000000 10125905344 10491641856 1211106592 12.879 100 80 158 0 1240779136 12151808 17520736
17:17:22 2995 8192000000 10155237440 10529169408 1239357856 13.140 100 87 173 0 1272331184 13706208 19267120
17:18:27 1560 8192000000 10174314752 10553036800 1263954656 13.366 100 82 164 0 1295330672 13292608 18083408
17:19:30 1473 8192000000 10200114432 10581155840 1285712352 13.565 100 84 164 0 1316210752 12358752 18139648
17:20:33 17059 8192000000 10214163616 10607738880 1304350432 13.735 100 57 112 0 1325201792 7509648 13341712
17:21:51 3019 8192000000 10214355040 10613342208 1312898832 13.812 100 45 91 1 1332048544 8424464 10725248
17:22:59 31593 8192000000 10200308240 10618380288 1315716368 13.838 100 26 51 0 1326871456 5608992 5546096
17:24:32 2586 8192000000 10219228016 10630651904 1322110016 13.896 100 28 56 0 1334364576 6451456 5803104
17:25:36 5007 8192000000 10214163712 10636386304 1328481216 13.953 100 26 50 1 1340994768 6713440 5800112
17:26:48 65834 8192000000 10221020048 10645520384 1323752064 13.911 99 32 64 2 1336772256 6177296 6842896
17:28:54 6466 8192000000 10211163872 10630967296 1321824240 13.893 99 35 70 0 1336868512 7173328 7870944
17:30:02 10086 8192000000 10195388528 10604216320 1279339296 13.507 98 18 36 0 1293348256 9907680 4101280
17:31:13 10920 8192000000 10121230336 10524131328 1181740992 12.606 94 13 26 1 1190576384 5289280 3546112
17:32:27 29917 8192000000 10117350448 10520412160 1212914624 12.896 98 29 56 1 1228954992 9401552 6638816
17:33:57 6903 8192000000 10135794656 10540511232 1249783280 13.236 100 22 44 0 1261213872 5984528 5446064
17:35:05 5954 8192000000 10164345744 10570469376 1263120240 13.359 99 23 45 1 1273058000 4483952 5453808
17:36:11 9441 8192000000 10181673856 10589925376 1288160992 13.587 100 17 33 1 1296896720 5034416 3701312
17:37:22 34956 8192000000 10184828576 10603606016 1300277680 13.698 100 20 40 0 1310312944 5227376 4807888
17:39:04 15039 8192000000 10243870240 10651209728 1333509088 13.999 100 46 92 0 1353233136 9938096 9785952
17:40:26 21495 8192000000 10254744800 10669170688 1348233424 14.132 100 36 72 0 1365384880 9097760 8053696
17:41:53 8636 8192000000 10267106032 10689593344 1363887856 14.272 100 40 80 2 1382507296 9584208 9035232
17:43:04 24065 8192000000 10276242976 10706321408 1375197360 14.374 100 32 64 0 1396922560 14115120 7610080
17:44:51 10633 8192000000 10297753568 10719899648 1385926528 14.470 100 52 101 1 1409860128 12442272 11491328
17:46:08 20253 8192000000 10312064752 10732044288 1393141312 14.534 100 47 94 0 1417138304 14049600 9947392
17:47:39 11310 8192000000 10310129424 10734944256 1393516672 14.537 100 49 98 0 1424037840 18543504 11977664
17:48:54 12056 8192000000 10314430128 10745958400 1403522208 14.626 100 37 74 1 1431073696 18512688 9038800
17:50:10 2312 8192000000 10327995200 10752237568 1401595488 14.609 100 57 114 1 1433996528 19168912 13232128
17:51:39 9030 8192000000 10326428704 10755796992 1404434544 14.634 100 57 114 1 1437744704 20218352 13091808
17:52:53 19103 8192000000 10341868736 10769092608 1410540704 14.689 100 57 112 1 1450401856 26640944 13220208
17:54:21 5183 8192000000 10339178016 10772041728 1414014928 14.720 100 46 90 0 1450906400 25831552 11059920
17:55:33 1202 8192000000 10324025488 10741919744 1385268560 14.464 99 73 147 1 1428352064 25255136 17828368
17:56:39 2887 8192000000 10322594416 10733953024 1377884016 14.398 99 62 119 0 1416697744 24950480 13863248
17:57:46 2597 8192000000 10333053072 10751397888 1396251568 14.562 100 70 139 0 1438641456 25511376 16878512
17:58:53 11989 8192000000 10285542272 10708938752 1335448288 14.016 97 30 60 0 1370362848 26990928 7923632
18:00:08 10480 8192000000 10277006512 10691948544 1317704016 13.856 97 37 72 0 1356686912 28955312 10027584
18:01:25 2313 8192000000 10300428816 10694766592 1334804480 14.011 100 77 150 0 1395544416 42413952 18325984
18:02:34 14407 8192000000 10307212672 10729443328 1345148752 14.104 99 28 56 0 1405669056 52089104 8431200
18:03:50 25922 8192000000 10304410416 10732068864 1358470992 14.224 99 34 69 0 1408515440 41205328 8839120
18:05:18 24406 8192000000 10287993968 10717528064 1349095712 14.139 99 35 67 0 1392956080 34796400 9063968
18:06:46 26871 8192000000 10292998464 10734608384 1346981360 14.120 99 21 41 0 1396828864 44471984 5375520
18:08:14 31930 8192000000 10290633920 10737229824 1356146704 14.203 100 13 26 0 1397708576 37915888 3645984
18:10:00 99612 8192000000 10291826512 10732118016 1340310128 14.060 98 11 22 0 1370959216 28031440 2617648
18:12:42 86941 8192000000 10288055600 10761162752 1354586640 14.189 99 11 22 0 1389416464 32385264 2444560
18:15:39 51285 8192000000 10302379888 10728620032 1353637984 14.180 98 55 105 1 1378852720 13198704 12016032
18:17:32 91085 8192000000 10295393312 10737999872 1360724976 14.244 99 25 48 0 1374433440 7473408 6235056
18:20:03 65009 8192000000 10300400000 10761347072 1385797936 14.468 100 18 36 0 1408233952 18379168 4056848
18:22:50 52597 8192000000 10310296976 10752868352 1364152512 14.275 98 24 49 1 1381967632 11459296 6355824
18:24:45 61324 8192000000 10298714032 10766807040 1397376384 14.572 100 23 45 1 1423005664 20054608 5574672
18:27:31 57751 8192000000 10289006752 10749321216 1379969392 14.416 99 11 22 0 1392368288 9732608 2666288
18:29:35 71113 8192000000 10315357936 10774228992 1389697008 14.503 99 31 62 0 1412392704 15310176 7385520
18:31:49 50962 8192000000 10299173872 10759147520 1399195232 14.588 100 12 24 0 1411927424 9748512 2983680
18:33:49 85335 8192000000 10294710720 10766925824 1364254000 14.276 97 5 10 1 1372082896 6920000 908896
18:36:15 33513 8192000000 10311230416 10768101376 1354134448 14.185 96 17 32 0 1374036832 15297824 4604560
18:37:48 37065 8192000000 10326597424 10788745216 1399640544 14.592 99 17 34 0 1417168976 13591040 3937392
18:39:26 59982 8192000000 10317259728 10791075840 1394868688 14.549 99 14 28 0 1417303024 19110672 3323664
18:41:32 60532 8192000000 10319196784 10778521600 1373106608 14.355 97 12 24 0 1388604352 12697904 2799840
18:43:37 67159 8192000000 10283639136 10745094144 1330736848 13.974 96 12 24 2 1349198512 16095104 2366560
18:45:46 51896 8192000000 10265477088 10709102592 1293855088 13.639 94 20 39 1 1307779856 9322320 4602448
18:47:39 52489 8192000000 10269671264 10709168128 1296818912 13.666 95 24 49 0 1319776048 17554976 5402160
18:49:38 91592 8192000000 10267942912 10718068736 1348384976 14.133 99 35 70 0 1368300496 11506960 8408560
18:52:29 51502 8192000000 10270553952 10713817088 1325764928 13.929 97 51 101 1 1351097840 14338640 10994272
18:54:29 76181 8192000000 10260054080 10716819456 1342031264 14.076 98 15 30 1 1356267440 11079312 3156864
18:56:49 24585 8192000000 10259479520 10718347264 1362478752 14.260 100 29 56 0 1379688080 10144976 7064352
18:58:34 99388 8192000000 10262248912 10718720000 1356191696 14.203 99 12 24 0 1370013648 10853936 2968016
19:01:14 37594 8192000000 10277128320 10740486144 1371103408 14.337 100 19 35 1 1387572784 12438896 4030480
19:02:52 63357 8192000000 10273254256 10736852992 1342106224 14.076 97 16 32 0 1360250256 14492640 3651392
19:04:56 50965 8192000000 10270191136 10725126144 1334698592 14.010 97 23 46 0 1353465600 13040208 5726800
19:06:47 56523 8192000000 10282653376 10734317568 1354154496 14.185 98 21 42 1 1377596384 18788368 4653520
19:08:48 35852 8192000000 10283126256 10729418752 1361259824 14.249 98 26 53 0 1381111552 14310112 5541616
19:10:31 116804 8192000000 10298684912 10757365760 1387128048 14.480 100 8 16 0 1402510240 14130688 1251504
19:13:28 12523 8192000000 10322849104 10765926400 1399479664 14.590 100 44 88 1 1419450208 10165664 9804880
19:14:45 66431 8192000000 10286112336 10748911616 1356954720 14.210 97 12 23 0 1369670048 10214256 2501072
19:16:54 100398 8192000000 10281183120 10735648768 1361303568 14.249 98 7 14 0 1375102112 12269568 1528976
19:19:51 14663 8192000000 10291358000 10746392576 1388746272 14.495 100 19 35 2 1398300784 5992480 3562032
19:21:06 58771 8192000000 10281670432 10755334144 1380051536 14.417 99 8 16 0 1391954688 9993328 1909824
19:23:37 64639 8192000000 10273506464 10739548160 1359515600 14.233 98 17 34 0 1372753536 9175776 4062160
19:25:49 73214 8192000000 10283400704 10745573376 1385557488 14.466 100 31 62 2 1401029664 8548784 6923392
19:28:06 45136 8192000000 10253653296 10714910720 1318387264 13.862 96 11 22 0 1328074848 7104848 2582736
19:29:53 35186 8192000000 10295122624 10746789888 1373669392 14.360 99 31 62 1 1395516992 14558160 7289440
19:31:41 70517 8192000000 10261730864 10750586880 1340317776 14.060 98 12 24 0 1371680752 27751680 3611296
19:33:57 61548 8192000000 10257537808 10695999488 1286130560 13.569 95 19 38 0 1301439152 11608080 3700512
19:36:03 21734 8192000000 10257878736 10694492160 1336581152 14.027 100 39 76 0 1357698896 13044960 8072784
19:37:26 74912 8192000000 9292049696 9575505920 441509232 5.113 33 10 20 0 448181680 4826512 1845936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-12-2022 19:39:30 to 13-12-2022 19:39:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-12-2022 19:39:46 to 13-12-2022 19:39:48.

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          241987
MON$OLDEST_ACTIVE               243056
MON$OLDEST_SNAPSHOT             243056
MON$NEXT_TRANSACTION            243056
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                       2031072
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:247: 
:248: mscs-test-161.budzdorov.ru    Tue Dec 13 19:38:44 2022
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
:251: 
:252: mscs-test-161.budzdorov.ru    Tue Dec 13 19:38:46 2022
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:254: 
:255: 
:256: mscs-test-161.budzdorov.ru    Tue Dec 13 19:38:46 2022
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:258: 
--- end of diff output ---

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

13.12.22 19:39:49. 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-12-13 19:39:52 No severe PSQL-related problems occured
13.12.22 19:39:53. End of report.