Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2021 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 16746744 kB
MemAvailable 31414276 kB
Buffers 0 kB
Cached 14958936 kB
SwapCached 444 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.9
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 2021-12-23 19:37:58
DTS_END                         2021-12-23 19:37:58.9770
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/49572
TRN_ID                          243436
ATT_ID                          669
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: 9029.26 28599 1083511
customer order: creation 170.26 351 20431
customer order: refuse 14.86 350 1783
order to supplier: creation 46.35 1254 5562
order to supplier: removal 3.53 94031 424
invoice (draft): creation 46.26 3228 5551
invoice (draft): removal 50.11 790 6013
invoice accept: apply 42.37 18417 5084
invoice accept: cancel 45.02 857 5402
customer reserve: creation 3869.55 184 464346
customer reserve: removal 4444.11 58 533293
realization accept: apply 82.90 177 9948
realization accept: cancel 83.24 117 9989
payment from customer: creation 59.58 112 7149
payment from customer: removal 5.76 90 691
payment to supplier: creation 49.47 131 5936
payment to supplier: removal 4.03 93 483
service: total inventory turnovers 4.58 1281 550
service: total monetary turnovers 6.48 29 778
service: aggregate perf. data 0.78 23355 94
service: refresh index statistics 0.03 427079 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-12-2021 19:39:55 to 23-12-2021 19:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5051
TEST_TIME 2 4853
TEST_TIME 3 5549
TEST_TIME 4 6358
TEST_TIME 5 5826
TEST_TIME 6 6116
TEST_TIME 7 5903
TEST_TIME 8 5939
TEST_TIME 9 6044
TEST_TIME 10 6054
TEST_TIME 11 6284
TEST_TIME 12 6372
TEST_TIME 13 6678
TEST_TIME 14 6639
TEST_TIME 15 7279
TEST_TIME 16 7230
TEST_TIME 17 7494
TEST_TIME 18 7571
TEST_TIME 19 7984
TEST_TIME 20 8052
TEST_TIME 21 8223
TEST_TIME 22 8455
TEST_TIME 23 8865
TEST_TIME 24 8902
TEST_TIME 25 8830
TEST_TIME 26 8851
TEST_TIME 27 10322
TEST_TIME 28 10503
TEST_TIME 29 10345
TEST_TIME 30 10278
TEST_TIME 31 10003
TEST_TIME 32 10027
TEST_TIME 33 9962
TEST_TIME 34 9828
TEST_TIME 35 9891
TEST_TIME 36 9710
TEST_TIME 37 9699
TEST_TIME 38 9654
TEST_TIME 39 9603
TEST_TIME 40 9501
TEST_TIME 41 9495
TEST_TIME 42 9404
TEST_TIME 43 9303
TEST_TIME 44 9265
TEST_TIME 45 9312
TEST_TIME 46 9183
TEST_TIME 47 9094
TEST_TIME 48 9204
TEST_TIME 49 9142
TEST_TIME 50 9063
TEST_TIME 51 8995
TEST_TIME 52 8931
TEST_TIME 53 8909
TEST_TIME 54 8854
TEST_TIME 55 8858
TEST_TIME 56 8892
TEST_TIME 57 8909
TEST_TIME 58 8934
TEST_TIME 59 9104
TEST_TIME 60 9068
TEST_TIME 61 9069
TEST_TIME 62 9188
TEST_TIME 63 9173
TEST_TIME 64 9216
TEST_TIME 65 9294
TEST_TIME 66 9262
TEST_TIME 67 9383
TEST_TIME 68 9394
TEST_TIME 69 9289
TEST_TIME 70 9210
TEST_TIME 71 9170
TEST_TIME 72 9159
TEST_TIME 73 9156
TEST_TIME 74 9148
TEST_TIME 75 9121
TEST_TIME 76 9084
TEST_TIME 77 9100
TEST_TIME 78 9062
TEST_TIME 79 9131
TEST_TIME 80 9155
TEST_TIME 81 9125
TEST_TIME 82 9100
TEST_TIME 83 9118
TEST_TIME 84 9208
TEST_TIME 85 9156
TEST_TIME 86 9235
TEST_TIME 87 9190
TEST_TIME 88 9175
TEST_TIME 89 9097
TEST_TIME 90 9123
TEST_TIME 91 9084
TEST_TIME 92 9067
TEST_TIME 93 9106
TEST_TIME 94 9041
TEST_TIME 95 9060
TEST_TIME 96 9061
TEST_TIME 97 9022
TEST_TIME 98 9067
TEST_TIME 99 9070
TEST_TIME 100 9047
TEST_TIME 101 9126
TEST_TIME 102 9079
TEST_TIME 103 9109
TEST_TIME 104 9185
TEST_TIME 105 9169
TEST_TIME 106 9203
TEST_TIME 107 9200
TEST_TIME 108 9207
TEST_TIME 109 9274
TEST_TIME 110 9290
TEST_TIME 111 9255
TEST_TIME 112 9192
TEST_TIME 113 9158
TEST_TIME 114 9182
TEST_TIME 115 9162
TEST_TIME 116 9132
TEST_TIME 117 9083
TEST_TIME 118 9053
TEST_TIME 119 9006
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-12-2021 19:39:58 to 23-12-2021 19:40:02.

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 1512593 1512593 0 0.00 0 23697 6 0 0
doc_list_biud 1512590 1512590 0 0.00 0 37496 26 0 0
sp_add_invoice_to_stock 5219 5084 135 2.58 46 162330 18417 0 135
sp_cancel_adding_invoice 5402 5402 0 0.00 4 53527 857 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 18942 351 0 0
sp_cancel_customer_reserve 533293 533293 0 0.00 1 19776 59 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4553 91 0 0
sp_cancel_pay_to_supplier 483 483 0 0.00 3 6108 93 0 0
sp_cancel_supplier_invoice 6029 6013 16 0.26 1 10864 790 0 16
sp_cancel_supplier_order 424 424 0 0.00 9 973654 94032 0 0
sp_cancel_write_off 9989 9989 0 0.00 1 18175 118 0 0
sp_client_order 20431 20431 0 0.00 3 23807 352 0 0
sp_customer_reserve 464346 464346 0 0.00 0 117546 185 0 0
sp_fill_shopping_cart 31537 31537 0 0.00 1 10247 39 0 0
sp_fill_shopping_cart_clo_res 464338 464338 0 0.00 0 17732 14 0 0
sp_get_clo_for_invoice 5071 5071 0 0.00 0 32596 289 0 0
sp_kill_cost_storno 16565 16565 0 0.00 0 18171 61 0 0
sp_kill_qstorno_ret_qs2qd 1083012 1083012 0 0.00 0 19769 32 0 0
sp_kill_qty_storno 571923 571923 0 0.00 0 37495 69 0 0
sp_lock_selected_doc 20985 20985 0 0.00 0 6012 5 0 0
sp_make_cost_storno 28104 28104 0 0.00 0 11747 54 0 0
sp_multiply_rows_for_pdistr 28104 28104 0 0.00 0 7059 30 0 0
sp_multiply_rows_for_qdistr 20431 20431 0 0.00 0 8885 150 0 0
sp_pay_from_customer 7149 7149 0 0.00 4 12198 112 0 0
sp_pay_to_supplier 5936 5936 0 0.00 5 11556 132 0 0
sp_payment_common 13085 13085 0 0.00 1 12187 28 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 18933 330 0 0
sp_qd_handle_on_invoice_upd_sts 10473 10473 0 0.00 0 37492 342 0 0
sp_qd_handle_on_reserve_upd_sts 9948 9948 0 0.00 0 14528 55 0 0
sp_reserve_write_off 9948 9948 0 0.00 6 17706 178 0 0
sp_supplier_invoice 5551 5551 0 0.00 6 78996 3229 0 0
sp_supplier_order 5562 5562 0 0.00 5 33459 1254 0 0
srv_aggregate_perf_data 94 94 0 0.00 2838 387836 23356 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2255 159043 51876 0 0
srv_find_qd_qs_mism 1035134 1035134 0 0.00 0 23696 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 36 79449 1281 0 0
srv_make_money_saldo 778 778 0 0.00 1 3955 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 128233 557205 427080 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5084 5084 0 0.00 0 28302 189 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 2654 66 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3599 15 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 8313 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 1345 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 483 483 0 0.00 0 733 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2572 20 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 33475 1611 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 3400 10 0 0
t$perf-norm:sp_client_order 20431 20431 0 0.00 0 10345 20 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 3417 11 0 0
t$perf-norm:sp_pay_from_customer 7150 7150 0 0.00 0 8269 11 0 0
t$perf-norm:sp_pay_to_supplier 5936 5936 0 0.00 0 7120 12 0 0
t$perf-norm:sp_reserve_write_off 9948 9948 0 0.00 0 9158 13 0 0
t$perf-norm:sp_supplier_invoice 5551 5551 0 0.00 0 10182 22 0 0
t$perf-norm:sp_supplier_order 5562 5562 0 0.00 0 2814 14 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 220 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 539 11 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 489 14 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 822 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10408 10138 270 2.59 0 7539 21 0 270
v_all_customers 37672 37672 0 0.00 0 29 1 0 0
v_all_suppliers 30668 30668 0 0.00 0 19 1 0 0
v_all_wares 40860 40860 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2418 10 0 0
v_cancel_client_order 3566 3566 0 0.00 0 3394 5 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4500 9 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 1655 5 0 0
v_cancel_payment_to_supplier 966 966 0 0.00 0 6084 12 0 0
v_cancel_supplier_invoice 916 884 32 3.49 0 2239 20 0 32
v_cancel_supplier_order 848 848 0 0.00 0 1224 5 0 0
v_cancel_write_off 1990 1990 0 0.00 0 5987 19 0 0
v_max_id_clo_ord 5560 5560 0 0.00 0 2324 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 2572 12 0 0
v_max_id_ord_sup 5547 5547 0 0.00 0 6794 4 0 0
v_max_non_paid_invoice 1709 1709 0 0.00 0 620 3 0 0
v_max_non_paid_realizn 3735 3735 0 0.00 0 953 4 0 0
v_min_id_clo_ord 5560 5560 0 0.00 0 8283 9 0 0
v_min_id_clo_res 995 995 0 0.00 0 5426 17 0 0
v_min_id_ord_sup 5547 5547 0 0.00 0 4154 4 0 0
v_min_non_paid_invoice 5936 5936 0 0.00 0 9137 18 0 0
v_min_non_paid_realizn 7149 7149 0 0.00 0 12182 21 0 0
v_our_firm 10016 10016 0 0.00 0 19 1 0 0
v_reserve_write_off 19892 19892 0 0.00 0 5804 7 0 0
x_lock_depdocs_on_canc_invoice 10973 10973 0 0.00 0 17534 135 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 15079 444 0 0
x_make_qstorno_1000_1200 5562 5562 0 0.00 1 33449 1158 0 0
x_make_qstorno_1200_2000 5551 5551 0 0.00 3 78978 3109 0 0
x_make_qstorno_2100_3300 464345 464345 0 0.00 0 117544 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-12-2021 19:40:03 to 23-12-2021 19:40:07.

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:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:04 29007 8192000000 9911932096 10225025024 991619808 10.797 100 88 176 0 1020409648 6351504 22438336
17:10:40 28328 8192000000 9952049520 10278903808 1033995120 11.207 100 85 170 0 1064640752 9224304 21421328
17:12:16 23521 8192000000 9990247024 10333089792 1079579712 11.643 100 82 163 0 1109584224 10279040 19725472
17:13:44 13593 8192000000 10043793424 10393681920 1126346640 12.087 100 77 154 1 1155872880 11088768 18437472
17:15:02 7954 8192000000 10093577600 10457546752 1185212528 12.639 100 78 156 0 1214620208 12118304 17289376
17:16:11 6350 8192000000 10144185024 10515660800 1229662224 13.051 100 75 148 1 1259487184 13534016 16290944
17:17:19 6700 8192000000 10162566592 10543206400 1255317552 13.287 100 78 155 1 1285740064 13170672 17251840
17:18:28 1361 8192000000 10200116880 10583560192 1285656512 13.565 100 91 183 2 1319767824 13566720 20544592
17:19:31 8300 8192000000 10211821728 10600665088 1300670624 13.701 100 79 152 3 1326372240 8464368 17237248
17:20:40 2715 8192000000 10198285552 10607194112 1308284160 13.771 100 26 52 1 1320153984 6317616 5552208
17:21:44 7888 8192000000 10210483248 10625105920 1315364624 13.835 100 17 34 0 1329920064 10395952 4159488
17:22:54 37889 8192000000 10216805840 10638794752 1325968944 13.931 100 35 71 0 1340184048 6334384 7880720
17:24:32 9241 8192000000 10215157552 10639966208 1328781040 13.956 100 25 50 0 1341082912 6697984 5603888
17:25:43 7520 8192000000 10227022880 10647232512 1333999872 14.003 100 20 41 0 1343953856 5420608 4533376
17:26:54 35469 8192000000 10212712832 10636402688 1319407024 13.871 99 20 40 0 1330360992 5817632 5136336
17:28:36 3464 8192000000 10206528960 10614906880 1272154480 13.441 96 36 71 0 1287234672 6739088 8341104
17:29:41 5614 8192000000 10145483680 10543685632 1212874576 12.896 96 11 22 2 1224407056 9258912 2273568
17:30:47 7332 8192000000 10139414352 10532831232 1203292496 12.807 96 20 40 1 1213227936 5225920 4709520
17:31:56 49752 8192000000 10134812256 10540060672 1196177728 12.741 95 28 55 1 1206970288 4687376 6105184
17:33:53 4809 8192000000 10163777312 10571374592 1268577584 13.409 99 32 64 4 1282386240 6398672 7409984
17:35:00 7652 8192000000 10187382976 10599563264 1293738896 13.638 100 33 66 0 1307184800 5465200 7980704
17:36:09 13865 8192000000 10194043440 10609004544 1304828864 13.739 100 27 54 3 1317206576 6107488 6270224
17:37:24 22819 8192000000 10196153920 10614333440 1309572160 13.782 100 28 56 1 1321664240 6066416 6025664
17:38:51 14648 8192000000 10258892208 10665652224 1343696464 14.091 100 60 119 0 1366027088 9568752 12761872
17:40:14 24997 8192000000 10261324432 10681647104 1356839072 14.209 100 49 100 0 1376556640 8567232 11150336
17:41:45 20486 8192000000 10274437344 10701488128 1370601696 14.332 100 34 68 0 1390109600 11675568 7832336
17:43:15 12917 8192000000 10289487344 10718289920 1384805200 14.459 100 35 70 1 1404528208 11563248 8159760
17:44:31 14409 8192000000 10297945680 10726055936 1393293248 14.535 100 48 94 0 1417929584 14002656 10633680
17:45:48 4805 8192000000 10315666992 10736357376 1393838352 14.540 100 73 145 3 1428902880 17812064 17252464
17:47:03 4582 8192000000 10313953312 10736758784 1394271056 14.544 100 60 117 5 1427263376 19098768 13893552
17:48:11 11471 8192000000 10308406736 10732822528 1370396928 14.331 98 58 114 3 1403857808 19860640 13600240
17:49:27 4608 8192000000 10326486416 10745020416 1389060800 14.497 100 67 133 0 1434752912 28659376 17032736
17:50:39 6308 8192000000 10312119536 10736250880 1382438352 14.438 100 57 114 4 1424388976 27411408 14539216
17:51:50 5401 8192000000 10332193248 10747039744 1389890688 14.505 100 76 151 1 1440129152 30681360 19557104
17:53:04 10426 8192000000 10323451456 10739568640 1356537008 14.206 97 62 125 1 1407485136 35167408 15780720
17:54:21 6389 8192000000 10338951296 10743746560 1380765728 14.423 100 72 142 0 1437242608 38837104 17639776
17:55:35 2252 8192000000 10344690096 10751127552 1381753968 14.432 99 90 179 1 1446676432 42677472 22244992
17:56:44 2551 8192000000 10348500928 10751946752 1380327088 14.419 100 91 182 1 1449014080 45874240 22812752
17:57:51 9720 8192000000 10359801120 10776797184 1398994528 14.586 100 69 138 3 1460024864 43369632 17660704
17:59:06 8189 8192000000 10343663824 10766651392 1383978960 14.452 99 50 100 1 1441636656 44215728 13441968
18:00:32 9829 8192000000 10307228928 10728599552 1362073104 14.256 100 44 86 0 1415113392 41611744 11428544
18:01:46 22758 8192000000 10304605376 10742808576 1366791536 14.298 100 26 52 1 1413195616 39357760 7046320
18:03:45 20218 8192000000 10314380000 10750328832 1352939344 14.174 100 25 48 1 1416602112 56259680 7403088
18:05:10 62479 8192000000 10293488144 10729459712 1346179056 14.113 99 11 22 1 1361015264 12540752 2295456
18:07:13 38918 8192000000 10290482368 10725507072 1372571952 14.350 100 35 71 3 1394319136 13749584 7997600
18:08:52 79141 8192000000 10279089136 10718445568 1343473440 14.089 98 26 53 1 1363824208 14602192 5748576
18:11:34 16906 8192000000 10288845008 10714370048 1363202624 14.266 99 55 108 0 1384529040 9177104 12149312
18:12:54 159042 8192000000 10281694128 10735345664 1366707952 14.298 99 12 24 0 1376450400 7455600 2286848
18:16:34 46665 8192000000 10283298928 10742439936 1375754576 14.379 99 14 28 0 1388283504 9642912 2886016
18:18:31 137037 8192000000 10299622032 10740633600 1364141360 14.275 98 17 34 1 1375970624 7963728 3865536
18:21:50 65390 8192000000 10291579152 10758160384 1399006800 14.586 100 28 56 0 1415040032 10347568 5685664
18:24:58 48213 8192000000 10292722848 10762174464 1390679264 14.512 99 11 21 1 1402718784 9186288 2853232
18:26:48 67249 8192000000 10298906880 10758782976 1388086352 14.489 99 14 29 1 1398154256 6922048 3145856
18:28:56 92160 8192000000 10287373168 10750644224 1354969472 14.192 97 24 48 0 1372300320 11817488 5513360
18:31:35 63555 8192000000 10289040832 10742423552 1353907744 14.183 97 24 48 0 1373263504 13616448 5739312
18:33:41 65138 8192000000 10304029616 10750029824 1369381472 14.322 99 23 46 0 1394361664 19241744 5738448
18:35:54 63981 8192000000 10276650960 10732953600 1357213184 14.212 98 12 24 1 1377070576 16623776 3233616
18:38:01 41258 8192000000 10281084608 10728112128 1339709808 14.055 97 29 58 0 1360950080 15204192 6036080
18:39:43 66366 8192000000 10287300592 10723123200 1352114432 14.166 99 36 72 2 1373642672 13154864 8373376
18:41:57 54057 8192000000 10248202304 10701987840 1297441104 13.672 95 20 40 1 1318323184 16497792 4384288
18:43:53 56617 8192000000 10259804880 10707038208 1317752832 13.856 97 21 42 0 1333019584 10882288 4384464
18:45:50 82621 8192000000 10270688176 10712645632 1360733472 14.244 100 22 44 2 1374964512 9566560 4664480
18:48:55 85113 8192000000 10265362320 10731511808 1345380512 14.106 98 9 18 0 1363534560 15890784 2263264
18:51:25 64067 8192000000 10275689216 10715959296 1341408864 14.070 98 20 38 2 1353597936 7131152 5057920
18:53:35 39657 8192000000 10250496320 10698264576 1329065856 13.959 98 26 52 2 1342744720 8417968 5260896
18:55:16 77591 8192000000 10274119296 10723737600 1352433616 14.169 99 16 32 0 1370154960 13358560 4362784
18:57:45 139838 8192000000 10276424400 10726711296 1316693744 13.847 96 15 30 1 1331016304 11272336 3050224
19:01:08 83453 8192000000 10288708048 10735599616 1375652400 14.378 100 11 22 1 1394684400 16599184 2432816
19:03:33 77329 8192000000 10308163536 10739998720 1351847680 14.164 98 18 36 0 1366311232 10496688 3966864
19:05:59 95222 8192000000 10293589760 10745769984 1388560928 14.493 100 34 68 2 1410215344 13341856 8312560
19:08:39 10955 8192000000 10283705056 10732957696 1375446656 14.376 100 23 46 0 1391135008 10686496 5001856
19:09:51 68539 8192000000 10296613824 10758975488 1385417152 14.465 99 9 17 1 1402333264 14389328 2526784
19:11:59 72976 8192000000 10294903088 10765357056 1386862400 14.478 99 12 22 1 1406823952 17399472 2562080
19:14:12 127762 8192000000 10285742672 10738315264 1308780528 13.775 94 11 22 0 1321753328 10749920 2222880
19:17:22 69128 8192000000 10281928448 10733940736 1302615904 13.719 94 19 38 0 1322851360 15721680 4513776
19:19:31 23061 8192000000 10309250240 10746167296 1374429888 14.367 99 53 106 0 1397767440 11413904 11923648
19:20:58 97972 8192000000 10301988480 10747760640 1346248224 14.114 97 12 24 1 1361876016 12824448 2803344
19:23:39 71898 8192000000 10282267600 10726072320 1348310416 14.132 98 15 30 2 1360981536 9578752 3092368
19:25:51 58507 8192000000 10303973968 10756861952 1381629264 14.431 100 20 40 0 1401283664 15004576 4649824
19:27:53 147438 8192000000 10299980336 10747871232 1337179840 14.032 96 15 30 1 1349645728 8693568 3772320
19:31:43 27771 8192000000 10279245760 10733936640 1363190880 14.266 99 17 34 1 1383062288 16041120 3830288
19:33:11 64461 8192000000 10250011616 10700279808 1309366496 13.780 95 10 20 0 1317604096 6058640 2178960
19:35:16 85889 8192000000 10276615408 10729349120 1356058608 14.202 99 22 45 0 1378623968 16951440 5613920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-12-2021 19:40:09 to 23-12-2021 19:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2021 19:40:24 to 23-12-2021 19:40:26.

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          239928
MON$OLDEST_ACTIVE               243544
MON$OLDEST_SNAPSHOT             243544
MON$NEXT_TRANSACTION            243544
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                       2014208
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Dec 23 19:38:05 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Dec 23 19:38:09 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

23.12.21 19:40:27. 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
2021-12-23 19:40:29 No severe PSQL-related problems occured
23.12.21 19:40:31. End of report.