Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15502164 kB
MemAvailable 30625512 kB
Buffers 20 kB
Cached 16108972 kB
SwapCached 3824 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.1
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 2021-10-13 04:37:07
DTS_END                         2021-10-13 04:37:07.5380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50484
TRN_ID                          233649
ATT_ID                          654
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: 8958.24 24026 1074989
customer order: creation 168.53 398 20224
customer order: refuse 15.00 367 1800
order to supplier: creation 46.43 1458 5571
order to supplier: removal 3.27 125659 392
invoice (draft): creation 45.16 3697 5419
invoice (draft): removal 51.28 1030 6154
invoice accept: apply 41.08 19430 4929
invoice accept: cancel 45.83 957 5500
customer reserve: creation 3788.65 190 454638
customer reserve: removal 4450.14 72 534017
realization accept: apply 82.49 215 9899
realization accept: cancel 92.12 128 11054
payment from customer: creation 59.12 127 7094
payment from customer: removal 5.78 114 693
payment to supplier: creation 48.58 155 5829
payment to supplier: removal 3.73 68 448
service: total inventory turnovers 3.73 4534 448
service: total monetary turnovers 6.41 640 769
service: aggregate perf. data 0.89 17241 107
service: refresh index statistics 0.03 304032 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-10-2021 04:38:05 to 13-10-2021 04:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6454
TEST_TIME 2 6892
TEST_TIME 3 5902
TEST_TIME 4 5586
TEST_TIME 5 5227
TEST_TIME 6 4787
TEST_TIME 7 4855
TEST_TIME 8 5351
TEST_TIME 9 5702
TEST_TIME 10 5929
TEST_TIME 11 5894
TEST_TIME 12 6302
TEST_TIME 13 6456
TEST_TIME 14 6510
TEST_TIME 15 6645
TEST_TIME 16 6755
TEST_TIME 17 6829
TEST_TIME 18 7161
TEST_TIME 19 7386
TEST_TIME 20 7411
TEST_TIME 21 7762
TEST_TIME 22 8066
TEST_TIME 23 8345
TEST_TIME 24 8333
TEST_TIME 25 8654
TEST_TIME 26 8445
TEST_TIME 27 8866
TEST_TIME 28 8850
TEST_TIME 29 9719
TEST_TIME 30 10596
TEST_TIME 31 10744
TEST_TIME 32 10591
TEST_TIME 33 10471
TEST_TIME 34 10623
TEST_TIME 35 10500
TEST_TIME 36 10401
TEST_TIME 37 10278
TEST_TIME 38 10146
TEST_TIME 39 10039
TEST_TIME 40 10030
TEST_TIME 41 9923
TEST_TIME 42 9797
TEST_TIME 43 9722
TEST_TIME 44 9786
TEST_TIME 45 9966
TEST_TIME 46 9904
TEST_TIME 47 9861
TEST_TIME 48 9777
TEST_TIME 49 9850
TEST_TIME 50 9686
TEST_TIME 51 9583
TEST_TIME 52 9524
TEST_TIME 53 9456
TEST_TIME 54 9337
TEST_TIME 55 9346
TEST_TIME 56 9295
TEST_TIME 57 9174
TEST_TIME 58 9282
TEST_TIME 59 9326
TEST_TIME 60 9267
TEST_TIME 61 9400
TEST_TIME 62 9310
TEST_TIME 63 9246
TEST_TIME 64 9194
TEST_TIME 65 9182
TEST_TIME 66 9173
TEST_TIME 67 9192
TEST_TIME 68 9231
TEST_TIME 69 9241
TEST_TIME 70 9154
TEST_TIME 71 9193
TEST_TIME 72 9256
TEST_TIME 73 9302
TEST_TIME 74 9290
TEST_TIME 75 9323
TEST_TIME 76 9284
TEST_TIME 77 9348
TEST_TIME 78 9281
TEST_TIME 79 9189
TEST_TIME 80 9132
TEST_TIME 81 9090
TEST_TIME 82 9118
TEST_TIME 83 9059
TEST_TIME 84 9073
TEST_TIME 85 9054
TEST_TIME 86 8984
TEST_TIME 87 8982
TEST_TIME 88 9074
TEST_TIME 89 9035
TEST_TIME 90 9032
TEST_TIME 91 9059
TEST_TIME 92 9033
TEST_TIME 93 9046
TEST_TIME 94 9054
TEST_TIME 95 9022
TEST_TIME 96 9131
TEST_TIME 97 9144
TEST_TIME 98 9129
TEST_TIME 99 9166
TEST_TIME 100 9146
TEST_TIME 101 9170
TEST_TIME 102 9185
TEST_TIME 103 9102
TEST_TIME 104 9085
TEST_TIME 105 9079
TEST_TIME 106 9113
TEST_TIME 107 9091
TEST_TIME 108 9074
TEST_TIME 109 9089
TEST_TIME 110 9064
TEST_TIME 111 9025
TEST_TIME 112 8989
TEST_TIME 113 8927
TEST_TIME 114 8905
TEST_TIME 115 8968
TEST_TIME 116 8968
TEST_TIME 117 8949
TEST_TIME 118 8974
TEST_TIME 119 9038
TEST_TIME 120 8968

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 04:38:09 to 13-10-2021 04:38:12.

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 1494446 1494446 0 0.00 0 19257 8 0 0
doc_list_biud 1494446 1494446 0 0.00 0 87588 33 0 0
sp_add_invoice_to_stock 5040 4929 111 2.20 43 189883 19431 0 111
sp_cancel_adding_invoice 5500 5500 0 0.00 6 65219 958 0 0
sp_cancel_client_order 1800 1800 0 0.00 5 7871 368 0 0
sp_cancel_customer_reserve 534017 534017 0 0.00 1 47638 72 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 6803 115 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 3870 69 0 0
sp_cancel_supplier_invoice 6165 6154 11 0.17 2 87591 1031 0 11
sp_cancel_supplier_order 392 392 0 0.00 5 1252139 125659 0 0
sp_cancel_write_off 11054 11054 0 0.00 1 19462 128 0 0
sp_client_order 20224 20224 0 0.00 4 37201 398 0 0
sp_customer_reserve 454638 454638 0 0.00 0 128221 191 0 0
sp_fill_shopping_cart 31209 31209 0 0.00 1 36822 52 0 0
sp_fill_shopping_cart_clo_res 454623 454623 0 0.00 0 23397 17 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 27157 382 0 0
sp_kill_cost_storno 17695 17695 0 0.00 0 19245 73 0 0
sp_kill_qstorno_ret_qs2qd 1085634 1085634 0 0.00 0 87587 39 0 0
sp_kill_qty_storno 573730 573730 0 0.00 0 87588 84 0 0
sp_lock_selected_doc 20663 20663 0 0.00 0 8414 4 0 0
sp_make_cost_storno 27736 27736 0 0.00 0 15724 65 0 0
sp_multiply_rows_for_pdistr 27736 27736 0 0.00 0 15284 40 0 0
sp_multiply_rows_for_qdistr 20224 20224 0 0.00 0 23409 193 0 0
sp_pay_from_customer 7094 7094 0 0.00 4 15378 128 0 0
sp_pay_to_supplier 5829 5829 0 0.00 5 19101 155 0 0
sp_payment_common 12923 12923 0 0.00 1 14504 27 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 7864 339 0 0
sp_qd_handle_on_invoice_upd_sts 10414 10414 0 0.00 0 21090 384 0 0
sp_qd_handle_on_reserve_upd_sts 9899 9899 0 0.00 0 19876 65 0 0
sp_reserve_write_off 9899 9899 0 0.00 7 19898 216 0 0
sp_supplier_invoice 5419 5419 0 0.00 7 87872 3697 0 0
sp_supplier_order 5571 5571 0 0.00 6 37004 1458 0 0
srv_aggregate_perf_data 107 107 0 0.00 2783 187204 17242 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1043 171936 42816 0 0
srv_find_qd_qs_mism 1027066 1027066 0 0.00 0 11550 6 0 0
srv_make_invnt_saldo 448 448 0 0.00 52 96471 4535 0 0
srv_make_money_saldo 769 769 0 0.00 1 23083 641 0 0
srv_recalc_idx_stat 4 4 0 0.00 158481 451308 304032 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4931 4931 0 0.00 0 10957 185 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 3782 100 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 2124 13 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 3593 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 1423 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 1921 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 1284 19 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 21159 1799 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 1299 7 0 0
t$perf-norm:sp_client_order 20224 20224 0 0.00 0 10165 21 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 405 3 0 0
t$perf-norm:sp_pay_from_customer 7094 7094 0 0.00 0 10659 14 0 0
t$perf-norm:sp_pay_to_supplier 5829 5829 0 0.00 0 5673 10 0 0
t$perf-norm:sp_reserve_write_off 9899 9899 0 0.00 0 6412 13 0 0
t$perf-norm:sp_supplier_invoice 5419 5419 0 0.00 0 6380 23 0 0
t$perf-norm:sp_supplier_order 5571 5571 0 0.00 0 32714 26 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1537 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 202 5 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 1038 16 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1007 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10050 9828 222 2.20 0 6563 22 0 222
v_all_customers 37166 37166 0 0.00 0 136 1 0 0
v_all_suppliers 30072 30072 0 0.00 0 28 1 0 0
v_all_wares 40448 40448 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 3864 16 0 0
v_cancel_client_order 3600 3600 0 0.00 0 3375 5 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 3039 8 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 3547 9 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 395 5 0 0
v_cancel_supplier_invoice 864 842 22 2.54 0 5417 25 0 22
v_cancel_supplier_order 784 784 0 0.00 0 3216 13 0 0
v_cancel_write_off 2060 2060 0 0.00 0 2215 14 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 3816 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 6331 22 0 0
v_max_id_ord_sup 5414 5414 0 0.00 0 6721 7 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 2618 6 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 4036 5 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 4383 9 0 0
v_min_id_clo_res 981 981 0 0.00 0 6706 17 0 0
v_min_id_ord_sup 5414 5414 0 0.00 0 9953 16 0 0
v_min_non_paid_invoice 5829 5829 0 0.00 0 12333 17 0 0
v_min_non_paid_realizn 7094 7094 0 0.00 0 14496 23 0 0
v_our_firm 10018 10018 0 0.00 0 25 1 0 0
v_reserve_write_off 19796 19796 0 0.00 0 6009 9 0 0
x_lock_depdocs_on_canc_invoice 11233 11233 0 0.00 0 12644 136 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 20973 408 0 0
x_make_qstorno_1000_1200 5571 5571 0 0.00 2 36992 1360 0 0
x_make_qstorno_1200_2000 5419 5419 0 0.00 3 87864 3504 0 0
x_make_qstorno_2100_3300 454638 454638 0 0.00 0 128219 172 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-10-2021 04:38:13 to 13-10-2021 04:38:18.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 27550 8192000000 9705909104 10002857984 788094112 8.776 100 84 168 1 817165568 7785488 21285968
02:09:43 24922 8192000000 9750727856 10057650176 826131712 9.160 100 83 166 0 858398688 11748304 20518672
02:11:27 22784 8192000000 9811299424 10129027072 863171760 9.532 100 85 170 0 914179408 31261696 19745952
02:12:53 16313 8192000000 9867231328 10199150592 914485104 10.042 100 79 158 0 967988176 36480064 17023008
02:14:13 8391 8192000000 9936043648 10282053632 967268480 10.560 100 78 156 1 1044858720 61219264 16370976
02:15:23 4542 8192000000 9977378032 10333040640 1009967936 10.975 100 81 163 1 1092628480 66453776 16206768
02:16:30 3663 8192000000 10011734560 10372005888 1022593376 11.097 100 83 166 1 1127663968 88790096 16280496
02:17:36 2081 8192000000 10011254384 10377265152 1041987136 11.284 100 86 171 2 1126302496 67121056 17194304
02:18:41 1688 8192000000 10041213776 10410561536 1060019552 11.457 100 87 175 1 1150573280 73133920 17419808
02:19:44 3566 8192000000 10020927776 10400813056 1062321472 11.479 100 73 144 0 1135182544 58022544 14838528
02:20:50 4268 8192000000 10004070048 10390921216 1065468240 11.509 100 50 100 2 1112058720 36343168 10247312
02:21:56 7057 8192000000 9970415040 10371407872 1067285536 11.526 100 31 61 3 1091101632 16870848 6945248
02:23:03 8174 8192000000 9984950096 10389692416 1073709008 11.587 100 24 47 1 1104870240 26474864 4686368
02:24:12 53291 8192000000 10021302160 10430672896 1078984528 11.638 100 25 50 1 1097410960 12992736 5433696
02:26:09 2271 8192000000 10014636384 10412961792 1082492752 11.671 100 55 111 1 1132748192 38702592 11552848
02:27:14 7446 8192000000 9974612096 10383278080 1062044960 11.476 98 40 80 1 1089396960 18017136 9334864
02:28:23 8203 8192000000 9972214688 10362920960 1039142800 11.256 97 31 59 3 1070085920 24773088 6170032
02:29:32 45803 8192000000 9945819792 10319925248 1004539360 10.923 96 53 105 1 1028668272 12521472 11607440
02:31:18 4771 8192000000 9922048480 10293235712 968877984 10.576 95 50 100 2 1001984144 22415648 10690512
02:32:24 10726 8192000000 9928610592 10316734464 1006892688 10.945 99 40 80 0 1040011024 23925392 9192944
02:33:36 5964 8192000000 9946922336 10327543808 1037635312 11.242 100 27 55 0 1053529776 9950848 5943616
02:34:44 46952 8192000000 9965197872 10350383104 1049211200 11.353 100 49 99 0 1075625664 14684032 11730432
02:36:32 10493 8192000000 9979606336 10373562368 1062918576 11.484 100 37 74 1 1083564960 13024400 7621984
02:37:45 17061 8192000000 10026448624 10416967680 1092976160 11.771 100 51 100 0 1130123184 26999696 10147328
02:39:07 6460 8192000000 10052952256 10452066304 1106252096 11.897 100 47 94 3 1167887408 51054592 10580720
02:40:22 30340 8192000000 10116930672 10526932992 1128239856 12.105 100 58 116 1 1202520592 61601696 12679040
02:42:30 14921 8192000000 10130204240 10536062976 1143188256 12.246 100 50 99 1 1246663440 92740320 10734864
02:43:58 15500 8192000000 10204702752 10617217024 1153680128 12.344 100 51 102 1 1314723248 149952672 11090448
02:45:20 15186 8192000000 10221156784 10636357632 1158792784 12.392 100 40 80 0 1330246592 162066160 9387648
02:46:37 30658 8192000000 10231209264 10665979904 1158449984 12.389 99 59 118 0 1323994576 152547872 12996720
02:48:17 1675 8192000000 10245296688 10654072832 1173163296 12.526 100 59 118 1 1350440352 164084592 13192464
02:49:23 8015 8192000000 10286522880 10717184000 1180662176 12.596 100 69 138 0 1394173296 198477920 15033200
02:50:35 1042 8192000000 10290076928 10695233536 1171580848 12.512 99 66 129 2 1384736464 199264192 13891424
02:51:41 1528 8192000000 10333037376 10738372608 1185830496 12.645 100 71 141 0 1429306992 227720784 15755712
02:52:48 2486 8192000000 10375071088 10786287616 1199972592 12.776 100 86 172 1 1480219696 261006048 19241056
02:53:55 7610 8192000000 10372850624 10788884480 1156258080 12.368 98 65 130 1 1470982272 299811680 14912512
02:55:10 1686 8192000000 10393378720 10795843584 1162355264 12.425 100 76 145 1 1495405808 315929120 17121424
02:56:18 3982 8192000000 10393852272 10803421184 1164913200 12.449 100 65 130 0 1495782464 316268704 14600560
02:57:27 1840 8192000000 10434080960 10829950976 1156852912 12.374 99 95 192 1 1523183136 344299056 22031168
02:58:33 3146 8192000000 10443027744 10845077504 1146692320 12.278 100 66 131 1 1544383008 382196944 15493744
02:59:42 8562 8192000000 10410397680 10832023552 1145426640 12.267 100 39 76 1 1515104704 360009248 9668816
03:00:59 11609 8192000000 10432484112 10854727680 1165681776 12.456 100 52 104 2 1512282224 334291328 12309120
03:02:16 9205 8192000000 10454633616 10889547776 1162115088 12.423 100 44 86 0 1557428128 384978928 10334112
03:03:33 21048 8192000000 10473982736 10930237440 1137192592 12.189 99 27 54 2 1573653296 429272992 7187712
03:05:11 9825 8192000000 10450851984 10888482816 1131788224 12.138 100 21 42 1 1550126272 412265680 6072368
03:06:22 13393 8192000000 10361354480 10880716800 1117698736 12.005 99 33 66 0 1456674432 330212672 8763024
03:07:40 24968 8192000000 10120349968 10536337408 1133719392 12.156 99 40 78 2 1197225136 55468880 8036864
03:09:07 32171 8192000000 10117817184 10551992320 1144567888 12.258 100 35 69 2 1227141072 74039616 8533568
03:10:42 28068 8192000000 10115915424 10587856896 1134669504 12.165 100 24 48 0 1242723216 103173504 4880208
03:12:11 57055 8192000000 10058452272 10494521344 1119523920 12.022 99 13 25 2 1155156336 33544272 2088144
03:14:09 49985 8192000000 10050753616 10473148416 1091897616 11.761 97 25 51 1 1125985696 28593408 5494672
03:16:01 87427 8192000000 10067523504 10495873024 1108737648 11.920 98 35 69 1 1141085392 25870000 6477744
03:18:32 40014 8192000000 10078545760 10518941696 1136821728 12.186 100 23 46 0 1165356592 24092928 4441936
03:20:15 58798 8192000000 10190248144 10643755008 1139916624 12.215 99 19 38 0 1251474464 107100640 4457200
03:23:12 145589 8192000000 10108571520 10553339904 1148932832 12.299 100 15 30 0 1201995472 49761808 3300832
03:26:38 101880 8192000000 10085774128 10525503488 1144817328 12.261 99 16 32 0 1171824272 23565392 3441552
03:29:27 13835 8192000000 10074456688 10507153408 1140183696 12.217 99 53 105 1 1168243760 17328784 10731280
03:30:44 102187 8192000000 10124663408 10554851328 1071345408 11.565 93 29 58 2 1138876064 60835648 6695008
03:33:30 28554 8192000000 10163619216 10585833472 1150505968 12.314 100 47 94 0 1250984800 89407408 11071424
03:35:03 77240 8192000000 10178974256 10648641536 1148905008 12.299 100 25 49 4 1315426112 159890800 6630304
03:37:29 59778 8192000000 10124446816 10589089792 1106054800 11.895 97 20 40 2 1214961888 104601936 4305152
03:39:30 43250 8192000000 10061836896 10512822272 1134722032 12.166 100 10 20 0 1157575328 21119568 1733728
03:41:18 68545 8192000000 10078926384 10517708800 1080064688 11.648 95 8 16 0 1116382688 35186592 1131408
03:43:27 68821 8192000000 10063485168 10514251776 1062833824 11.484 94 16 32 3 1122209872 56195664 3180384
03:45:37 23084 8192000000 10052818672 10484928512 1107283168 11.907 100 38 76 2 1144787280 30158352 7345760
03:47:01 39159 8192000000 10109255456 10523648000 1120007552 12.027 100 44 89 1 1192571840 63220480 9343808
03:48:43 63142 8192000000 10106486912 10548801536 1088783056 11.731 97 14 28 0 1179458608 87278672 3396880
03:50:55 47196 8192000000 10078774512 10518573056 1094886432 11.789 97 30 60 3 1136964128 35862144 6215552
03:52:49 55014 8192000000 10144032592 10574745600 1138034544 12.197 100 39 78 0 1227022320 81161376 7826400
03:54:45 89970 8192000000 10066157360 10488487936 1134611184 12.165 100 31 62 0 1180697200 38928656 7157360
03:57:18 20571 8192000000 10097076464 10526203904 1124871760 12.073 99 40 80 0 1184707232 51535376 8300096
03:58:39 54483 8192000000 10081342320 10590670848 1098839952 11.827 98 11 22 0 1200733024 99370080 2522992
04:00:41 37680 8192000000 10085608208 10525220864 1111895360 11.950 99 16 32 0 1174363376 59138000 3330016
04:02:19 65332 8192000000 10094323328 10548154368 1125025664 12.074 99 19 38 0 1186529008 57420368 4082976
04:04:31 61339 8192000000 10063748688 10607652864 1117620928 12.005 99 14 28 1 1222060800 101426048 3013824
04:07:25 66355 8192000000 10089328224 10546089984 1119173712 12.019 99 23 46 0 1181195120 57814592 4206816
04:09:32 23896 8192000000 10094947456 10530607104 1142156224 12.236 100 42 79 1 1178457120 28888192 7412704
04:10:57 47228 8192000000 10124330400 10570969088 1138081776 12.197 99 28 56 0 1192193392 48489696 5621920
04:12:54 91486 8192000000 10086473568 10573262848 1125054320 12.075 98 9 18 0 1182472384 55815488 1602576
04:15:35 42813 8192000000 10131232848 10588741632 1135977808 12.178 98 37 74 0 1193940688 51021824 6941056
04:17:29 94878 8192000000 10110158336 10580029440 1119769760 12.025 97 10 20 1 1154577008 32656016 2151232
04:20:04 171936 8192000000 10127015696 10616561664 1159760752 12.401 100 31 62 0 1249310912 82014464 7535696
04:23:59 52470 8192000000 10093057776 10538889216 1115801888 11.987 96 21 42 0 1151173120 31414400 3956832
04:25:55 41104 8192000000 10084299744 10558259200 1145971632 12.272 100 8 16 0 1182270416 34323728 1975056
04:27:42 94141 8192000000 10082904752 10539298816 1121372720 12.040 98 14 28 0 1157055056 32655488 3026848
04:31:27 60319 8192000000 10130094944 10629431296 1142064768 12.235 99 21 42 0 1232099008 85524976 4509264
04:33:28 44923 8192000000 10098471952 10573971456 1087386992 11.718 95 15 30 0 1154876848 63793664 3696192
04:35:13 69518 8192000000 10107141408 10584702976 1098613152 11.824 97 26 52 1 1178745808 74543840 5588816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-10-2021 04:38:19 to 13-10-2021 04:38:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-10-2021 04:38:35 to 13-10-2021 04:38:37.

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          233648
MON$OLDEST_ACTIVE               234721
MON$OLDEST_SNAPSHOT             234721
MON$NEXT_TRANSACTION            234721
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                       2072864
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2628 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2628 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2628 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2628 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Wed Oct 13 04:37:15 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Oct 13 04:37:15 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

13.10.21 04:38:38. 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
2021-10-13 04:38:41 No severe PSQL-related problems occured
13.10.21 04:38:42. End of report.