Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.04.2022 07:37
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 16275924 kB
MemAvailable 31543104 kB
Buffers 0 kB
Cached 15389096 kB
SwapCached 2228 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-04-03 07:37:10
DTS_END                         2022-04-03 07:37:10.4720
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/48028
TRN_ID                          222949
ATT_ID                          605
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: 8852.92 22568 1062350
customer order: creation 175.25 459 21030
customer order: refuse 15.18 397 1821
order to supplier: creation 44.64 1321 5357
order to supplier: removal 3.33 159334 400
invoice (draft): creation 45.89 3349 5507
invoice (draft): removal 48.25 1108 5790
invoice accept: apply 41.28 25221 4954
invoice accept: cancel 44.25 1292 5310
customer reserve: creation 3809.68 241 457161
customer reserve: removal 4326.64 96 519197
realization accept: apply 83.43 280 10012
realization accept: cancel 87.00 157 10440
payment from customer: creation 57.86 175 6943
payment from customer: removal 5.67 120 680
payment to supplier: creation 49.20 196 5904
payment to supplier: removal 3.27 147 392
service: total inventory turnovers 3.96 3766 475
service: total monetary turnovers 7.20 351 864
service: aggregate perf. data 0.89 17355 107
service: refresh index statistics 0.05 235985 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-04-2022 07:37:50 to 03-04-2022 07:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7274
TEST_TIME 2 5543
TEST_TIME 3 5318
TEST_TIME 4 5540
TEST_TIME 5 5304
TEST_TIME 6 5397
TEST_TIME 7 5285
TEST_TIME 8 5594
TEST_TIME 9 5756
TEST_TIME 10 5826
TEST_TIME 11 6009
TEST_TIME 12 6226
TEST_TIME 13 6429
TEST_TIME 14 6735
TEST_TIME 15 7203
TEST_TIME 16 7237
TEST_TIME 17 7296
TEST_TIME 18 7484
TEST_TIME 19 7737
TEST_TIME 20 7805
TEST_TIME 21 7827
TEST_TIME 22 8157
TEST_TIME 23 8194
TEST_TIME 24 8217
TEST_TIME 25 8242
TEST_TIME 26 8724
TEST_TIME 27 9143
TEST_TIME 28 10081
TEST_TIME 29 10585
TEST_TIME 30 10653
TEST_TIME 31 11058
TEST_TIME 32 10952
TEST_TIME 33 10879
TEST_TIME 34 10952
TEST_TIME 35 11133
TEST_TIME 36 11199
TEST_TIME 37 11163
TEST_TIME 38 10957
TEST_TIME 39 10817
TEST_TIME 40 10654
TEST_TIME 41 10479
TEST_TIME 42 10533
TEST_TIME 43 10482
TEST_TIME 44 10387
TEST_TIME 45 10212
TEST_TIME 46 10140
TEST_TIME 47 10084
TEST_TIME 48 9923
TEST_TIME 49 9791
TEST_TIME 50 9695
TEST_TIME 51 9624
TEST_TIME 52 9630
TEST_TIME 53 9593
TEST_TIME 54 9711
TEST_TIME 55 9623
TEST_TIME 56 9507
TEST_TIME 57 9404
TEST_TIME 58 9290
TEST_TIME 59 9339
TEST_TIME 60 9240
TEST_TIME 61 9279
TEST_TIME 62 9295
TEST_TIME 63 9252
TEST_TIME 64 9306
TEST_TIME 65 9292
TEST_TIME 66 9237
TEST_TIME 67 9252
TEST_TIME 68 9338
TEST_TIME 69 9324
TEST_TIME 70 9401
TEST_TIME 71 9348
TEST_TIME 72 9342
TEST_TIME 73 9414
TEST_TIME 74 9473
TEST_TIME 75 9449
TEST_TIME 76 9355
TEST_TIME 77 9295
TEST_TIME 78 9271
TEST_TIME 79 9241
TEST_TIME 80 9158
TEST_TIME 81 9208
TEST_TIME 82 9160
TEST_TIME 83 9101
TEST_TIME 84 9036
TEST_TIME 85 9023
TEST_TIME 86 9009
TEST_TIME 87 9023
TEST_TIME 88 8992
TEST_TIME 89 8965
TEST_TIME 90 9064
TEST_TIME 91 9017
TEST_TIME 92 9149
TEST_TIME 93 9129
TEST_TIME 94 9132
TEST_TIME 95 9136
TEST_TIME 96 9193
TEST_TIME 97 9196
TEST_TIME 98 9175
TEST_TIME 99 9129
TEST_TIME 100 9120
TEST_TIME 101 9103
TEST_TIME 102 9091
TEST_TIME 103 9026
TEST_TIME 104 9007
TEST_TIME 105 8984
TEST_TIME 106 8935
TEST_TIME 107 8911
TEST_TIME 108 8894
TEST_TIME 109 8882
TEST_TIME 110 8883
TEST_TIME 111 8866
TEST_TIME 112 8849
TEST_TIME 113 8834
TEST_TIME 114 8822
TEST_TIME 115 8819
TEST_TIME 116 8843
TEST_TIME 117 8910
TEST_TIME 118 8880
TEST_TIME 119 8942
TEST_TIME 120 8874

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-04-2022 07:37:54 to 03-04-2022 07:37:57.

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 1483383 1483383 0 0.00 0 19632 13 0 0
doc_list_biud 1483374 1483374 0 0.00 0 30360 40 0 0
sp_add_invoice_to_stock 5091 4954 137 2.69 32 218873 25221 0 137
sp_cancel_adding_invoice 5310 5310 0 0.00 7 64400 1292 0 0
sp_cancel_client_order 1821 1821 0 0.00 5 10414 397 0 0
sp_cancel_customer_reserve 519197 519197 0 0.00 1 19640 96 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 5130 120 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 5 13512 148 0 0
sp_cancel_supplier_invoice 5800 5790 10 0.17 3 30371 1108 0 10
sp_cancel_supplier_order 400 400 0 0.00 10 1384399 159334 0 0
sp_cancel_write_off 10440 10440 0 0.00 2 16473 157 0 0
sp_client_order 21030 21030 0 0.00 6 14406 459 0 0
sp_customer_reserve 457161 457161 0 0.00 1 41364 242 0 0
sp_fill_shopping_cart 31888 31888 0 0.00 2 35843 90 0 0
sp_fill_shopping_cart_clo_res 457136 457136 0 0.00 0 17669 19 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 33490 318 0 0
sp_kill_cost_storno 16822 16822 0 0.00 0 16439 68 0 0
sp_kill_qstorno_ret_qs2qd 1055024 1055024 0 0.00 0 30358 42 0 0
sp_kill_qty_storno 557880 557880 0 0.00 0 30359 97 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 9083 7 0 0
sp_make_cost_storno 27769 27769 0 0.00 0 15471 79 0 0
sp_multiply_rows_for_pdistr 27769 27769 0 0.00 0 17752 39 0 0
sp_multiply_rows_for_qdistr 21030 21030 0 0.00 0 12669 180 0 0
sp_pay_from_customer 6943 6943 0 0.00 6 12739 176 0 0
sp_pay_to_supplier 5904 5904 0 0.00 7 21778 196 0 0
sp_payment_common 12847 12847 0 0.00 2 9379 47 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 10403 326 0 0
sp_qd_handle_on_invoice_upd_sts 10220 10220 0 0.00 0 17031 493 0 0
sp_qd_handle_on_reserve_upd_sts 10012 10012 0 0.00 0 13837 63 0 0
sp_reserve_write_off 10012 10012 0 0.00 8 16206 281 0 0
sp_supplier_invoice 5507 5507 0 0.00 9 97189 3349 0 0
sp_supplier_order 5357 5357 0 0.00 9 29121 1322 0 0
srv_aggregate_perf_data 107 107 0 0.00 2715 215654 17356 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 702 118757 36937 0 0
srv_find_qd_qs_mism 1013683 1013683 0 0.00 0 16993 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 69 58728 3767 0 0
srv_make_money_saldo 864 864 0 0.00 1 17005 351 0 0
srv_recalc_idx_stat 6 6 0 0.00 122476 522128 235986 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 4954 4954 0 0.00 1 11082 845 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 1 7911 514 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 5103 27 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 4961 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 6461 41 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3172 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 3301 34 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 87665 6875 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 1746 16 0 0
t$perf-norm:sp_client_order 21030 21030 0 0.00 0 22778 36 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 669 14 0 0
t$perf-norm:sp_pay_from_customer 6943 6943 0 0.00 0 5096 18 0 0
t$perf-norm:sp_pay_to_supplier 5904 5904 0 0.00 0 7095 19 0 0
t$perf-norm:sp_reserve_write_off 10012 10012 0 0.00 0 6653 22 0 0
t$perf-norm:sp_supplier_invoice 5507 5507 0 0.00 0 6691 29 0 0
t$perf-norm:sp_supplier_order 5357 5357 0 0.00 0 9767 29 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 519 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1890 37 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1443 18 0 0
t$perf-norm:srv_make_money_saldo 864 864 0 0.00 0 346 4 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 197 73 0 0
v_add_invoice_to_stock 10094 9820 274 2.71 0 8136 30 0 274
v_all_customers 37746 37746 0 0.00 0 57 3 0 0
v_all_suppliers 30486 30486 0 0.00 0 233 4 0 0
v_all_wares 42058 42058 0 0.00 0 125 4 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 4765 29 0 0
v_cancel_client_order 3642 3642 0 0.00 0 5474 13 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 5082 16 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 5208 22 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 8976 29 0 0
v_cancel_supplier_invoice 790 770 20 2.53 0 5562 39 0 20
v_cancel_supplier_order 800 800 0 0.00 0 4761 22 0 0
v_cancel_write_off 1914 1914 0 0.00 0 4746 26 0 0
v_max_id_clo_ord 5357 5357 0 0.00 0 2997 5 0 0
v_max_id_clo_res 972 972 0 0.00 0 3949 16 0 0
v_max_id_ord_sup 5502 5502 0 0.00 0 3815 5 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 1804 8 0 0
v_max_non_paid_realizn 3794 3794 0 0.00 0 2229 7 0 0
v_min_id_clo_ord 5357 5357 0 0.00 0 2873 7 0 0
v_min_id_clo_res 972 972 0 0.00 0 1739 8 0 0
v_min_id_ord_sup 5502 5502 0 0.00 0 11179 25 0 0
v_min_non_paid_invoice 5904 5904 0 0.00 0 9373 22 0 0
v_min_non_paid_realizn 6943 6943 0 0.00 0 5585 17 0 0
v_our_firm 10610 10610 0 0.00 0 56 4 0 0
v_reserve_write_off 20023 20023 0 0.00 0 6333 13 0 0
x_lock_depdocs_on_canc_invoice 10715 10715 0 0.00 0 17047 115 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 4619 312 0 0
x_make_qstorno_1000_1200 5357 5357 0 0.00 3 29103 1162 0 0
x_make_qstorno_1200_2000 5507 5507 0 0.00 4 97073 3067 0 0
x_make_qstorno_2100_3300 457160 457160 0 0.00 0 31076 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-04-2022 07:37:58 to 03-04-2022 07:38:02.

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
05:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 40520 8192000000 9742083920 10046590976 820068144 9.099 100 88 176 0 848356064 7661584 20626336
05:09:59 22017 8192000000 9769259904 10080686080 843947744 9.339 100 90 180 0 879092672 13801968 21342960
05:11:31 16687 8192000000 9810795776 10137145344 877901312 9.679 100 78 155 1 921806880 26670608 17234960
05:12:55 4275 8192000000 9885928784 10221748224 928054336 10.175 100 90 177 1 1002570640 55685216 18831088
05:14:02 3861 8192000000 9949856912 10294001664 963787008 10.526 100 97 193 0 1067393984 83103536 20503440
05:15:09 2503 8192000000 9957472496 10317660160 989419616 10.776 100 83 164 0 1072709952 66471616 16818720
05:16:14 1216 8192000000 9980577936 10344337408 1018324272 11.056 100 86 165 0 1109383984 74126848 16932864
05:17:16 1041 8192000000 10003850368 10377940992 1045262848 11.315 100 96 180 1 1133061280 68848432 18950000
05:18:19 1074 8192000000 10033656880 10403487744 1054991712 11.409 100 97 188 0 1155663728 81601072 19070944
05:19:22 3089 8192000000 10002868192 10378473472 1057475104 11.432 100 76 136 0 1122101184 51633136 12992944
05:20:26 1106 8192000000 10067171776 10444582912 1065068176 11.505 100 99 190 1 1188619440 103848720 19702544
05:21:28 1434 8192000000 10048259232 10434846720 1072752720 11.578 100 87 169 1 1170861584 81297360 16811504
05:22:30 5769 8192000000 10035378016 10427461632 1081464416 11.661 100 74 143 0 1167744224 71833120 14446688
05:23:37 1180 8192000000 10047197216 10436894720 1085422176 11.699 100 97 176 2 1169199664 64555376 19222112
05:24:39 1277 8192000000 10051219200 10438381568 1093347184 11.774 100 94 176 0 1170875872 59806688 17722000
05:25:42 1066 8192000000 10051176992 10438139904 1096329872 11.803 100 95 187 2 1169004848 53125296 19549680
05:26:43 1279 8192000000 10073336528 10460401664 1103641440 11.872 100 97 188 2 1197076848 72895216 20540192
05:27:46 1241 8192000000 10060520944 10448224256 1089063280 11.734 100 95 184 0 1181676304 73934464 18678560
05:28:48 1609 8192000000 10044854480 10441068544 1090809904 11.750 100 72 138 0 1161985504 57264128 13911472
05:29:55 1920 8192000000 10030779440 10420391936 1081273296 11.660 100 85 163 1 1152709568 54834224 16602048
05:30:58 1989 8192000000 10015694256 10401738752 1064082448 11.496 100 85 164 0 1137805776 56712064 17011264
05:32:08 1323 8192000000 10003657008 10382458880 1044083200 11.304 100 96 181 1 1124558464 62319552 18155712
05:33:11 1066 8192000000 9982970112 10356842496 1037526064 11.241 100 89 175 2 1104799472 49365696 17907712
05:34:13 1149 8192000000 9977068464 10346561536 1026567024 11.135 100 97 188 2 1101565600 54979424 20019152
05:35:15 1213 8192000000 9973616272 10345009152 1025190976 11.122 100 98 185 2 1097176368 52279568 19705824
05:36:17 2301 8192000000 10003672976 10381873152 1043677760 11.300 100 81 155 1 1116240464 56198160 16364544
05:37:24 4274 8192000000 9986392720 10368823296 1062724320 11.483 100 63 107 2 1121308736 48658816 9925600
05:38:36 14792 8192000000 10005122368 10392059904 1074467504 11.595 100 58 111 1 1129784704 44280928 11036272
05:40:02 2204 8192000000 10052852688 10435768320 1085315904 11.698 100 79 148 1 1171688768 71973488 14399376
05:41:14 4250 8192000000 10101760352 10496241664 1096223872 11.802 100 71 137 0 1230527184 120522352 13780960
05:42:24 5592 8192000000 10114635024 10505883648 1111535376 11.947 100 76 149 2 1229407456 101261104 16610976
05:43:36 1485 8192000000 10143772544 10546880512 1121875440 12.045 100 67 129 1 1259905520 124404000 13626080
05:44:44 3916 8192000000 10176219120 10568851456 1130091360 12.122 100 73 136 1 1289128448 143569888 15467200
05:45:51 1177 8192000000 10208405712 10605404160 1143849072 12.252 100 77 149 1 1325225440 165075408 16300960
05:46:57 2575 8192000000 10263923616 10664611840 1165507824 12.455 100 88 169 0 1377285968 193146960 18631184
05:48:05 828 8192000000 10307876528 10705145856 1180077808 12.591 100 95 185 0 1419963680 219416688 20469184
05:49:08 1450 8192000000 10349374416 10759557120 1191471760 12.697 100 93 176 1 1463050112 250838720 20739632
05:50:13 692 8192000000 10363659648 10776084480 1186564432 12.651 100 88 160 1 1473727152 268054208 19108512
05:51:17 1360 8192000000 10375915664 10819379200 1191901632 12.701 100 95 187 0 1483092960 268792416 22398912
05:52:21 1487 8192000000 10395152624 10820173824 1197135920 12.750 100 92 178 0 1504718032 287190448 20391664
05:53:26 1459 8192000000 10425932976 10837278720 1211461888 12.883 100 93 181 1 1537424688 304742272 21220528
05:54:30 1011 8192000000 10476672528 10885476352 1213065056 12.897 100 96 189 0 1587032112 351057552 22909504
05:55:34 1507 8192000000 10508298880 10949824512 1208885424 12.859 100 95 184 0 1610989984 379015072 23089488
05:56:38 1198 8192000000 10546212672 10958692352 1216728640 12.931 100 97 190 0 1651140304 411249536 23162128
05:57:41 1030 8192000000 10567279568 10988892160 1210553344 12.874 100 96 186 1 1673168912 439003024 23612544
05:58:44 7510 8192000000 10529850688 11004989440 1212868096 12.896 100 49 96 1 1647337232 421495008 12974128
05:59:53 8650 8192000000 10613814624 11043860480 1213113840 12.898 100 66 128 1 1716111584 487282544 15715200
06:01:25 14557 8192000000 10613340272 11068518400 1201723104 12.792 100 47 94 0 1716571168 502362160 12485904
06:03:01 22379 8192000000 10584507248 11067805696 1150270944 12.312 98 36 70 2 1696576112 535856320 10448848
06:04:29 11491 8192000000 10413197184 10918711296 1137419328 12.191 99 42 80 0 1572145488 424910096 9816064
06:05:42 7559 8192000000 10207558384 10747183104 1123237808 12.058 98 41 78 0 1298533808 166649264 8646736
06:06:52 2475 8192000000 10195397056 10613043200 1111462304 11.946 98 68 135 2 1301717152 176412048 13842800
06:07:56 9176 8192000000 10127747072 10603528192 1127450240 12.097 100 41 78 2 1226978960 89996912 9531808
06:09:07 9701 8192000000 10149077248 10565697536 1144459552 12.257 100 69 124 3 1246236656 88490144 13286960
06:10:20 25833 8192000000 10200065600 10650243072 1160912272 12.412 100 25 48 0 1299003264 132416544 5674448
06:11:50 26836 8192000000 10192679360 10642010112 1130088320 12.122 98 17 34 1 1289020320 154991680 3940320
06:13:44 28250 8192000000 10103843216 10568282112 1126977776 12.093 99 15 29 1 1211726832 81656160 3092896
06:15:16 94769 8192000000 10120576432 10584702976 1125437584 12.078 98 22 44 0 1194119664 64040816 4641264
06:17:52 82835 8192000000 10096694640 10589188096 1118344608 12.011 98 23 46 1 1212717760 88952192 5420960
06:20:22 108878 8192000000 10055022576 10510475264 1093059872 11.772 96 12 24 1 1128944096 33307584 2576640
06:23:21 112707 8192000000 10074519088 10504556544 1077114496 11.620 95 35 64 1 1116191200 32775168 6301536
06:26:17 67128 8192000000 10131095552 10575589376 1142331360 12.237 100 26 52 0 1225000304 77518320 5150624
06:28:27 13277 8192000000 10148002128 10588114944 1139435488 12.210 99 50 91 3 1238753584 90397056 8921040
06:29:48 47463 8192000000 10127043408 10591875072 1131644960 12.137 99 31 62 0 1248560816 110120432 6795424
06:31:37 29594 8192000000 10055290384 10512547840 1137108128 12.188 100 11 20 2 1179344928 40375376 1861424
06:33:08 89536 8192000000 10083357680 10535198720 1127191648 12.095 99 20 40 0 1176148496 44879152 4077696
06:35:46 25468 8192000000 10088832784 10548572160 1120725552 12.034 98 19 37 1 1180350112 55402992 4221568
06:37:15 81208 8192000000 10067940560 10564677632 1122351520 12.049 98 12 24 1 1182982640 57847088 2784032
06:39:58 39585 8192000000 10065025008 10530582528 1092413680 11.766 96 11 22 0 1140619440 46226336 1979424
06:41:38 54422 8192000000 10061025456 10493546496 1047870752 11.340 93 21 42 0 1077038224 25208384 3959088
06:43:33 20658 8192000000 10119819760 10545369088 1096741440 11.807 97 61 117 1 1159509280 50549920 12217920
06:44:53 42056 8192000000 10105465008 10542911488 1104327168 11.879 98 30 60 1 1192954080 82333168 6293744
06:46:37 11249 8192000000 10108582896 10535141376 1129014800 12.112 100 56 112 0 1201608336 61544224 11049312
06:47:52 31533 8192000000 10069459632 10499612672 1128622144 12.108 100 28 54 2 1165921184 31983520 5315520
06:49:27 48805 8192000000 10151048976 10617139200 1134166736 12.161 99 25 50 0 1258896640 119492768 5237136
06:51:18 98697 8192000000 10076971232 10526420992 1061215008 11.468 93 23 47 0 1154906016 89095296 4595712
06:53:58 93102 8192000000 10099463936 10566967296 1115176688 11.981 99 33 66 2 1164953120 42496368 7280064
06:56:34 84155 8192000000 10099702352 10611220480 1118803072 12.016 98 10 20 0 1210297808 88966784 2527952
06:58:59 87734 8192000000 10058616768 10515951616 1114482896 11.975 98 9 18 0 1165074608 48308560 2283152
07:01:28 47835 8192000000 10059197824 10537914368 1135302240 12.171 100 18 36 2 1190371264 50983600 4085424
07:03:16 57024 8192000000 10105892288 10545307648 1100815344 11.845 96 19 38 0 1161533456 56924688 3793424
07:05:14 27439 8192000000 10110685184 10544013312 1117799904 12.006 98 29 56 1 1193356848 69387664 6169280
07:06:41 57274 8192000000 10183650240 10656415744 1137305872 12.190 99 32 64 1 1282554416 138056448 7192096
07:08:44 70513 8192000000 10046020896 10469457920 1048614032 11.347 92 18 32 1 1100874592 48261248 3999312
07:10:56 40530 8192000000 10077963680 10520305664 1108933056 11.922 99 40 81 2 1165416160 48611008 7872096
07:12:37 18765 8192000000 10134365952 10572091392 1121416448 12.040 99 54 107 1 1224448320 92415616 10616256
07:14:00 46457 8192000000 10120696192 10547310592 1117658624 12.005 98 36 70 1 1189944320 65222384 7063312
07:15:50 118757 8192000000 10103627280 10579202048 1137509776 12.192 100 42 84 0 1231912624 85837344 8565504
07:19:00 55530 8192000000 10098784400 10573131776 1140926464 12.224 100 23 44 1 1234127584 88545456 4655664
07:20:55 86842 8192000000 10086620784 10579156992 1135008432 12.169 100 11 22 0 1168856160 31488800 2358928
07:23:58 73295 8192000000 10079672016 10527731712 1111468640 11.946 97 20 40 1 1165538768 49550032 4520096
07:26:12 69606 8192000000 10070679344 10529824768 1117192848 12.000 98 18 36 1 1172421920 51330720 3898352
07:28:22 52315 8192000000 10088628256 10536525824 1114709872 11.977 98 16 32 1 1163245376 45068144 3467360
07:30:14 28328 8192000000 10130764144 10568925184 1137941888 12.196 100 33 64 2 1220019120 74302288 7774944
07:31:43 47528 8192000000 10150418096 10588975104 1123282592 12.058 98 33 66 0 1226809904 96506464 7020848
07:33:37 76663 8192000000 10052921248 10527453184 1050640512 11.367 93 18 36 1 1150651120 96225344 3785264
07:35:54 56044 8192000000 10073216272 10563325952 1098511216 11.824 98 34 68 1 1165526640 59968688 7046736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-04-2022 07:38:04 to 03-04-2022 07:38:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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 03-04-2022 07:38:21 to 03-04-2022 07:38:23.

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          220206
MON$OLDEST_ACTIVE               223173
MON$OLDEST_SNAPSHOT             223173
MON$NEXT_TRANSACTION            223173
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                       2076448
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
--- end of diff output ---

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

03.04.22 07:38:24. 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-04-03 07:38:27 No severe PSQL-related problems occured
03.04.22 07:38:28. End of report.