Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.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 15013380 kB
MemAvailable 30490612 kB
Buffers 0 kB
Cached 16712624 kB
SwapCached 2020 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.3
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-10-23 10:37:21
DTS_END                         2022-10-23 10:37:21.9180
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/48170
TRN_ID                          215264
ATT_ID                          663
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: 8749.74 24923 1049969
customer order: creation 165.30 497 19836
customer order: refuse 13.74 476 1649
order to supplier: creation 43.07 1341 5168
order to supplier: removal 3.63 164293 436
invoice (draft): creation 43.44 3305 5213
invoice (draft): removal 49.96 1083 5995
invoice accept: apply 39.81 27578 4777
invoice accept: cancel 44.35 1365 5322
customer reserve: creation 3702.09 260 444251
customer reserve: removal 4365.88 106 523905
realization accept: apply 80.53 315 9663
realization accept: cancel 75.28 182 9034
payment from customer: creation 55.88 186 6706
payment from customer: removal 5.33 173 640
payment to supplier: creation 45.90 194 5508
payment to supplier: removal 3.12 135 374
service: total inventory turnovers 3.99 4204 479
service: total monetary turnovers 7.56 376 907
service: aggregate perf. data 0.85 15970 102
service: refresh index statistics 0.03 276428 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-10-2022 10:39:13 to 23-10-2022 10:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4307
TEST_TIME 2 4450
TEST_TIME 3 4353
TEST_TIME 4 4311
TEST_TIME 5 4412
TEST_TIME 6 4606
TEST_TIME 7 4796
TEST_TIME 8 5250
TEST_TIME 9 6154
TEST_TIME 10 6050
TEST_TIME 11 6101
TEST_TIME 12 6136
TEST_TIME 13 6142
TEST_TIME 14 6287
TEST_TIME 15 6410
TEST_TIME 16 6640
TEST_TIME 17 6682
TEST_TIME 18 7208
TEST_TIME 19 7780
TEST_TIME 20 7905
TEST_TIME 21 7912
TEST_TIME 22 7997
TEST_TIME 23 8055
TEST_TIME 24 8161
TEST_TIME 25 8206
TEST_TIME 26 8453
TEST_TIME 27 8457
TEST_TIME 28 8359
TEST_TIME 29 8352
TEST_TIME 30 8613
TEST_TIME 31 8919
TEST_TIME 32 8887
TEST_TIME 33 9321
TEST_TIME 34 9511
TEST_TIME 35 10205
TEST_TIME 36 10227
TEST_TIME 37 10149
TEST_TIME 38 9948
TEST_TIME 39 10184
TEST_TIME 40 10175
TEST_TIME 41 10064
TEST_TIME 42 9945
TEST_TIME 43 9874
TEST_TIME 44 9814
TEST_TIME 45 9766
TEST_TIME 46 9728
TEST_TIME 47 9619
TEST_TIME 48 9601
TEST_TIME 49 9578
TEST_TIME 50 9754
TEST_TIME 51 9847
TEST_TIME 52 9735
TEST_TIME 53 9766
TEST_TIME 54 9710
TEST_TIME 55 9596
TEST_TIME 56 9561
TEST_TIME 57 9452
TEST_TIME 58 9445
TEST_TIME 59 9339
TEST_TIME 60 9300
TEST_TIME 61 9283
TEST_TIME 62 9283
TEST_TIME 63 9314
TEST_TIME 64 9326
TEST_TIME 65 9230
TEST_TIME 66 9241
TEST_TIME 67 9182
TEST_TIME 68 9252
TEST_TIME 69 9167
TEST_TIME 70 9202
TEST_TIME 71 9195
TEST_TIME 72 9154
TEST_TIME 73 9098
TEST_TIME 74 9155
TEST_TIME 75 9133
TEST_TIME 76 9105
TEST_TIME 77 9085
TEST_TIME 78 9115
TEST_TIME 79 9174
TEST_TIME 80 9211
TEST_TIME 81 9218
TEST_TIME 82 9171
TEST_TIME 83 9190
TEST_TIME 84 9295
TEST_TIME 85 9256
TEST_TIME 86 9211
TEST_TIME 87 9147
TEST_TIME 88 9118
TEST_TIME 89 9065
TEST_TIME 90 9049
TEST_TIME 91 8995
TEST_TIME 92 8974
TEST_TIME 93 8938
TEST_TIME 94 8911
TEST_TIME 95 8892
TEST_TIME 96 8887
TEST_TIME 97 8902
TEST_TIME 98 8875
TEST_TIME 99 8845
TEST_TIME 100 8856
TEST_TIME 101 8876
TEST_TIME 102 8862
TEST_TIME 103 8868
TEST_TIME 104 8946
TEST_TIME 105 9024
TEST_TIME 106 9053
TEST_TIME 107 9058
TEST_TIME 108 9017
TEST_TIME 109 8966
TEST_TIME 110 8967
TEST_TIME 111 8978
TEST_TIME 112 8953
TEST_TIME 113 8949
TEST_TIME 114 8911
TEST_TIME 115 8926
TEST_TIME 116 8921
TEST_TIME 117 8886
TEST_TIME 118 8840
TEST_TIME 119 8836
TEST_TIME 120 8774

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-10-2022 10:39:17 to 23-10-2022 10:39:20.

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 1460526 1460526 0 0.00 0 32346 14 0 0
doc_list_biud 1460523 1460523 0 0.00 0 34158 45 0 0
sp_add_invoice_to_stock 4955 4777 178 3.59 66 202555 27579 0 178
sp_cancel_adding_invoice 5322 5322 0 0.00 4 79870 1366 0 0
sp_cancel_client_order 1649 1649 0 0.00 6 15673 477 0 0
sp_cancel_customer_reserve 523905 523905 0 0.00 1 34189 107 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 4 14535 174 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 4 3938 136 0 0
sp_cancel_supplier_invoice 6010 5995 15 0.24 2 30615 1084 0 15
sp_cancel_supplier_order 436 436 0 0.00 19 1328156 164294 0 0
sp_cancel_write_off 9034 9034 0 0.00 2 12875 182 0 0
sp_client_order 19836 19836 0 0.00 5 35619 498 0 0
sp_customer_reserve 444251 444251 0 0.00 0 121759 260 0 0
sp_fill_shopping_cart 30212 30212 0 0.00 1 14017 86 0 0
sp_fill_shopping_cart_clo_res 444239 444239 0 0.00 0 17181 22 0 0
sp_get_clo_for_invoice 4732 4732 0 0.00 0 67392 339 0 0
sp_kill_cost_storno 15370 15370 0 0.00 0 32331 85 0 0
sp_kill_qstorno_ret_qs2qd 1063275 1063275 0 0.00 0 30603 47 0 0
sp_kill_qty_storno 560735 560735 0 0.00 0 34154 108 0 0
sp_lock_selected_doc 19867 19867 0 0.00 0 13143 10 0 0
sp_make_cost_storno 26608 26608 0 0.00 0 18454 91 0 0
sp_multiply_rows_for_pdistr 26607 26607 0 0.00 0 9049 42 0 0
sp_multiply_rows_for_qdistr 19836 19836 0 0.00 0 11082 179 0 0
sp_pay_from_customer 6706 6706 0 0.00 5 21729 186 0 0
sp_pay_to_supplier 5508 5508 0 0.00 6 9431 195 0 0
sp_payment_common 12213 12213 0 0.00 2 17089 50 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 1 15664 397 0 0
sp_qd_handle_on_invoice_upd_sts 10053 10053 0 0.00 0 25220 596 0 0
sp_qd_handle_on_reserve_upd_sts 9663 9663 0 0.00 0 26022 66 0 0
sp_reserve_write_off 9663 9663 0 0.00 8 26088 316 0 0
sp_supplier_invoice 5213 5213 0 0.00 9 106845 3305 0 0
sp_supplier_order 5168 5168 0 0.00 7 39151 1342 0 0
srv_aggregate_perf_data 102 102 0 0.00 2053 231178 15970 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 773 141877 33195 0 0
srv_find_qd_qs_mism 1004024 1004024 0 0.00 0 26640 10 0 0
srv_make_invnt_saldo 479 479 0 0.00 32 93151 4204 0 0
srv_make_money_saldo 907 907 0 0.00 1 12052 377 0 0
srv_recalc_idx_stat 4 4 0 0.00 119929 337156 276428 0 0
t$perf-abend:sp_add_invoice_to_stock 178 178 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4777 4777 0 0.00 0 10892 988 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 2 6661 563 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 3583 35 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2241 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 887 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 4306 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1770 37 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 105546 7886 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 1403 23 0 0
t$perf-norm:sp_client_order 19836 19836 0 0.00 0 8657 39 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 2033 18 0 0
t$perf-norm:sp_pay_from_customer 6706 6706 0 0.00 0 13147 21 0 0
t$perf-norm:sp_pay_to_supplier 5508 5508 0 0.00 0 6936 24 0 0
t$perf-norm:sp_reserve_write_off 9663 9663 0 0.00 0 5803 25 0 0
t$perf-norm:sp_supplier_invoice 5213 5213 0 0.00 0 5525 35 0 0
t$perf-norm:sp_supplier_order 5168 5168 0 0.00 0 6272 36 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 479 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 528 14 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 8785 43 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 478 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 199 65 0 0
v_add_invoice_to_stock 9816 9460 356 3.62 0 5458 28 0 356
v_all_customers 36230 36230 0 0.00 0 83 4 0 0
v_all_suppliers 28836 28836 0 0.00 0 74 5 0 0
v_all_wares 39668 39668 0 0.00 0 169 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 157 15 0 0
v_cancel_client_order 3298 3298 0 0.00 0 5487 13 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 2347 14 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2454 16 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 1074 17 0 0
v_cancel_supplier_invoice 816 786 30 3.67 0 485 24 0 30
v_cancel_supplier_order 872 872 0 0.00 0 5726 18 0 0
v_cancel_write_off 1908 1908 0 0.00 0 2707 29 0 0
v_max_id_clo_ord 5168 5168 0 0.00 0 3424 6 0 0
v_max_id_clo_res 978 978 0 0.00 0 1231 11 0 0
v_max_id_ord_sup 5209 5209 0 0.00 0 4436 7 0 0
v_max_non_paid_invoice 1467 1467 0 0.00 0 3202 10 0 0
v_max_non_paid_realizn 3517 3517 0 0.00 0 17079 13 0 0
v_min_id_clo_ord 5168 5168 0 0.00 0 6312 11 0 0
v_min_id_clo_res 978 978 0 0.00 0 3152 13 0 0
v_min_id_ord_sup 5209 5209 0 0.00 0 4209 9 0 0
v_min_non_paid_invoice 5508 5508 0 0.00 0 3995 14 0 0
v_min_non_paid_realizn 6705 6705 0 0.00 0 4302 20 0 0
v_our_firm 9814 9814 0 0.00 0 111 5 0 0
v_reserve_write_off 19326 19326 0 0.00 0 3478 12 0 0
x_lock_depdocs_on_canc_invoice 10924 10924 0 0.00 0 15897 140 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 17371 402 0 0
x_make_qstorno_1000_1200 5168 5168 0 0.00 2 33818 1163 0 0
x_make_qstorno_1200_2000 5213 5213 0 0.00 3 106826 3075 0 0
x_make_qstorno_2100_3300 444251 444251 0 0.00 0 121648 225 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 23-10-2022 10:39:21 to 23-10-2022 10:39:26.

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:25 6 8192000000 8799363456 8810909696 4473472 0.054 2 1 2 0 4572864 63616 35776
08:08:27 15574 8192000000 9709634944 10003865600 794034352 8.836 100 90 180 0 824068848 7752864 22281632
08:09:49 22007 8192000000 9757151056 10064457728 834805728 9.248 100 90 181 0 867417264 11445408 21166128
08:11:14 14483 8192000000 9783285968 10099560448 858926880 9.489 100 86 172 0 897968352 19052176 19989296
08:12:31 7488 8192000000 9841264048 10173939712 894721760 9.846 100 81 162 1 954792928 42348560 17722608
08:13:40 3591 8192000000 9899688944 10236887040 930976640 10.204 100 90 179 1 1006542880 56009184 19557056
08:14:47 2894 8192000000 9959156480 10306355200 965991136 10.548 100 89 168 1 1074466192 91091136 17383920
08:15:53 2116 8192000000 9952179184 10311389184 998094768 10.860 100 80 154 2 1071675312 58555728 15024816
08:16:57 1000 8192000000 10003253232 10369716224 1023335248 11.104 100 89 176 3 1122674176 81808352 17530576
08:17:59 769 8192000000 10018241872 10391171072 1044993392 11.313 100 96 187 1 1137995648 74028352 18973904
08:19:01 1004 8192000000 10031502320 10407280640 1059227936 11.449 100 93 180 0 1151946816 74013232 18705648
08:20:03 2937 8192000000 10031587936 10405183488 1065113856 11.505 100 84 158 0 1147375104 66802144 15459104
08:21:07 1805 8192000000 10032739376 10416676864 1072130720 11.572 100 83 162 0 1152826752 64696736 15999296
08:22:14 1144 8192000000 10046052736 10431299584 1076596208 11.615 100 93 184 1 1169823504 74954976 18272320
08:23:16 1168 8192000000 10042154032 10428628992 1083108704 11.677 100 94 179 1 1160679152 59368288 18202160
08:24:18 1086 8192000000 10064603040 10458030080 1092291104 11.764 100 90 174 1 1188762544 78974736 17496704
08:25:20 1094 8192000000 10070041648 10454171648 1090502256 11.747 100 92 180 1 1186326368 76237776 19586336
08:26:22 1006 8192000000 10047762544 10441965568 1089742096 11.740 100 89 162 2 1170517952 63359088 17416768
08:27:25 1109 8192000000 10028070992 10416058368 1081456416 11.661 100 94 177 3 1154154336 53815696 18882224
08:28:26 3543 8192000000 10034704736 10430615552 1073961808 11.590 99 64 116 5 1144831024 59246208 11623008
08:29:31 7316 8192000000 10018023920 10420711424 1053957344 11.399 97 54 100 2 1120119376 56263552 9898480
08:30:39 7559 8192000000 10008225344 10399395840 1067063952 11.524 99 52 100 1 1129620320 52594032 9962336
08:31:48 4999 8192000000 10022074864 10408009728 1053702592 11.396 99 64 121 1 1147164656 80920208 12541856
08:32:54 1301 8192000000 9997744944 10369302528 1044797568 11.311 100 95 170 0 1122574880 60178096 17599216
08:33:57 1151 8192000000 9977431760 10344415232 1018979584 11.062 99 95 182 2 1098965712 62058128 17928000
08:34:58 5521 8192000000 9997289824 10373066752 1029938368 11.168 99 73 132 1 1111960848 70480912 11541568
08:36:05 1472 8192000000 9988168592 10370179072 1051151392 11.372 100 85 161 3 1117985344 49431936 17402016
08:37:08 1164 8192000000 10010668800 10389835776 1064024496 11.495 100 94 172 3 1134636528 53105312 17506720
08:38:15 21903 8192000000 10043949184 10435829760 1080038800 11.648 100 66 124 2 1156944256 64583424 12322032
08:39:42 28835 8192000000 10060252976 10449108992 1092385136 11.765 100 68 127 0 1182073184 76316144 13371904
08:41:14 3702 8192000000 10111036048 10512441344 1110264768 11.935 100 83 156 1 1233025056 106853312 15906976
08:42:25 7628 8192000000 10127594224 10523922432 1109031232 11.923 99 66 124 1 1234356496 111897392 13427872
08:43:37 5304 8192000000 10184104528 10580692992 1119337968 12.021 100 74 142 0 1301024128 166644512 15041648
08:44:46 3030 8192000000 10197373344 10596065280 1127085392 12.094 100 82 144 4 1309956720 166506016 16365312
08:45:52 1567 8192000000 10194037984 10624647168 1136768528 12.185 100 91 178 1 1308792096 152251824 19771744
08:46:56 4503 8192000000 10169757200 10575933440 1144585152 12.259 100 64 122 1 1283105472 125247152 13273168
08:48:07 1335 8192000000 10231045280 10631983104 1158943808 12.393 100 78 148 0 1341141664 166147744 16050112
08:49:12 1958 8192000000 10251331264 10653196288 1164165504 12.442 100 85 165 1 1367874112 185274800 18433808
08:50:18 1090 8192000000 10296988864 10697887744 1168171376 12.480 100 88 170 1 1404115088 217390928 18552784
08:51:23 1112 8192000000 10334703632 10743689216 1169495488 12.492 100 98 188 2 1440556512 249501088 21559936
08:52:28 1424 8192000000 10382763392 10793586688 1172294912 12.518 100 92 177 1 1490093008 297335616 20462480
08:53:36 918 8192000000 10325938816 10760417280 1158969408 12.394 99 83 157 0 1434659504 258185184 17504912
08:54:42 791 8192000000 10370297664 10807894016 1176036080 12.553 100 96 185 0 1491940016 294679920 21224016
08:55:45 1407 8192000000 10354616336 10816921600 1167311632 12.472 100 93 179 0 1460608800 272424976 20872192
08:56:49 1402 8192000000 10340198608 10776236032 1174827040 12.542 100 97 188 1 1440263040 243903296 21532704
08:57:54 4126 8192000000 10346783680 10770309120 1168847360 12.486 99 60 120 3 1449447584 266614752 13985472
08:59:06 2951 8192000000 10348084656 10778578944 1184518736 12.632 100 80 156 0 1458961120 256608976 17833408
09:00:12 2958 8192000000 10385063232 10806820864 1172362704 12.519 100 67 130 0 1495624944 307946224 15316016
09:01:19 761 8192000000 10382625488 10805948416 1163809296 12.439 100 97 188 0 1487655248 301963872 21882080
09:02:22 808 8192000000 10406756688 10846859264 1179500448 12.586 100 96 181 2 1509491920 309051104 20940368
09:03:26 910 8192000000 10410039744 10877390848 1180831312 12.598 100 98 193 1 1514442592 310883664 22727616
09:04:31 2935 8192000000 10427998656 10846543872 1175794256 12.551 100 68 134 1 1530398336 337495104 17108976
09:05:42 3707 8192000000 10495460256 10922827776 1182794112 12.616 100 67 134 1 1599812368 399701280 17316976
09:07:09 8195 8192000000 10493380240 10950467584 1164295568 12.443 99 61 117 0 1594914928 415278784 15340576
09:08:21 4682 8192000000 10484400096 10968956928 1179177824 12.583 100 56 106 0 1585999888 393125232 13696832
09:09:27 23129 8192000000 10477518032 10943160320 1169789984 12.495 100 38 74 0 1588887952 409310224 9787744
09:10:52 14255 8192000000 10346772864 10849710080 1134631392 12.165 99 27 54 0 1450397840 308660736 7105712
09:12:08 17442 8192000000 10220402176 10745335808 1127991664 12.102 99 37 68 1 1316599664 179899856 8708144
09:13:37 5696 8192000000 10192349600 10627264512 1142495456 12.239 100 49 97 1 1289447168 136806112 10145600
09:14:44 41096 8192000000 10184666944 10646609920 1138784448 12.204 100 48 91 1 1297085600 147775696 10525456
09:16:30 56047 8192000000 10069577312 10590425088 1116192592 11.991 99 13 26 0 1198337504 79518512 2626400
09:19:12 43889 8192000000 10081972496 10532151296 1127278048 12.096 99 23 45 1 1158196432 26416096 4502288
09:20:57 51607 8192000000 10096081904 10561896448 1138679200 12.203 100 28 54 0 1207446976 62682384 6085392
09:23:01 73233 8192000000 10098992992 10548326400 1094086960 11.782 96 10 20 0 1147040992 51007296 1946736
09:25:17 93953 8192000000 10159286480 10645295104 1096266720 11.802 96 15 28 0 1224968384 125432672 3268992
09:28:13 80908 8192000000 10063975248 10497306624 1092649472 11.768 97 9 18 0 1119827600 25512176 1665952
09:30:37 61341 8192000000 10069098576 10543738880 1129451536 12.116 100 19 38 1 1220747504 85561776 5734192
09:32:40 105558 8192000000 10079515824 10500108288 1104118912 11.877 97 51 95 2 1146457024 32494368 9843744
09:35:28 119240 8192000000 10082130608 10528591872 1093366176 11.775 97 18 36 0 1172695584 75349136 3980272
09:38:30 25181 8192000000 10149934816 10574544896 1141810080 12.233 100 60 107 1 1253183744 99540544 11833120
09:39:56 23970 8192000000 10168952832 10629324800 1154390176 12.351 100 36 72 0 1287748352 125691296 7666880
09:41:37 45307 8192000000 10114676544 10594045952 1135023824 12.169 99 15 28 1 1217691296 79401936 3265536
09:43:27 76383 8192000000 10138636784 10595237888 1099034720 11.828 97 15 30 0 1203337712 100544112 3758880
09:45:44 52959 8192000000 10078081776 10521845760 1120031024 12.027 99 20 36 0 1211427424 87849968 3546432
09:47:41 79626 8192000000 10056659552 10582712320 1126821856 12.091 100 9 18 0 1182656528 53918192 1916480
09:50:01 23983 8192000000 10066801600 10504622080 1123240800 12.058 99 34 63 1 1149804368 20841488 5722080
09:51:29 54665 8192000000 10070515040 10557497344 1106832624 11.902 98 18 36 0 1159178720 48854704 3491392
09:53:29 48414 8192000000 10121536000 10555777024 1113403280 11.965 98 39 78 0 1203325328 81946656 7975392
09:55:19 37694 8192000000 10118549456 10559696896 1107956752 11.913 98 30 60 0 1219791712 105746624 6088336
09:57:42 40547 8192000000 10076825056 10538737664 1121899888 12.045 99 18 36 0 1164084368 38421792 3762688
09:59:23 10039 8192000000 10082601616 10496802816 1115878784 11.988 99 51 94 3 1169944576 44616608 9449184
10:00:36 31174 8192000000 10144167664 10613829632 1129763888 12.119 100 32 64 0 1243436480 107069408 6603184
10:02:16 36528 8192000000 10107956128 10552475648 1133321024 12.153 100 21 42 1 1182891200 45248416 4321760
10:04:02 91568 8192000000 10077192336 10581164032 1139456432 12.210 100 14 28 0 1210252848 67895232 2901184
10:06:48 68289 8192000000 10059432416 10531643392 1100469600 11.842 97 12 24 0 1139435072 36665552 2299920
10:08:56 43437 8192000000 10030620416 10504798208 1081049728 11.657 96 4 9 0 1110433712 28823872 560112
10:10:42 43903 8192000000 10021949936 10487074816 1103362928 11.870 99 8 11 0 1139621728 35736496 522304
10:12:27 34830 8192000000 10063804000 10509176832 1115320256 11.983 99 27 54 0 1167057552 45922416 5814880
10:14:07 50796 8192000000 10098491616 10545745920 1131651200 12.137 100 28 54 1 1192037328 54775200 5610928
10:16:02 34595 8192000000 10127551440 10556104704 1139511920 12.211 99 41 82 0 1213368880 64909024 8947936
10:17:38 20302 8192000000 10176819584 10612604928 1141022928 12.225 99 40 78 0 1261584544 112751184 7810432
10:19:00 31086 8192000000 10134559840 10584420352 1117369056 12.002 98 31 62 0 1233202768 108963408 6870304
10:20:33 22180 8192000000 10195618384 10641358848 1119906816 12.026 98 41 81 0 1273439216 144834128 8698272
10:21:59 73982 8192000000 10085261712 10620108800 1108809728 11.921 97 17 34 0 1243535632 130883136 3842768
10:24:25 40997 8192000000 10122314720 10573090816 1109432496 11.927 98 29 56 0 1162652128 47641824 5577808
10:26:14 141876 8192000000 10057308304 10551615488 1118746960 12.015 99 29 58 1 1238148736 112685008 6716768
10:29:42 51854 8192000000 10117514320 10592198656 1140400768 12.219 100 33 65 0 1229085616 81918960 6765888
10:31:35 67630 8192000000 10080464288 10564067328 1130670048 12.128 99 14 26 0 1199184112 65140192 3373872
10:33:44 84170 8192000000 10065749456 10531426304 1100092704 11.839 97 6 12 0 1125293840 24323104 878032
10:36:08 8020 8192000000 10082846224 10529775616 1126648192 12.090 100 42 80 1 1185878496 50674192 8556112
10:37:17 19582 8192000000 9124331648 9396060160 281281728 3.319 26 1 2 0 288186144 6869008 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-10-2022 10:39:28 to 23-10-2022 10:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 178 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2022 10:39:45 to 23-10-2022 10:39:47.

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          215561
MON$OLDEST_ACTIVE               215562
MON$OLDEST_SNAPSHOT             215562
MON$NEXT_TRANSACTION            215562
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                       2083936
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 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 ---
:767: 
:768: mscs-test-161.budzdorov.ru    Sun Oct 23 10:37:49 2022
:769:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:770: 
:771: 
:772: mscs-test-161.budzdorov.ru    Sun Oct 23 10:37:49 2022
:773:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:774: 
:775: 
:776: mscs-test-161.budzdorov.ru    Sun Oct 23 10:37:49 2022
:777:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:778: 
:779: 
:780: mscs-test-161.budzdorov.ru    Sun Oct 23 10:37:49 2022
:781:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:782: 
:783: 
:784: mscs-test-161.budzdorov.ru    Sun Oct 23 10:37:49 2022
:785:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:786: 
--- end of diff output ---

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

23.10.22 10:39:48. 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-10-23 10:39:51 No severe PSQL-related problems occured
23.10.22 10:39:52. End of report.