Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 10: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 15706200 kB
MemAvailable 30925100 kB
Buffers 0 kB
Cached 16006640 kB
SwapCached 2508 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-08-12 10:37:20
DTS_END                         2022-08-12 10:37:20.6080
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(68:17)
IP                              ::1/45184
TRN_ID                          209763
ATT_ID                          579
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: 8692.13 26110 1043055
customer order: creation 156.16 479 18739
customer order: refuse 13.48 374 1617
order to supplier: creation 42.26 1296 5071
order to supplier: removal 3.15 193588 378
invoice (draft): creation 42.16 3303 5059
invoice (draft): removal 49.91 1060 5989
invoice accept: apply 38.04 26006 4565
invoice accept: cancel 44.89 1206 5387
customer reserve: creation 3583.64 245 430037
customer reserve: removal 4439.93 107 532792
realization accept: apply 76.33 277 9159
realization accept: cancel 84.87 171 10184
payment from customer: creation 53.40 176 6408
payment from customer: removal 5.38 113 645
payment to supplier: creation 44.00 209 5280
payment to supplier: removal 3.01 110 361
service: total inventory turnovers 3.87 3676 464
service: total monetary turnovers 6.73 363 808
service: aggregate perf. data 0.89 16234 107
service: refresh index statistics 0.04 273212 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2022 10:38:41 to 12-08-2022 10:38:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8934
TEST_TIME 2 6350
TEST_TIME 3 6124
TEST_TIME 4 5939
TEST_TIME 5 5642
TEST_TIME 6 5926
TEST_TIME 7 5960
TEST_TIME 8 6017
TEST_TIME 9 6019
TEST_TIME 10 6101
TEST_TIME 11 6130
TEST_TIME 12 6200
TEST_TIME 13 6223
TEST_TIME 14 6535
TEST_TIME 15 6858
TEST_TIME 16 7127
TEST_TIME 17 7135
TEST_TIME 18 7431
TEST_TIME 19 7351
TEST_TIME 20 7461
TEST_TIME 21 7943
TEST_TIME 22 7961
TEST_TIME 23 8057
TEST_TIME 24 8060
TEST_TIME 25 7923
TEST_TIME 26 8107
TEST_TIME 27 8377
TEST_TIME 28 8789
TEST_TIME 29 9425
TEST_TIME 30 10130
TEST_TIME 31 11365
TEST_TIME 32 11633
TEST_TIME 33 11425
TEST_TIME 34 11153
TEST_TIME 35 11117
TEST_TIME 36 11040
TEST_TIME 37 10996
TEST_TIME 38 10800
TEST_TIME 39 10678
TEST_TIME 40 10540
TEST_TIME 41 10448
TEST_TIME 42 10293
TEST_TIME 43 10149
TEST_TIME 44 10121
TEST_TIME 45 10024
TEST_TIME 46 10068
TEST_TIME 47 9918
TEST_TIME 48 9858
TEST_TIME 49 9824
TEST_TIME 50 9723
TEST_TIME 51 9622
TEST_TIME 52 9507
TEST_TIME 53 9576
TEST_TIME 54 9597
TEST_TIME 55 9483
TEST_TIME 56 9358
TEST_TIME 57 9445
TEST_TIME 58 9490
TEST_TIME 59 9434
TEST_TIME 60 9342
TEST_TIME 61 9355
TEST_TIME 62 9290
TEST_TIME 63 9181
TEST_TIME 64 9056
TEST_TIME 65 9160
TEST_TIME 66 9183
TEST_TIME 67 9134
TEST_TIME 68 9097
TEST_TIME 69 9083
TEST_TIME 70 9107
TEST_TIME 71 9149
TEST_TIME 72 9138
TEST_TIME 73 9303
TEST_TIME 74 9278
TEST_TIME 75 9291
TEST_TIME 76 9312
TEST_TIME 77 9305
TEST_TIME 78 9315
TEST_TIME 79 9354
TEST_TIME 80 9240
TEST_TIME 81 9164
TEST_TIME 82 9131
TEST_TIME 83 9093
TEST_TIME 84 9071
TEST_TIME 85 9129
TEST_TIME 86 9072
TEST_TIME 87 9078
TEST_TIME 88 9011
TEST_TIME 89 9005
TEST_TIME 90 8919
TEST_TIME 91 8861
TEST_TIME 92 8910
TEST_TIME 93 8901
TEST_TIME 94 8901
TEST_TIME 95 8958
TEST_TIME 96 8948
TEST_TIME 97 8950
TEST_TIME 98 8955
TEST_TIME 99 8932
TEST_TIME 100 8954
TEST_TIME 101 8988
TEST_TIME 102 8974
TEST_TIME 103 8992
TEST_TIME 104 8999
TEST_TIME 105 9010
TEST_TIME 106 9045
TEST_TIME 107 8975
TEST_TIME 108 8959
TEST_TIME 109 8922
TEST_TIME 110 8907
TEST_TIME 111 8873
TEST_TIME 112 8844
TEST_TIME 113 8801
TEST_TIME 114 8814
TEST_TIME 115 8792
TEST_TIME 116 8771
TEST_TIME 117 8756
TEST_TIME 118 8765
TEST_TIME 119 8760
TEST_TIME 120 8715

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 10:38:45 to 12-08-2022 10:38:48.

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 1439968 1439968 0 0.00 0 22258 13 0 0
doc_list_biud 1439967 1439967 0 0.00 0 23637 45 0 0
sp_add_invoice_to_stock 4711 4565 146 3.09 50 206206 26006 0 146
sp_cancel_adding_invoice 5387 5387 0 0.00 6 69710 1207 0 0
sp_cancel_client_order 1617 1617 0 0.00 6 12063 374 0 0
sp_cancel_customer_reserve 532792 532792 0 0.00 1 20822 107 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 8441 114 0 0
sp_cancel_pay_to_supplier 361 361 0 0.00 3 2617 110 0 0
sp_cancel_supplier_invoice 6008 5989 19 0.31 3 23653 1060 0 19
sp_cancel_supplier_order 378 378 0 0.00 6 1632226 193588 0 0
sp_cancel_write_off 10184 10184 0 0.00 2 11844 172 0 0
sp_client_order 18739 18739 0 0.00 5 22655 480 0 0
sp_customer_reserve 430037 430037 0 0.00 0 91993 245 0 0
sp_fill_shopping_cart 28862 28862 0 0.00 1 52481 85 0 0
sp_fill_shopping_cart_clo_res 430019 430019 0 0.00 0 15739 21 0 0
sp_get_clo_for_invoice 4528 4528 0 0.00 0 20565 357 0 0
sp_kill_cost_storno 16577 16577 0 0.00 0 22253 79 0 0
sp_kill_qstorno_ret_qs2qd 1082135 1082135 0 0.00 0 23634 46 0 0
sp_kill_qty_storno 570032 570032 0 0.00 0 23636 105 0 0
sp_lock_selected_doc 18967 18967 0 0.00 0 6715 8 0 0
sp_make_cost_storno 25373 25373 0 0.00 0 20715 83 0 0
sp_multiply_rows_for_pdistr 25373 25373 0 0.00 0 11029 37 0 0
sp_multiply_rows_for_qdistr 18739 18739 0 0.00 0 11813 181 0 0
sp_pay_from_customer 6408 6408 0 0.00 5 18887 177 0 0
sp_pay_to_supplier 5280 5280 0 0.00 6 17702 210 0 0
sp_payment_common 11688 11688 0 0.00 2 17175 51 0 0
sp_qd_handle_on_cancel_clo 1617 1617 0 0.00 0 12054 310 0 0
sp_qd_handle_on_invoice_upd_sts 9913 9913 0 0.00 0 15337 524 0 0
sp_qd_handle_on_reserve_upd_sts 9159 9159 0 0.00 0 16998 75 0 0
sp_reserve_write_off 9159 9159 0 0.00 8 18109 277 0 0
sp_supplier_invoice 5059 5059 0 0.00 8 103720 3303 0 0
sp_supplier_order 5071 5071 0 0.00 7 27815 1296 0 0
srv_aggregate_perf_data 107 107 0 0.00 2328 265598 16234 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 783 115994 38368 0 0
srv_find_qd_qs_mism 998661 998661 0 0.00 0 13367 10 0 0
srv_make_invnt_saldo 464 464 0 0.00 35 103827 3676 0 0
srv_make_money_saldo 808 808 0 0.00 1 15479 363 0 0
srv_recalc_idx_stat 5 5 0 0.00 71468 535891 273212 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4566 4566 0 0.00 0 10552 819 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 1 6193 451 0 0
t$perf-norm:sp_cancel_client_order 1617 1617 0 0.00 0 8252 29 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 765 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 1185 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 361 361 0 0.00 0 214 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 1953 37 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 106586 7288 0 0
t$perf-norm:sp_cancel_write_off 918 918 0 0.00 0 2372 19 0 0
t$perf-norm:sp_client_order 18739 18739 0 0.00 0 6117 32 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 2852 17 0 0
t$perf-norm:sp_pay_from_customer 6408 6408 0 0.00 0 3949 16 0 0
t$perf-norm:sp_pay_to_supplier 5280 5280 0 0.00 0 5892 20 0 0
t$perf-norm:sp_reserve_write_off 9159 9159 0 0.00 0 5103 21 0 0
t$perf-norm:sp_supplier_invoice 5059 5059 0 0.00 0 6861 30 0 0
t$perf-norm:sp_supplier_order 5071 5071 0 0.00 0 5652 26 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1094 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 3034 52 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 1410 15 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1713 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 199 80 0 0
v_add_invoice_to_stock 9344 9052 292 3.12 0 8878 26 0 292
v_all_customers 34780 34780 0 0.00 0 131 3 0 0
v_all_suppliers 28372 28372 0 0.00 0 133 4 0 0
v_all_wares 37478 37478 0 0.00 0 77 4 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 663 12 0 0
v_cancel_client_order 3234 3234 0 0.00 0 3563 9 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 1494 10 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 2129 11 0 0
v_cancel_payment_to_supplier 722 722 0 0.00 0 2602 14 0 0
v_cancel_supplier_invoice 808 770 38 4.70 0 1782 25 0 38
v_cancel_supplier_order 756 756 0 0.00 0 318 11 0 0
v_cancel_write_off 1836 1836 0 0.00 0 2300 24 0 0
v_max_id_clo_ord 5070 5070 0 0.00 0 711 4 0 0
v_max_id_clo_res 934 934 0 0.00 0 7959 22 0 0
v_max_id_ord_sup 5053 5053 0 0.00 0 4242 7 0 0
v_max_non_paid_invoice 1217 1217 0 0.00 0 276 8 0 0
v_max_non_paid_realizn 3057 3057 0 0.00 0 1068 7 0 0
v_min_id_clo_ord 5070 5070 0 0.00 0 2422 8 0 0
v_min_id_clo_res 934 934 0 0.00 0 8781 26 0 0
v_min_id_ord_sup 5053 5053 0 0.00 0 5892 11 0 0
v_min_non_paid_invoice 5280 5280 0 0.00 0 15751 26 0 0
v_min_non_paid_realizn 6408 6408 0 0.00 0 11205 22 0 0
v_our_firm 9400 9400 0 0.00 0 61 4 0 0
v_reserve_write_off 18318 18318 0 0.00 0 7751 12 0 0
x_lock_depdocs_on_canc_invoice 10991 10991 0 0.00 0 15288 130 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 14608 524 0 0
x_make_qstorno_1000_1200 5071 5071 0 0.00 3 27745 1154 0 0
x_make_qstorno_1200_2000 5059 5059 0 0.00 4 89716 3046 0 0
x_make_qstorno_2100_3300 430037 430037 0 0.00 0 91974 214 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 10:38:49 to 12-08-2022 10:38:53.

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
08:07:24 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:24 24089 8192000000 9719176848 10015645696 791859984 8.814 100 88 176 0 821673008 7526208 22286816
08:09:57 19368 8192000000 9756376256 10065567744 833963872 9.239 100 83 166 0 865493008 11571376 19957760
08:11:26 16225 8192000000 9798476816 10119667712 870155488 9.602 100 88 174 0 909285136 19053136 20076512
08:12:51 7312 8192000000 9851594128 10185060352 913856000 10.035 100 85 164 0 965238224 33333248 18048976
08:14:02 6004 8192000000 9901776960 10243936256 956932064 10.459 100 91 178 2 1019294368 43784464 18577840
08:15:10 1563 8192000000 9960005488 10314993664 991416112 10.795 100 89 171 0 1074255600 65412512 17426976
08:16:14 809 8192000000 9982971312 10345246720 1023916976 11.110 100 92 175 1 1101819840 60952816 16950048
08:17:17 1253 8192000000 10031714768 10409000960 1041588144 11.280 100 93 180 1 1153729408 94026704 18114560
08:18:19 1165 8192000000 10041377328 10415284224 1055370608 11.412 100 94 182 0 1160858720 87341824 18146288
08:19:22 8135 8192000000 10019361360 10398420992 1064668304 11.501 100 89 176 1 1150799568 68323728 17807536
08:20:31 1612 8192000000 10047379520 10427461632 1072781120 11.579 100 89 169 2 1175161840 85765680 16615040
08:21:34 1741 8192000000 10039845952 10420514816 1076739376 11.616 100 83 163 1 1160232000 66780800 16711824
08:22:37 1093 8192000000 10048735792 10432057344 1083606336 11.682 100 98 185 0 1171126368 68152640 19367392
08:23:39 1053 8192000000 10046438880 10435833856 1086849136 11.713 100 94 184 2 1167188976 61465184 18874656
08:24:41 969 8192000000 10060518992 10444734464 1091170336 11.754 100 92 176 3 1183871088 74103888 18596864
08:25:45 1370 8192000000 10048082432 10444681216 1094658256 11.787 100 81 162 0 1172756336 60971584 17126496
08:26:47 955 8192000000 10054466320 10439593984 1087686624 11.721 99 94 186 0 1178506160 71666000 19153536
08:27:50 964 8192000000 10084485984 10474508288 1099605632 11.834 100 93 179 0 1206620864 88762704 18252528
08:28:52 1141 8192000000 10055810416 10451099648 1097358208 11.813 100 84 156 2 1178136944 64670096 16108640
08:29:54 1891 8192000000 10059844080 10456117248 1093314448 11.774 100 70 136 1 1185947152 79083776 13548928
08:30:57 998 8192000000 10032772768 10418294784 1075952992 11.609 100 97 186 1 1155640832 60217456 19470384
08:31:59 1513 8192000000 10020734160 10400243712 1060609008 11.462 100 98 187 5 1144716336 65460832 18646496
08:33:01 1207 8192000000 10000566576 10370277376 1030097952 11.169 100 96 181 2 1120444800 71069952 19276896
08:34:03 1674 8192000000 9968573328 10344202240 1030626320 11.174 100 81 154 3 1093246352 45551632 17068400
08:35:05 3068 8192000000 9987222176 10361262080 1026828128 11.138 100 87 155 5 1114678752 71558240 16292384
08:36:09 1111 8192000000 9978241008 10352615424 1037116256 11.237 100 85 157 4 1101408304 48382016 15910032
08:37:11 12226 8192000000 10008692192 10402258944 1044512496 11.308 99 69 137 2 1125244368 66806784 13925088
08:38:28 11990 8192000000 9992019904 10380279808 1075248544 11.602 100 59 109 2 1114432096 27779760 11403792
08:39:45 6906 8192000000 10064712112 10456784896 1090027168 11.743 100 64 125 3 1177648016 75420048 12200800
08:40:58 1047 8192000000 10106342576 10497105920 1109491504 11.928 100 93 172 1 1223874272 95946032 18436736
08:42:01 1626 8192000000 10140870560 10530586624 1125384272 12.078 100 89 171 2 1258493344 114681552 18427520
08:43:09 8246 8192000000 10163985600 10566172672 1121514912 12.041 100 71 133 0 1289603472 153739072 14349488
08:44:29 964 8192000000 10201878208 10597289984 1137299232 12.190 100 95 175 1 1316331408 158820704 20211472
08:45:35 1178 8192000000 10240525904 10639937536 1147708208 12.288 100 85 166 0 1356996816 191412128 17876480
08:46:40 1437 8192000000 10284856960 10683228160 1163788048 12.439 100 90 169 3 1398501856 215610304 19103504
08:47:46 1362 8192000000 10319168144 10718715904 1178761920 12.579 100 94 183 0 1436975056 236604992 21608144
08:48:49 855 8192000000 10364507424 10759524352 1176052304 12.553 100 93 184 3 1472233808 274685408 21496096
08:49:54 1600 8192000000 10410404304 10813890560 1190685552 12.690 100 88 171 1 1525457792 315437040 19335200
08:50:59 853 8192000000 10453295040 10876592128 1197393456 12.752 100 99 195 0 1562839888 342312208 23134224
08:52:05 1746 8192000000 10472848544 10912018432 1207567440 12.847 100 92 182 1 1579430384 349954624 21908320
08:53:13 982 8192000000 10516520656 10941771776 1209825968 12.867 100 98 192 0 1622361568 389933504 22602096
08:54:16 1143 8192000000 10548808800 10983034880 1214832032 12.914 100 92 175 2 1656672320 419717520 22122768
08:55:20 1190 8192000000 10581422160 10998124544 1213082720 12.898 100 96 190 3 1686758736 450564352 23111664
08:56:22 2721 8192000000 10600010304 11026153472 1183935888 12.627 99 93 183 3 1695719760 488577168 23206704
08:57:28 774 8192000000 10660461616 11100889088 1204785392 12.821 100 96 190 0 1765600784 538274544 22540848
08:58:30 4094 8192000000 10650649424 11104276480 1205569600 12.828 100 61 122 0 1757610224 536500912 15539712
08:59:46 3365 8192000000 10680594144 11110342656 1203066304 12.805 100 69 127 1 1786318624 566542544 16709776
09:01:02 6561 8192000000 10721517856 11149615104 1202452032 12.799 100 62 123 0 1823909456 605461024 15996400
09:02:15 20002 8192000000 10778307792 11227475968 1176538720 12.558 99 52 104 0 1874087760 683301872 14247168
09:03:49 13584 8192000000 10743594256 11217195008 1178335664 12.575 100 48 96 0 1838374192 646498304 13540224
09:05:10 9873 8192000000 10688455408 11175063552 1162938080 12.431 100 42 82 0 1795227360 621041040 11248240
09:06:37 5403 8192000000 10491630944 11063685120 1134471920 12.163 99 41 78 0 1608612272 463520336 10620016
09:08:15 59153 8192000000 10113696112 10663272448 1131490832 12.135 100 15 30 1 1332031648 196699952 3840864
09:10:15 4308 8192000000 10079830272 10504953856 1143499168 12.248 100 76 126 2 1179457920 20465872 15492880
09:11:22 29320 8192000000 10149229904 10575585280 1148638432 12.297 100 69 131 0 1242261024 80644432 12978160
09:12:57 115992 8192000000 10081939424 10583633920 1136456944 12.182 99 12 24 0 1201421168 62611744 2352480
09:15:58 55828 8192000000 10091084544 10541314048 1136462224 12.182 98 41 76 0 1170385264 25851408 8071632
09:17:54 47539 8192000000 10071552288 10531307520 1124922848 12.073 99 30 53 1 1166085104 35681488 5480768
09:19:46 65994 8192000000 10119112576 10557558784 1117261056 12.001 98 43 82 1 1226494720 98065328 11168336
09:21:59 80543 8192000000 10150130656 10602266624 1107296640 11.907 97 21 42 0 1216997856 104832880 4868336
09:24:51 75569 8192000000 10106904480 10550767616 1126391904 12.087 100 34 68 1 1194363664 60583488 7388272
09:27:08 27692 8192000000 10109473408 10550575104 1135618336 12.174 100 34 68 1 1210636352 66954912 8063104
09:28:37 39503 8192000000 10158298896 10616070144 1116160432 11.991 97 42 83 1 1237111808 112009264 8942112
09:30:21 83974 8192000000 10143903584 10691444736 1139939440 12.215 100 17 35 1 1231084400 87487840 3657120
09:32:45 94635 8192000000 10128951584 10626285568 1121096544 12.037 98 13 26 0 1222994064 98858736 3038784
09:35:21 82211 8192000000 10106352032 10557677568 1143301616 12.247 100 33 66 0 1176634432 26259456 7073360
09:37:57 47193 8192000000 10077408784 10543079424 1109380544 11.927 97 20 40 0 1168805968 55124336 4301088
09:39:50 61829 8192000000 10109806672 10584383488 1150897808 12.318 100 9 18 0 1207397248 54567664 1931776
09:42:05 62599 8192000000 10108238016 10626609152 1134966272 12.168 99 17 34 0 1202408112 63784432 3657408
09:44:07 23756 8192000000 10050600528 10497794048 1129307360 12.115 99 18 35 0 1162194912 29354720 3532832
09:45:33 50912 8192000000 10108896752 10563289088 1123991264 12.065 98 34 64 1 1206353296 75538256 6823776
09:47:27 37998 8192000000 10136897200 10597289984 1111245968 11.944 98 24 48 0 1226578480 109913248 5419264
09:49:32 47205 8192000000 10132911648 10629328896 1119467104 12.022 99 24 48 0 1215511680 90612192 5432384
09:51:26 21354 8192000000 10097467728 10559488000 1130694256 12.128 100 50 100 0 1188509568 47146432 10668880
09:52:50 55113 8192000000 10105232704 10547781632 1120294704 12.030 99 30 57 1 1183058864 57304608 5459552
09:54:46 35699 8192000000 10072113536 10521235456 1099540608 11.833 97 41 82 0 1141878384 33816816 8520960
09:56:22 73052 8192000000 10062073152 10558672896 1117781664 12.006 99 7 14 0 1159770048 40797744 1190640
09:58:49 88814 8192000000 10133757440 10594111488 1129610576 12.118 99 27 54 0 1225282256 90507536 5164144
10:01:22 56243 8192000000 10097556256 10545700864 1090762144 11.750 96 14 28 0 1186103664 92366096 2975424
10:03:20 52764 8192000000 10061575648 10524532736 1109795840 11.930 99 21 41 0 1157748640 43818032 4134768
10:05:13 47901 8192000000 10063669552 10524725248 1115542880 11.985 99 8 16 0 1147929504 30775056 1611568
10:08:11 63415 8192000000 10058444640 10566025216 1127076576 12.094 100 25 49 0 1186620304 54734928 4808800
10:10:17 61647 8192000000 10070232112 10544594944 1113086448 11.962 97 12 24 0 1170353216 54718048 2548720
10:12:24 52468 8192000000 10075636144 10525286400 1130309520 12.124 99 25 49 0 1192551264 56971936 5269808
10:14:18 36677 8192000000 10073745760 10520903680 1130523536 12.126 99 21 40 0 1164657872 29902592 4231744
10:15:59 41141 8192000000 10088538832 10515091456 1070320320 11.555 95 37 70 0 1127737376 50463456 6953600
10:17:40 71447 8192000000 10063906448 10509824000 1066508272 11.519 95 20 40 0 1145649264 75072016 4068976
10:19:55 90637 8192000000 10126322480 10586439680 1124795680 12.072 99 30 60 0 1221771024 91035264 5940080
10:22:26 51983 8192000000 10112250288 10596319232 1100613968 11.843 97 41 81 0 1202229104 93709232 7905904
10:24:18 87483 8192000000 10047699024 10494963712 1106123616 11.896 98 33 64 1 1159297008 46510464 6662928
10:26:51 80267 8192000000 10040323840 10511192064 1102506400 11.861 98 16 32 1 1152959952 47295504 3158048
10:29:18 81778 8192000000 10089955872 10553151488 1137884256 12.196 100 19 35 0 1185548096 43763936 3899904
10:31:51 111789 8192000000 10077016752 10528526336 1092987728 11.771 96 18 34 2 1151715728 54708592 4019408
10:34:44 24460 8192000000 10113620112 10552836096 1134264704 12.162 100 30 60 0 1213765728 73075776 6425248
10:36:09 45490 8192000000 10153347888 10603872256 1140012768 12.216 100 31 62 0 1253513200 106601152 6899280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-08-2022 10:38:55 to 12-08-2022 10:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 10:39:12 to 12-08-2022 10:39:14.

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          203080
MON$OLDEST_ACTIVE               209900
MON$OLDEST_SNAPSHOT             209900
MON$NEXT_TRANSACTION            209900
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                       2059680
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             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:155: 
:156: mscs-test-161.budzdorov.ru    Fri Aug 12 10:37:26 2022
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:158: 
:159: 
:160: mscs-test-161.budzdorov.ru    Fri Aug 12 10:37:27 2022
:161:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:162: 
:163: 
:164: mscs-test-161.budzdorov.ru    Fri Aug 12 10:37:28 2022
:165:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:166: 
:167: 
:168: mscs-test-161.budzdorov.ru    Fri Aug 12 10:37:29 2022
:169:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:170: 
--- end of diff output ---

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

12.08.22 10:39:15. 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-08-12 10:39:18 No severe PSQL-related problems occured
12.08.22 10:39:19. End of report.