Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 14947828 kB
MemAvailable 30554128 kB
Buffers 0 kB
Cached 16781116 kB
SwapCached 2108 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-11 10:37:22
DTS_END                         2022-10-11 10:37:22.9670
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/34290
TRN_ID                          210671
ATT_ID                          653
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: 8842.56 32063 1061107
customer order: creation 155.45 461 18654
customer order: refuse 13.45 470 1614
order to supplier: creation 41.62 1309 4994
order to supplier: removal 3.19 177353 383
invoice (draft): creation 43.24 3278 5189
invoice (draft): removal 50.33 1072 6039
invoice accept: apply 39.63 27292 4755
invoice accept: cancel 45.49 1319 5459
customer reserve: creation 3738.34 253 448601
customer reserve: removal 4425.92 99 531110
realization accept: apply 77.93 290 9352
realization accept: cancel 88.27 149 10592
payment from customer: creation 54.84 192 6581
payment from customer: removal 5.86 136 703
payment to supplier: creation 44.41 218 5329
payment to supplier: removal 3.21 158 385
service: total inventory turnovers 3.23 5016 387
service: total monetary turnovers 7.24 374 869
service: aggregate perf. data 0.88 19948 105
service: refresh index statistics 0.05 401873 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2022 10:39:10 to 11-10-2022 10:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6027
TEST_TIME 2 5953
TEST_TIME 3 5707
TEST_TIME 4 5481
TEST_TIME 5 5389
TEST_TIME 6 5697
TEST_TIME 7 6261
TEST_TIME 8 6338
TEST_TIME 9 6509
TEST_TIME 10 6593
TEST_TIME 11 6720
TEST_TIME 12 6803
TEST_TIME 13 6939
TEST_TIME 14 7086
TEST_TIME 15 7185
TEST_TIME 16 7303
TEST_TIME 17 7359
TEST_TIME 18 7456
TEST_TIME 19 7819
TEST_TIME 20 8030
TEST_TIME 21 7946
TEST_TIME 22 7968
TEST_TIME 23 8303
TEST_TIME 24 8455
TEST_TIME 25 8517
TEST_TIME 26 8573
TEST_TIME 27 8369
TEST_TIME 28 8313
TEST_TIME 29 8634
TEST_TIME 30 8855
TEST_TIME 31 9188
TEST_TIME 32 9655
TEST_TIME 33 10158
TEST_TIME 34 10079
TEST_TIME 35 10184
TEST_TIME 36 10156
TEST_TIME 37 9925
TEST_TIME 38 10128
TEST_TIME 39 10339
TEST_TIME 40 10630
TEST_TIME 41 10570
TEST_TIME 42 10378
TEST_TIME 43 10196
TEST_TIME 44 10158
TEST_TIME 45 10165
TEST_TIME 46 10128
TEST_TIME 47 10132
TEST_TIME 48 10085
TEST_TIME 49 10273
TEST_TIME 50 10157
TEST_TIME 51 10006
TEST_TIME 52 9916
TEST_TIME 53 9780
TEST_TIME 54 9681
TEST_TIME 55 9564
TEST_TIME 56 9506
TEST_TIME 57 9492
TEST_TIME 58 9422
TEST_TIME 59 9390
TEST_TIME 60 9485
TEST_TIME 61 9409
TEST_TIME 62 9413
TEST_TIME 63 9367
TEST_TIME 64 9288
TEST_TIME 65 9214
TEST_TIME 66 9153
TEST_TIME 67 9246
TEST_TIME 68 9221
TEST_TIME 69 9261
TEST_TIME 70 9189
TEST_TIME 71 9251
TEST_TIME 72 9228
TEST_TIME 73 9240
TEST_TIME 74 9268
TEST_TIME 75 9266
TEST_TIME 76 9245
TEST_TIME 77 9289
TEST_TIME 78 9323
TEST_TIME 79 9392
TEST_TIME 80 9446
TEST_TIME 81 9343
TEST_TIME 82 9311
TEST_TIME 83 9270
TEST_TIME 84 9209
TEST_TIME 85 9213
TEST_TIME 86 9127
TEST_TIME 87 9056
TEST_TIME 88 9088
TEST_TIME 89 9025
TEST_TIME 90 9102
TEST_TIME 91 9158
TEST_TIME 92 9128
TEST_TIME 93 9114
TEST_TIME 94 9144
TEST_TIME 95 9228
TEST_TIME 96 9218
TEST_TIME 97 9227
TEST_TIME 98 9226
TEST_TIME 99 9223
TEST_TIME 100 9234
TEST_TIME 101 9287
TEST_TIME 102 9241
TEST_TIME 103 9211
TEST_TIME 104 9140
TEST_TIME 105 9163
TEST_TIME 106 9133
TEST_TIME 107 9078
TEST_TIME 108 9047
TEST_TIME 109 9037
TEST_TIME 110 8980
TEST_TIME 111 8959
TEST_TIME 112 8977
TEST_TIME 113 8958
TEST_TIME 114 8951
TEST_TIME 115 8952
TEST_TIME 116 8970
TEST_TIME 117 8940
TEST_TIME 118 8938
TEST_TIME 119 8918
TEST_TIME 120 8873

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2022 10:39:13 to 11-10-2022 10:39:17.

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 1473266 1473266 0 0.00 0 18101 13 0 0
doc_list_biud 1473261 1473261 0 0.00 0 22860 42 0 0
sp_add_invoice_to_stock 4904 4755 149 3.03 43 206221 27293 0 149
sp_cancel_adding_invoice 5459 5459 0 0.00 7 91569 1319 0 0
sp_cancel_client_order 1614 1614 0 0.00 6 9660 471 0 0
sp_cancel_customer_reserve 531110 531110 0 0.00 1 22894 99 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 3 3182 137 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 3694 159 0 0
sp_cancel_supplier_invoice 6055 6039 16 0.26 3 21935 1073 0 16
sp_cancel_supplier_order 383 383 0 0.00 13 1545810 177354 0 0
sp_cancel_write_off 10592 10592 0 0.00 2 12803 149 0 0
sp_client_order 18654 18654 0 0.00 6 23999 462 0 0
sp_customer_reserve 448601 448601 0 0.00 0 100331 253 0 0
sp_fill_shopping_cart 28826 28826 0 0.00 1 12203 86 0 0
sp_fill_shopping_cart_clo_res 448557 448557 0 0.00 0 12907 19 0 0
sp_get_clo_for_invoice 4700 4700 0 0.00 0 27640 290 0 0
sp_kill_cost_storno 17139 17139 0 0.00 0 18086 76 0 0
sp_kill_qstorno_ret_qs2qd 1079234 1079234 0 0.00 0 22858 44 0 0
sp_kill_qty_storno 569248 569248 0 0.00 0 22860 101 0 0
sp_lock_selected_doc 19475 19475 0 0.00 0 7533 7 0 0
sp_make_cost_storno 25961 25961 0 0.00 0 11354 90 0 0
sp_multiply_rows_for_pdistr 25961 25961 0 0.00 0 6435 40 0 0
sp_multiply_rows_for_qdistr 18653 18653 0 0.00 0 12345 162 0 0
sp_pay_from_customer 6581 6581 0 0.00 5 8867 192 0 0
sp_pay_to_supplier 5329 5329 0 0.00 6 10251 218 0 0
sp_payment_common 11910 11910 0 0.00 2 7225 53 0 0
sp_qd_handle_on_cancel_clo 1614 1614 0 0.00 0 9646 379 0 0
sp_qd_handle_on_invoice_upd_sts 10158 10158 0 0.00 0 16746 598 0 0
sp_qd_handle_on_reserve_upd_sts 9352 9352 0 0.00 0 17632 63 0 0
sp_reserve_write_off 9352 9352 0 0.00 9 17695 290 0 0
sp_supplier_invoice 5189 5189 0 0.00 9 78963 3278 0 0
sp_supplier_order 4994 4994 0 0.00 7 39872 1309 0 0
srv_aggregate_perf_data 105 105 0 0.00 2247 190037 19949 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 730 152082 35634 0 0
srv_find_qd_qs_mism 1014768 1014768 0 0.00 0 13963 10 0 0
srv_make_invnt_saldo 387 387 0 0.00 83 69420 5017 0 0
srv_make_money_saldo 869 869 0 0.00 1 16731 374 0 0
srv_recalc_idx_stat 6 6 0 0.00 307021 568525 401873 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4759 4759 0 0.00 0 12220 1060 0 0
t$perf-norm:sp_cancel_adding_invoice 384 384 0 0.00 1 6287 516 0 0
t$perf-norm:sp_cancel_client_order 1614 1614 0 0.00 0 2093 31 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 1224 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 867 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 413 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3193 59 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 98060 8667 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 540 18 0 0
t$perf-norm:sp_client_order 18654 18654 0 0.00 0 12090 42 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 1506 17 0 0
t$perf-norm:sp_pay_from_customer 6581 6581 0 0.00 0 12028 22 0 0
t$perf-norm:sp_pay_to_supplier 5329 5329 0 0.00 0 9330 22 0 0
t$perf-norm:sp_reserve_write_off 9352 9352 0 0.00 0 7714 27 0 0
t$perf-norm:sp_supplier_invoice 5189 5189 0 0.00 0 5974 34 0 0
t$perf-norm:sp_supplier_order 4994 4994 0 0.00 0 9849 35 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 269 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 507 11 0 0
t$perf-norm:srv_make_invnt_saldo 387 387 0 0.00 0 1237 15 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 2513 6 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 285 112 0 0
v_add_invoice_to_stock 9694 9396 298 3.07 0 8186 31 0 298
v_all_customers 34250 34250 0 0.00 0 219 4 0 0
v_all_suppliers 28332 28332 0 0.00 0 227 5 0 0
v_all_wares 37306 37306 0 0.00 0 144 4 0 0
v_cancel_adding_invoice 768 768 0 0.00 0 1963 17 0 0
v_cancel_client_order 3228 3228 0 0.00 0 5308 16 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3117 15 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 1 1585 17 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 3179 18 0 0
v_cancel_supplier_invoice 840 808 32 3.80 0 919 29 0 32
v_cancel_supplier_order 766 766 0 0.00 0 2096 15 0 0
v_cancel_write_off 1874 1874 0 0.00 0 4307 28 0 0
v_max_id_clo_ord 4994 4994 0 0.00 0 327 5 0 0
v_max_id_clo_res 975 975 0 0.00 0 2540 14 0 0
v_max_id_ord_sup 5179 5179 0 0.00 0 4532 9 0 0
v_max_non_paid_invoice 1336 1336 0 0.00 0 84 9 0 0
v_max_non_paid_realizn 3528 3528 0 0.00 0 4553 11 0 0
v_min_id_clo_ord 4994 4994 0 0.00 0 5218 12 0 0
v_min_id_clo_res 975 975 0 0.00 0 4221 15 0 0
v_min_id_ord_sup 5179 5179 0 0.00 0 3936 7 0 0
v_min_non_paid_invoice 5329 5329 0 0.00 0 4894 17 0 0
v_min_non_paid_realizn 6581 6581 0 0.00 0 7219 25 0 0
v_our_firm 9162 9162 0 0.00 0 108 5 0 0
v_reserve_write_off 18704 18704 0 0.00 0 5427 15 0 0
x_lock_depdocs_on_canc_invoice 11094 11094 0 0.00 0 14430 141 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 3617 398 0 0
x_make_qstorno_1000_1200 4994 4994 0 0.00 2 39854 1125 0 0
x_make_qstorno_1200_2000 5189 5189 0 0.00 4 72016 3051 0 0
x_make_qstorno_2100_3300 448601 448601 0 0.00 0 100264 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2022 10:39:18 to 11-10-2022 10:39:22.

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:26 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 22489 8192000000 9727291472 10027872256 795590272 8.852 100 84 168 0 824051952 7691584 20770096
08:09:57 21741 8192000000 9772043216 10079604736 847303680 9.373 100 91 182 0 880675888 11848032 21524176
08:11:24 13478 8192000000 9804729696 10123960320 872864720 9.629 100 86 172 0 910972272 18103056 20004496
08:12:49 8818 8192000000 9863205008 10194501632 918831376 10.085 100 78 156 1 971650128 36461920 16356832
08:13:59 2793 8192000000 9908234864 10253791232 954878176 10.439 100 81 160 1 1028117648 56803648 16435824
08:15:06 4458 8192000000 9977939696 10330095616 986959312 10.752 100 83 164 1 1087869856 84821664 16088880
08:16:13 3578 8192000000 9984151168 10345009152 1007770048 10.954 100 80 152 0 1104366368 81721184 14875136
08:17:19 1009 8192000000 9997068656 10365640704 1036614304 11.232 100 94 182 1 1117513264 62283232 18615728
08:18:23 1594 8192000000 10015593696 10386292736 1054222192 11.401 100 89 171 1 1131408288 59892160 17293936
08:19:27 1086 8192000000 10048080608 10429587456 1069280320 11.545 100 95 184 2 1175750352 87863024 18607008
08:20:29 1556 8192000000 10062466816 10442936320 1075103632 11.601 100 92 179 2 1186864144 94160816 17599696
08:21:32 1118 8192000000 10067389520 10449690624 1072790576 11.579 100 92 180 0 1186509680 95531552 18187552
08:22:36 1205 8192000000 10050968560 10436743168 1077373056 11.622 100 92 178 4 1172119984 75879872 18867056
08:23:38 1266 8192000000 10039728464 10420912128 1079661648 11.644 100 96 186 2 1160072816 62076656 18334512
08:24:40 1001 8192000000 10044400112 10431926272 1086703600 11.711 100 91 168 0 1168008384 62443248 18861536
08:25:43 1338 8192000000 10047265312 10439356416 1090321584 11.746 100 82 156 0 1171891472 66025728 15544160
08:26:45 1012 8192000000 10067087376 10458820608 1091499024 11.757 100 87 165 0 1191815168 83021008 17295136
08:27:47 1144 8192000000 10060941920 10449141760 1093334976 11.774 100 93 180 1 1183910384 72732928 17842480
08:28:51 5882 8192000000 10040017760 10433540096 1090606096 11.748 100 56 103 0 1168266560 67446224 10214240
08:29:59 1112 8192000000 10035057552 10426097664 1089660624 11.739 100 86 169 2 1161585456 54514832 17410000
08:31:02 1124 8192000000 10021035360 10399924224 1075884880 11.608 100 94 182 0 1141272480 46585712 18801888
08:32:04 1115 8192000000 10030646112 10417491968 1071657856 11.568 100 86 170 2 1153680992 64648176 17374960
08:33:07 6255 8192000000 9984196016 10370035712 1030928656 11.177 98 65 130 0 1113873888 69308048 13637184
08:34:15 877 8192000000 9974877552 10348859392 1029967936 11.168 98 90 169 4 1097862832 50274176 17620720
08:35:17 3026 8192000000 9940515168 10305384448 999112880 10.870 96 69 129 5 1062861536 50361248 13387408
08:36:21 1913 8192000000 9959864704 10336866304 1033116880 11.198 100 71 136 0 1088235424 41426224 13692320
08:37:24 17584 8192000000 9982555264 10368569344 1036439600 11.230 100 64 126 2 1100696608 51746016 12510992
08:38:53 1685 8192000000 10027400080 10404753408 1051804448 11.378 100 88 164 0 1152616992 83071424 17741120
08:40:00 3473 8192000000 10005625504 10386456576 1061775024 11.473 100 73 137 1 1131437616 55672880 13989712
08:41:08 3355 8192000000 10065949216 10452193280 1069044768 11.543 100 74 137 1 1178584544 95867312 13672464
08:42:18 3548 8192000000 10120911504 10500702208 1085202400 11.697 100 81 152 1 1229063568 129401824 14459344
08:43:28 8537 8192000000 10148129984 10535710720 1093818400 11.779 100 79 149 1 1270445376 159369056 17257920
08:44:42 8267 8192000000 10122953120 10516475904 1113438208 11.965 100 71 134 1 1242178432 114739936 14000288
08:45:59 1730 8192000000 10189960608 10577989632 1127966176 12.102 100 79 153 2 1303044544 158831664 16246704
08:47:03 728 8192000000 10215696032 10619269120 1149557616 12.305 100 97 192 1 1326102704 155731536 20813552
08:48:08 2237 8192000000 10236261888 10647199744 1157065840 12.376 100 90 170 2 1353481504 177675456 18740208
08:49:15 1572 8192000000 10271297744 10670927872 1168282464 12.481 100 86 169 0 1382517152 196009888 18224800
08:50:19 1083 8192000000 10306268048 10719797248 1182453888 12.613 100 94 185 3 1419576672 215864800 21257984
08:51:24 1697 8192000000 10326683184 10740871168 1182433936 12.613 100 93 185 1 1437155808 234585456 20136416
08:52:32 858 8192000000 10381823696 10782830592 1194797968 12.728 100 95 187 0 1494475008 278009520 21667520
08:53:35 744 8192000000 10409673056 10821054464 1187735024 12.662 100 95 185 1 1517130832 308407184 20988624
08:54:39 770 8192000000 10436017152 10853195776 1196419616 12.743 100 98 190 0 1545835600 326731680 22684304
08:55:43 1008 8192000000 10455329280 10885419008 1194832976 12.728 100 93 182 0 1563640976 347892768 20915232
08:56:46 959 8192000000 10432763328 10868224000 1197656592 12.755 100 93 184 0 1537801088 318184528 21959968
08:57:52 842 8192000000 10443963584 10854653952 1195306096 12.733 100 99 192 1 1548171024 329360704 23504224
08:58:57 2004 8192000000 10488575824 10900766720 1199533968 12.772 100 96 181 0 1588809664 368596592 20679104
09:00:02 2972 8192000000 10459577872 10907250688 1171226176 12.508 100 82 157 1 1563513232 373535728 18751328
09:01:10 2448 8192000000 10439461936 10884075520 1173626384 12.531 99 85 164 0 1531464880 337387088 20451408
09:02:18 8574 8192000000 10448713120 10890682368 1179928112 12.590 100 47 93 1 1551519600 359677088 11914400
09:03:29 13743 8192000000 10458504176 10911707136 1173560448 12.530 99 33 66 0 1555712464 373555760 8596256
09:04:48 12399 8192000000 10525065984 10965172224 1176617312 12.559 100 46 92 0 1636820208 448533872 11669024
09:06:11 4409 8192000000 10515289856 10991702016 1166076064 12.460 100 51 95 0 1620088912 441014848 12998000
09:07:21 3511 8192000000 10426746144 10929958912 1155990832 12.366 100 68 136 0 1528822112 356439568 16391712
09:08:28 3226 8192000000 10333762240 10854576128 1144822272 12.261 100 78 155 0 1441692368 277259168 19610928
09:09:33 6010 8192000000 10292711648 10785546240 1152543872 12.333 100 52 102 1 1411701712 246360368 12797472
09:10:42 12976 8192000000 10218221792 10669756416 1131606016 12.136 99 32 65 0 1331016240 191387056 8023168
09:12:12 23935 8192000000 10230187776 10698387456 1126336496 12.087 99 31 60 0 1346677088 213038032 7302560
09:13:40 38140 8192000000 10236352272 10663415808 1111583056 11.947 98 36 70 1 1317587792 196736192 9268544
09:15:40 34913 8192000000 10231950304 10674073600 1130313408 12.124 100 29 56 0 1323918320 187030848 6574064
09:17:40 117072 8192000000 10124085856 10628104192 1117223152 12.001 99 8 16 0 1188023424 69128544 1671728
09:20:38 80860 8192000000 10142074272 10611310592 1113627808 11.967 98 16 32 1 1221469120 104385776 3455536
09:23:00 36712 8192000000 10087204928 10517798912 1128192112 12.104 100 21 41 0 1182381424 50952848 3236464
09:24:48 152082 8192000000 10070514240 10603585536 1096529552 11.805 96 16 29 0 1232691488 133447856 2714080
09:28:21 16255 8192000000 10074437376 10499534848 1140427904 12.220 100 57 111 0 1169444688 18185392 10831392
09:29:43 91443 8192000000 10081830448 10521014272 1142100720 12.235 100 29 51 2 1165823440 18311792 5410928
09:32:19 65081 8192000000 10095030768 10519113728 1147161504 12.283 100 83 150 9 1183763824 23182320 13420000
09:34:25 24551 8192000000 10124121392 10571776000 1149326320 12.303 100 39 78 0 1219315056 61488528 8500208
09:35:56 14903 8192000000 10140040112 10576367616 1124182096 12.066 99 65 128 1 1222348592 83979696 14186800
09:37:17 37390 8192000000 10115624320 10605150208 1136743728 12.185 100 35 65 0 1222140208 78450960 6945520
09:38:57 55428 8192000000 10090581680 10586566656 1122081952 12.047 98 15 28 0 1206475376 81072560 3320864
09:40:53 24268 8192000000 10084009136 10563080192 1128688656 12.109 99 11 23 0 1195047120 63890352 2468112
09:43:02 73901 8192000000 10128732000 10614714368 1137744560 12.194 100 18 36 0 1221467600 80086752 3636288
09:45:31 86605 8192000000 10095910176 10585395200 1124055776 12.065 99 26 51 0 1165501264 36027152 5418336
09:48:03 99540 8192000000 10090209664 10535858176 1122394208 12.050 99 20 40 0 1190123344 63202880 4526256
09:50:43 76750 8192000000 10104146576 10561269760 1124841904 12.073 99 23 46 0 1200526768 70998512 4686352
09:53:00 78745 8192000000 10153289552 10602831872 1119081248 12.018 98 17 34 0 1218502704 95716640 3704816
09:56:10 86809 8192000000 10078385120 10574610432 1134520848 12.164 100 16 32 0 1216191264 78377392 3293024
09:58:39 102009 8192000000 10121087760 10571288576 1075084560 11.601 94 35 70 0 1132775312 50851936 6838816
10:01:23 9985 8192000000 10110724224 10571853824 1128334384 12.106 99 31 60 1 1205226656 70921856 5970416
10:02:34 58642 8192000000 10118832816 10595250176 1127889216 12.101 98 18 36 0 1200231856 68608272 3734368
10:04:37 79103 8192000000 10137326960 10641342464 1143387696 12.247 100 22 44 0 1266404624 117784112 5232816
10:07:00 55460 8192000000 10096041216 10604855296 1096090352 11.801 97 14 28 0 1211113264 111906816 3116096
10:08:57 38324 8192000000 10075837088 10511855616 1102846064 11.865 98 43 78 4 1152697936 41498784 8353088
10:10:38 30210 8192000000 10126018016 10578972672 1119572464 12.023 99 16 31 0 1200501552 77713824 3215264
10:12:30 65537 8192000000 10126239616 10583244800 1086966864 11.714 96 12 24 0 1194606032 104832976 2806192
10:14:39 106342 8192000000 10115507392 10573697024 1074405216 11.594 95 25 50 0 1140342512 60953200 4984096
10:17:27 43505 8192000000 10117379216 10554478592 1100428416 11.842 98 38 76 0 1185178704 76841584 7908704
10:19:16 21150 8192000000 10119501696 10540224512 1128595392 12.108 100 57 116 2 1188721728 48875200 11251136
10:20:38 89372 8192000000 10100946960 10576736256 1097397440 11.813 97 31 62 0 1196208224 92573648 6237136
10:23:09 60289 8192000000 10039895792 10515894272 1098252496 11.821 98 9 18 0 1150128768 49545952 2330320
10:25:13 75487 8192000000 10076167088 10547793920 1118597008 12.014 99 16 32 0 1169598736 46965984 4035744
10:27:29 83557 8192000000 10095981856 10583343104 1134504144 12.164 100 14 28 0 1217179088 79403232 3271712
10:29:53 48662 8192000000 10078806864 10541064192 1141347888 12.228 100 16 30 0 1183846816 39766448 2732480
10:31:50 21702 8192000000 10114202864 10565378048 1138351696 12.200 100 37 74 0 1218997840 73236944 7409200
10:33:12 44132 8192000000 10133691872 10597527552 1145536816 12.268 99 30 59 0 1222368032 69193920 7637296
10:34:58 31387 8192000000 10206053024 10651238400 1148567376 12.296 99 42 83 0 1304266528 146815504 8883648
10:36:33 16497 8192000000 10238237104 10677735424 1150802688 12.317 99 44 85 2 1319658288 159905728 8949872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-10-2022 10:39:24 to 11-10-2022 10:39:40.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2022 10:39:41 to 11-10-2022 10:39:43.

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          200333
MON$OLDEST_ACTIVE               211394
MON$OLDEST_SNAPSHOT             211394
MON$NEXT_TRANSACTION            211394
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                       2102528
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2858 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2858 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2858 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2858 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Oct 11 10:37:38 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

11.10.22 10:39:44. 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-11 10:39:46 No severe PSQL-related problems occured
11.10.22 10:39:48. End of report.