Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.07.2022 07:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15488744 kB
MemAvailable 31121232 kB
Buffers 0 kB
Cached 16236800 kB
SwapCached 2528 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-07-15 07:37:17
DTS_END                         2022-07-15 07:37:17.6560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52614
TRN_ID                          218821
ATT_ID                          636
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: 8591.98 23458 1031037
customer order: creation 167.59 489 20111
customer order: refuse 13.84 484 1661
order to supplier: creation 45.99 1451 5519
order to supplier: removal 3.10 206809 372
invoice (draft): creation 44.23 3700 5308
invoice (draft): removal 46.48 1158 5578
invoice accept: apply 41.31 27100 4957
invoice accept: cancel 42.08 1485 5050
customer reserve: creation 3789.71 256 454765
customer reserve: removal 4113.98 123 493678
realization accept: apply 82.91 352 9949
realization accept: cancel 73.33 214 8799
payment from customer: creation 57.47 208 6896
payment from customer: removal 5.81 186 697
payment to supplier: creation 47.94 224 5753
payment to supplier: removal 3.28 153 394
service: total inventory turnovers 3.45 5155 414
service: total monetary turnovers 8.48 399 1017
service: aggregate perf. data 0.94 16173 113
service: refresh index statistics 0.05 203038 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-07-2022 07:39:19 to 15-07-2022 07:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7661
TEST_TIME 2 6666
TEST_TIME 3 6422
TEST_TIME 4 6077
TEST_TIME 5 6076
TEST_TIME 6 5818
TEST_TIME 7 5798
TEST_TIME 8 6168
TEST_TIME 9 6163
TEST_TIME 10 6256
TEST_TIME 11 6222
TEST_TIME 12 6148
TEST_TIME 13 6051
TEST_TIME 14 6170
TEST_TIME 15 6405
TEST_TIME 16 6455
TEST_TIME 17 6517
TEST_TIME 18 6584
TEST_TIME 19 6671
TEST_TIME 20 6948
TEST_TIME 21 6929
TEST_TIME 22 7194
TEST_TIME 23 7465
TEST_TIME 24 7498
TEST_TIME 25 7968
TEST_TIME 26 8526
TEST_TIME 27 8550
TEST_TIME 28 8948
TEST_TIME 29 9365
TEST_TIME 30 9409
TEST_TIME 31 9820
TEST_TIME 32 10008
TEST_TIME 33 10108
TEST_TIME 34 10243
TEST_TIME 35 10333
TEST_TIME 36 10320
TEST_TIME 37 10387
TEST_TIME 38 10392
TEST_TIME 39 10440
TEST_TIME 40 10195
TEST_TIME 41 10030
TEST_TIME 42 9966
TEST_TIME 43 9951
TEST_TIME 44 10007
TEST_TIME 45 10003
TEST_TIME 46 10387
TEST_TIME 47 10273
TEST_TIME 48 10200
TEST_TIME 49 10107
TEST_TIME 50 10062
TEST_TIME 51 10007
TEST_TIME 52 9863
TEST_TIME 53 9817
TEST_TIME 54 9767
TEST_TIME 55 9827
TEST_TIME 56 9690
TEST_TIME 57 9680
TEST_TIME 58 9646
TEST_TIME 59 9628
TEST_TIME 60 9488
TEST_TIME 61 9426
TEST_TIME 62 9357
TEST_TIME 63 9259
TEST_TIME 64 9276
TEST_TIME 65 9271
TEST_TIME 66 9226
TEST_TIME 67 9160
TEST_TIME 68 9087
TEST_TIME 69 9108
TEST_TIME 70 9061
TEST_TIME 71 9115
TEST_TIME 72 9137
TEST_TIME 73 9099
TEST_TIME 74 9100
TEST_TIME 75 9154
TEST_TIME 76 9141
TEST_TIME 77 9099
TEST_TIME 78 9107
TEST_TIME 79 9129
TEST_TIME 80 9178
TEST_TIME 81 9185
TEST_TIME 82 9152
TEST_TIME 83 9146
TEST_TIME 84 9083
TEST_TIME 85 9034
TEST_TIME 86 8956
TEST_TIME 87 8948
TEST_TIME 88 8959
TEST_TIME 89 8921
TEST_TIME 90 8895
TEST_TIME 91 8854
TEST_TIME 92 8837
TEST_TIME 93 8848
TEST_TIME 94 8826
TEST_TIME 95 8812
TEST_TIME 96 8809
TEST_TIME 97 8803
TEST_TIME 98 8789
TEST_TIME 99 8792
TEST_TIME 100 8786
TEST_TIME 101 8830
TEST_TIME 102 8808
TEST_TIME 103 8820
TEST_TIME 104 8899
TEST_TIME 105 8903
TEST_TIME 106 8930
TEST_TIME 107 9072
TEST_TIME 108 9027
TEST_TIME 109 8982
TEST_TIME 110 8966
TEST_TIME 111 8937
TEST_TIME 112 8891
TEST_TIME 113 8870
TEST_TIME 114 8822
TEST_TIME 115 8771
TEST_TIME 116 8763
TEST_TIME 117 8729
TEST_TIME 118 8727
TEST_TIME 119 8685
TEST_TIME 120 8617

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-07-2022 07:39:22 to 15-07-2022 07:39:26.

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 1449283 1449283 0 0.00 0 18382 15 0 0
doc_list_biud 1449280 1449280 0 0.00 0 37697 49 0 0
sp_add_invoice_to_stock 5116 4957 159 3.10 91 208384 27100 0 159
sp_cancel_adding_invoice 5050 5050 0 0.00 5 103262 1486 0 0
sp_cancel_client_order 1661 1661 0 0.00 5 7269 484 0 0
sp_cancel_customer_reserve 493678 493678 0 0.00 1 36745 124 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 11249 186 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 4 9217 153 0 0
sp_cancel_supplier_invoice 5586 5578 8 0.14 3 37766 1158 0 8
sp_cancel_supplier_order 372 372 0 0.00 15 1488272 206810 0 0
sp_cancel_write_off 8799 8799 0 0.00 2 21860 214 0 0
sp_client_order 20111 20111 0 0.00 6 34575 490 0 0
sp_customer_reserve 454765 454765 0 0.00 0 80602 257 0 0
sp_fill_shopping_cart 30927 30927 0 0.00 1 29589 104 0 0
sp_fill_shopping_cart_clo_res 454739 454739 0 0.00 0 17276 20 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 31728 320 0 0
sp_kill_cost_storno 14940 14940 0 0.00 0 11126 93 0 0
sp_kill_qstorno_ret_qs2qd 1002105 1002105 0 0.00 0 37689 53 0 0
sp_kill_qty_storno 529995 529995 0 0.00 0 37694 123 0 0
sp_lock_selected_doc 20526 20526 0 0.00 0 7813 8 0 0
sp_make_cost_storno 27506 27506 0 0.00 0 13152 95 0 0
sp_multiply_rows_for_pdistr 27506 27506 0 0.00 0 16674 42 0 0
sp_multiply_rows_for_qdistr 20111 20111 0 0.00 0 15224 166 0 0
sp_pay_from_customer 6896 6896 0 0.00 5 15889 209 0 0
sp_pay_to_supplier 5753 5753 0 0.00 7 16759 225 0 0
sp_payment_common 12649 12649 0 0.00 2 12751 57 0 0
sp_qd_handle_on_cancel_clo 1661 1661 0 0.00 0 7262 392 0 0
sp_qd_handle_on_invoice_upd_sts 9958 9958 0 0.00 0 21879 653 0 0
sp_qd_handle_on_reserve_upd_sts 9949 9949 0 0.00 0 22751 82 0 0
sp_reserve_write_off 9949 9949 0 0.00 8 30466 352 0 0
sp_supplier_invoice 5308 5308 0 0.00 12 103317 3701 0 0
sp_supplier_order 5519 5519 0 0.00 7 34775 1452 0 0
srv_aggregate_perf_data 113 113 0 0.00 2635 183623 16174 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 432 158925 33553 0 0
srv_find_qd_qs_mism 982778 982778 0 0.00 0 18381 11 0 0
srv_make_invnt_saldo 414 414 0 0.00 37 102457 5156 0 0
srv_make_money_saldo 1017 1017 0 0.00 1 20385 399 0 0
srv_recalc_idx_stat 6 6 0 0.00 32833 459039 203038 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4958 4958 0 0.00 1 21435 1136 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 8904 589 0 0
t$perf-norm:sp_cancel_client_order 1661 1661 0 0.00 0 1591 28 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 1309 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 1144 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1664 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 726 30 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 100934 8415 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 3109 26 0 0
t$perf-norm:sp_client_order 20111 20111 0 0.00 0 13281 42 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 4285 22 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 4372 22 0 0
t$perf-norm:sp_pay_to_supplier 5753 5753 0 0.00 0 1810 20 0 0
t$perf-norm:sp_reserve_write_off 9949 9949 0 0.00 0 6830 27 0 0
t$perf-norm:sp_supplier_invoice 5308 5308 0 0.00 0 5545 39 0 0
t$perf-norm:sp_supplier_order 5519 5519 0 0.00 0 7587 39 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 547 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 83 5 0 0
t$perf-norm:srv_make_invnt_saldo 414 414 0 0.00 0 690 17 0 0
t$perf-norm:srv_make_money_saldo 1017 1017 0 0.00 0 319 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 203 106 0 0
v_add_invoice_to_stock 10134 9816 318 3.13 0 14241 39 0 318
v_all_customers 36376 36376 0 0.00 0 96 4 0 0
v_all_suppliers 30048 30048 0 0.00 0 158 5 0 0
v_all_wares 40222 40222 0 0.00 0 157 5 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 267 15 0 0
v_cancel_client_order 3322 3322 0 0.00 0 6084 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 10207 29 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 1444 13 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 8627 24 0 0
v_cancel_supplier_invoice 874 858 16 1.83 0 476 26 0 16
v_cancel_supplier_order 744 744 0 0.00 0 489 13 0 0
v_cancel_write_off 2064 2064 0 0.00 0 2954 29 0 0
v_max_id_clo_ord 5519 5519 0 0.00 0 1722 7 0 0
v_max_id_clo_res 996 996 0 0.00 0 1753 13 0 0
v_max_id_ord_sup 5297 5297 0 0.00 0 3312 7 0 0
v_max_non_paid_invoice 1545 1545 0 0.00 0 72 9 0 0
v_max_non_paid_realizn 3706 3706 0 0.00 0 8999 13 0 0
v_min_id_clo_ord 5519 5519 0 0.00 0 3426 11 0 0
v_min_id_clo_res 996 996 0 0.00 0 4621 15 0 0
v_min_id_ord_sup 5297 5297 0 0.00 0 2403 8 0 0
v_min_non_paid_invoice 5753 5753 0 0.00 0 5774 18 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 11834 25 0 0
v_our_firm 10226 10226 0 0.00 0 64 6 0 0
v_reserve_write_off 19898 19898 0 0.00 0 27405 23 0 0
x_lock_depdocs_on_canc_invoice 10199 10199 0 0.00 0 18176 146 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 5938 408 0 0
x_make_qstorno_1000_1200 5519 5519 0 0.00 2 34668 1226 0 0
x_make_qstorno_1200_2000 5308 5308 0 0.00 4 103197 3427 0 0
x_make_qstorno_2100_3300 454765 454765 0 0.00 0 80600 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-07-2022 07:39:27 to 15-07-2022 07:39:31.

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
05:07:21 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 26929 8192000000 9716788544 10012528640 793273680 8.828 100 93 186 0 824195296 7922640 22998976
05:09:55 19143 8192000000 9750493200 10057011200 824819200 9.147 100 82 162 0 857053712 12501968 19732544
05:11:21 9422 8192000000 9792255424 10109927424 862803808 9.528 100 83 166 0 901757904 19494064 19460032
05:12:32 8652 8192000000 9845148688 10173452288 896864096 9.867 100 79 156 1 950239056 36039072 17335888
05:13:49 4660 8192000000 9903717760 10242985984 940669632 10.300 100 88 174 0 1025798784 66239008 18890144
05:14:59 2874 8192000000 9939609792 10291412992 984339328 10.726 100 92 180 1 1056781184 54305360 18136496
05:16:07 1701 8192000000 9983261392 10341527552 1014214416 11.016 100 86 167 0 1105096928 74712448 16170064
05:17:11 897 8192000000 10010402448 10376552448 1039179872 11.257 100 93 179 3 1135181872 78358416 17643584
05:18:14 801 8192000000 10021053872 10394030080 1055568848 11.414 100 97 191 2 1145987488 71963904 18454736
05:19:16 1174 8192000000 10033766368 10410295296 1059158064 11.448 100 97 185 1 1157108448 79126224 18824160
05:20:20 1238 8192000000 10063846000 10446745600 1061661840 11.472 100 92 185 0 1181725248 102450176 17613232
05:21:22 855 8192000000 10029793648 10410840064 1069365968 11.546 100 88 171 2 1151572800 65107472 17099360
05:22:24 1187 8192000000 10045603888 10430787584 1077002608 11.619 100 97 190 1 1168450480 71884224 19563648
05:23:27 1210 8192000000 10042321968 10423545856 1077537568 11.624 100 96 191 3 1160996256 64559328 18899360
05:24:30 1201 8192000000 10066751568 10451759104 1087323600 11.717 100 97 187 3 1192302288 86580048 18398640
05:25:32 1030 8192000000 10062198320 10449571840 1084236384 11.688 100 97 179 1 1179347920 76542384 18569152
05:26:35 914 8192000000 10022006368 10410164224 1088534688 11.729 100 78 148 0 1146501568 42298464 15668416
05:27:37 2033 8192000000 10050720384 10442100736 1086279744 11.707 100 76 144 3 1168249504 66369312 15600448
05:28:40 1132 8192000000 10024493824 10417881088 1083081344 11.677 100 86 166 0 1147853168 47369328 17402496
05:29:42 5267 8192000000 10041976192 10431995904 1074754848 11.597 100 79 155 1 1166923520 77114608 15054064
05:30:51 1275 8192000000 10044741856 10433130496 1078077520 11.629 100 88 176 1 1167785856 72828032 16880304
05:31:53 1193 8192000000 10040592928 10420371456 1053011744 11.390 99 99 182 3 1166404064 95240432 18151888
05:32:55 1260 8192000000 10051228256 10435289088 1073402608 11.585 100 98 190 0 1173198144 79770272 20025264
05:33:58 1080 8192000000 9962502928 10330570752 1018993280 11.062 98 93 172 2 1082289424 47616048 15680096
05:35:01 3628 8192000000 9955718496 10323599360 1010300560 10.978 98 84 149 0 1082666864 57014992 15351312
05:36:05 2888 8192000000 9965160928 10339577856 1026961392 11.139 100 68 126 3 1083506608 44124256 12420960
05:37:09 1540 8192000000 9981680416 10352250880 1043145536 11.295 100 88 163 2 1104804960 45179824 16479600
05:38:19 1109 8192000000 10011476832 10389811200 1060647248 11.463 100 93 165 1 1134048736 57310384 16091104
05:39:29 11454 8192000000 10053064848 10446192640 1078018304 11.629 100 63 118 0 1172480864 82616480 11846080
05:40:47 2145 8192000000 10064183888 10455433216 1097291232 11.812 100 75 135 2 1184103792 73462576 13349984
05:42:04 1036 8192000000 10115031856 10503876608 1107861200 11.912 100 91 178 1 1231098960 106114096 17123664
05:43:13 4986 8192000000 10142900656 10544812032 1119860864 12.026 100 75 140 1 1254652272 119300288 15491120
05:44:32 2777 8192000000 10153823488 10552188928 1133238656 12.152 100 93 174 0 1273430320 120145056 20046608
05:45:41 4028 8192000000 10204767584 10615185408 1146950800 12.281 100 78 154 1 1315285040 151429152 16905088
05:46:50 2140 8192000000 10242515440 10646073344 1150658736 12.316 100 78 156 1 1355328640 187835488 16834416
05:47:58 1056 8192000000 10264756048 10666270720 1164726752 12.448 100 87 160 2 1378419360 193928368 19764240
05:49:07 1161 8192000000 10281960624 10681094144 1167383072 12.472 99 86 162 0 1390738016 206426720 16928224
05:50:12 1469 8192000000 10348644240 10750795776 1185044624 12.637 100 96 187 1 1459038320 251557040 22436656
05:51:21 1314 8192000000 10389539264 10797260800 1190391504 12.687 100 93 178 1 1495459920 283469408 21599008
05:52:25 1885 8192000000 10399033040 10823725056 1199686912 12.773 100 86 165 0 1507375328 288334656 19353760
05:53:32 938 8192000000 10458742416 10865061888 1196949328 12.748 100 100 197 0 1567932800 348272368 22711104
05:54:38 1987 8192000000 10472992528 10879537152 1192998688 12.711 100 92 179 2 1578728480 365516272 20213520
05:55:43 982 8192000000 10525501264 10944323584 1184323792 12.631 100 97 186 0 1633835984 426428336 23083856
05:56:47 2624 8192000000 10544352640 10966175744 1190583616 12.689 100 96 184 2 1648359824 434812432 22963776
05:57:51 890 8192000000 10552232144 10964029440 1180462960 12.595 100 90 175 1 1660224976 458563360 21198656
05:58:56 821 8192000000 10570077024 11005181952 1176263872 12.555 100 94 177 1 1675032480 477117152 21651456
06:00:01 997 8192000000 10575812672 11012505600 1180544816 12.595 100 97 188 3 1683715392 479634800 23535776
06:01:08 987 8192000000 10566566944 10996613120 1176483216 12.557 100 94 188 1 1670698528 470343424 23871888
06:02:11 910 8192000000 10533806016 11028713472 1182371264 12.612 100 98 195 1 1640338016 432919616 25047136
06:03:16 3491 8192000000 10504893248 10940698624 1171549712 12.511 100 63 125 1 1619852368 432473856 15828800
06:04:22 22570 8192000000 10449068000 10926190592 1169201232 12.489 100 36 72 1 1577703440 399013088 9489120
06:05:51 14608 8192000000 10395401168 10853351424 1125925856 12.083 97 31 56 2 1480201680 346947600 7328224
06:07:07 26937 8192000000 10324699184 10816212992 1130877136 12.130 99 20 40 0 1440127088 303397440 5852512
06:08:36 28074 8192000000 10218305664 10705850368 1135874656 12.177 100 31 58 0 1345040032 202629616 6535760
06:10:07 1595 8192000000 10208794880 10667274240 1150223680 12.312 100 90 178 0 1311928320 141444176 20260464
06:11:11 2227 8192000000 10196187680 10648694784 1147470432 12.286 100 94 181 1 1290294416 121395920 21428064
06:12:16 1425 8192000000 10194923936 10601312256 1152884128 12.337 99 97 184 1 1291942224 119194352 19863744
06:13:19 774 8192000000 10260005520 10663493632 1173849392 12.533 100 98 194 1 1357106736 161398752 21858592
06:14:21 431 8192000000 10280990880 10705494016 1168496816 12.483 100 76 150 0 1382394256 196093424 17804016
06:15:23 9547 8192000000 10292169056 10752573440 1166670176 12.466 99 43 84 0 1381145104 203844352 10630576
06:16:48 71836 8192000000 10297932336 10732802048 1165454800 12.454 99 48 89 1 1384388352 207684672 11248880
06:19:02 103765 8192000000 10295561760 10779840512 1171370528 12.510 100 26 52 0 1409311328 231661280 6279520
06:21:48 26545 8192000000 10237927392 10698584064 1128106304 12.104 98 27 53 0 1339663200 204257680 7299216
06:23:45 50343 8192000000 10085006800 10540011520 1113297168 11.964 98 16 32 0 1192538768 75886848 3354752
06:26:22 99969 8192000000 10112327040 10579779584 1110200576 11.934 97 27 49 0 1179550976 64303568 5046832
06:29:26 132223 8192000000 10126793440 10638446592 1137866064 12.195 99 17 34 0 1251046912 109668704 3512144
06:32:41 158925 8192000000 10146926016 10634575872 1155957216 12.365 100 24 48 1 1228812576 67588800 5266560
06:36:39 55960 8192000000 10073147488 10514169856 1119482384 12.022 97 34 66 0 1146583168 19972992 7127792
06:38:37 1680 8192000000 10096818048 10529067008 1149312608 12.303 100 76 133 2 1200034560 35349552 15372400
06:39:40 42350 8192000000 10127365264 10561400832 1141210448 12.227 99 40 79 1 1205470496 56029600 8230448
06:41:25 41462 8192000000 10118174128 10610958336 1119694976 12.024 99 17 34 0 1211635744 88389472 3551296
06:43:42 61082 8192000000 10073910704 10546126848 1124242912 12.067 99 12 24 0 1184903536 58350656 2309968
06:46:06 67825 8192000000 10048594640 10503020544 1103700064 11.873 97 9 18 0 1140897136 35559968 1637104
06:48:15 98878 8192000000 10068957488 10543644672 1096404256 11.804 96 18 34 1 1151806864 50792752 4609856
06:51:00 37252 8192000000 10084023360 10511044608 1055317968 11.412 92 48 96 0 1139672384 74879200 9475216
06:52:40 48524 8192000000 10068178560 10492686336 1095152560 11.792 98 48 96 0 1140633728 36118672 9362496
06:54:30 8968 8192000000 10065398864 10496266240 1108574224 11.919 100 40 76 1 1165760544 48713536 8472784
06:55:40 52916 8192000000 10102607632 10549284864 1106647712 11.901 99 24 48 0 1194693584 83005952 5039920
06:57:33 73611 8192000000 10050759296 10527453184 1090290272 11.745 97 23 44 1 1135961392 41230864 4440256
06:59:50 28304 8192000000 10085113968 10516299776 1103209936 11.868 99 32 64 0 1152056672 42689552 6157184
07:01:37 98988 8192000000 10031256640 10462834688 1084323936 11.689 97 37 74 0 1130496800 37685168 8487696
07:04:17 19507 8192000000 10077728928 10518417408 1110883936 11.941 100 52 102 0 1175428480 53873552 10670992
07:05:45 59706 8192000000 10056865392 10525814784 1111334672 11.945 99 7 14 0 1155478752 42730048 1414032
07:07:58 75189 8192000000 10085945056 10550849536 1135656608 12.175 100 12 24 0 1179279440 41247328 2375504
07:10:14 37505 8192000000 10079079024 10544537600 1128386624 12.106 99 18 32 0 1186924144 55471312 3066208
07:11:52 65105 8192000000 10124381104 10565541888 1137326080 12.190 99 21 38 1 1209737328 68212944 4198304
07:14:03 3516 8192000000 10143746144 10568642560 1148707792 12.297 100 70 126 5 1239395408 78980304 11707312
07:15:07 29696 8192000000 10153663376 10600673280 1136972832 12.187 98 21 42 0 1231013376 89047792 4992752
07:16:37 35059 8192000000 10198700512 10638708736 1142284704 12.237 99 32 64 0 1268288528 118409824 7594000
07:18:12 24810 8192000000 10204974160 10654777344 1154090080 12.348 100 34 65 0 1304159264 143253856 6815328
07:19:44 22324 8192000000 10232139824 10678427648 1151193424 12.321 99 36 72 0 1316186176 157219776 7772976
07:21:14 124173 8192000000 10181210144 10653319168 1073377728 11.584 94 17 30 1 1213424336 136723808 3322800
07:24:20 21070 8192000000 10089222656 10524934144 1123369312 12.059 99 34 67 0 1180960560 50749632 6841616
07:25:42 81906 8192000000 10074427600 10523467776 1132728416 12.147 100 36 69 1 1178554064 38745312 7080336
07:28:04 65436 8192000000 10063019344 10535440384 1126525568 12.089 99 3 6 0 1161457200 34761984 169648
07:30:10 39562 8192000000 10053035520 10510688256 1128149648 12.104 99 15 29 0 1168228480 36928176 3150656
07:32:03 82974 8192000000 10055440144 10542387200 1104366768 11.879 97 23 46 1 1152018192 42643968 5007456
07:34:33 28167 8192000000 10096305360 10539233280 1132080656 12.141 99 35 70 1 1181882912 42706752 7095504
07:36:09 78913 8192000000 9277891104 9593085952 416842528 4.842 37 7 14 0 430968848 12780704 1345616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 15-07-2022 07:39:32 to 15-07-2022 07:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-07-2022 07:39:50 to 15-07-2022 07:39:52.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          219056
MON$OLDEST_ACTIVE               219057
MON$OLDEST_SNAPSHOT             219057
MON$NEXT_TRANSACTION            219057
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-30 07:30:08.6030 GMT
MON$PAGES                       2115904
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Fri Jul 15 07:37:39 2022
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
--- end of diff output ---

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

15.07.22 07:39:53. There are 3 logs in /var/tmp/logs-oltp40 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-07-15 07:39:55 No severe PSQL-related problems occured
15.07.22 07:39:57. End of report.