Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.01.2023 13:39
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 15046444 kB
MemAvailable 30401832 kB
Buffers 0 kB
Cached 16685768 kB
SwapCached 4552 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 2023-01-06 13:37:23
DTS_END                         2023-01-06 13:37:23.1130
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/47492
TRN_ID                          226718
ATT_ID                          618
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: 9055.25 22902 1086630
customer order: creation 178.98 462 21478
customer order: refuse 14.97 377 1796
order to supplier: creation 48.62 1228 5834
order to supplier: removal 3.37 182044 404
invoice (draft): creation 46.40 2946 5568
invoice (draft): removal 48.14 1124 5777
invoice accept: apply 43.26 27594 5191
invoice accept: cancel 42.88 1276 5146
customer reserve: creation 4081.90 260 489828
customer reserve: removal 4250.58 111 510069
realization accept: apply 88.33 256 10600
realization accept: cancel 70.58 177 8470
payment from customer: creation 62.71 154 7525
payment from customer: removal 5.79 143 695
payment to supplier: creation 51.81 173 6217
payment to supplier: removal 3.56 203 427
service: total inventory turnovers 4.24 3864 509
service: total monetary turnovers 8.21 341 985
service: aggregate perf. data 0.88 15671 106
service: refresh index statistics 0.04 219645 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-01-2023 13:39:28 to 06-01-2023 13:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10465
TEST_TIME 2 8040
TEST_TIME 3 6962
TEST_TIME 4 7219
TEST_TIME 5 7432
TEST_TIME 6 6760
TEST_TIME 7 6975
TEST_TIME 8 6570
TEST_TIME 9 6535
TEST_TIME 10 6499
TEST_TIME 11 6619
TEST_TIME 12 6640
TEST_TIME 13 6746
TEST_TIME 14 6679
TEST_TIME 15 6885
TEST_TIME 16 6782
TEST_TIME 17 6857
TEST_TIME 18 7079
TEST_TIME 19 7077
TEST_TIME 20 7135
TEST_TIME 21 7420
TEST_TIME 22 7798
TEST_TIME 23 7702
TEST_TIME 24 7679
TEST_TIME 25 7925
TEST_TIME 26 8279
TEST_TIME 27 8770
TEST_TIME 28 8668
TEST_TIME 29 9370
TEST_TIME 30 10207
TEST_TIME 31 10236
TEST_TIME 32 10869
TEST_TIME 33 11232
TEST_TIME 34 11202
TEST_TIME 35 11108
TEST_TIME 36 11101
TEST_TIME 37 10922
TEST_TIME 38 10803
TEST_TIME 39 10707
TEST_TIME 40 10525
TEST_TIME 41 10401
TEST_TIME 42 10525
TEST_TIME 43 10457
TEST_TIME 44 10290
TEST_TIME 45 10186
TEST_TIME 46 10258
TEST_TIME 47 10188
TEST_TIME 48 10186
TEST_TIME 49 10078
TEST_TIME 50 9993
TEST_TIME 51 9830
TEST_TIME 52 9813
TEST_TIME 53 9826
TEST_TIME 54 9737
TEST_TIME 55 9739
TEST_TIME 56 9723
TEST_TIME 57 9649
TEST_TIME 58 9635
TEST_TIME 59 9579
TEST_TIME 60 9635
TEST_TIME 61 9664
TEST_TIME 62 9649
TEST_TIME 63 9635
TEST_TIME 64 9656
TEST_TIME 65 9675
TEST_TIME 66 9696
TEST_TIME 67 9675
TEST_TIME 68 9749
TEST_TIME 69 9665
TEST_TIME 70 9588
TEST_TIME 71 9607
TEST_TIME 72 9581
TEST_TIME 73 9498
TEST_TIME 74 9518
TEST_TIME 75 9453
TEST_TIME 76 9421
TEST_TIME 77 9363
TEST_TIME 78 9334
TEST_TIME 79 9360
TEST_TIME 80 9333
TEST_TIME 81 9401
TEST_TIME 82 9402
TEST_TIME 83 9370
TEST_TIME 84 9334
TEST_TIME 85 9378
TEST_TIME 86 9351
TEST_TIME 87 9475
TEST_TIME 88 9503
TEST_TIME 89 9571
TEST_TIME 90 9512
TEST_TIME 91 9466
TEST_TIME 92 9454
TEST_TIME 93 9416
TEST_TIME 94 9382
TEST_TIME 95 9313
TEST_TIME 96 9302
TEST_TIME 97 9287
TEST_TIME 98 9256
TEST_TIME 99 9256
TEST_TIME 100 9244
TEST_TIME 101 9222
TEST_TIME 102 9215
TEST_TIME 103 9201
TEST_TIME 104 9209
TEST_TIME 105 9194
TEST_TIME 106 9227
TEST_TIME 107 9396
TEST_TIME 108 9355
TEST_TIME 109 9345
TEST_TIME 110 9374
TEST_TIME 111 9306
TEST_TIME 112 9310
TEST_TIME 113 9289
TEST_TIME 114 9266
TEST_TIME 115 9230
TEST_TIME 116 9192
TEST_TIME 117 9135
TEST_TIME 118 9161
TEST_TIME 119 9133
TEST_TIME 120 9073

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-01-2023 13:39:31 to 06-01-2023 13:39:35.

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 1533873 1533873 0 0.00 0 14893 15 0 0
doc_list_biud 1533860 1533860 0 0.00 0 91496 43 0 0
sp_add_invoice_to_stock 5315 5191 124 2.33 47 246625 27594 0 124
sp_cancel_adding_invoice 5146 5146 0 0.00 6 81534 1277 0 0
sp_cancel_client_order 1796 1796 0 0.00 6 7081 377 0 0
sp_cancel_customer_reserve 510069 510069 0 0.00 1 75834 112 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 7679 143 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 4 14731 203 0 0
sp_cancel_supplier_invoice 5787 5777 10 0.17 3 91545 1125 0 10
sp_cancel_supplier_order 404 404 0 0.00 10 1543374 182044 0 0
sp_cancel_write_off 8470 8470 0 0.00 2 9392 177 0 0
sp_client_order 21478 21478 0 0.00 6 27775 463 0 0
sp_customer_reserve 489828 489828 0 0.00 1 71271 261 0 0
sp_fill_shopping_cart 32870 32870 0 0.00 1 34919 83 0 0
sp_fill_shopping_cart_clo_res 489793 489793 0 0.00 0 19897 19 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 42945 268 0 0
sp_kill_cost_storno 14738 14738 0 0.00 0 11312 80 0 0
sp_kill_qstorno_ret_qs2qd 1034789 1034789 0 0.00 0 91492 48 0 0
sp_kill_qty_storno 547406 547406 0 0.00 0 91495 111 0 0
sp_lock_selected_doc 21569 21569 0 0.00 0 9718 9 0 0
sp_make_cost_storno 29488 29488 0 0.00 0 14888 68 0 0
sp_multiply_rows_for_pdistr 29488 29488 0 0.00 0 6793 33 0 0
sp_multiply_rows_for_qdistr 21478 21478 0 0.00 0 14568 188 0 0
sp_pay_from_customer 7525 7525 0 0.00 5 6763 155 0 0
sp_pay_to_supplier 6217 6217 0 0.00 6 8453 173 0 0
sp_payment_common 13742 13742 0 0.00 2 8439 42 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 7057 303 0 0
sp_qd_handle_on_invoice_upd_sts 10292 10292 0 0.00 0 46265 559 0 0
sp_qd_handle_on_reserve_upd_sts 10598 10598 0 0.00 0 19344 59 0 0
sp_reserve_write_off 10600 10600 0 0.00 9 19521 256 0 0
sp_supplier_invoice 5568 5568 0 0.00 9 126222 2947 0 0
sp_supplier_order 5834 5834 0 0.00 8 45241 1229 0 0
srv_aggregate_perf_data 106 106 0 0.00 2715 123638 15671 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 713 157578 32685 0 0
srv_find_qd_qs_mism 1033216 1033216 0 0.00 0 11019 12 0 0
srv_make_invnt_saldo 509 509 0 0.00 80 76860 3865 0 0
srv_make_money_saldo 985 985 0 0.00 1 11686 341 0 0
srv_recalc_idx_stat 5 5 0 0.00 52990 392689 219645 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5193 5193 0 0.00 0 35170 877 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 2 6116 369 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 3107 27 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 1546 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 1808 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1039 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 451 451 0 0.00 0 4290 44 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 94202 7587 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 7613 25 0 0
t$perf-norm:sp_client_order 21478 21478 0 0.00 0 11671 36 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 611 13 0 0
t$perf-norm:sp_pay_from_customer 7525 7525 0 0.00 0 14196 22 0 0
t$perf-norm:sp_pay_to_supplier 6217 6217 0 0.00 0 5939 20 0 0
t$perf-norm:sp_reserve_write_off 10601 10601 0 0.00 0 3461 20 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 10065 29 0 0
t$perf-norm:sp_supplier_order 5834 5834 0 0.00 0 10916 33 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 186 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 401 11 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 4784 26 0 0
t$perf-norm:srv_make_money_saldo 985 985 0 0.00 0 3733 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 12 189 61 0 0
v_add_invoice_to_stock 10534 10286 248 2.35 0 9767 26 0 248
v_all_customers 39546 39546 0 0.00 0 306 4 0 0
v_all_suppliers 32129 32129 0 0.00 0 80 4 0 0
v_all_wares 42952 42952 0 0.00 0 162 4 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 2450 19 0 0
v_cancel_client_order 3592 3592 0 0.00 0 4117 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2853 14 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 436 10 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 4926 20 0 0
v_cancel_supplier_invoice 922 902 20 2.16 0 2016 26 0 20
v_cancel_supplier_order 808 808 0 0.00 0 3757 18 0 0
v_cancel_write_off 1994 1994 0 0.00 0 1977 24 0 0
v_max_id_clo_ord 5831 5831 0 0.00 0 558 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 821 11 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 9045 8 0 0
v_max_non_paid_invoice 1545 1545 0 0.00 0 266 8 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 1147 8 0 0
v_min_id_clo_ord 5831 5831 0 0.00 0 7481 11 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 359 7 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 8216 10 0 0
v_min_non_paid_invoice 6217 6217 0 0.00 0 8434 12 0 0
v_min_non_paid_realizn 7525 7525 0 0.00 0 6750 14 0 0
v_our_firm 10652 10652 0 0.00 0 59 5 0 0
v_reserve_write_off 21194 21194 0 0.00 0 4121 11 0 0
x_lock_depdocs_on_canc_invoice 10472 10472 0 0.00 0 15174 144 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 13759 412 0 0
x_make_qstorno_1000_1200 5834 5834 0 0.00 3 45231 1062 0 0
x_make_qstorno_1200_2000 5568 5568 0 0.00 5 122364 2724 0 0
x_make_qstorno_2100_3300 489827 489827 0 0.00 0 68509 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-01-2023 13:39:36 to 06-01-2023 13:39:40.

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
11:07:27 807 8192000000 8934533520 8991027200 79723328 0.963 15 14 23 0 81954960 662416 1569216
11:08:29 22985 8192000000 9730045296 10030858240 805924944 8.956 100 93 186 0 836851856 7747200 23179712
11:10:04 19559 8192000000 9770511152 10079354880 843850224 9.338 100 93 186 0 878285728 11547360 22888144
11:11:28 13371 8192000000 9808589568 10127343616 872545872 9.625 100 85 168 0 915760704 23630608 19584224
11:12:49 6913 8192000000 9859604448 10193965056 915251376 10.049 100 72 140 1 968640592 38439136 14950080
11:14:00 3842 8192000000 9909051984 10251231232 952816288 10.419 100 84 165 1 1021494672 51358720 17319664
11:15:07 2034 8192000000 9958448960 10312757248 996110288 10.841 100 90 170 2 1075400192 62112672 17177232
11:16:11 2308 8192000000 10006366688 10368638976 1021409568 11.086 100 90 170 1 1126834432 88954288 16470576
11:17:15 1070 8192000000 10028698032 10400428032 1044224800 11.305 100 91 174 0 1154108944 91397168 18486976
11:18:19 1079 8192000000 10044310032 10418290688 1054440080 11.403 100 89 173 0 1169071856 95084928 19546848
11:19:21 1025 8192000000 10017748176 10394423296 1060778768 11.464 100 90 175 0 1141111248 62824752 17507728
11:20:24 1060 8192000000 10031649472 10406711296 1065719216 11.511 100 99 191 2 1152996672 67505312 19772144
11:21:27 1129 8192000000 10052002864 10432180224 1071596416 11.567 100 99 189 4 1175906672 85706288 18603968
11:22:29 1328 8192000000 10056578752 10437758976 1079168368 11.640 100 97 196 2 1174402192 75291504 19942320
11:23:32 1173 8192000000 10074524960 10462171136 1086142416 11.706 100 94 188 1 1195256464 90301392 18812656
11:24:34 1108 8192000000 10040640064 10423934976 1088981680 11.733 100 95 186 1 1162936288 55008688 18945920
11:25:35 1098 8192000000 10041927744 10422829056 1084045936 11.686 99 93 181 1 1162556016 60379248 18130832
11:26:38 2792 8192000000 10062887872 10451898368 1094234992 11.783 100 86 162 0 1193624304 82473328 16915984
11:27:42 2006 8192000000 10048837024 10442674176 1099244864 11.830 100 76 148 1 1173794688 59844944 14704880
11:28:45 1046 8192000000 10042489536 10434650112 1092706512 11.768 100 83 158 2 1171335008 61276128 17352368
11:29:47 1137 8192000000 10060726384 10450485248 1097962368 11.818 100 92 169 0 1183936672 68275216 17699088
11:30:49 1081 8192000000 10011530896 10391687168 1066507648 11.519 98 92 175 0 1130924832 46631856 17785328
11:31:52 1187 8192000000 10021486016 10407403520 1070429504 11.556 100 82 160 0 1143563248 56583376 16550368
11:32:54 1221 8192000000 10022172480 10399145984 1054963248 11.408 100 88 158 9 1138683360 67539216 16180896
11:33:57 1299 8192000000 9995095312 10369814528 1036028976 11.226 100 96 180 2 1114766608 58424496 20313136
11:34:59 1175 8192000000 10006606176 10385969152 1049137328 11.352 100 95 186 2 1128147952 59828768 19181856
11:36:01 2514 8192000000 9968967568 10353475584 1043240288 11.296 99 59 112 1 1096137968 40442624 12455056
11:37:04 1063 8192000000 10001746800 10377490432 1045165600 11.314 100 92 169 2 1127428416 65137008 17125808
11:38:09 985 8192000000 10012329488 10394488832 1062593504 11.481 99 86 167 0 1140799856 61347472 16858880
11:39:12 9779 8192000000 10046186144 10433507328 1082625520 11.672 100 69 134 0 1166707296 70540256 13541520
11:40:26 3521 8192000000 10049688112 10441879552 1097419408 11.813 100 75 142 3 1169320320 57413840 14487072
11:41:37 1569 8192000000 10104014432 10489581568 1113197424 11.963 100 97 182 0 1222303328 90599296 18506608
11:42:40 1823 8192000000 10138563664 10538024960 1119593888 12.023 100 88 172 1 1254464064 115893536 18976640
11:43:47 8824 8192000000 10190763664 10592026624 1135436656 12.173 100 69 134 0 1302406176 153085792 13883728
11:45:01 5765 8192000000 10188487808 10589409280 1147162720 12.283 100 70 137 1 1314301200 152618368 14520112
11:46:09 1554 8192000000 10259395728 10667782144 1163120160 12.432 100 70 130 1 1379056800 201307328 14629312
11:47:15 1787 8192000000 10271406816 10678112256 1166917440 12.468 100 93 179 0 1383434832 196651600 19865792
11:48:21 1701 8192000000 10309350320 10715697152 1181562480 12.605 100 84 164 0 1420280224 219790752 18926992
11:49:26 976 8192000000 10370133344 10775236608 1180194320 12.592 99 94 187 1 1483210432 280840592 22175520
11:50:32 1677 8192000000 10375379248 10789302272 1192545344 12.707 100 82 153 1 1485456016 274905008 18005664
11:51:37 1195 8192000000 10434009408 10840461312 1193055232 12.712 100 93 182 0 1546744304 331987744 21701328
11:52:42 803 8192000000 10459036592 10875219968 1194386848 12.724 100 91 178 1 1568186400 353446256 20353296
11:53:46 880 8192000000 10499932016 10922434560 1198668640 12.764 100 93 181 0 1610431024 390586192 21176192
11:54:49 742 8192000000 10526950576 10952675328 1204313088 12.816 100 94 186 1 1639453584 411494368 23646128
11:55:52 1713 8192000000 10566331424 10976149504 1203568736 12.809 100 96 191 1 1676184224 449647008 22968480
11:56:57 992 8192000000 10581993712 11006267392 1197889360 12.757 100 94 185 2 1686971920 466310016 22772544
11:58:01 895 8192000000 10604011984 11047329792 1191711504 12.699 100 95 186 0 1712393104 497897968 22783632
11:59:05 1578 8192000000 10621650000 11054526464 1206551600 12.837 100 85 161 0 1727640960 502859840 18229520
12:00:09 3753 8192000000 10597603472 11024228352 1195777024 12.737 100 62 120 1 1705644176 494000736 15866416
12:01:17 705 8192000000 10679848896 11106004992 1197339312 12.752 100 92 183 0 1785637376 565139424 23158640
12:02:19 2564 8192000000 10674785712 11138523136 1186345136 12.649 100 76 150 0 1777516800 571702816 19468848
12:03:25 8909 8192000000 10610163264 11142479872 1190926144 12.692 100 60 119 0 1744161664 537596896 15638624
12:04:35 17300 8192000000 10582594672 11036495872 1184192016 12.629 100 44 88 0 1693728496 497448384 12088096
12:06:34 34988 8192000000 10361022400 10909913088 1148899568 12.299 99 54 103 1 1489031952 326318656 13813728
12:08:30 33046 8192000000 10218498928 10769592320 1135995408 12.178 98 21 38 0 1379676208 238598736 5082064
12:10:13 107423 8192000000 10065641312 10545938432 1095437312 11.794 95 8 16 0 1141289792 44992544 859936
12:13:04 3434 8192000000 10084418080 10504949760 1136512080 12.183 100 47 94 0 1172206656 26382592 9311984
12:14:25 27695 8192000000 10149374096 10586943488 1143446688 12.248 100 63 117 4 1210528304 54914352 12167264
12:15:56 157578 8192000000 10156164800 10621526016 1121062656 12.037 98 41 82 0 1271740640 140305024 10372960
12:19:57 28034 8192000000 10088116720 10545709056 1132828160 12.148 100 21 41 0 1168347072 31012368 4506544
12:21:35 73090 8192000000 10142110000 10585944064 1112756624 11.959 97 46 88 0 1210431168 87441552 10232992
12:24:02 31099 8192000000 10075143760 10576711680 1111693072 11.948 99 5 8 0 1177791168 64970480 1127616
12:25:34 49116 8192000000 10076124992 10549096448 1105750400 11.892 98 9 18 0 1155737088 48023296 1963392
12:27:23 78300 8192000000 10093076160 10544582656 1114141920 11.972 99 13 26 0 1179447008 62346304 2958784
12:30:13 112371 8192000000 10094338352 10581131264 1094272688 11.783 97 12 24 0 1168295344 71116928 2905728
12:33:09 49036 8192000000 10013466880 10509520896 1079365216 11.641 97 9 16 0 1114935712 34091168 1479328
12:34:59 5799 8192000000 10057715536 10489212928 1117471072 12.003 100 67 136 0 1166367712 33646352 15250288
12:36:05 8876 8192000000 10059600400 10492465152 1125194816 12.076 100 56 106 1 1167200080 29638096 12367168
12:37:16 34082 8192000000 10060790528 10497572864 1096920624 11.808 97 27 50 1 1142239136 40652016 4666496
12:38:55 13618 8192000000 10059749664 10491633664 1122251648 12.048 100 39 75 1 1163771536 34033184 7486704
12:40:09 28814 8192000000 10088684544 10535792640 1123182048 12.057 99 43 86 0 1175796496 44181472 8432976
12:41:42 27521 8192000000 10117559312 10561581056 1112533536 11.956 98 44 88 0 1208192944 86908256 8751152
12:43:10 57974 8192000000 10076969136 10501693440 1079796336 11.646 96 28 52 1 1126339056 41485120 5057600
12:45:08 36073 8192000000 10064435792 10548572160 1112118928 11.952 100 33 66 0 1187760784 68903344 6738512
12:46:56 108163 8192000000 10104900976 10597527552 1120285840 12.030 100 43 86 2 1264948560 134992416 9670304
12:49:46 38583 8192000000 10069599328 10501976064 1116320288 11.992 99 38 72 0 1171796752 47866416 7610048
12:51:25 54741 8192000000 10062069424 10519662592 1100685936 11.844 97 22 44 0 1172437392 67299952 4451504
12:53:32 34618 8192000000 10110784032 10558967808 1126363776 12.087 99 24 46 0 1193469936 61540800 5565360
12:55:10 74706 8192000000 10140922528 10583117824 1095084576 11.791 96 19 38 0 1173750160 74550208 4115376
12:57:37 50784 8192000000 10086759088 10570448896 1100993328 11.847 97 21 42 0 1184601040 78569056 5038656
12:59:32 17394 8192000000 10111627264 10535739392 1136639360 12.184 100 52 98 0 1207907552 60481696 10786496
13:00:49 21720 8192000000 10154704400 10586955776 1129687376 12.118 100 47 92 0 1243353184 103975456 9690352
13:02:11 31130 8192000000 10147407472 10619129856 1091753360 11.759 97 40 80 1 1240397728 139693792 8950576
13:03:48 43166 8192000000 10062888288 10495639552 1094014176 11.781 98 34 66 0 1168719040 67901280 6803584
13:05:33 63326 8192000000 10092952800 10586443776 1117294544 12.001 99 19 38 0 1210172480 88934848 3943088
13:07:37 63116 8192000000 10119674704 10555166720 1115543888 11.985 98 45 90 1 1180184528 55939984 8700656
13:10:12 17554 8192000000 10064027872 10532970496 1113948448 11.970 99 27 53 0 1166907504 47378592 5580464
13:11:32 72618 8192000000 10076978704 10544988160 1133408816 12.153 100 12 24 0 1184191456 48371584 2411056
13:13:45 41823 8192000000 10060062544 10529542144 1123680480 12.062 99 13 26 0 1160828736 34704864 2443392
13:15:27 21799 8192000000 10082814512 10515599360 1122580352 12.051 99 24 46 0 1178186912 50267920 5338640
13:16:49 31654 8192000000 10119479984 10560847872 1123447040 12.060 99 33 64 0 1213311968 83016768 6848160
13:18:27 32607 8192000000 10152183856 10594361344 1115696336 11.986 98 34 66 0 1229547872 106530192 7321344
13:20:02 36844 8192000000 10175932464 10614272000 1120897600 12.035 99 38 75 0 1267513696 138500592 8115504
13:21:39 49439 8192000000 10165817584 10630504448 1131003408 12.131 100 37 75 0 1293402304 153951728 8447168
13:23:31 57372 8192000000 10137545232 10682830848 1129117280 12.113 100 37 74 0 1326059344 188749776 8192288
13:25:38 12222 8192000000 10086417280 10505871360 1086852448 11.713 96 60 117 1 1136360112 37719024 11788640
13:27:02 62453 8192000000 10103883392 10542125056 1082468960 11.671 97 25 47 0 1175050032 88077200 4503872
13:29:06 101308 8192000000 10045321280 10518372352 1104037104 11.876 99 25 46 1 1148569584 39842656 4689824
13:31:48 52200 8192000000 10027066128 10480156672 1096255504 11.802 98 16 32 0 1136052592 36318336 3478752
13:33:46 57911 8192000000 10104438080 10546958336 1125288816 12.077 100 26 52 0 1179915184 48677312 5949056
13:35:54 56452 8192000000 10082722784 10549968896 1137810832 12.195 100 16 29 1 1209782528 68954912 3016784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 06-01-2023 13:39:42 to 06-01-2023 13:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-01-2023 13:39:59 to 06-01-2023 13:40:01.

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          225611
MON$OLDEST_ACTIVE               227341
MON$OLDEST_SNAPSHOT             227341
MON$NEXT_TRANSACTION            227341
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                       2065952
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2886 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2886 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2886 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2886 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Jan  6 13:37:24 2023
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Jan  6 13:37:33 2023
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Jan  6 13:37:34 2023
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Fri Jan  6 13:37:36 2023
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
--- end of diff output ---

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

06.01.23 13:40:03. 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
2023-01-06 13:40:05 No severe PSQL-related problems occured
06.01.23 13:40:07. End of report.