Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 07:50
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 15938612 kB
MemAvailable 30670860 kB
Buffers 0 kB
Cached 15751908 kB
SwapCached 3820 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 07:50:01
DTS_END                         2021-10-13 07:50:01.5510
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/52284
TRN_ID                          226012
ATT_ID                          610
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: 8700.92 25557 1044111
customer order: creation 166.06 408 19927
customer order: refuse 14.18 363 1702
order to supplier: creation 43.53 1579 5224
order to supplier: removal 3.33 116484 399
invoice (draft): creation 43.78 4013 5254
invoice (draft): removal 50.93 974 6111
invoice accept: apply 39.17 19332 4700
invoice accept: cancel 46.03 1066 5524
customer reserve: creation 3564.03 191 427684
customer reserve: removal 4449.87 69 533984
realization accept: apply 79.78 208 9573
realization accept: cancel 79.97 140 9597
payment from customer: creation 55.58 130 6670
payment from customer: removal 5.16 103 619
payment to supplier: creation 45.44 141 5453
payment to supplier: removal 3.40 119 408
service: total inventory turnovers 3.85 3588 462
service: total monetary turnovers 6.03 611 723
service: aggregate perf. data 0.78 21163 93
service: refresh index statistics 0.03 340450 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2021 07:50:59 to 13-10-2021 07:51:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4373
TEST_TIME 2 6069
TEST_TIME 3 5043
TEST_TIME 4 4668
TEST_TIME 5 4636
TEST_TIME 6 4762
TEST_TIME 7 4498
TEST_TIME 8 4730
TEST_TIME 9 4838
TEST_TIME 10 5390
TEST_TIME 11 5305
TEST_TIME 12 5601
TEST_TIME 13 5944
TEST_TIME 14 6071
TEST_TIME 15 6452
TEST_TIME 16 6871
TEST_TIME 17 7333
TEST_TIME 18 7698
TEST_TIME 19 8006
TEST_TIME 20 8041
TEST_TIME 21 8177
TEST_TIME 22 8296
TEST_TIME 23 8769
TEST_TIME 24 8974
TEST_TIME 25 9294
TEST_TIME 26 9499
TEST_TIME 27 9584
TEST_TIME 28 10351
TEST_TIME 29 10554
TEST_TIME 30 10370
TEST_TIME 31 10263
TEST_TIME 32 10358
TEST_TIME 33 10207
TEST_TIME 34 9991
TEST_TIME 35 9950
TEST_TIME 36 9830
TEST_TIME 37 9909
TEST_TIME 38 9807
TEST_TIME 39 9671
TEST_TIME 40 9502
TEST_TIME 41 9336
TEST_TIME 42 9184
TEST_TIME 43 9265
TEST_TIME 44 9237
TEST_TIME 45 9237
TEST_TIME 46 9116
TEST_TIME 47 9134
TEST_TIME 48 9134
TEST_TIME 49 9070
TEST_TIME 50 8971
TEST_TIME 51 8871
TEST_TIME 52 8760
TEST_TIME 53 8676
TEST_TIME 54 8969
TEST_TIME 55 8854
TEST_TIME 56 8864
TEST_TIME 57 8924
TEST_TIME 58 8933
TEST_TIME 59 8885
TEST_TIME 60 8924
TEST_TIME 61 8938
TEST_TIME 62 8956
TEST_TIME 63 8951
TEST_TIME 64 9038
TEST_TIME 65 9111
TEST_TIME 66 9098
TEST_TIME 67 9031
TEST_TIME 68 8984
TEST_TIME 69 8915
TEST_TIME 70 8850
TEST_TIME 71 8858
TEST_TIME 72 8878
TEST_TIME 73 8813
TEST_TIME 74 8755
TEST_TIME 75 8795
TEST_TIME 76 8751
TEST_TIME 77 8803
TEST_TIME 78 8786
TEST_TIME 79 8832
TEST_TIME 80 8778
TEST_TIME 81 8798
TEST_TIME 82 8815
TEST_TIME 83 8777
TEST_TIME 84 8846
TEST_TIME 85 8992
TEST_TIME 86 9075
TEST_TIME 87 9056
TEST_TIME 88 8974
TEST_TIME 89 8941
TEST_TIME 90 8881
TEST_TIME 91 8906
TEST_TIME 92 8868
TEST_TIME 93 8798
TEST_TIME 94 8777
TEST_TIME 95 8815
TEST_TIME 96 8789
TEST_TIME 97 8810
TEST_TIME 98 8842
TEST_TIME 99 8812
TEST_TIME 100 8840
TEST_TIME 101 8907
TEST_TIME 102 8910
TEST_TIME 103 8895
TEST_TIME 104 8954
TEST_TIME 105 9048
TEST_TIME 106 9027
TEST_TIME 107 9005
TEST_TIME 108 8972
TEST_TIME 109 8900
TEST_TIME 110 8878
TEST_TIME 111 8894
TEST_TIME 112 8846
TEST_TIME 113 8805
TEST_TIME 114 8757
TEST_TIME 115 8725
TEST_TIME 116 8737
TEST_TIME 117 8704
TEST_TIME 118 8779
TEST_TIME 119 8774
TEST_TIME 120 8702

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-10-2021 07:51:03 to 13-10-2021 07:51:06.

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 1441011 1441011 0 0.00 0 22160 7 0 0
doc_list_biud 1441011 1441011 0 0.00 0 78506 33 0 0
sp_add_invoice_to_stock 4853 4700 153 3.15 20 185088 19333 0 153
sp_cancel_adding_invoice 5524 5524 0 0.00 6 76031 1067 0 0
sp_cancel_client_order 1702 1702 0 0.00 4 15140 363 0 0
sp_cancel_customer_reserve 533984 533984 0 0.00 1 78507 69 0 0
sp_cancel_pay_from_customer 619 619 0 0.00 3 6985 103 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 8144 120 0 0
sp_cancel_supplier_invoice 6123 6111 12 0.19 2 23769 974 0 12
sp_cancel_supplier_order 399 399 0 0.00 8 1056919 116484 0 0
sp_cancel_write_off 9597 9597 0 0.00 1 11623 140 0 0
sp_client_order 19927 19927 0 0.00 4 19173 409 0 0
sp_customer_reserve 427684 427684 0 0.00 0 76076 192 0 0
sp_fill_shopping_cart 30393 30393 0 0.00 1 21316 51 0 0
sp_fill_shopping_cart_clo_res 427682 427682 0 0.00 0 18708 18 0 0
sp_get_clo_for_invoice 4698 4698 0 0.00 0 45994 418 0 0
sp_kill_cost_storno 16148 16148 0 0.00 0 10006 71 0 0
sp_kill_qstorno_ret_qs2qd 1084075 1084075 0 0.00 0 78504 38 0 0
sp_kill_qty_storno 571588 571588 0 0.00 0 78506 82 0 0
sp_lock_selected_doc 19853 19853 0 0.00 0 8242 5 0 0
sp_make_cost_storno 26394 26394 0 0.00 0 16207 63 0 0
sp_multiply_rows_for_pdistr 26394 26394 0 0.00 0 9878 37 0 0
sp_multiply_rows_for_qdistr 19927 19927 0 0.00 0 12617 192 0 0
sp_pay_from_customer 6670 6670 0 0.00 4 10846 131 0 0
sp_pay_to_supplier 5453 5453 0 0.00 5 18607 142 0 0
sp_payment_common 12123 12123 0 0.00 1 9792 26 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 15130 339 0 0
sp_qd_handle_on_invoice_upd_sts 10222 10222 0 0.00 0 50027 395 0 0
sp_qd_handle_on_reserve_upd_sts 9573 9573 0 0.00 0 15116 68 0 0
sp_reserve_write_off 9573 9573 0 0.00 7 15208 208 0 0
sp_supplier_invoice 5254 5254 0 0.00 8 90451 4013 0 0
sp_supplier_order 5224 5224 0 0.00 6 72058 1580 0 0
srv_aggregate_perf_data 93 93 0 0.00 2832 398630 21163 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1347 133507 48793 0 0
srv_find_qd_qs_mism 999731 999731 0 0.00 0 22159 6 0 0
srv_make_invnt_saldo 462 462 0 0.00 56 106831 3589 0 0
srv_make_money_saldo 723 723 0 0.00 1 18848 611 0 0
srv_recalc_idx_stat 4 4 0 0.00 252951 403206 340451 0 0
t$perf-abend:sp_add_invoice_to_stock 153 153 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4700 4700 0 0.00 0 24671 189 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 21566 190 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 765 8 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 5138 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 619 619 0 0.00 0 2673 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 1630 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 1391 18 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 24167 1915 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1012 6 0 0
t$perf-norm:sp_client_order 19927 19927 0 0.00 0 24253 25 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 1357 7 0 0
t$perf-norm:sp_pay_from_customer 6670 6670 0 0.00 0 5132 12 0 0
t$perf-norm:sp_pay_to_supplier 5453 5453 0 0.00 0 14848 18 0 0
t$perf-norm:sp_reserve_write_off 9573 9573 0 0.00 0 15841 13 0 0
t$perf-norm:sp_supplier_invoice 5254 5254 0 0.00 0 4779 18 0 0
t$perf-norm:sp_supplier_order 5224 5224 0 0.00 0 9663 20 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 466 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 330 7 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 5549 15 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 8469 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 9702 9396 306 3.15 0 6063 16 0 306
v_all_customers 36206 36206 0 0.00 0 19 1 0 0
v_all_suppliers 28774 28774 0 0.00 0 17 1 0 0
v_all_wares 39854 39854 0 0.00 0 79 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2792 16 0 0
v_cancel_client_order 3404 3404 0 0.00 0 1392 4 0 0
v_cancel_customer_prepayment 1238 1238 0 0.00 0 1250 6 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 2260 9 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 3575 13 0 0
v_cancel_supplier_invoice 896 872 24 2.67 0 3276 20 0 24
v_cancel_supplier_order 798 798 0 0.00 0 1164 8 0 0
v_cancel_write_off 1896 1896 0 0.00 0 3268 16 0 0
v_max_id_clo_ord 5224 5224 0 0.00 0 4135 3 0 0
v_max_id_clo_res 968 968 0 0.00 0 3271 18 0 0
v_max_id_ord_sup 5242 5242 0 0.00 0 10341 6 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 992 3 0 0
v_max_non_paid_realizn 3499 3499 0 0.00 0 2539 3 0 0
v_min_id_clo_ord 5224 5224 0 0.00 0 2479 7 0 0
v_min_id_clo_res 968 968 0 0.00 0 5692 22 0 0
v_min_id_ord_sup 5242 5242 0 0.00 0 7035 17 0 0
v_min_non_paid_invoice 5453 5453 0 0.00 0 5863 18 0 0
v_min_non_paid_realizn 6670 6670 0 0.00 0 7250 20 0 0
v_our_firm 9990 9990 0 0.00 0 13 1 0 0
v_reserve_write_off 19146 19146 0 0.00 0 9805 8 0 0
x_lock_depdocs_on_canc_invoice 11199 11199 0 0.00 0 13127 125 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 5901 334 0 0
x_make_qstorno_1000_1200 5224 5224 0 0.00 2 72002 1487 0 0
x_make_qstorno_1200_2000 5254 5254 0 0.00 4 90408 3817 0 0
x_make_qstorno_2100_3300 427684 427684 0 0.00 0 76073 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2021 07:51:07 to 13-10-2021 07:51:11.

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:20:05 23 8192000000 8808363104 8823234560 12035488 0.146 5 3 3 0 12214720 113152 66080
05:21:06 26891 8192000000 9738510624 10037919744 814913680 9.047 100 87 174 0 843548272 8190192 20444400
05:22:38 33233 8192000000 9778800528 10087591936 849411792 9.394 100 88 176 0 883127216 13267120 20448304
05:24:39 17156 8192000000 9833002816 10159538176 886355952 9.763 100 80 160 0 932225680 27973888 17895840
05:26:02 13016 8192000000 9892869008 10229747712 940339872 10.296 100 80 159 0 1004210928 47139728 16731328
05:27:17 10920 8192000000 9946869936 10300436480 978583664 10.670 100 76 152 1 1063064864 69183440 15297760
05:28:30 9727 8192000000 9982522688 10346528768 1008857488 10.964 100 77 155 0 1104116960 79751616 15507856
05:29:43 2411 8192000000 9998067712 10361290752 1040596368 11.270 100 86 168 1 1115335840 57509664 17229808
05:30:46 1178 8192000000 10015247040 10383368192 1056200752 11.420 100 93 185 1 1131108016 56250960 18656304
05:31:49 2684 8192000000 10029264160 10414567424 1063271088 11.488 100 59 116 1 1146846768 72542048 11033632
05:32:56 9633 8192000000 9963186560 10362122240 1067237280 11.526 100 21 41 0 1100749472 29151008 4361184
05:34:08 4980 8192000000 9996038560 10389454848 1074707136 11.597 100 47 93 0 1116894672 31363552 10823984
05:35:19 5536 8192000000 10002462896 10399764480 1086550464 11.710 100 48 98 0 1115978208 18598736 10829008
05:36:28 56302 8192000000 10006830848 10423914496 1092865120 11.770 100 29 57 1 1111710912 12717440 6128352
05:38:24 6241 8192000000 10011252496 10419539968 1096690320 11.806 100 40 80 3 1148653984 43982624 7981040
05:39:32 4238 8192000000 10006109600 10405076992 1093304448 11.774 100 47 94 2 1116542816 12740400 10497968
05:40:37 5178 8192000000 9995766976 10390540288 1073748352 11.588 99 36 70 2 1096654656 15495216 7411088
05:41:47 40444 8192000000 9970681008 10368548864 991461984 10.796 92 19 38 1 1027281632 31917744 3901904
05:43:27 6907 8192000000 9954924912 10345713664 1030780320 11.176 100 46 92 1 1058267456 16819344 10667792
05:44:35 8648 8192000000 9931112752 10317438976 998998544 10.869 98 24 49 0 1024904528 19650448 6255536
05:45:47 9983 8192000000 9947572064 10331963392 1030124480 11.170 100 26 51 2 1063770976 27619040 6027456
05:46:58 45346 8192000000 9962714288 10365325312 1035443808 11.221 100 33 64 0 1067369456 25348176 6577472
05:48:44 4979 8192000000 9987819824 10372087808 1057487104 11.432 100 52 104 1 1102939840 33664896 11787840
05:49:51 8989 8192000000 9978278160 10384461824 1064160208 11.496 100 39 78 0 1099953536 27085200 8708128
05:51:08 32900 8192000000 10058653632 10449657856 1095191888 11.792 100 60 118 2 1159249744 51689120 12368736
05:52:46 67521 8192000000 10096697392 10502848512 1116157232 11.991 100 48 95 0 1177248080 50417792 10673056
05:55:00 39656 8192000000 10148743632 10558386176 1133449728 12.154 100 42 83 1 1244870288 102567744 8852816
05:56:43 37133 8192000000 10147281184 10559119360 1141766592 12.232 100 28 55 0 1266111344 118238240 6106512
05:58:27 13957 8192000000 10176846208 10588921856 1156089984 12.367 100 37 74 2 1285794736 121626848 8077904
05:59:48 6916 8192000000 10274208096 10678521856 1168867424 12.486 100 72 144 0 1373461328 187988112 16605792
06:01:04 16518 8192000000 10273324832 10689146880 1165921968 12.459 99 62 124 2 1375923312 195898048 14103296
06:02:41 6030 8192000000 10314699568 10722750464 1181946864 12.608 100 69 136 1 1410029808 212662544 15420400
06:03:53 3065 8192000000 10367081040 10771341312 1177789760 12.570 100 80 160 1 1466786928 270540880 18456288
06:05:00 6777 8192000000 10400053664 10818420736 1183001792 12.618 100 70 141 1 1505018576 306233712 15783072
06:06:15 9071 8192000000 10354852224 10794754048 1173260400 12.527 99 61 121 0 1446813280 259366448 14186432
06:07:31 1346 8192000000 10320036736 10726621184 1177998848 12.572 100 82 165 0 1419042880 222046176 18997856
06:08:38 4997 8192000000 10313200240 10721173504 1163706896 12.438 99 76 151 1 1401198432 220677696 16813840
06:09:48 1507 8192000000 10313837088 10735968256 1159195504 12.396 100 83 166 2 1413875952 235549328 19131120
06:10:55 3830 8192000000 10334715408 10750357504 1159850336 12.402 100 67 133 1 1430616656 255164992 15601328
06:12:05 2022 8192000000 10372606416 10775924736 1158799648 12.392 100 89 177 3 1461951408 282208256 20943504
06:13:12 7570 8192000000 10351412672 10799550464 1159222048 12.396 100 44 88 1 1454849024 283915760 11711216
06:14:23 8198 8192000000 10300147072 10738020352 1153594240 12.343 100 40 80 0 1387170608 223572976 10003392
06:15:43 22728 8192000000 10305987808 10774974464 1156374352 12.369 100 28 56 3 1407108416 243511200 7222864
06:17:14 41613 8192000000 10256806384 10689327104 1105788208 11.893 98 18 35 2 1357805600 247491248 4526144
06:18:59 25501 8192000000 10093634128 10576932864 1067706720 11.530 96 21 42 0 1177308880 104944160 4658000
06:20:25 68934 8192000000 10063182064 10478776320 1086840112 11.713 98 38 74 1 1159802560 65284720 7677728
06:22:36 93631 8192000000 10079566128 10563108864 1116964384 11.998 99 13 26 0 1179399584 60010928 2424272
06:25:11 91655 8192000000 10090262384 10527404032 1107784000 11.911 98 23 46 0 1179310304 66591552 4934752
06:28:03 60281 8192000000 10065884144 10512105472 1133277120 12.152 100 21 42 1 1169025184 30601712 5146352
06:30:06 106560 8192000000 10101646288 10540400640 1127087680 12.094 98 22 44 0 1183324128 50994912 5241536
06:32:56 86084 8192000000 10103992352 10608930816 1139181072 12.208 100 28 56 1 1251655088 105932432 6541584
06:35:24 11850 8192000000 10112480704 10546778112 1138735408 12.204 100 26 53 0 1209894272 65648896 5509968
06:36:40 22843 8192000000 10104227024 10532798464 1133901728 12.158 99 51 102 2 1195722304 50385440 11435136
06:38:05 114174 8192000000 10056424912 10531254272 1107867680 11.912 97 16 32 0 1151654272 40159584 3627008
06:41:06 24561 8192000000 10074230720 10549313536 1141678624 12.231 100 17 35 0 1186345728 41360016 3307088
06:42:31 67753 8192000000 10148777072 10609123328 1152466000 12.333 100 14 28 0 1220432768 64663424 3303344
06:44:40 54734 8192000000 10067910656 10584395776 1131754080 12.138 99 6 12 0 1188693984 55789520 1150384
06:46:35 96138 8192000000 10083130896 10598129664 1140918272 12.224 99 9 18 0 1202346384 59388752 2039360
06:49:13 17237 8192000000 10096823120 10520748032 1136775120 12.185 99 61 123 1 1178012912 28457984 12779808
06:50:34 79530 8192000000 10087979152 10549456896 1137314928 12.190 98 17 34 0 1179880464 39584272 2981264
06:53:08 79225 8192000000 10146914016 10604322816 1114820944 11.978 97 21 42 1 1235730112 116074400 4834768
06:55:28 31415 8192000000 10060462912 10513891328 1118348912 12.011 99 7 13 0 1154041200 34657344 1034944
06:57:11 13762 8192000000 10076600848 10486820864 1134956912 12.168 100 60 118 1 1164385696 16272672 13156112
06:58:25 133506 8192000000 10103903792 10542133248 1107568016 11.909 97 26 52 1 1149158144 36470512 5119616
07:01:40 63105 8192000000 10068348768 10535219200 1113947408 11.970 98 18 37 0 1187456496 68498560 5010528
07:03:45 58376 8192000000 10098938464 10536443904 1114182176 11.972 98 18 36 0 1186560160 68581904 3796080
07:05:44 74855 8192000000 10044837264 10498875392 1066110352 11.515 94 15 30 1 1124683760 54928272 3645136
07:07:59 44883 8192000000 10076718784 10513686528 1085463008 11.699 96 16 32 0 1123945072 34951616 3530448
07:09:49 50158 8192000000 10079758928 10503639040 1082257904 11.669 96 32 64 0 1145219664 56212752 6749008
07:11:39 65710 8192000000 10124812736 10588123136 1099683632 11.835 98 36 72 1 1197307136 90125008 7498496
07:14:28 59029 8192000000 10059561712 10541367296 1085763088 11.702 97 21 42 4 1172069824 81993072 4313664
07:16:27 102732 8192000000 10042663616 10488573952 1073533792 11.586 96 18 36 0 1113082784 36201968 3347024
07:19:18 16749 8192000000 10032982112 10456223744 1106073280 11.895 99 44 87 2 1127436176 12108992 9253904
07:20:41 48399 8192000000 10097876960 10541166592 1106558336 11.900 99 29 58 1 1164861136 52710320 5592480
07:22:58 49679 8192000000 10078265568 10517753856 1122887472 12.054 100 13 26 1 1179679040 54211408 2580160
07:25:33 60308 8192000000 10069460688 10515980288 1129484720 12.117 100 18 37 0 1180471968 47154160 3833088
07:27:35 59397 8192000000 10072749072 10531549184 1130097504 12.122 100 18 36 0 1189167200 55323472 3746224
07:29:35 47712 8192000000 10125610448 10608738304 1122995216 12.055 99 24 48 1 1187168192 59240096 4932880
07:31:32 64593 8192000000 10104935856 10532233216 1129555712 12.117 99 29 58 0 1181017056 45706992 5754352
07:33:40 68194 8192000000 10165913824 10645258240 1143125056 12.245 100 30 60 0 1252234512 102736992 6372464
07:35:56 92357 8192000000 10063393472 10495234048 1090395792 11.746 96 22 44 1 1136952976 42440400 4116784
07:38:31 126948 8192000000 10084539728 10535530496 1106398384 11.898 97 36 72 0 1164273520 50001296 7873840
07:41:44 48693 8192000000 10050785792 10528923648 1104822416 11.883 98 9 18 0 1142505152 35302688 2380048
07:43:48 76709 8192000000 10101516784 10549391360 1138886608 12.205 100 18 36 0 1209373248 66212608 4274032
07:46:16 68066 8192000000 10105938064 10564677632 1078811152 11.636 95 14 28 0 1184896720 103184272 2901296
07:48:25 65667 8192000000 10049923888 10501193728 1087160400 11.716 97 13 26 0 1132856688 43319440 2376848

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 07:51:13 to 13-10-2021 07:51:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 153 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 13-10-2021 07:51:29 to 13-10-2021 07:51:30.

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          225024
MON$OLDEST_ACTIVE               226266
MON$OLDEST_SNAPSHOT             226266
MON$NEXT_TRANSACTION            226266
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                       2034848
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Oct 13 07:50:03 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Oct 13 07:50:05 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Wed Oct 13 07:50:05 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

13.10.21 07:51:32. 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 07:51:34 No severe PSQL-related problems occured
13.10.21 07:51:35. End of report.