Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 04: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 15746044 kB
MemAvailable 31060044 kB
Buffers 20 kB
Cached 15880348 kB
SwapCached 2520 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-12 04:37:17
DTS_END                         2022-07-12 04:37:17.7250
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/60828
TRN_ID                          214784
ATT_ID                          638
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: 8777.20 25201 1053264
customer order: creation 166.87 507 20024
customer order: refuse 14.23 496 1707
order to supplier: creation 44.24 1450 5309
order to supplier: removal 3.36 197321 403
invoice (draft): creation 45.27 3358 5432
invoice (draft): removal 51.15 1175 6138
invoice accept: apply 40.13 28588 4815
invoice accept: cancel 45.81 1315 5497
customer reserve: creation 3673.52 277 440822
customer reserve: removal 4401.21 115 528145
realization accept: apply 81.83 325 9820
realization accept: cancel 80.78 175 9693
payment from customer: creation 58.78 196 7053
payment from customer: removal 5.57 148 668
payment to supplier: creation 48.27 202 5792
payment to supplier: removal 3.39 140 407
service: total inventory turnovers 3.88 4539 466
service: total monetary turnovers 8.01 422 961
service: aggregate perf. data 0.89 16719 107
service: refresh index statistics 0.04 246544 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-07-2022 04:38:30 to 12-07-2022 04:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6384
TEST_TIME 2 5922
TEST_TIME 3 5654
TEST_TIME 4 5472
TEST_TIME 5 6460
TEST_TIME 6 6623
TEST_TIME 7 6790
TEST_TIME 8 6957
TEST_TIME 9 7146
TEST_TIME 10 7029
TEST_TIME 11 7185
TEST_TIME 12 7113
TEST_TIME 13 6893
TEST_TIME 14 6908
TEST_TIME 15 6895
TEST_TIME 16 6964
TEST_TIME 17 7000
TEST_TIME 18 6902
TEST_TIME 19 6859
TEST_TIME 20 6783
TEST_TIME 21 6994
TEST_TIME 22 7169
TEST_TIME 23 7136
TEST_TIME 24 7262
TEST_TIME 25 7375
TEST_TIME 26 7473
TEST_TIME 27 7474
TEST_TIME 28 7552
TEST_TIME 29 7817
TEST_TIME 30 8225
TEST_TIME 31 8393
TEST_TIME 32 8726
TEST_TIME 33 9025
TEST_TIME 34 9384
TEST_TIME 35 9478
TEST_TIME 36 10095
TEST_TIME 37 10108
TEST_TIME 38 10300
TEST_TIME 39 10173
TEST_TIME 40 10090
TEST_TIME 41 10227
TEST_TIME 42 10205
TEST_TIME 43 10142
TEST_TIME 44 10103
TEST_TIME 45 9981
TEST_TIME 46 9892
TEST_TIME 47 10001
TEST_TIME 48 9914
TEST_TIME 49 9823
TEST_TIME 50 9932
TEST_TIME 51 9831
TEST_TIME 52 9722
TEST_TIME 53 9654
TEST_TIME 54 9574
TEST_TIME 55 9460
TEST_TIME 56 9447
TEST_TIME 57 9401
TEST_TIME 58 9429
TEST_TIME 59 9384
TEST_TIME 60 9379
TEST_TIME 61 9494
TEST_TIME 62 9398
TEST_TIME 63 9439
TEST_TIME 64 9390
TEST_TIME 65 9297
TEST_TIME 66 9236
TEST_TIME 67 9214
TEST_TIME 68 9206
TEST_TIME 69 9220
TEST_TIME 70 9220
TEST_TIME 71 9139
TEST_TIME 72 9293
TEST_TIME 73 9263
TEST_TIME 74 9261
TEST_TIME 75 9219
TEST_TIME 76 9272
TEST_TIME 77 9224
TEST_TIME 78 9344
TEST_TIME 79 9323
TEST_TIME 80 9363
TEST_TIME 81 9332
TEST_TIME 82 9393
TEST_TIME 83 9290
TEST_TIME 84 9279
TEST_TIME 85 9297
TEST_TIME 86 9271
TEST_TIME 87 9229
TEST_TIME 88 9185
TEST_TIME 89 9142
TEST_TIME 90 9092
TEST_TIME 91 9026
TEST_TIME 92 8988
TEST_TIME 93 9013
TEST_TIME 94 9021
TEST_TIME 95 9004
TEST_TIME 96 9025
TEST_TIME 97 9014
TEST_TIME 98 9002
TEST_TIME 99 9010
TEST_TIME 100 8990
TEST_TIME 101 8987
TEST_TIME 102 9016
TEST_TIME 103 9081
TEST_TIME 104 9096
TEST_TIME 105 9142
TEST_TIME 106 9106
TEST_TIME 107 9060
TEST_TIME 108 9083
TEST_TIME 109 9014
TEST_TIME 110 8996
TEST_TIME 111 8974
TEST_TIME 112 8923
TEST_TIME 113 8883
TEST_TIME 114 8857
TEST_TIME 115 8837
TEST_TIME 116 8863
TEST_TIME 117 8861
TEST_TIME 118 8867
TEST_TIME 119 8857
TEST_TIME 120 8803

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 04:38:34 to 12-07-2022 04:38:37.

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 1461758 1461758 0 0.00 0 21658 15 0 0
doc_list_biud 1461746 1461746 0 0.00 0 31413 48 0 0
sp_add_invoice_to_stock 4899 4815 84 1.71 60 223869 28588 0 84
sp_cancel_adding_invoice 5497 5497 0 0.00 7 94407 1316 0 0
sp_cancel_client_order 1707 1707 0 0.00 5 11477 497 0 0
sp_cancel_customer_reserve 528145 528145 0 0.00 1 31420 116 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 10776 148 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 2589 141 0 0
sp_cancel_supplier_invoice 6142 6138 4 0.06 3 31240 1175 0 4
sp_cancel_supplier_order 403 403 0 0.00 8 1441367 197321 0 0
sp_cancel_write_off 9693 9693 0 0.00 2 10943 175 0 0
sp_client_order 20024 20024 0 0.00 5 26163 507 0 0
sp_customer_reserve 440822 440822 0 0.00 1 52031 277 0 0
sp_fill_shopping_cart 30753 30753 0 0.00 1 14400 87 0 0
sp_fill_shopping_cart_clo_res 440797 440797 0 0.00 0 17825 21 0 0
sp_get_clo_for_invoice 4787 4787 0 0.00 0 21439 287 0 0
sp_kill_cost_storno 16265 16265 0 0.00 0 13353 85 0 0
sp_kill_qstorno_ret_qs2qd 1072524 1072524 0 0.00 0 31230 51 0 0
sp_kill_qty_storno 566188 566188 0 0.00 0 31412 116 0 0
sp_lock_selected_doc 20259 20259 0 0.00 0 6011 7 0 0
sp_make_cost_storno 27451 27451 0 0.00 0 11316 83 0 0
sp_multiply_rows_for_pdistr 27451 27451 0 0.00 0 8503 43 0 0
sp_multiply_rows_for_qdistr 20024 20024 0 0.00 0 18016 184 0 0
sp_pay_from_customer 7053 7053 0 0.00 5 13729 196 0 0
sp_pay_to_supplier 5792 5792 0 0.00 6 11981 203 0 0
sp_payment_common 12845 12845 0 0.00 2 7774 47 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 11453 393 0 0
sp_qd_handle_on_invoice_upd_sts 10283 10283 0 0.00 0 29598 581 0 0
sp_qd_handle_on_reserve_upd_sts 9819 9819 0 0.00 0 22667 80 0 0
sp_reserve_write_off 9820 9820 0 0.00 8 22703 326 0 0
sp_supplier_invoice 5432 5432 0 0.00 10 85192 3358 0 0
sp_supplier_order 5309 5309 0 0.00 8 40813 1450 0 0
srv_aggregate_perf_data 107 107 0 0.00 2086 124714 16719 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 732 145476 29613 0 0
srv_find_qd_qs_mism 1007023 1007023 0 0.00 0 21653 11 0 0
srv_make_invnt_saldo 466 466 0 0.00 33 83989 4540 0 0
srv_make_money_saldo 961 961 0 0.00 1 12863 423 0 0
srv_recalc_idx_stat 5 5 0 0.00 84569 431327 246545 0 0
t$perf-abend:sp_add_invoice_to_stock 84 84 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4825 4825 0 0.00 1 31678 1032 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 8153 426 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 4287 35 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 2644 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 2217 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 2391 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2211 48 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 94067 8530 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 11296 39 0 0
t$perf-norm:sp_client_order 20025 20025 0 0.00 0 9883 45 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 1760 21 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 9522 22 0 0
t$perf-norm:sp_pay_to_supplier 5792 5792 0 0.00 0 9849 24 0 0
t$perf-norm:sp_reserve_write_off 9820 9820 0 0.00 0 4814 25 0 0
t$perf-norm:sp_supplier_invoice 5432 5432 0 0.00 0 7742 34 0 0
t$perf-norm:sp_supplier_order 5309 5309 0 0.00 0 12113 37 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 288 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 12111 168 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 6696 30 0 0
t$perf-norm:srv_make_money_saldo 961 961 0 0.00 0 2188 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 192 83 0 0
v_add_invoice_to_stock 9732 9564 168 1.72 0 10637 33 0 168
v_all_customers 36988 36988 0 0.00 0 226 4 0 0
v_all_suppliers 30188 30188 0 0.00 0 236 5 0 0
v_all_wares 40047 40047 0 0.00 0 233 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 2368 18 0 0
v_cancel_client_order 3414 3414 0 0.00 0 10853 25 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 10643 25 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1650 13 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 796 11 0 0
v_cancel_supplier_invoice 856 848 8 0.93 0 1832 28 0 8
v_cancel_supplier_order 806 806 0 0.00 0 14066 36 0 0
v_cancel_write_off 1958 1958 0 0.00 0 5187 31 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 749 5 0 0
v_max_id_clo_res 924 924 0 0.00 0 1544 17 0 0
v_max_id_ord_sup 5423 5423 0 0.00 0 8251 10 0 0
v_max_non_paid_invoice 1427 1427 0 0.00 0 1498 9 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 687 8 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 2872 8 0 0
v_min_id_clo_res 924 924 0 0.00 0 3409 16 0 0
v_min_id_ord_sup 5423 5423 0 0.00 0 8107 11 0 0
v_min_non_paid_invoice 5792 5792 0 0.00 0 5156 16 0 0
v_min_non_paid_realizn 7053 7053 0 0.00 0 7770 18 0 0
v_our_firm 10030 10030 0 0.00 0 81 5 0 0
v_reserve_write_off 19634 19634 0 0.00 0 10853 17 0 0
x_lock_depdocs_on_canc_invoice 11211 11211 0 0.00 0 17866 152 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 7031 425 0 0
x_make_qstorno_1000_1200 5309 5309 0 0.00 3 40787 1274 0 0
x_make_qstorno_1200_2000 5432 5432 0 0.00 4 85151 3124 0 0
x_make_qstorno_2100_3300 440817 440817 0 0.00 0 48824 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 04:38:39 to 12-07-2022 04:38:43.

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
02:07:21 3 8192000000 8800709712 8812486656 6225296 0.075 3 1 2 0 6326496 65424 35776
02:08:23 20004 8192000000 9717741664 10015809536 798361552 8.880 100 88 176 0 828545616 8518848 21665216
02:09:53 23533 8192000000 9767199744 10077270016 846727136 9.367 100 88 176 0 881360992 13901936 20731920
02:11:21 12240 8192000000 9798586992 10120192000 870531808 9.605 100 82 162 0 913157712 23403360 19222544
02:12:37 11242 8192000000 9855930848 10190696448 913434896 10.031 100 81 160 1 973232064 42195184 17601984
02:13:50 3631 8192000000 9920658000 10263326720 959837712 10.487 100 92 180 1 1033571968 54957296 18776960
02:14:57 5248 8192000000 9961699712 10313363456 986750288 10.750 100 85 169 0 1072939808 69376720 16812800
02:16:04 1757 8192000000 9998682624 10355826688 1009172976 10.967 100 96 186 0 1114596704 87139984 18283744
02:17:08 1244 8192000000 10005020240 10371563520 1029308384 11.162 100 96 186 1 1128831072 79842976 19679712
02:18:10 1161 8192000000 10006541984 10389106688 1049157088 11.353 100 92 178 3 1130807104 62767776 18882240
02:19:12 1184 8192000000 10035550368 10413731840 1065157840 11.506 100 99 191 1 1160423184 75116544 20148800
02:20:15 9193 8192000000 10040617584 10422218752 1069888624 11.551 100 80 149 1 1155229072 70544960 14795488
02:21:27 1244 8192000000 10022068416 10402799616 1074547168 11.595 100 79 149 2 1151973216 63659840 13766208
02:22:30 1017 8192000000 10047054064 10442117120 1082589600 11.672 100 82 151 1 1172002608 73884176 15528832
02:23:32 1021 8192000000 10059024864 10448056320 1090527888 11.748 100 91 174 2 1182425648 74242400 17655360
02:24:34 1340 8192000000 10046913824 10437087232 1101561344 11.852 100 90 174 3 1170122064 52126912 16433808
02:25:37 1638 8192000000 10046036880 10438139904 1099395328 11.832 100 80 161 2 1169748608 53924880 16428400
02:26:41 1163 8192000000 10076174384 10468388864 1101235888 11.849 100 88 171 1 1197427712 78858528 17333296
02:27:44 1135 8192000000 10081331792 10469875712 1101408368 11.851 100 97 188 2 1203104480 83619392 18076720
02:28:46 1242 8192000000 10052381760 10443603968 1096446848 11.804 100 92 172 2 1173881488 60883856 16550784
02:30:00 1764 8192000000 10037658912 10435809280 1080778480 11.655 99 77 150 2 1152985040 57083200 15123360
02:31:03 848 8192000000 10039324080 10429923328 1080922992 11.656 100 84 158 0 1161819904 63766320 17130592
02:32:04 1076 8192000000 9978427808 10357870592 1046102304 11.323 99 78 149 3 1106600064 45273728 15224032
02:33:06 885 8192000000 9994644832 10371780608 1040283504 11.267 100 88 173 0 1119732400 61177536 18271360
02:34:08 1281 8192000000 9986538368 10365816832 1035397680 11.220 100 75 142 2 1111845824 61596160 14851984
02:35:10 1717 8192000000 9996067840 10376536064 1039482096 11.260 99 71 135 1 1113390912 60009264 13899552
02:36:13 1579 8192000000 10022093888 10398449664 1038883840 11.254 99 87 172 1 1144289728 87686784 17719104
02:37:15 1493 8192000000 9976057488 10361237504 1032597760 11.193 99 67 119 2 1098351120 54327616 11425744
02:38:28 3424 8192000000 10008807184 10386264064 1052176464 11.382 100 79 145 2 1126839568 60068768 14594336
02:39:38 1007 8192000000 10031573280 10408681472 1081732224 11.664 100 86 152 3 1150042944 51812432 16498288
02:40:46 3042 8192000000 10088836208 10474487808 1084566416 11.691 100 75 145 0 1204562400 104477088 15518896
02:42:02 1653 8192000000 10126430640 10522984448 1106033792 11.895 100 80 142 2 1234778000 114314816 14429392
02:43:06 3421 8192000000 10148244976 10541977600 1114251456 11.973 100 87 166 2 1268132992 136011200 17870336
02:44:15 3938 8192000000 10102942160 10515570688 1125910432 12.083 100 51 87 1 1219597728 84027888 9659408
02:45:24 1947 8192000000 10155841408 10551795712 1132727776 12.147 100 82 157 1 1274579408 125390464 16461168
02:46:30 1244 8192000000 10189432208 10591367168 1140685152 12.222 100 93 170 1 1305175936 145210944 19279840
02:47:39 5577 8192000000 10209805312 10613542912 1151650448 12.325 100 70 135 0 1332959216 166323136 14985632
02:48:48 724 8192000000 10212640016 10624241664 1155384928 12.360 100 84 158 0 1322168752 149741248 17042576
02:49:53 826 8192000000 10264223728 10665668608 1156488480 12.370 100 93 182 0 1371503664 193309600 21705584
02:50:58 4144 8192000000 10311962304 10726690816 1175630976 12.549 100 71 139 0 1426975696 235048736 16295984
02:52:07 1580 8192000000 10348705072 10752618496 1181311504 12.602 100 93 179 2 1456440240 254473392 20655344
02:53:12 1167 8192000000 10400251744 10805784576 1192680176 12.708 100 96 186 2 1508764752 294003744 22080832
02:54:17 1358 8192000000 10413967824 10827579392 1193896944 12.720 100 94 178 0 1521210768 307220368 20093456
02:55:25 2537 8192000000 10464839216 10865291264 1200720384 12.783 100 93 183 5 1571402496 349306320 21375792
02:56:33 2285 8192000000 10500668928 10920607744 1199608368 12.773 100 84 161 0 1607062912 387763552 19690992
02:57:42 1227 8192000000 10521916032 10963972096 1200103952 12.777 100 89 178 0 1626823264 405502752 21216560
02:58:48 2079 8192000000 10548398976 10967683072 1208691520 12.857 100 92 177 1 1650290704 420264960 21334224
02:59:53 1707 8192000000 10575406160 10986643456 1194592224 12.726 100 98 190 0 1681735008 463149040 23993744
03:00:59 783 8192000000 10605219280 11051155456 1210473952 12.873 100 98 193 3 1709007552 474582832 23950768
03:02:03 1711 8192000000 10621096496 11063177216 1208892256 12.859 100 94 180 0 1721604624 489834896 22877472
03:03:08 2229 8192000000 10625187984 11066724352 1210275568 12.872 100 91 174 1 1723628512 491592800 21760144
03:04:14 1502 8192000000 10665074224 11092951040 1200855728 12.784 100 84 166 0 1768311840 546037200 21418912
03:05:28 1019 8192000000 10652744352 11129020416 1206222288 12.834 100 95 186 0 1757535872 528110832 23202752
03:06:33 5449 8192000000 10664025728 11143684096 1178428048 12.576 99 69 138 0 1772402288 575335568 18638672
03:07:54 11832 8192000000 10544934928 11051298816 1169788976 12.495 99 46 92 0 1645632304 462815120 13028208
03:09:37 7391 8192000000 10467128352 10930241536 1149722592 12.307 99 40 76 1 1571324736 411811280 9790864
03:10:46 12374 8192000000 10403811504 10877960192 1160114816 12.404 100 36 73 0 1515756336 345861760 9779760
03:12:00 35255 8192000000 10339806000 10842169344 1151936704 12.328 100 24 46 0 1431871280 274253056 5681520
03:13:36 49013 8192000000 10159594384 10664620032 1159617808 12.400 100 18 36 0 1317471744 153777536 4076400
03:15:27 55403 8192000000 10113288480 10545020928 1118825968 12.016 97 17 34 0 1185131424 62444560 3860896
03:17:31 61179 8192000000 10078638544 10570698752 1103344880 11.869 97 30 54 3 1189257856 80103856 5809120
03:19:37 72151 8192000000 10054146720 10515488768 1091533216 11.757 96 27 53 0 1125297168 27133552 6630400
03:21:54 33641 8192000000 10078925472 10498244608 1129443264 12.116 99 52 94 3 1156512128 17524592 9544272
03:23:29 23988 8192000000 10100697024 10534543360 1111003344 11.942 98 39 77 0 1172778032 53287952 8486736
03:24:59 56065 8192000000 10074490912 10518810624 1086150208 11.706 96 24 48 0 1129274512 37733776 5390528
03:26:58 145476 8192000000 10068563728 10534490112 1124148112 12.066 100 17 34 0 1233367904 105035616 4184176
03:30:34 20615 8192000000 10092135040 10519580672 1120878752 12.035 99 65 129 1 1180715280 45968384 13868144
03:31:55 38888 8192000000 10067087168 10509643776 1078240912 11.631 95 16 32 0 1130404688 48398208 3765568
03:33:40 23555 8192000000 10104963456 10544508928 1126144560 12.085 100 38 72 1 1202574560 68183264 8246736
03:35:07 21731 8192000000 10073552496 10516303872 1096374880 11.803 97 35 66 1 1152126656 48939936 6811840
03:36:39 5871 8192000000 10165460416 10584576000 1131813296 12.138 100 67 129 1 1247042400 101730288 13498816
03:37:47 21865 8192000000 10140273616 10588102656 1129872048 12.120 100 50 95 0 1261508144 121063872 10572224
03:39:13 19724 8192000000 10089777296 10522267648 1139398960 12.210 100 27 48 0 1188264192 43255184 5610048
03:40:34 42220 8192000000 10076642848 10535477248 1100211600 11.840 96 25 50 0 1155822288 50343904 5266784
03:42:18 24225 8192000000 10125828112 10562838528 1143618992 12.250 100 40 75 2 1230151376 78092864 8439520
03:43:44 103486 8192000000 10118905808 10641510400 1140234976 12.218 100 31 63 0 1240218688 92786704 7197008
03:46:29 79908 8192000000 10109141888 10601873408 1117844864 12.007 98 15 30 1 1200557680 79538016 3174800
03:49:34 70653 8192000000 10088368656 10524839936 1124966336 12.074 98 19 36 0 1194757536 65960512 3830688
03:51:45 23313 8192000000 10116332512 10558976000 1142004496 12.234 99 37 69 1 1208248048 58733344 7510208
03:53:12 76595 8192000000 10163750608 10608332800 1129633312 12.118 98 16 32 0 1223178784 89873680 3671792
03:55:29 45605 8192000000 10092754000 10533396480 1113143296 11.962 97 28 53 0 1166211008 47651232 5416480
03:57:17 20920 8192000000 10135709120 10576920576 1106362880 11.898 96 35 68 1 1194176864 80490544 7323440
03:58:38 56412 8192000000 10082789904 10562170880 1077138352 11.620 95 29 54 0 1181801024 99037424 5625248
04:00:36 59678 8192000000 10120084512 10565595136 1119360064 12.021 99 55 108 5 1215915136 85092144 11462928
04:02:55 22646 8192000000 10090633136 10598170624 1133894912 12.158 100 24 48 0 1195038704 55507360 5636432
04:04:53 47169 8192000000 10058247216 10518392832 1126187552 12.085 99 13 26 0 1165782336 37049104 2545680
04:06:47 66507 8192000000 10102973904 10559184896 1109630960 11.929 97 14 26 0 1171889104 59331696 2926448
04:08:54 64304 8192000000 10107401696 10612236288 1135663312 12.175 100 16 32 0 1248718416 109496160 3558944
04:11:09 50537 8192000000 10058667232 10506440704 1122938368 12.055 98 13 24 0 1135959216 10568688 2452160
04:12:59 68850 8192000000 10029532720 10475388928 1051988304 11.380 92 23 46 0 1096799552 39962480 4848768
04:15:08 13567 8192000000 10045191760 10479345664 1097394864 11.813 99 38 73 2 1145803664 41402608 7006192
04:16:24 46887 8192000000 10104203584 10558238720 1112091856 11.952 99 33 65 0 1223687600 104994432 6601312
04:18:14 18805 8192000000 10160259232 10592595968 1124937120 12.074 99 48 96 1 1250050096 115355488 9757488
04:19:35 25657 8192000000 10126762272 10577661952 1110051344 11.933 99 45 89 0 1248171808 128819184 9301280
04:21:07 12442 8192000000 10129962576 10567831552 1125430272 12.078 100 49 94 0 1200740352 65805088 9504992
04:22:26 41894 8192000000 10082137696 10531794944 1128740064 12.109 100 28 57 0 1184286224 50185840 5360320
04:24:11 81264 8192000000 10126776240 10621673472 1132361696 12.144 100 39 78 1 1213569088 73272160 7935232
04:27:20 53803 8192000000 10087890272 10555871232 1115852256 11.988 99 41 82 0 1190925232 66784032 8288944
04:29:16 38894 8192000000 10085768256 10560323584 1119163264 12.019 99 5 10 0 1172019312 51792784 1063264
04:30:56 79117 8192000000 10124204080 10612277248 1131458960 12.135 100 19 38 0 1243322208 107571616 4291632
04:33:17 38609 8192000000 10075095056 10541858816 1129605936 12.118 100 18 37 0 1182221584 49295600 3320048
04:34:56 50824 8192000000 10074154352 10540158976 1117205152 12.001 98 21 42 0 1178791632 56379072 5207408
04:36:54 22174 8192000000 10119308144 10563919872 1134709184 12.166 100 39 71 1 1202807360 60724160 7374016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-07-2022 04:38:45 to 12-07-2022 04:39:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 84 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

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

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          211568
MON$OLDEST_ACTIVE               215184
MON$OLDEST_SNAPSHOT             215184
MON$NEXT_TRANSACTION            215184
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                       2068832
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:45: 
:46: mscs-test-161.budzdorov.ru    Tue Jul 12 04:37:22 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Tue Jul 12 04:37:23 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Tue Jul 12 04:37:24 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Tue Jul 12 04:37:24 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Tue Jul 12 04:37:24 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

12.07.22 04:39:05. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-12 04:39:08 No severe PSQL-related problems occured
12.07.22 04:39:09. End of report.