Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2021 07:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16756960 kB
MemAvailable 31191660 kB
Buffers 0 kB
Cached 14937764 kB
SwapCached 4780 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.0
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-02-13 07:26:56
DTS_END                         2021-02-13 07:26:56.9010
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/59718
TRN_ID                          225575
ATT_ID                          655
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: 8639.46 22291 1036735
customer order: creation 160.51 432 19261
customer order: refuse 14.18 308 1701
order to supplier: creation 42.28 1364 5074
order to supplier: removal 3.23 120901 388
invoice (draft): creation 42.46 3281 5095
invoice (draft): removal 48.95 964 5874
invoice accept: apply 39.23 17705 4707
invoice accept: cancel 44.13 876 5296
customer reserve: creation 3637.08 171 436450
customer reserve: removal 4334.79 71 520175
realization accept: apply 77.21 174 9265
realization accept: cancel 75.33 122 9039
payment from customer: creation 55.28 105 6634
payment from customer: removal 5.40 70 648
payment to supplier: creation 45.71 134 5485
payment to supplier: removal 3.31 61 397
service: total inventory turnovers 3.81 3194 457
service: total monetary turnovers 5.74 560 689
service: aggregate perf. data 0.80 16968 96
service: refresh index statistics 0.03 278352 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2021 07:28:17 to 13-02-2021 07:28:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1593
TEST_TIME 2 2746
TEST_TIME 3 4000
TEST_TIME 4 4385
TEST_TIME 5 4648
TEST_TIME 6 4896
TEST_TIME 7 5085
TEST_TIME 8 4914
TEST_TIME 9 4913
TEST_TIME 10 4920
TEST_TIME 11 5061
TEST_TIME 12 5195
TEST_TIME 13 5557
TEST_TIME 14 5781
TEST_TIME 15 6189
TEST_TIME 16 6453
TEST_TIME 17 6579
TEST_TIME 18 7105
TEST_TIME 19 7769
TEST_TIME 20 8573
TEST_TIME 21 8448
TEST_TIME 22 8701
TEST_TIME 23 9076
TEST_TIME 24 9224
TEST_TIME 25 9963
TEST_TIME 26 11016
TEST_TIME 27 10668
TEST_TIME 28 10502
TEST_TIME 29 10294
TEST_TIME 30 10160
TEST_TIME 31 9965
TEST_TIME 32 9908
TEST_TIME 33 9727
TEST_TIME 34 9888
TEST_TIME 35 9765
TEST_TIME 36 9625
TEST_TIME 37 9490
TEST_TIME 38 9348
TEST_TIME 39 9475
TEST_TIME 40 9363
TEST_TIME 41 9269
TEST_TIME 42 9196
TEST_TIME 43 9045
TEST_TIME 44 8997
TEST_TIME 45 8950
TEST_TIME 46 8958
TEST_TIME 47 8950
TEST_TIME 48 8906
TEST_TIME 49 9103
TEST_TIME 50 9034
TEST_TIME 51 9186
TEST_TIME 52 9167
TEST_TIME 53 9141
TEST_TIME 54 9168
TEST_TIME 55 9132
TEST_TIME 56 9331
TEST_TIME 57 9308
TEST_TIME 58 9402
TEST_TIME 59 9275
TEST_TIME 60 9160
TEST_TIME 61 9073
TEST_TIME 62 8968
TEST_TIME 63 8962
TEST_TIME 64 8995
TEST_TIME 65 8982
TEST_TIME 66 8942
TEST_TIME 67 8984
TEST_TIME 68 8997
TEST_TIME 69 8949
TEST_TIME 70 8985
TEST_TIME 71 9063
TEST_TIME 72 9085
TEST_TIME 73 9087
TEST_TIME 74 9106
TEST_TIME 75 9216
TEST_TIME 76 9234
TEST_TIME 77 9229
TEST_TIME 78 9126
TEST_TIME 79 9035
TEST_TIME 80 8979
TEST_TIME 81 8943
TEST_TIME 82 8883
TEST_TIME 83 8849
TEST_TIME 84 8824
TEST_TIME 85 8809
TEST_TIME 86 8850
TEST_TIME 87 8833
TEST_TIME 88 8822
TEST_TIME 89 8811
TEST_TIME 90 8794
TEST_TIME 91 8788
TEST_TIME 92 8956
TEST_TIME 93 8919
TEST_TIME 94 8895
TEST_TIME 95 8966
TEST_TIME 96 8928
TEST_TIME 97 8879
TEST_TIME 98 8817
TEST_TIME 99 8791
TEST_TIME 100 8761
TEST_TIME 101 8725
TEST_TIME 102 8763
TEST_TIME 103 8739
TEST_TIME 104 8717
TEST_TIME 105 8704
TEST_TIME 106 8688
TEST_TIME 107 8748
TEST_TIME 108 8720
TEST_TIME 109 8762
TEST_TIME 110 8736
TEST_TIME 111 8826
TEST_TIME 112 8856
TEST_TIME 113 8864
TEST_TIME 114 8810
TEST_TIME 115 8769
TEST_TIME 116 8728
TEST_TIME 117 8693
TEST_TIME 118 8647
TEST_TIME 119 8624
TEST_TIME 120 8641

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-02-2021 07:28:20 to 13-02-2021 07:28:24.

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 1440054 1440054 0 0.00 0 17894 6 0 0
doc_list_biud 1440052 1440052 0 0.00 0 78441 32 0 0
sp_add_invoice_to_stock 4845 4707 138 2.84 33 152706 17705 0 138
sp_cancel_adding_invoice 5296 5296 0 0.00 5 69717 877 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 10766 309 0 0
sp_cancel_customer_reserve 520175 520175 0 0.00 1 78442 72 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 3 2641 70 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 5272 62 0 0
sp_cancel_supplier_invoice 5885 5874 11 0.18 1 55755 965 0 11
sp_cancel_supplier_order 388 388 0 0.00 8 1150125 120902 0 0
sp_cancel_write_off 9039 9039 0 0.00 1 14483 123 0 0
sp_client_order 19261 19261 0 0.00 4 39831 432 0 0
sp_customer_reserve 436450 436450 0 0.00 0 100696 172 0 0
sp_fill_shopping_cart 29421 29421 0 0.00 1 27867 46 0 0
sp_fill_shopping_cart_clo_res 436447 436447 0 0.00 0 23120 19 0 0
sp_get_clo_for_invoice 4704 4704 0 0.00 0 49165 521 0 0
sp_kill_cost_storno 15380 15380 0 0.00 0 11156 60 0 0
sp_kill_qstorno_ret_qs2qd 1055651 1055651 0 0.00 0 78437 39 0 0
sp_kill_qty_storno 556441 556441 0 0.00 0 78440 82 0 0
sp_lock_selected_doc 19367 19367 0 0.00 0 5975 4 0 0
sp_make_cost_storno 26088 26088 0 0.00 0 15966 59 0 0
sp_multiply_rows_for_pdistr 26088 26088 0 0.00 0 11176 30 0 0
sp_multiply_rows_for_qdistr 19261 19261 0 0.00 0 33445 251 0 0
sp_pay_from_customer 6634 6634 0 0.00 4 11183 105 0 0
sp_pay_to_supplier 5485 5485 0 0.00 4 17543 134 0 0
sp_payment_common 12118 12118 0 0.00 1 11098 20 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 10759 287 0 0
sp_qd_handle_on_invoice_upd_sts 10000 10000 0 0.00 0 21026 350 0 0
sp_qd_handle_on_reserve_upd_sts 9264 9264 0 0.00 0 10175 50 0 0
sp_reserve_write_off 9265 9265 0 0.00 7 22137 174 0 0
sp_supplier_invoice 5095 5095 0 0.00 6 80711 3282 0 0
sp_supplier_order 5074 5074 0 0.00 6 47707 1365 0 0
srv_aggregate_perf_data 96 96 0 0.00 2663 569723 16969 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 952 177707 54962 0 0
srv_find_qd_qs_mism 991670 991670 0 0.00 0 17253 5 0 0
srv_make_invnt_saldo 457 457 0 0.00 56 99136 3195 0 0
srv_make_money_saldo 689 689 0 0.00 1 13466 561 0 0
srv_recalc_idx_stat 4 4 0 0.00 213024 360354 278353 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 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 4707 4707 0 0.00 0 12291 159 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 4992 81 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 3667 16 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 775 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 644 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 699 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 4968 24 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 23067 1503 0 0
t$perf-norm:sp_cancel_write_off 891 891 0 0.00 0 3085 12 0 0
t$perf-norm:sp_client_order 19261 19261 0 0.00 0 8938 16 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 882 4 0 0
t$perf-norm:sp_pay_from_customer 6634 6634 0 0.00 0 3068 7 0 0
t$perf-norm:sp_pay_to_supplier 5485 5485 0 0.00 0 4266 10 0 0
t$perf-norm:sp_reserve_write_off 9265 9265 0 0.00 0 6391 9 0 0
t$perf-norm:sp_supplier_invoice 5095 5095 0 0.00 0 8319 27 0 0
t$perf-norm:sp_supplier_order 5074 5074 0 0.00 0 9370 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 181 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 416 8 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 337 6 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 629 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 9682 9406 276 2.85 0 10615 17 0 276
v_all_customers 34984 34984 0 0.00 0 126 1 0 0
v_all_suppliers 28344 28344 0 0.00 0 35 1 0 0
v_all_wares 38518 38518 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 48 4 0 0
v_cancel_client_order 3402 3402 0 0.00 0 1209 4 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 1585 5 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 2000 5 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2198 6 0 0
v_cancel_supplier_invoice 778 756 22 2.82 0 582 16 0 22
v_cancel_supplier_order 776 776 0 0.00 0 326 3 0 0
v_cancel_write_off 1782 1782 0 0.00 0 2773 15 0 0
v_max_id_clo_ord 5070 5070 0 0.00 0 1803 1 0 0
v_max_id_clo_res 952 952 0 0.00 0 904 7 0 0
v_max_id_ord_sup 5090 5090 0 0.00 0 4405 6 0 0
v_max_non_paid_invoice 1473 1473 0 0.00 0 270 2 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 4047 4 0 0
v_min_id_clo_ord 5070 5070 0 0.00 0 2316 4 0 0
v_min_id_clo_res 952 952 0 0.00 0 6651 24 0 0
v_min_id_ord_sup 5090 5090 0 0.00 0 5823 6 0 0
v_min_non_paid_invoice 5485 5485 0 0.00 0 8122 18 0 0
v_min_non_paid_realizn 6633 6633 0 0.00 0 11091 12 0 0
v_our_firm 9556 9556 0 0.00 0 12 1 0 0
v_reserve_write_off 18528 18528 0 0.00 0 2886 5 0 0
x_lock_depdocs_on_canc_invoice 10792 10792 0 0.00 0 20959 125 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 3998 365 0 0
x_make_qstorno_1000_1200 5074 5074 0 0.00 2 47692 1293 0 0
x_make_qstorno_1200_2000 5095 5095 0 0.00 3 75658 3099 0 0
x_make_qstorno_2100_3300 436450 436450 0 0.00 0 100692 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2021 07:28:25 to 13-02-2021 07:28:29.

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
04:57:00 21 8192000000 8799971184 8811700224 5617312 0.068 2 2 3 0 5769264 90592 61360
04:58:02 5295 8192000000 9709699600 10006827008 797673568 8.873 100 87 174 0 825560112 7274400 20612144
04:59:11 30838 8192000000 9775238176 10082234368 845160832 9.352 100 91 182 0 882381504 16087744 21132928
05:00:49 19122 8192000000 9810306288 10127716352 874884240 9.649 100 85 170 1 917597440 23580176 19133024
05:02:12 13446 8192000000 9864137536 10195292160 906783728 9.965 100 79 158 0 969981280 45758688 17438864
05:03:31 7488 8192000000 9910491552 10254897152 950450720 10.396 100 70 140 2 1019272608 53746656 15075232
05:04:40 7155 8192000000 9938318352 10293383168 992534192 10.806 100 71 140 3 1055027008 48184320 14308496
05:05:52 2791 8192000000 9987383248 10352029696 1019750304 11.070 100 75 151 1 1101524192 66872400 14901488
05:06:57 1967 8192000000 10030437952 10395971584 1048682544 11.348 100 92 183 0 1147356208 80749520 17924144
05:08:00 5625 8192000000 10038362592 10409984000 1056670752 11.425 100 76 149 2 1155191840 83523968 14997120
05:09:08 4134 8192000000 10000436064 10394324992 1071108800 11.563 100 47 95 0 1128212112 47197696 9905616
05:10:17 49537 8192000000 9993165168 10384535552 1077646480 11.625 100 51 98 5 1099513312 11802704 10064128
05:12:07 4564 8192000000 10013153328 10407555072 1082839904 11.675 100 51 101 0 1115740096 21683072 11217120
05:13:15 10990 8192000000 10006483936 10414260224 1089138816 11.734 100 43 85 3 1119819520 21965680 8715024
05:14:26 3673 8192000000 10001420064 10401460224 1095524080 11.795 100 29 58 1 1113472624 11987184 5961360
05:15:30 48969 8192000000 10003260768 10417819648 1099305792 11.831 100 30 60 0 1113591024 7360720 6924512
05:17:19 6002 8192000000 9968776624 10359255040 1038776336 11.253 96 41 82 0 1073666208 25493456 9396416
05:18:28 7558 8192000000 9945632976 10343604224 1012969584 11.004 96 20 38 3 1043945216 26816640 4158992
05:19:36 10858 8192000000 9915919232 10305511424 975221584 10.638 95 25 50 0 989933168 8704992 6006592
05:20:51 25503 8192000000 9945199728 10337419264 1014269504 11.017 99 28 56 2 1049317040 29228352 5819184
05:22:22 6434 8192000000 9948896320 10332663808 1037582864 11.241 100 25 48 1 1057500944 14279360 5638720
05:23:32 5252 8192000000 9960517520 10351521792 1044556560 11.308 100 46 93 0 1086551648 31616000 10379088
05:24:39 11321 8192000000 9967276256 10372796416 1059288976 11.450 100 39 77 1 1081726272 13429424 9007872
05:25:55 35091 8192000000 9978180816 10391281664 1065125824 11.506 100 19 38 1 1091203584 22024704 4053056
05:27:34 19258 8192000000 10019481152 10413219840 1087723344 11.721 100 45 88 0 1122531232 26158752 8649136
05:28:59 6171 8192000000 10079976640 10476216320 1111740608 11.949 100 53 106 1 1189806304 66586416 11479280
05:30:13 15899 8192000000 10116203184 10519773184 1129861680 12.120 100 52 103 2 1213460256 73076400 10522176
05:31:33 37506 8192000000 10170707440 10590351360 1145573504 12.268 100 57 114 0 1275817776 117808096 12436176
05:33:15 8030 8192000000 10206395888 10615758848 1151391840 12.323 100 58 116 0 1309276128 145848800 12035488
05:34:44 33039 8192000000 10256877008 10678239232 1162416256 12.426 100 44 88 2 1349770128 177500240 9853632
05:36:21 12516 8192000000 10274406944 10693095424 1179830496 12.589 100 41 82 0 1388283824 198698608 9754720
05:37:43 5757 8192000000 10347800336 10758189056 1195059200 12.730 100 67 134 0 1444433776 234323072 15051504
05:38:52 2743 8192000000 10406656400 10816446464 1197620528 12.754 100 82 164 0 1516761472 299467904 19673040
05:40:02 950 8192000000 10423585888 10841522176 1178352896 12.575 99 90 180 0 1532082384 333542368 20187120
05:41:09 2247 8192000000 10463685952 10871177216 1164953728 12.450 99 65 130 0 1554692720 374363952 15375040
05:42:17 1981 8192000000 10433631008 10841534464 1163933440 12.440 100 71 141 0 1536806736 355195792 17677504
05:43:37 3643 8192000000 10494650240 10914246656 1164893296 12.449 100 69 138 2 1601247056 419137936 17215824
05:44:44 1494 8192000000 10500614704 10912276480 1168203616 12.480 100 91 176 4 1595635168 405527776 21903776
05:45:50 7279 8192000000 10423066864 10849701888 1145381392 12.266 98 64 128 0 1538430608 377964128 15085088
05:46:59 14475 8192000000 10392426960 10841825280 1155213824 12.358 100 28 56 1 1490500032 327486528 7799680
05:48:16 19440 8192000000 10428228944 10873102336 1141138640 12.226 100 35 70 2 1534595024 384318640 9137744
05:49:40 27688 8192000000 10353257584 10789142528 1112902816 11.960 99 22 44 2 1453018096 333775072 6340208
05:51:08 24574 8192000000 10273549024 10741309440 1092642320 11.768 99 30 60 1 1420058144 319319840 8095984
05:52:41 87143 8192000000 10052639728 10483286016 1112900560 11.960 99 25 47 1 1146287760 28234704 5152496
05:55:09 159949 8192000000 10118441664 10571681792 1097375728 11.813 97 10 19 0 1176025152 76612336 2037088
05:59:03 177707 8192000000 10040715408 10495631360 1086274912 11.707 96 18 38 0 1176118512 85409136 4434464
06:03:02 37677 8192000000 10073824336 10532892672 1135996752 12.178 100 27 54 0 1191505584 49881280 5627552
06:04:40 110854 8192000000 10059147168 10515329024 1117685168 12.005 98 30 60 1 1168891040 45056976 6148896
06:07:34 58645 8192000000 10059871680 10508353536 1101368336 11.851 97 19 38 2 1137887808 32302992 4216480
06:09:41 87915 8192000000 10093995888 10577735680 1136042144 12.178 100 16 32 1 1212134096 72269104 3822848
06:12:11 33816 8192000000 10073660592 10506850304 1143737856 12.251 100 38 74 0 1183356256 31375760 8242640
06:13:54 51686 8192000000 10142766080 10599641088 1154512464 12.352 100 22 44 2 1239440032 80536528 4391040
06:15:47 62383 8192000000 10109399008 10556641280 1128785328 12.110 98 15 29 0 1170856496 39065616 3005552
06:17:59 57844 8192000000 10071198560 10544979968 1108665584 11.920 97 20 40 2 1164141248 51336176 4139488
06:19:58 37297 8192000000 10101409072 10542481408 1126292656 12.086 99 27 53 1 1192152768 60851008 5009104
06:21:42 76116 8192000000 10079680096 10563796992 1121462000 12.041 98 19 38 0 1190590144 65008320 4119824
06:24:01 113513 8192000000 10089964880 10597511168 1119733760 12.024 98 22 44 0 1171745200 47738464 4272976
06:27:08 66200 8192000000 10086273344 10543538176 1142989376 12.244 100 11 22 0 1177025808 31593056 2443376
06:29:14 77435 8192000000 10099477856 10583617536 1108204784 11.915 96 11 22 1 1179787536 69222720 2360032
06:32:14 79649 8192000000 10078696144 10530758656 1085956752 11.704 95 19 38 0 1127064608 37185664 3922192
06:34:37 44255 8192000000 10075079984 10531360768 1091455728 11.756 97 19 38 0 1137414560 41814704 4144128
06:36:25 77448 8192000000 10091203984 10615263232 1120329552 12.030 99 17 34 0 1199695008 75799856 3565600
06:38:45 61736 8192000000 10095177408 10539835392 1131918048 12.139 99 27 54 2 1208859744 70906272 6035424
06:40:47 74999 8192000000 10100900608 10602455040 1082650304 11.673 96 30 60 0 1162562304 73972064 5939936
06:43:02 130911 8192000000 10057594384 10549198848 1093983408 11.781 97 8 16 0 1141260032 45702608 1574016
06:46:13 76693 8192000000 10054805824 10519687168 1120553504 12.032 100 11 22 0 1157675536 34897872 2224160
06:48:35 60007 8192000000 10057983824 10497007616 1110232096 11.935 98 11 22 0 1162014240 49389168 2392976
06:50:38 60742 8192000000 10082117936 10532634624 1096144256 11.801 98 26 52 1 1143105616 41390240 5571120
06:52:40 39959 8192000000 10061318112 10490265600 1102460432 11.861 99 23 46 0 1142691856 35572560 4658864
06:54:30 63146 8192000000 10116839856 10547847168 1130519008 12.126 100 27 54 0 1204760304 68120272 6121024
06:56:38 56390 8192000000 10153016128 10635550720 1133135104 12.151 100 27 54 0 1249114592 110956384 5023104
06:58:40 73439 8192000000 10078125504 10514481152 1072595616 11.577 94 11 22 0 1097360704 23078128 1686960
07:01:30 135389 8192000000 10088827808 10543230976 1110654320 11.939 97 17 34 0 1155395744 41339520 3401904
07:05:26 47414 8192000000 10076238688 10565128192 1145673104 12.269 100 4 8 0 1178258992 32072368 513520
07:07:14 90616 8192000000 10079452208 10580099072 1120872672 12.035 97 19 37 2 1180461872 55536032 4053168
07:09:45 48809 8192000000 10082893344 10543841280 1114634160 11.976 97 16 32 0 1149233520 31370144 3229216
07:11:40 53067 8192000000 10116862336 10571198464 1137629952 12.193 98 36 72 1 1205408592 59939088 7839552
07:13:43 71465 8192000000 10130547792 10575249408 1143564320 12.249 99 23 45 0 1194102000 45870320 4667360
07:15:54 40552 8192000000 10135075952 10588721152 1126650368 12.090 98 38 74 2 1180185344 45757504 7777472
07:17:35 81093 8192000000 10106949728 10624069632 1130515760 12.126 99 11 22 2 1186617200 53985312 2116128
07:20:00 98444 8192000000 10081964208 10536361984 1145932960 12.271 100 29 58 1 1193171904 41160672 6078272
07:22:39 53425 8192000000 10077740000 10546655232 1144934080 12.262 100 8 16 0 1181013696 34249376 1830240
07:24:35 82180 8192000000 10111223504 10554880000 1142687808 12.241 99 14 28 1 1193732240 47990656 3053776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-02-2021 07:28:31 to 13-02-2021 07:28:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 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-02-2021 07:28:46 to 13-02-2021 07:28:48.

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          224928
MON$OLDEST_ACTIVE               227412
MON$OLDEST_SNAPSHOT             227412
MON$NEXT_TRANSACTION            227412
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                       2074912
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.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:350: 
:351: mscs-test-161.budzdorov.ru    Sat Feb 13 07:26:58 2021
:352:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:353: 
:354: 
:355: mscs-test-161.budzdorov.ru    Sat Feb 13 07:26:58 2021
:356:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:357: 
:358: 
:359: mscs-test-161.budzdorov.ru    Sat Feb 13 07:26:58 2021
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:361: 
:362: 
:363: mscs-test-161.budzdorov.ru    Sat Feb 13 07:27:05 2021
:364:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:365: 
:366: 
:367: mscs-test-161.budzdorov.ru    Sat Feb 13 07:27:07 2021
:368:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:369: 
:370: 
:371: mscs-test-161.budzdorov.ru    Sat Feb 13 07:27:08 2021
:372:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:373: 
:374: 
:375: mscs-test-161.budzdorov.ru    Sat Feb 13 07:27:08 2021
:376:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:377: 
--- end of diff output ---

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

13.02.21 07:28:49. 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-02-13 07:28:51 No severe PSQL-related problems occured
13.02.21 07:28:53. End of report.