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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-15 19:38:42
DTS_END                         2023-02-15 19:38:42.0860
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/57516
TRN_ID                          239367
ATT_ID                          720
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: 9009.75 22722 1081170
customer order: creation 171.34 373 20561
customer order: refuse 14.63 351 1756
order to supplier: creation 45.48 1320 5457
order to supplier: removal 3.45 85535 414
invoice (draft): creation 45.82 3778 5498
invoice (draft): removal 51.30 748 6156
invoice accept: apply 41.62 18196 4994
invoice accept: cancel 46.33 824 5559
customer reserve: creation 3780.75 183 453690
customer reserve: removal 4516.41 49 541969
realization accept: apply 85.03 192 10204
realization accept: cancel 77.33 112 9280
payment from customer: creation 58.93 121 7072
payment from customer: removal 6.00 99 720
payment to supplier: creation 49.58 131 5950
payment to supplier: removal 3.58 91 430
service: total inventory turnovers 4.88 1149 586
service: total monetary turnovers 6.40 27 768
service: aggregate perf. data 0.85 19734 102
service: refresh index statistics 0.03 321425 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-02-2023 19:40:33 to 15-02-2023 19:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3869
TEST_TIME 2 4652
TEST_TIME 3 4258
TEST_TIME 4 3994
TEST_TIME 5 3982
TEST_TIME 6 4805
TEST_TIME 7 5033
TEST_TIME 8 4879
TEST_TIME 9 4843
TEST_TIME 10 4879
TEST_TIME 11 5604
TEST_TIME 12 5403
TEST_TIME 13 5828
TEST_TIME 14 5941
TEST_TIME 15 6072
TEST_TIME 16 6844
TEST_TIME 17 7477
TEST_TIME 18 7813
TEST_TIME 19 8037
TEST_TIME 20 8321
TEST_TIME 21 8209
TEST_TIME 22 8623
TEST_TIME 23 8815
TEST_TIME 24 9089
TEST_TIME 25 9472
TEST_TIME 26 9727
TEST_TIME 27 9669
TEST_TIME 28 9364
TEST_TIME 29 9326
TEST_TIME 30 9246
TEST_TIME 31 9690
TEST_TIME 32 9499
TEST_TIME 33 9704
TEST_TIME 34 9673
TEST_TIME 35 9661
TEST_TIME 36 9730
TEST_TIME 37 9755
TEST_TIME 38 9562
TEST_TIME 39 9543
TEST_TIME 40 9451
TEST_TIME 41 9380
TEST_TIME 42 9325
TEST_TIME 43 9247
TEST_TIME 44 9169
TEST_TIME 45 9073
TEST_TIME 46 9035
TEST_TIME 47 8987
TEST_TIME 48 8896
TEST_TIME 49 8947
TEST_TIME 50 8848
TEST_TIME 51 8795
TEST_TIME 52 8842
TEST_TIME 53 8804
TEST_TIME 54 8796
TEST_TIME 55 8779
TEST_TIME 56 8819
TEST_TIME 57 8877
TEST_TIME 58 8876
TEST_TIME 59 8849
TEST_TIME 60 8961
TEST_TIME 61 8913
TEST_TIME 62 9028
TEST_TIME 63 9022
TEST_TIME 64 9025
TEST_TIME 65 9087
TEST_TIME 66 9140
TEST_TIME 67 9102
TEST_TIME 68 9291
TEST_TIME 69 9228
TEST_TIME 70 9131
TEST_TIME 71 9077
TEST_TIME 72 9013
TEST_TIME 73 8992
TEST_TIME 74 9002
TEST_TIME 75 8971
TEST_TIME 76 8935
TEST_TIME 77 8885
TEST_TIME 78 8886
TEST_TIME 79 8844
TEST_TIME 80 8855
TEST_TIME 81 8885
TEST_TIME 82 8876
TEST_TIME 83 8896
TEST_TIME 84 8885
TEST_TIME 85 8987
TEST_TIME 86 8978
TEST_TIME 87 8998
TEST_TIME 88 8964
TEST_TIME 89 8951
TEST_TIME 90 8988
TEST_TIME 91 9085
TEST_TIME 92 9093
TEST_TIME 93 9188
TEST_TIME 94 9194
TEST_TIME 95 9103
TEST_TIME 96 9057
TEST_TIME 97 9019
TEST_TIME 98 8994
TEST_TIME 99 9023
TEST_TIME 100 9028
TEST_TIME 101 9013
TEST_TIME 102 9003
TEST_TIME 103 9013
TEST_TIME 104 9010
TEST_TIME 105 9032
TEST_TIME 106 9014
TEST_TIME 107 9007
TEST_TIME 108 9008
TEST_TIME 109 9088
TEST_TIME 110 9118
TEST_TIME 111 9112
TEST_TIME 112 9208
TEST_TIME 113 9176
TEST_TIME 114 9127
TEST_TIME 115 9114
TEST_TIME 116 9093
TEST_TIME 117 9071
TEST_TIME 118 9034
TEST_TIME 119 9047
TEST_TIME 120 9011

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-02-2023 19:40:36 to 15-02-2023 19:40:40.

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 1502255 1502255 0 0.00 0 24792 7 0 0
doc_list_biud 1502254 1502254 0 0.00 0 33440 24 0 0
sp_add_invoice_to_stock 5107 4994 113 2.21 24 203084 18196 0 113
sp_cancel_adding_invoice 5559 5559 0 0.00 3 70866 824 0 0
sp_cancel_client_order 1756 1756 0 0.00 3 11797 352 0 0
sp_cancel_customer_reserve 541969 541969 0 0.00 0 33409 50 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 2 6556 99 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 9626 92 0 0
sp_cancel_supplier_invoice 6162 6156 6 0.09 1 33447 749 0 6
sp_cancel_supplier_order 414 414 0 0.00 7 884481 85536 0 0
sp_cancel_write_off 9280 9280 0 0.00 1 17942 112 0 0
sp_client_order 20561 20561 0 0.00 3 24827 373 0 0
sp_customer_reserve 453690 453690 0 0.00 0 37440 184 0 0
sp_fill_shopping_cart 31501 31501 0 0.00 1 15240 48 0 0
sp_fill_shopping_cart_clo_res 453688 453688 0 0.00 0 16221 14 0 0
sp_get_clo_for_invoice 4992 4992 0 0.00 0 27937 335 0 0
sp_kill_cost_storno 15989 15989 0 0.00 0 15956 57 0 0
sp_kill_qstorno_ret_qs2qd 1099788 1099788 0 0.00 0 33437 27 0 0
sp_kill_qty_storno 580330 580330 0 0.00 0 33439 61 0 0
sp_lock_selected_doc 21011 21011 0 0.00 0 8619 5 0 0
sp_make_cost_storno 28218 28218 0 0.00 0 24783 54 0 0
sp_multiply_rows_for_pdistr 28218 28218 0 0.00 0 16816 31 0 0
sp_multiply_rows_for_qdistr 20561 20561 0 0.00 0 20217 135 0 0
sp_pay_from_customer 7072 7072 0 0.00 4 17604 121 0 0
sp_pay_to_supplier 5950 5950 0 0.00 5 16831 131 0 0
sp_payment_common 13022 13022 0 0.00 1 16815 35 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 11788 319 0 0
sp_qd_handle_on_invoice_upd_sts 10551 10551 0 0.00 0 30067 346 0 0
sp_qd_handle_on_reserve_upd_sts 10204 10204 0 0.00 0 30103 67 0 0
sp_reserve_write_off 10204 10204 0 0.00 6 30192 192 0 0
sp_supplier_invoice 5498 5498 0 0.00 5 89876 3778 0 0
sp_supplier_order 5457 5457 0 0.00 5 71328 1320 0 0
srv_aggregate_perf_data 102 102 0 0.00 2800 159644 19734 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2426 120374 44056 0 0
srv_find_qd_qs_mism 1034214 1034214 0 0.00 0 17951 6 0 0
srv_make_invnt_saldo 586 586 0 0.00 33 44382 1150 0 0
srv_make_money_saldo 768 768 0 0.00 1 1295 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 55295 600769 321426 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 12799 151 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 11466 134 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 3653 18 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 8268 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 2532 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 2580 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1192 11 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 26672 1551 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 672 3 0 0
t$perf-norm:sp_client_order 20561 20561 0 0.00 0 10476 18 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 3504 13 0 0
t$perf-norm:sp_pay_from_customer 7072 7072 0 0.00 0 6589 9 0 0
t$perf-norm:sp_pay_to_supplier 5950 5950 0 0.00 0 10445 14 0 0
t$perf-norm:sp_reserve_write_off 10204 10204 0 0.00 0 12568 17 0 0
t$perf-norm:sp_supplier_invoice 5498 5498 0 0.00 0 7590 15 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 6753 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 148 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 568 11 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 1678 22 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 1452 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10210 9984 226 2.21 0 7199 22 0 226
v_all_customers 36888 36888 0 0.00 0 32 1 0 0
v_all_suppliers 30618 30618 0 0.00 0 20 1 0 0
v_all_wares 41122 41122 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1758 11 0 0
v_cancel_client_order 3512 3512 0 0.00 0 8055 7 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 5604 10 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 5524 10 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 715 4 0 0
v_cancel_supplier_invoice 774 762 12 1.55 0 5599 26 0 12
v_cancel_supplier_order 828 828 0 0.00 0 5459 13 0 0
v_cancel_write_off 2042 2042 0 0.00 0 1854 14 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 106 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 5020 19 0 0
v_max_id_ord_sup 5489 5489 0 0.00 0 6846 6 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 4568 5 0 0
v_max_non_paid_realizn 3930 3930 0 0.00 0 2815 4 0 0
v_min_id_clo_ord 5451 5451 0 0.00 0 13725 13 0 0
v_min_id_clo_res 992 992 0 0.00 0 3051 13 0 0
v_min_id_ord_sup 5489 5489 0 0.00 0 8572 9 0 0
v_min_non_paid_invoice 5950 5950 0 0.00 0 16759 26 0 0
v_min_non_paid_realizn 7072 7072 0 0.00 0 14176 26 0 0
v_our_firm 10518 10518 0 0.00 0 18 1 0 0
v_reserve_write_off 20408 20408 0 0.00 0 8056 8 0 0
x_lock_depdocs_on_canc_invoice 11334 11334 0 0.00 0 13261 123 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 4946 326 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 1 71316 1236 0 0
x_make_qstorno_1200_2000 5498 5498 0 0.00 2 86015 3595 0 0
x_make_qstorno_2100_3300 453690 453690 0 0.00 0 37434 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-02-2023 19:40:41 to 15-02-2023 19:40:45.

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:46 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:47 32678 8192000000 9895161376 10202394624 969622208 10.583 100 82 164 0 997590448 5630864 22337376
17:11:24 24236 8192000000 9949965584 10267512832 1023246496 11.103 100 88 176 0 1053911680 7825280 22839904
17:12:50 24081 8192000000 9981884416 10312691712 1063893216 11.494 100 74 148 0 1091463248 9151200 18418832
17:14:16 9411 8192000000 10022693520 10369187840 1113732208 11.968 100 79 158 0 1142949040 9722464 19494368
17:15:36 4788 8192000000 10083378944 10441289728 1172588896 12.521 100 78 154 0 1202647584 12267280 17791408
17:16:43 3845 8192000000 10117631056 10483167232 1209158768 12.861 100 84 168 0 1242632304 14018976 19454560
17:17:50 2343 8192000000 10150377552 10525835264 1239717104 13.144 100 80 157 0 1269368416 12196528 17454784
17:18:53 1485 8192000000 10185398048 10563956736 1270842064 13.429 100 83 166 0 1304545984 15236432 18467488
17:19:57 1322 8192000000 10206600672 10588663808 1295543216 13.655 100 88 170 1 1331353968 17578192 18232560
17:20:59 3267 8192000000 10194386080 10599616512 1305452320 13.745 100 37 74 0 1322722784 8538832 8731632
17:22:04 42964 8192000000 10219791072 10622443520 1314755472 13.829 100 48 95 2 1332649168 6137680 11756016
17:23:52 5604 8192000000 10205489952 10623676416 1319690800 13.874 100 21 42 0 1330848672 5284128 5873744
17:24:58 6737 8192000000 10212025664 10628608000 1322050080 13.895 100 15 30 0 1330752480 5227232 3475168
17:26:07 11707 8192000000 10222951008 10639642624 1330571728 13.972 100 12 25 0 1338303136 5140288 2591120
17:27:20 7282 8192000000 10235981552 10641801216 1336044912 14.022 100 49 97 1 1352865424 5284128 11536384
17:28:28 6251 8192000000 10225295872 10638139392 1318009888 13.859 99 36 73 0 1333088240 7005040 8073312
17:29:36 12787 8192000000 10188583456 10602811392 1265298192 13.379 97 20 40 0 1276047440 6587344 4161904
17:30:50 62827 8192000000 10169780976 10571530240 1223372304 12.993 95 26 52 1 1234351168 4759088 6219776
17:32:53 5419 8192000000 10143754480 10544627712 1231301824 13.066 98 33 66 0 1250109264 11596160 7211280
17:33:59 14197 8192000000 10153576496 10553810944 1249089920 13.230 98 18 35 0 1262718208 9810448 3817840
17:35:14 3890 8192000000 10180277264 10585260032 1267057648 13.395 98 28 57 0 1279172688 5750880 6364160
17:36:18 22039 8192000000 10201603632 10599985152 1295679488 13.656 100 57 113 1 1316305776 6842544 13783744
17:37:44 5692 8192000000 10189758080 10606403584 1302907056 13.722 100 24 50 0 1314259456 5816480 5535920
17:38:51 19984 8192000000 10240614928 10652753920 1338501744 14.044 100 40 81 1 1353891024 5506144 9883136
17:40:26 47629 8192000000 10265318144 10684669952 1360810864 14.245 100 30 60 0 1382580320 14722496 7046960
17:42:16 87924 8192000000 10277809440 10706673664 1376074336 14.381 100 39 79 1 1399114272 12533824 10506112
17:44:59 13748 8192000000 10291405056 10720407552 1386639776 14.476 100 33 66 0 1408581648 13910352 8031520
17:46:22 15915 8192000000 10307924336 10735796224 1395022896 14.551 100 33 67 0 1421455456 18222224 8210336
17:47:43 13043 8192000000 10322015984 10746695680 1402949056 14.621 100 51 102 1 1430831632 15030912 12851664
17:49:03 3481 8192000000 10339759248 10765737984 1413407744 14.714 100 52 104 0 1449533520 23419632 12706144
17:50:16 12733 8192000000 10339732272 10761875456 1414574768 14.725 100 57 114 0 1446544992 18311168 13659056
17:51:38 3373 8192000000 10342345520 10767589376 1415231280 14.730 100 50 97 2 1449062560 20842368 12988912
17:52:49 2426 8192000000 10351377296 10768539648 1415752464 14.735 100 71 142 0 1456842928 23854432 17236032
17:53:54 3159 8192000000 10368299424 10783006720 1418037200 14.755 100 80 158 1 1470163440 32745728 19380512
17:55:05 3453 8192000000 10343759712 10757480448 1396665744 14.565 99 81 157 1 1446175008 29132160 20377104
17:56:14 14730 8192000000 10335026928 10763001856 1405182400 14.641 100 40 80 1 1442270288 25534336 11553552
17:57:45 3892 8192000000 10336034688 10749423616 1388923488 14.496 99 74 145 3 1432372304 24894800 18554016
17:58:53 17646 8192000000 10304993088 10739798016 1389550160 14.502 100 27 54 0 1418610672 21540224 7520288
18:00:18 8883 8192000000 10309120368 10735202304 1378252064 14.401 100 46 93 0 1417247456 26589168 12406224
18:01:38 4886 8192000000 10268758032 10687430656 1326902752 13.939 97 32 65 1 1352950176 18467856 7579568
18:02:50 18351 8192000000 10256176816 10685554688 1308375040 13.771 97 23 46 0 1338939280 24301120 6263120
18:04:17 14742 8192000000 10254564896 10687574016 1326706080 13.937 99 31 62 0 1354667760 19736064 8225616
18:05:35 65591 8192000000 10275606896 10697834496 1346079504 14.112 99 61 123 2 1377245504 16171936 14994064
18:08:18 31666 8192000000 10270146752 10704543744 1351152832 14.158 99 27 54 0 1380254368 22350560 6750976
18:10:12 66929 8192000000 10265520800 10714497024 1324004672 13.913 97 29 60 0 1348796160 17444048 7347440
18:12:20 58659 8192000000 10274542032 10712223744 1365126080 14.283 100 23 43 0 1388193632 18228384 4839168
18:15:11 76518 8192000000 10274115712 10725781504 1364152960 14.275 100 15 30 0 1384293472 16456432 3684080
18:17:30 70688 8192000000 10273768544 10716086272 1347005664 14.121 98 17 34 1 1361468672 10877728 3585280
18:19:47 69077 8192000000 10275528480 10720649216 1370507520 14.332 100 25 50 1 1387374752 11024672 5842560
18:22:04 61747 8192000000 10268422016 10718101504 1363307856 14.267 99 31 60 1 1377652352 7459440 6885056
18:24:12 43359 8192000000 10282247264 10729881600 1381544832 14.430 100 21 41 0 1393067680 7043648 4479200
18:26:04 52510 8192000000 10280546160 10727878656 1363820832 14.272 98 35 70 0 1382497712 10147712 8529168
18:27:57 39618 8192000000 10289989104 10742636544 1377386000 14.393 99 16 32 0 1389934096 9093920 3454176
18:29:41 26338 8192000000 10287740320 10744123392 1390729632 14.512 100 15 30 2 1405994176 11475120 3789424
18:31:07 83315 8192000000 10280357744 10743631872 1367061328 14.301 98 9 18 0 1376646288 7711392 1873568
18:33:32 41447 8192000000 10279191408 10731384832 1350419600 14.151 97 17 34 0 1362096992 7532592 4144800
18:35:15 51870 8192000000 10276985392 10734092288 1362463616 14.259 98 20 40 1 1378634672 11654256 4516800
18:37:20 27819 8192000000 10306168096 10742693888 1374851984 14.370 99 37 73 1 1394326160 11704672 7769504
18:38:50 43569 8192000000 10297581584 10740477952 1357802304 14.218 97 24 48 0 1375086064 12043152 5240608
18:40:52 42613 8192000000 10279753024 10716614656 1324853312 13.921 96 35 68 0 1343160496 11142416 7164768
18:42:36 30253 8192000000 10283304976 10718601216 1352015984 14.166 98 35 69 0 1370648032 10787248 7844800
18:44:11 88451 8192000000 10278095568 10710016000 1314533856 13.827 95 28 54 0 1332253424 11729232 5990336
18:47:16 105143 8192000000 10256817776 10697240576 1311977184 13.804 96 13 26 0 1327956560 12889120 3090256
18:50:12 46588 8192000000 10272220416 10705055744 1351531920 14.161 99 42 82 1 1370144896 10141472 8471504
18:51:59 34116 8192000000 10243314192 10697277440 1331995312 13.985 98 16 31 1 1348820112 12763776 4061024
18:53:34 66107 8192000000 10233403312 10679955456 1278052848 13.495 94 16 32 0 1289939904 7956272 3930784
18:55:49 61733 8192000000 10250618864 10692239360 1334782624 14.010 99 27 49 2 1354174096 13501552 5889920
18:58:13 53699 8192000000 10235976928 10693378048 1321863664 13.894 98 16 32 1 1340088336 14607568 3617104
19:00:11 78105 8192000000 10260775984 10701754368 1337817600 14.038 99 12 24 0 1352193088 11810672 2564816
19:02:31 44249 8192000000 10257453968 10698035200 1346310336 14.114 99 29 58 0 1367957456 15373056 6274064
19:04:25 58494 8192000000 10252879760 10693373952 1332181968 13.987 98 22 44 1 1349180320 12369184 4629168
19:06:24 12478 8192000000 10266971984 10699386880 1342011072 14.076 99 54 108 1 1363331264 9509952 11810240
19:07:42 23101 8192000000 10295643008 10730446848 1373067520 14.355 100 44 88 0 1397965648 15247264 9650864
19:09:06 120372 8192000000 10313556576 10778836992 1381629104 14.431 100 10 20 0 1405102144 21470928 2002112
19:12:07 97854 8192000000 10296620368 10739380224 1384378096 14.456 100 16 32 0 1402104448 14409888 3316464
19:14:47 69425 8192000000 10275543568 10731175936 1374129248 14.364 99 15 30 0 1386256784 8448672 3678864
19:16:56 66275 8192000000 10300503808 10755891200 1349709488 14.145 97 13 25 0 1361462016 8927680 2824848
19:19:35 35403 8192000000 10310155360 10748407808 1391468272 14.519 100 38 75 1 1410165936 10035488 8662176
19:21:29 48964 8192000000 10295489264 10738003968 1344623872 14.099 97 14 28 0 1355402240 7684512 3093856
19:23:18 53225 8192000000 10303023424 10753785856 1390884752 14.514 100 11 22 0 1404296768 11135184 2276832
19:25:45 31092 8192000000 10310895904 10743861248 1359131504 14.230 98 30 60 0 1377085712 10675392 7278816
19:27:16 42505 8192000000 10288487888 10754633728 1358524352 14.224 99 8 14 0 1384488672 24142016 1822304
19:29:01 87120 8192000000 10289410256 10742702080 1305473584 13.745 95 11 22 0 1329877872 22029024 2375264
19:31:28 86571 8192000000 10292928928 10737995776 1379208240 14.409 100 26 52 0 1396979472 11619792 6151440
19:34:36 47785 8192000000 10253737648 10704211968 1315710800 13.838 96 22 44 0 1330088176 9477168 4900208
19:36:24 10534 8192000000 10287168080 10721759232 1371800288 14.343 100 47 93 0 1394208864 11454208 10954368
19:37:35 56449 8192000000 10266296464 10711420928 1334174224 14.005 98 21 42 0 1354905248 16184080 4546944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 15-02-2023 19:40:47 to 15-02-2023 19:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-02-2023 19:41:04 to 15-02-2023 19:41:06.

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          236718
MON$OLDEST_ACTIVE               239600
MON$OLDEST_SNAPSHOT             239600
MON$NEXT_TRANSACTION            239600
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                       2017920
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Feb 15 19:38:50 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

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