Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.05.2022 19: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 16682180 kB
MemAvailable 31430448 kB
Buffers 0 kB
Cached 15065576 kB
SwapCached 2588 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-23 19:38:17
DTS_END                         2022-05-23 19:38:17.0830
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/54988
TRN_ID                          238683
ATT_ID                          677
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: 8918.63 22593 1070235
customer order: creation 170.13 371 20416
customer order: refuse 14.91 378 1789
order to supplier: creation 44.92 1276 5390
order to supplier: removal 3.46 89255 415
invoice (draft): creation 45.03 3225 5403
invoice (draft): removal 49.53 786 5943
invoice accept: apply 41.14 17456 4937
invoice accept: cancel 44.49 863 5339
customer reserve: creation 3816.83 170 458019
customer reserve: removal 4396.63 55 527595
realization accept: apply 82.38 192 9885
realization accept: cancel 80.18 99 9621
payment from customer: creation 59.86 119 7183
payment from customer: removal 5.32 77 638
payment to supplier: creation 48.46 118 5815
payment to supplier: removal 3.23 114 388
service: total inventory turnovers 5.00 1214 600
service: total monetary turnovers 6.24 22 749
service: aggregate perf. data 0.88 19704 106
service: refresh index statistics 0.03 316373 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-05-2022 19:39:59 to 23-05-2022 19:40:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7497
TEST_TIME 2 4743
TEST_TIME 3 4010
TEST_TIME 4 4115
TEST_TIME 5 4964
TEST_TIME 6 5676
TEST_TIME 7 5493
TEST_TIME 8 5423
TEST_TIME 9 5781
TEST_TIME 10 5894
TEST_TIME 11 5917
TEST_TIME 12 6510
TEST_TIME 13 6363
TEST_TIME 14 6637
TEST_TIME 15 7188
TEST_TIME 16 7337
TEST_TIME 17 7619
TEST_TIME 18 8076
TEST_TIME 19 7956
TEST_TIME 20 8202
TEST_TIME 21 8336
TEST_TIME 22 8460
TEST_TIME 23 8887
TEST_TIME 24 9464
TEST_TIME 25 9916
TEST_TIME 26 9872
TEST_TIME 27 10055
TEST_TIME 28 10084
TEST_TIME 29 10211
TEST_TIME 30 10175
TEST_TIME 31 9997
TEST_TIME 32 9972
TEST_TIME 33 9933
TEST_TIME 34 9835
TEST_TIME 35 9709
TEST_TIME 36 9569
TEST_TIME 37 9602
TEST_TIME 38 9461
TEST_TIME 39 9367
TEST_TIME 40 9514
TEST_TIME 41 9466
TEST_TIME 42 9363
TEST_TIME 43 9337
TEST_TIME 44 9297
TEST_TIME 45 9229
TEST_TIME 46 9186
TEST_TIME 47 9200
TEST_TIME 48 9125
TEST_TIME 49 9067
TEST_TIME 50 8971
TEST_TIME 51 9085
TEST_TIME 52 8991
TEST_TIME 53 8898
TEST_TIME 54 9014
TEST_TIME 55 8955
TEST_TIME 56 8964
TEST_TIME 57 8972
TEST_TIME 58 8951
TEST_TIME 59 9042
TEST_TIME 60 9104
TEST_TIME 61 9144
TEST_TIME 62 9210
TEST_TIME 63 9220
TEST_TIME 64 9251
TEST_TIME 65 9262
TEST_TIME 66 9316
TEST_TIME 67 9381
TEST_TIME 68 9426
TEST_TIME 69 9299
TEST_TIME 70 9233
TEST_TIME 71 9143
TEST_TIME 72 9145
TEST_TIME 73 9183
TEST_TIME 74 9124
TEST_TIME 75 9093
TEST_TIME 76 9033
TEST_TIME 77 8971
TEST_TIME 78 8957
TEST_TIME 79 9068
TEST_TIME 80 9076
TEST_TIME 81 9120
TEST_TIME 82 9109
TEST_TIME 83 9101
TEST_TIME 84 9084
TEST_TIME 85 9127
TEST_TIME 86 9199
TEST_TIME 87 9199
TEST_TIME 88 9222
TEST_TIME 89 9245
TEST_TIME 90 9226
TEST_TIME 91 9208
TEST_TIME 92 9126
TEST_TIME 93 9126
TEST_TIME 94 9076
TEST_TIME 95 9031
TEST_TIME 96 9023
TEST_TIME 97 9018
TEST_TIME 98 8993
TEST_TIME 99 9064
TEST_TIME 100 9036
TEST_TIME 101 9085
TEST_TIME 102 9059
TEST_TIME 103 9084
TEST_TIME 104 9075
TEST_TIME 105 9084
TEST_TIME 106 9083
TEST_TIME 107 9137
TEST_TIME 108 9195
TEST_TIME 109 9214
TEST_TIME 110 9190
TEST_TIME 111 9140
TEST_TIME 112 9108
TEST_TIME 113 9061
TEST_TIME 114 9097
TEST_TIME 115 9064
TEST_TIME 116 9023
TEST_TIME 117 9024
TEST_TIME 118 8994
TEST_TIME 119 8981
TEST_TIME 120 8920

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-05-2022 19:40:03 to 23-05-2022 19:40:06.

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 1493843 1493843 0 0.00 0 18604 6 0 0
doc_list_biud 1493842 1493842 0 0.00 0 56062 26 0 0
sp_add_invoice_to_stock 5041 4937 104 2.06 33 145347 17457 0 104
sp_cancel_adding_invoice 5339 5339 0 0.00 4 92309 863 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 20578 378 0 0
sp_cancel_customer_reserve 527595 527595 0 0.00 1 55380 56 0 0
sp_cancel_pay_from_customer 638 638 0 0.00 2 5701 77 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 3 9867 115 0 0
sp_cancel_supplier_invoice 5956 5943 13 0.21 2 35558 787 0 13
sp_cancel_supplier_order 415 415 0 0.00 8 1018033 89255 0 0
sp_cancel_write_off 9621 9621 0 0.00 1 11897 100 0 0
sp_client_order 20416 20416 0 0.00 4 23366 371 0 0
sp_customer_reserve 458019 458019 0 0.00 0 65052 171 0 0
sp_fill_shopping_cart 31209 31209 0 0.00 1 20593 42 0 0
sp_fill_shopping_cart_clo_res 458019 458019 0 0.00 0 15551 14 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 30015 340 0 0
sp_kill_cost_storno 15986 15986 0 0.00 0 9861 57 0 0
sp_kill_qstorno_ret_qs2qd 1071169 1071169 0 0.00 0 56057 30 0 0
sp_kill_qty_storno 565520 565520 0 0.00 0 56058 67 0 0
sp_lock_selected_doc 20553 20553 0 0.00 0 6908 6 0 0
sp_make_cost_storno 27816 27816 0 0.00 0 16873 56 0 0
sp_multiply_rows_for_pdistr 27816 27816 0 0.00 0 10639 32 0 0
sp_multiply_rows_for_qdistr 20416 20416 0 0.00 0 15957 176 0 0
sp_pay_from_customer 7183 7183 0 0.00 4 13805 120 0 0
sp_pay_to_supplier 5815 5815 0 0.00 4 16562 118 0 0
sp_payment_common 12998 12998 0 0.00 2 10028 35 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 20565 351 0 0
sp_qd_handle_on_invoice_upd_sts 10273 10273 0 0.00 0 29756 341 0 0
sp_qd_handle_on_reserve_upd_sts 9884 9884 0 0.00 0 12756 77 0 0
sp_reserve_write_off 9885 9885 0 0.00 6 18685 193 0 0
sp_supplier_invoice 5403 5403 0 0.00 5 80299 3226 0 0
sp_supplier_order 5390 5390 0 0.00 5 30804 1277 0 0
srv_aggregate_perf_data 106 106 0 0.00 3075 322670 19704 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1979 119630 45899 0 0
srv_find_qd_qs_mism 1022669 1022669 0 0.00 0 16095 5 0 0
srv_make_invnt_saldo 600 600 0 0.00 41 99644 1215 0 0
srv_make_money_saldo 749 749 0 0.00 1 982 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 163170 490756 316374 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4937 4937 0 0.00 0 15785 174 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 4916 84 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 2324 15 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 3935 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 638 638 0 0.00 0 2582 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1095 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 2555 26 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22146 1637 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 5586 15 0 0
t$perf-norm:sp_client_order 20417 20417 0 0.00 0 7874 17 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 4432 15 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 6056 12 0 0
t$perf-norm:sp_pay_to_supplier 5815 5815 0 0.00 0 6138 11 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 5327 13 0 0
t$perf-norm:sp_supplier_invoice 5403 5403 0 0.00 0 6173 17 0 0
t$perf-norm:sp_supplier_order 5390 5390 0 0.00 0 4886 14 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 975 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 15 0 0 0
t$perf-norm:srv_make_invnt_saldo 600 600 0 0.00 0 1183 20 0 0
t$perf-norm:srv_make_money_saldo 749 749 0 0.00 0 1559 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10072 9864 208 2.06 0 12221 22 0 208
v_all_customers 37436 37436 0 0.00 0 13 0 0 0
v_all_suppliers 30114 30114 0 0.00 0 21 1 0 0
v_all_wares 40832 40832 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 2752 8 0 0
v_cancel_client_order 3578 3578 0 0.00 0 4034 5 0 0
v_cancel_customer_prepayment 1276 1276 0 0.00 0 1417 6 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 5557 9 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 3060 9 0 0
v_cancel_supplier_invoice 824 798 26 3.15 0 954 18 0 26
v_cancel_supplier_order 830 830 0 0.00 0 1996 7 0 0
v_cancel_write_off 2018 2018 0 0.00 0 1388 12 0 0
v_max_id_clo_ord 5390 5390 0 0.00 0 799 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 1785 8 0 0
v_max_id_ord_sup 5403 5403 0 0.00 0 4961 5 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 3432 5 0 0
v_max_non_paid_realizn 3712 3712 0 0.00 0 3466 8 0 0
v_min_id_clo_ord 5390 5390 0 0.00 0 4407 9 0 0
v_min_id_clo_res 986 986 0 0.00 0 1273 6 0 0
v_min_id_ord_sup 5403 5403 0 0.00 0 4314 6 0 0
v_min_non_paid_invoice 5815 5815 0 0.00 0 8735 24 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 0 9552 26 0 0
v_our_firm 10338 10338 0 0.00 0 15 1 0 0
v_reserve_write_off 19766 19766 0 0.00 0 5590 7 0 0
x_lock_depdocs_on_canc_invoice 10883 10883 0 0.00 0 24000 117 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 19468 328 0 0
x_make_qstorno_1000_1200 5390 5390 0 0.00 2 30774 1200 0 0
x_make_qstorno_1200_2000 5403 5403 0 0.00 2 80054 3064 0 0
x_make_qstorno_2100_3300 458019 458019 0 0.00 0 65051 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-05-2022 19:40:07 to 23-05-2022 19:40:11.

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:20 10 8192000000 8806593680 8820334592 11742656 0.143 3 3 5 0 11937136 77360 117120
17:09:21 20381 8192000000 9889967824 10196385792 976418032 10.649 100 89 178 1 1005844624 6125584 23301008
17:10:49 20662 8192000000 9930825536 10247897088 1013790448 11.012 100 91 182 0 1045265536 8041216 23433872
17:12:14 13751 8192000000 9958463600 10287607808 1042933312 11.293 100 87 174 1 1074430928 9533664 21963952
17:13:36 12961 8192000000 10032675104 10378510336 1114589216 11.976 100 76 152 0 1142902352 10206336 18106800
17:14:53 9378 8192000000 10094601808 10451435520 1175380496 12.547 100 84 168 1 1208518560 14264880 18873184
17:16:04 4367 8192000000 10122675840 10488049664 1206136080 12.833 100 85 168 1 1239901552 15007776 18757696
17:17:12 3722 8192000000 10163040192 10538307584 1246092096 13.202 100 76 152 1 1274055632 11067872 16895664
17:18:17 2314 8192000000 10194957504 10575491072 1280769440 13.520 100 87 176 0 1314940816 14924016 19247360
17:19:20 45247 8192000000 10207194944 10593525760 1290436768 13.608 100 57 112 2 1312661296 8821648 13402880
17:21:06 3599 8192000000 10194244416 10601705472 1300587552 13.701 100 37 74 0 1317227216 8429376 8210288
17:22:10 6279 8192000000 10189971008 10605174784 1305019328 13.741 100 29 59 0 1317840480 5992304 6828848
17:23:19 6991 8192000000 10198916592 10613170176 1312234576 13.806 100 33 64 2 1326736928 6997808 7504544
17:24:27 38913 8192000000 10207491376 10627989504 1318019584 13.859 100 15 30 0 1326811520 5108288 3683648
17:26:07 3496 8192000000 10225620448 10637426688 1313856656 13.821 99 39 77 1 1329838288 7413936 8567696
17:27:10 6044 8192000000 10207904640 10627993600 1309675920 13.783 99 19 38 2 1319392128 5641344 4074864
17:28:17 6099 8192000000 10196970352 10610839552 1296503104 13.663 99 24 48 0 1308954560 6533888 5917568
17:29:25 32443 8192000000 10162406192 10574323712 1217173712 12.936 94 27 53 1 1228726912 5068192 6485008
17:31:01 5512 8192000000 10139430800 10532364288 1225441488 13.012 97 33 66 0 1239166112 6449648 7274976
17:32:08 9104 8192000000 10152091408 10546913280 1243671408 13.180 99 32 64 0 1257143664 5227552 8244704
17:33:19 33076 8192000000 10155378416 10568036352 1261954800 13.348 99 35 70 1 1276095696 5747984 8392912
17:35:12 5408 8192000000 10181845328 10586841088 1284577296 13.555 100 37 74 0 1299953600 7044208 8332096
17:36:18 6485 8192000000 10185097216 10601705472 1299970064 13.695 100 20 40 1 1310792768 6270160 4552544
17:37:26 11892 8192000000 10197743392 10613018624 1307082096 13.760 100 24 48 0 1318859872 6021616 5756160
17:38:39 37403 8192000000 10239449072 10653999104 1337642432 14.036 100 38 76 1 1353685600 7521408 8521760
17:40:23 82132 8192000000 10266106912 10689978368 1367792560 14.307 100 42 84 2 1385278144 8395376 9090208
17:42:50 22377 8192000000 10270654048 10696654848 1371426032 14.340 100 38 76 0 1393252560 12658368 9168160
17:44:17 10794 8192000000 10303551344 10719629312 1386045152 14.471 100 48 97 1 1408925840 11204768 11675920
17:45:31 10318 8192000000 10298300128 10712854528 1380856288 14.424 99 57 113 3 1409271488 14184624 14230576
17:46:47 22051 8192000000 10333458608 10751713280 1403248736 14.624 100 60 118 2 1440402048 23042672 14110640
17:48:14 9624 8192000000 10330174624 10748592128 1398623296 14.583 99 65 128 1 1442452288 28485408 15343584
17:49:29 4721 8192000000 10338384304 10758164480 1408743056 14.673 100 62 121 1 1453746448 30564208 14439184
17:50:40 6743 8192000000 10343560704 10767347712 1408916384 14.674 100 62 124 1 1458814640 34259392 15638864
17:51:52 5061 8192000000 10356341008 10772189184 1415401872 14.732 100 68 135 1 1465664144 33395792 16866480
17:53:07 3954 8192000000 10366834752 10782806016 1414551808 14.724 100 86 170 1 1475125920 39231792 21342320
17:54:16 10778 8192000000 10346593184 10761760768 1395927312 14.559 100 64 126 0 1447930624 36625472 15377840
17:55:36 1978 8192000000 10322385664 10731966464 1368555648 14.314 99 72 145 0 1422367840 36311168 17501024
17:56:43 9496 8192000000 10304854976 10728529920 1375965184 14.380 100 42 84 2 1424548080 37155056 11427840
17:58:14 2408 8192000000 10327745312 10737631232 1378380160 14.402 100 70 140 0 1431697184 35298176 18018848
17:59:19 20905 8192000000 10307514816 10733473792 1361093520 14.247 99 28 56 0 1407681456 38569536 8018400
18:00:47 2123 8192000000 10323636320 10739892224 1361802512 14.254 100 68 136 3 1425298752 46076240 17420000
18:01:53 12775 8192000000 10280913696 10707533824 1337541440 14.035 100 33 66 0 1390138000 43259840 9336720
18:03:08 5120 8192000000 10285932640 10715037696 1346485072 14.116 99 34 68 1 1383031168 27488192 9057904
18:04:15 20454 8192000000 10274729776 10716618752 1352423872 14.169 100 17 34 1 1379097744 22378128 4295744
18:05:51 21782 8192000000 10261733616 10701791232 1344936832 14.102 99 22 44 3 1364873616 14579664 5357120
18:07:48 63748 8192000000 10263160512 10706849792 1343873952 14.092 99 15 30 0 1361789520 14650592 3264976
18:09:54 88906 8192000000 10279057760 10711793664 1339391024 14.052 97 23 45 0 1352128608 8435776 4301808
18:12:23 68252 8192000000 10276775552 10719985664 1359089920 14.229 98 13 26 0 1369265408 7408464 2767024
18:14:34 30081 8192000000 10279461152 10725961728 1381915888 14.434 100 34 68 2 1398382256 8410896 8055472
18:16:34 49121 8192000000 10305434272 10755727360 1396909632 14.567 100 20 40 1 1415834032 14063648 4860752
18:18:45 64163 8192000000 10297482016 10756603904 1400879296 14.603 100 12 24 0 1412401040 7803648 3718096
18:20:51 38905 8192000000 10309502688 10761699328 1404619008 14.636 100 14 28 0 1421138304 13123184 3396112
18:22:34 64897 8192000000 10293187232 10754961408 1392400064 14.527 99 11 22 0 1411982896 16660160 2922672
18:24:41 83759 8192000000 10294627968 10760253440 1358292224 14.222 97 26 52 0 1377263456 12140480 6830752
18:28:10 58735 8192000000 10306469872 10765090816 1376563952 14.386 98 19 38 1 1402805856 21638464 4603440
18:30:22 56791 8192000000 10299341456 10758963200 1394213728 14.543 99 17 34 1 1411837984 13656496 3967760
18:32:19 82192 8192000000 10297198384 10740948992 1346445872 14.115 96 16 32 0 1368660144 18593904 3620368
18:34:43 43333 8192000000 10309853920 10761388032 1386116720 14.471 99 41 82 0 1414620560 19287360 9216480
18:36:30 60947 8192000000 10281279344 10742972416 1363828384 14.272 97 20 40 1 1385637376 17088192 4720800
18:38:32 53458 8192000000 10288532800 10749329408 1370246656 14.329 98 19 38 2 1385414256 11218592 3949008
18:40:26 69944 8192000000 10284347408 10716749824 1279135120 13.505 92 17 34 1 1299636272 16315296 4185856
18:42:40 56438 8192000000 10297453088 10737930240 1348407664 14.133 97 27 53 0 1364311552 10357024 5546864
18:44:37 119630 8192000000 10297541056 10763538432 1359304448 14.231 98 8 16 0 1372315216 11221776 1788992
18:47:39 68360 8192000000 10247303152 10678005760 1238762624 13.135 90 32 64 0 1254823152 9382272 6678256
18:50:02 7519 8192000000 10270640224 10701807616 1346598304 14.117 99 44 88 1 1370820208 14464384 9757520
18:51:13 50426 8192000000 10240868656 10694574080 1320492208 13.881 98 8 16 0 1336959600 14991520 1475872
18:53:07 73452 8192000000 10243531056 10688548864 1326563760 13.936 98 16 32 0 1337776608 7681488 3531360
18:55:47 59067 8192000000 10279899936 10723393536 1353030256 14.175 99 24 48 1 1372969696 13852448 6086992
18:57:56 67409 8192000000 10255696336 10701230080 1315053136 13.832 96 17 35 0 1332110448 13171264 3886048
19:00:05 44418 8192000000 10271683856 10718244864 1357171024 14.212 99 19 38 0 1370320848 8603296 4546528
19:01:50 57867 8192000000 10285391920 10727198720 1371244944 14.338 100 29 58 0 1389438128 11448688 6744496
19:03:52 90131 8192000000 10276590688 10735083520 1369299184 14.321 99 10 20 1 1388807040 17015840 2492016
19:06:24 88740 8192000000 10290869920 10750566400 1344550688 14.098 97 14 28 0 1359284496 12211904 2521904
19:08:53 96742 8192000000 10298052016 10740056064 1377971120 14.398 99 28 56 1 1395345120 11235360 6138640
19:11:50 40615 8192000000 10272656688 10736320512 1383610576 14.449 100 7 14 0 1392577056 7822896 1143584
19:13:32 43307 8192000000 10288505056 10747740160 1392957376 14.532 100 7 14 2 1401882688 7619424 1305888
19:15:16 49721 8192000000 10291188320 10747809792 1383141824 14.445 99 14 28 1 1397731920 11440768 3149328
19:17:06 60344 8192000000 10301923760 10758873088 1390831024 14.513 99 19 38 2 1403996896 8652864 4513008
19:19:12 30763 8192000000 10291689024 10742882304 1379210064 14.409 99 33 68 0 1395729120 8760080 7758976
19:20:54 86920 8192000000 10302146560 10751115264 1356231584 14.204 97 26 50 1 1370970624 9307744 5431296
19:23:22 44400 8192000000 10304066608 10750545920 1359851456 14.236 97 21 42 0 1374853408 10613376 4388576
19:25:27 58965 8192000000 10305096128 10771787776 1359886560 14.236 97 16 32 0 1382238208 18835200 3516448
19:27:33 67700 8192000000 10310058768 10743980032 1341804464 14.074 96 20 40 0 1358666640 12648848 4213328
19:29:41 79340 8192000000 10276293024 10724724736 1345390496 14.106 97 21 42 0 1357438896 7206864 4841536
19:32:02 22682 8192000000 10259418144 10709839872 1352751888 14.172 99 27 54 0 1369894848 11712272 5430688
19:33:25 79539 8192000000 10263102624 10716188672 1331779696 13.983 97 12 24 0 1346302016 11735968 2786352
19:35:54 59616 8192000000 10260187408 10707623936 1325698416 13.928 97 9 18 1 1342603184 14775056 2129712
19:38:01 33102 8192000000 9073307056 9312100352 235569024 2.795 18 2 4 0 243045264 7223024 253216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-05-2022 19:40:13 to 23-05-2022 19:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-05-2022 19:40:29 to 23-05-2022 19:40:31.

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          240613
MON$OLDEST_ACTIVE               240614
MON$OLDEST_SNAPSHOT             240614
MON$NEXT_TRANSACTION            240614
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                       2010560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:255: 
:256: mscs-test-161.budzdorov.ru    Mon May 23 19:38:41 2022
:257:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:258: 
:259: 
:260: mscs-test-161.budzdorov.ru    Mon May 23 19:38:41 2022
:261:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:262: 
:263: 
:264: mscs-test-161.budzdorov.ru    Mon May 23 19:38:48 2022
:265:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:266: 
--- end of diff output ---

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

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