Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2020 07:36
Common Performance Final Results
  1. Hardware and OS info
  2. DB and 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, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. 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 16945276 kB
MemAvailable 31587956 kB
Buffers 0 kB
Cached 14752832 kB
SwapCached 5152 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 2020-10-21 07:35:15
DTS_END                         2020-10-21 07:35:15.3020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/45086
TRN_ID                          230282
ATT_ID                          657
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: 8718.60 27033 1046232
customer order: creation 158.57 428 19029
customer order: refuse 13.74 315 1649
order to supplier: creation 41.28 1449 4953
order to supplier: removal 3.31 111176 397
invoice (draft): creation 41.23 3501 4948
invoice (draft): removal 51.57 898 6188
invoice accept: apply 37.85 16148 4542
invoice accept: cancel 46.48 803 5578
customer reserve: creation 3489.58 155 418750
customer reserve: removal 4568.83 64 548259
realization accept: apply 76.22 211 9147
realization accept: cancel 71.96 122 8635
payment from customer: creation 53.83 124 6459
payment from customer: removal 5.38 81 646
payment to supplier: creation 46.13 142 5535
payment to supplier: removal 3.11 98 373
service: total inventory turnovers 3.20 3535 384
service: total monetary turnovers 5.60 498 672
service: aggregate perf. data 0.70 17072 84
service: refresh index statistics 0.03 383846 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2020 07:37:08 to 21-10-2020 07:37:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4590
TEST_TIME 2 2961
TEST_TIME 3 2541
TEST_TIME 4 3043
TEST_TIME 5 3579
TEST_TIME 6 4304
TEST_TIME 7 5089
TEST_TIME 8 5665
TEST_TIME 9 5634
TEST_TIME 10 5702
TEST_TIME 11 6479
TEST_TIME 12 6602
TEST_TIME 13 6579
TEST_TIME 14 6998
TEST_TIME 15 7468
TEST_TIME 16 7815
TEST_TIME 17 8006
TEST_TIME 18 8468
TEST_TIME 19 8576
TEST_TIME 20 9327
TEST_TIME 21 9463
TEST_TIME 22 9753
TEST_TIME 23 9933
TEST_TIME 24 10644
TEST_TIME 25 10716
TEST_TIME 26 10431
TEST_TIME 27 10468
TEST_TIME 28 10117
TEST_TIME 29 10141
TEST_TIME 30 9943
TEST_TIME 31 9946
TEST_TIME 32 10179
TEST_TIME 33 10243
TEST_TIME 34 10037
TEST_TIME 35 9911
TEST_TIME 36 9756
TEST_TIME 37 9664
TEST_TIME 38 9621
TEST_TIME 39 9467
TEST_TIME 40 9319
TEST_TIME 41 9309
TEST_TIME 42 9327
TEST_TIME 43 9300
TEST_TIME 44 9310
TEST_TIME 45 9178
TEST_TIME 46 9288
TEST_TIME 47 9176
TEST_TIME 48 9234
TEST_TIME 49 9177
TEST_TIME 50 9095
TEST_TIME 51 9102
TEST_TIME 52 9044
TEST_TIME 53 9139
TEST_TIME 54 9208
TEST_TIME 55 9291
TEST_TIME 56 9393
TEST_TIME 57 9318
TEST_TIME 58 9371
TEST_TIME 59 9366
TEST_TIME 60 9378
TEST_TIME 61 9430
TEST_TIME 62 9298
TEST_TIME 63 9192
TEST_TIME 64 9104
TEST_TIME 65 9121
TEST_TIME 66 9086
TEST_TIME 67 9028
TEST_TIME 68 9006
TEST_TIME 69 8982
TEST_TIME 70 8944
TEST_TIME 71 8961
TEST_TIME 72 8956
TEST_TIME 73 8964
TEST_TIME 74 8986
TEST_TIME 75 8979
TEST_TIME 76 9050
TEST_TIME 77 9083
TEST_TIME 78 9104
TEST_TIME 79 9057
TEST_TIME 80 8997
TEST_TIME 81 8942
TEST_TIME 82 8875
TEST_TIME 83 8799
TEST_TIME 84 8803
TEST_TIME 85 8774
TEST_TIME 86 8751
TEST_TIME 87 8723
TEST_TIME 88 8744
TEST_TIME 89 8739
TEST_TIME 90 8705
TEST_TIME 91 8799
TEST_TIME 92 8853
TEST_TIME 93 8887
TEST_TIME 94 8944
TEST_TIME 95 8976
TEST_TIME 96 8942
TEST_TIME 97 9025
TEST_TIME 98 9000
TEST_TIME 99 8917
TEST_TIME 100 8874
TEST_TIME 101 8863
TEST_TIME 102 8817
TEST_TIME 103 8750
TEST_TIME 104 8756
TEST_TIME 105 8726
TEST_TIME 106 8709
TEST_TIME 107 8721
TEST_TIME 108 8778
TEST_TIME 109 8767
TEST_TIME 110 8742
TEST_TIME 111 8738
TEST_TIME 112 8736
TEST_TIME 113 8820
TEST_TIME 114 8878
TEST_TIME 115 8863
TEST_TIME 116 8923
TEST_TIME 117 8854
TEST_TIME 118 8798
TEST_TIME 119 8781
TEST_TIME 120 8722

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2020 07:37:12 to 21-10-2020 07:37:15.

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 1434393 1434393 0 0.00 0 24238 6 0 0
doc_list_biud 1434390 1434390 0 0.00 0 70110 31 0 0
sp_add_invoice_to_stock 4701 4542 159 3.38 31 148437 16149 0 159
sp_cancel_adding_invoice 5578 5578 0 0.00 5 69602 803 0 0
sp_cancel_client_order 1649 1649 0 0.00 5 7115 316 0 0
sp_cancel_customer_reserve 548259 548259 0 0.00 1 48705 65 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 3 5583 82 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 5981 99 0 0
sp_cancel_supplier_invoice 6194 6188 6 0.09 2 70124 898 0 6
sp_cancel_supplier_order 397 397 0 0.00 6 1040395 111176 0 0
sp_cancel_write_off 8635 8635 0 0.00 1 11687 123 0 0
sp_client_order 19029 19029 0 0.00 4 63828 428 0 0
sp_customer_reserve 418750 418750 0 0.00 0 44112 155 0 0
sp_fill_shopping_cart 28925 28925 0 0.00 1 35015 50 0 0
sp_fill_shopping_cart_clo_res 418745 418745 0 0.00 0 25460 19 0 0
sp_get_clo_for_invoice 4534 4534 0 0.00 0 42893 579 0 0
sp_kill_cost_storno 15232 15232 0 0.00 0 11627 55 0 0
sp_kill_qstorno_ret_qs2qd 1111738 1111738 0 0.00 0 70109 35 0 0
sp_kill_qty_storno 584381 584381 0 0.00 0 70109 76 0 0
sp_lock_selected_doc 19107 19107 0 0.00 0 9139 6 0 0
sp_make_cost_storno 25671 25671 0 0.00 0 17199 62 0 0
sp_multiply_rows_for_pdistr 25669 25669 0 0.00 0 9153 33 0 0
sp_multiply_rows_for_qdistr 19029 19029 0 0.00 0 63820 256 0 0
sp_pay_from_customer 6459 6459 0 0.00 4 24808 125 0 0
sp_pay_to_supplier 5535 5535 0 0.00 5 24410 142 0 0
sp_payment_common 11994 11994 0 0.00 1 14151 26 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 7104 276 0 0
sp_qd_handle_on_invoice_upd_sts 10108 10108 0 0.00 0 32656 349 0 0
sp_qd_handle_on_reserve_upd_sts 9145 9145 0 0.00 0 10280 58 0 0
sp_reserve_write_off 9147 9147 0 0.00 7 24356 211 0 0
sp_supplier_invoice 4948 4948 0 0.00 7 84900 3501 0 0
sp_supplier_order 4953 4953 0 0.00 5 37024 1449 0 0
srv_aggregate_perf_data 85 84 1 1.17 3041 95884 17073 0 1
srv_fill_mon_cache_memory 58 58 0 0.00 891 171114 56650 0 0
srv_find_qd_qs_mism 1002882 1002882 0 0.00 0 19823 5 0 0
srv_make_invnt_saldo 384 384 0 0.00 72 85435 3536 0 0
srv_make_money_saldo 672 672 0 0.00 1 17873 499 0 0
srv_recalc_idx_stat 4 4 0 0.00 300884 496219 383846 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4542 4542 0 0.00 0 13688 156 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 7671 121 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 1442 10 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 13802 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 1108 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 13329 48 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 720 17 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 14015 1437 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 8033 14 0 0
t$perf-norm:sp_client_order 19029 19029 0 0.00 0 12113 21 0 0
t$perf-norm:sp_customer_reserve 852 852 0 0.00 0 1470 6 0 0
t$perf-norm:sp_pay_from_customer 6459 6459 0 0.00 0 13681 10 0 0
t$perf-norm:sp_pay_to_supplier 5535 5535 0 0.00 0 6492 9 0 0
t$perf-norm:sp_reserve_write_off 9147 9147 0 0.00 0 7254 10 0 0
t$perf-norm:sp_supplier_invoice 4948 4948 0 0.00 0 6153 19 0 0
t$perf-norm:sp_supplier_order 4953 4953 0 0.00 0 13642 23 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 12809 165 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 93 4 0 0
t$perf-norm:srv_make_invnt_saldo 384 384 0 0.00 0 698 7 0 0
t$perf-norm:srv_make_money_saldo 672 672 0 0.00 0 717 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9378 9060 318 3.39 0 8660 21 0 318
v_all_customers 34158 34158 0 0.00 0 23 1 0 0
v_all_suppliers 27842 27842 0 0.00 0 17 1 0 0
v_all_wares 38058 38058 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 3053 16 0 0
v_cancel_client_order 3298 3298 0 0.00 0 6635 13 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 583 5 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 1813 5 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 2699 16 0 0
v_cancel_supplier_invoice 816 804 12 1.47 0 3130 22 0 12
v_cancel_supplier_order 794 794 0 0.00 0 2430 8 0 0
v_cancel_write_off 1866 1866 0 0.00 0 1938 12 0 0
v_max_id_clo_ord 4952 4952 0 0.00 0 1780 1 0 0
v_max_id_clo_res 852 852 0 0.00 0 2302 17 0 0
v_max_id_ord_sup 4944 4944 0 0.00 0 6463 7 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 2166 3 0 0
v_max_non_paid_realizn 3584 3584 0 0.00 0 7722 5 0 0
v_min_id_clo_ord 4952 4952 0 0.00 0 15750 8 0 0
v_min_id_clo_res 852 852 0 0.00 0 7720 36 0 0
v_min_id_ord_sup 4944 4944 0 0.00 0 7163 9 0 0
v_min_non_paid_invoice 5535 5535 0 0.00 0 14147 17 0 0
v_min_non_paid_realizn 6459 6459 0 0.00 0 8267 19 0 0
v_our_firm 9650 9650 0 0.00 0 56 1 0 0
v_reserve_write_off 18290 18290 0 0.00 0 8264 9 0 0
x_lock_depdocs_on_canc_invoice 11364 11364 0 0.00 0 17247 96 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 32788 473 0 0
x_make_qstorno_1000_1200 4953 4953 0 0.00 2 37015 1371 0 0
x_make_qstorno_1200_2000 4948 4948 0 0.00 3 84890 3290 0 0
x_make_qstorno_2100_3300 418750 418750 0 0.00 0 44111 134 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2020 07:37:16 to 21-10-2020 07:37:20.

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:05:18 2 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
05:06:19 27645 8192000000 9719384656 10012917760 789386144 8.789 100 88 177 1 818258160 7498560 21373456
05:07:54 22807 8192000000 9759261568 10068987904 837619072 9.276 100 84 169 2 869239296 11877760 19742464
05:09:18 11865 8192000000 9807389328 10127794176 872785040 9.628 100 82 164 0 915841232 24418352 18637840
05:10:38 9814 8192000000 9884355168 10219372544 931919680 10.214 100 80 160 1 991549872 42620080 17010112
05:11:50 6662 8192000000 9939029472 10285772800 969696128 10.584 100 83 166 1 1057668880 70312064 17660688
05:12:59 3323 8192000000 9981210640 10334715904 1006342960 10.940 100 89 180 1 1096042784 70829280 18870544
05:14:04 1898 8192000000 10000579376 10365272064 1031900336 11.187 100 81 162 2 1105241648 56900592 16440720
05:15:07 1714 8192000000 10017458672 10391326720 1057528704 11.433 100 80 158 0 1132415072 58561296 16325072
05:16:09 8129 8192000000 9991012128 10385997824 1060828816 11.464 100 52 103 1 1105525344 33229040 11467488
05:17:20 5385 8192000000 10012199104 10394918912 1072483920 11.576 100 46 94 1 1131355792 49068224 9803648
05:18:28 6760 8192000000 9990482896 10383417344 1081633600 11.663 100 50 100 3 1116707696 23127952 11946144
05:19:39 56265 8192000000 10005140112 10394968064 1086836384 11.713 100 48 96 0 1105458912 7555040 11067488
05:21:35 4392 8192000000 10014625136 10410819584 1095732336 11.797 100 52 104 4 1119295600 12109472 11453792
05:22:41 6393 8192000000 9986532432 10392530944 1088362832 11.727 99 36 72 1 1111540656 15928576 7249248
05:23:49 10396 8192000000 10008248224 10419408896 1074204960 11.592 98 21 43 0 1102884688 24748800 3930928
05:25:01 58100 8192000000 9985201536 10390392832 1013654192 11.011 93 26 52 2 1047438016 28246240 5537584
05:27:00 7283 8192000000 9929661504 10316566528 999843264 10.877 97 20 39 3 1030002672 26281904 3877504
05:28:08 6470 8192000000 9937268048 10320863232 981340784 10.697 96 34 67 0 1018130304 29477680 7311840
05:29:17 4525 8192000000 9929698624 10304069632 1007156336 10.948 99 35 65 5 1038204736 23652816 7395584
05:30:22 56804 8192000000 9953555600 10347089920 1034097152 11.208 100 28 54 5 1054353968 14089392 6167424
05:32:19 4299 8192000000 9957482208 10350919680 1048231984 11.344 100 30 62 1 1070816400 15419392 7165024
05:33:25 9103 8192000000 9963050976 10366304256 1063072720 11.486 100 50 100 2 1087644656 13035936 11536000
05:34:35 7612 8192000000 9984326112 10386345984 1069750016 11.550 100 50 100 2 1093104736 13081552 10273168
05:35:46 40191 8192000000 10018581392 10409308160 1093001472 11.771 100 34 69 2 1120724560 20151616 7571472
05:37:28 81069 8192000000 10091752320 10497806336 1122756464 12.053 100 33 66 4 1176541216 46826704 6958048
05:40:08 30273 8192000000 10152430832 10558689280 1136717680 12.185 100 38 76 0 1264114528 119161024 8235824
05:41:41 3995 8192000000 10207848560 10609336320 1149481264 12.305 100 66 131 3 1315771312 152549936 13740112
05:42:50 15382 8192000000 10196458352 10611814400 1154309200 12.350 100 54 108 1 1309470256 142293984 12867072
05:44:14 3577 8192000000 10206249248 10626019328 1157128064 12.376 100 57 113 2 1318433552 148755856 12549632
05:45:20 13777 8192000000 10285301136 10697940992 1161411792 12.416 99 61 122 2 1381881488 206458080 14011616
05:46:40 2426 8192000000 10258665472 10664734720 1156689552 12.372 99 78 146 4 1356938832 184173600 16075680
05:47:47 2839 8192000000 10289345440 10694156288 1167848496 12.477 100 74 145 1 1396613120 211828608 16936016
05:48:55 3556 8192000000 10287877232 10691936256 1116176784 11.991 97 65 131 1 1374883056 243670512 15035760
05:50:06 890 8192000000 10295226656 10692882432 1141284592 12.228 100 87 169 0 1391395600 230713904 19397104
05:51:13 1873 8192000000 10283778992 10675212288 1133046448 12.150 100 66 131 0 1379086032 230665344 15374240
05:52:22 5924 8192000000 10298124768 10704539648 1127448624 12.097 99 50 99 2 1388285328 249008400 11828304
05:53:33 14594 8192000000 10249906416 10682486784 1103224864 11.868 99 28 56 3 1347560512 236826496 7509152
05:54:52 17609 8192000000 10257306704 10705731584 1122090704 12.047 100 27 54 1 1399104112 269770080 7243328
05:56:15 22383 8192000000 10238167264 10674380800 1124042768 12.065 100 25 49 2 1334265616 203887728 6335120
05:57:40 16907 8192000000 10231100592 10659287040 1128333264 12.106 100 20 40 1 1332529616 199158400 5037952
05:58:58 12401 8192000000 10193187680 10650497024 1109818144 11.931 99 25 50 2 1297837472 181686544 6332784
06:00:12 25997 8192000000 10119648128 10555633664 1096511648 11.805 100 14 28 1 1210036752 110159136 3365968
06:01:41 10427 8192000000 10129300512 10538930176 1115911488 11.988 100 49 97 0 1221881472 93853312 12116672
06:02:53 72943 8192000000 10147164640 10567258112 1106970496 11.904 99 31 62 1 1233892624 119510672 7411456
06:05:11 65775 8192000000 10154166656 10571616256 1091868848 11.760 98 29 57 2 1223031696 124675712 6487136
06:07:41 63063 8192000000 10048717712 10519642112 1101849296 11.855 99 9 18 2 1147125136 43566672 1709168
06:09:44 70962 8192000000 10082592960 10495135744 1117100416 12.000 99 29 58 0 1170138240 46776336 6261488
06:12:00 111365 8192000000 10067536464 10516971520 1128310688 12.105 100 20 40 2 1210023504 77440960 4271856
06:15:07 60231 8192000000 10166145296 10607329280 1135844304 12.176 99 35 70 1 1243788384 99364480 8579600
06:17:22 65008 8192000000 10136919968 10584264704 1139165184 12.208 100 21 41 0 1257817024 113366176 5285664
06:19:27 90102 8192000000 10145297744 10606350336 1110562672 11.938 97 10 20 0 1193630256 80898912 2168672
06:22:26 67422 8192000000 10063299712 10542907392 1120488448 12.032 98 13 26 1 1176845088 53745552 2611088
06:24:34 49464 8192000000 10065270640 10498265088 1124809408 12.072 99 19 39 1 1148843072 20453712 3579952
06:26:25 44897 8192000000 10109242096 10549628928 1138267296 12.199 100 32 64 0 1189617056 44277520 7072240
06:28:11 100977 8192000000 10132577872 10625634304 1126488480 12.088 98 17 34 1 1206942160 77334240 3119440
06:30:57 98128 8192000000 10071336880 10534141952 1124594000 12.070 98 8 16 0 1190450000 64442736 1413264
06:33:35 140852 8192000000 10095068960 10540101632 1094395680 11.784 95 13 26 0 1151867776 55041776 2430320
06:37:01 171114 8192000000 10100003792 10550140928 1089053760 11.734 95 18 35 0 1150807728 58045728 3708240
06:41:00 68132 8192000000 10047688432 10526986240 1095129520 11.791 97 8 16 0 1134988816 38396592 1462704
06:43:09 60708 8192000000 10055391952 10519494656 1106868048 11.903 98 11 22 0 1150012192 40594576 2549568
06:45:35 11061 8192000000 10077575952 10485755904 1122407408 12.050 100 61 120 0 1161378352 25769776 13201168
06:46:47 48370 8192000000 10054593968 10482683904 1047223472 11.334 94 22 44 1 1097253056 45120704 4908880
06:49:07 62875 8192000000 10105301856 10570395648 1076127232 11.611 96 27 54 1 1158996928 77172608 5697088
06:51:14 77740 8192000000 10097981696 10522378240 1110276224 11.935 100 12 24 1 1172919552 60020880 2622448
06:53:45 56868 8192000000 10065219136 10500861952 1114079760 11.971 99 33 66 0 1158117664 37898144 6139760
06:56:32 73097 8192000000 10063862544 10533285888 1116406752 11.993 99 10 20 0 1161809344 43338080 2064512
06:59:35 73302 8192000000 10054614800 10501488640 1115196416 11.982 99 18 35 0 1147212144 28734864 3280864
07:01:57 47118 8192000000 10109933520 10540175360 1087534592 11.719 96 30 61 2 1159404912 65380416 6489904
07:03:57 64202 8192000000 10128971536 10554609664 1095257728 11.793 97 25 51 2 1186522272 85529936 5734608
07:06:09 76889 8192000000 10127328176 10648694784 1121144832 12.038 99 21 42 0 1294917376 169058640 4713904
07:08:26 102249 8192000000 10108660160 10574462976 1099669856 11.835 97 12 24 0 1169276944 67310240 2296848
07:11:09 36754 8192000000 10095247248 10517024768 1113055328 11.961 98 30 60 0 1152463456 33579888 5828240
07:12:51 131325 8192000000 10061338384 10509275136 1114297696 11.973 98 14 28 0 1149571488 32678832 2594960
07:16:11 78236 8192000000 10064428544 10520686592 1115060752 11.980 98 11 22 1 1147101024 29790288 2249984
07:18:29 90947 8192000000 10062861264 10543333376 1094340432 11.784 96 14 28 0 1150929376 53913392 2675552
07:21:05 93150 8192000000 10148878640 10588602368 1124548080 12.070 98 21 42 3 1202076576 73125056 4403440
07:23:39 73083 8192000000 10092148384 10530430976 1121363296 12.040 98 13 26 1 1177240768 53151568 2725904
07:25:55 77921 8192000000 10161144256 10598305792 1118270592 12.011 97 30 60 2 1199743568 74813648 6659328
07:28:24 117123 8192000000 10041195808 10467942400 1051683024 11.377 93 13 24 1 1102140176 47832416 2624736
07:31:22 132401 8192000000 10062351440 10560528384 1092941472 11.771 97 7 14 2 1152283520 58021888 1320160
07:34:55 33656 8192000000 9109243536 9382785024 254274512 3.010 23 3 6 0 290777376 35848016 654848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-10-2020 07:37:22 to 21-10-2020 07:37:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 159 v_add_invoice_to_stock 335544517
user_exc 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-10-2020 07:37:37 to 21-10-2020 07:37:39.

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          230544
MON$OLDEST_ACTIVE               230545
MON$OLDEST_SNAPSHOT             230545
MON$NEXT_TRANSACTION            230545
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                       2103808
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2229 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2229 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2229 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.2229 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 ---
--- end of diff output ---

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

21.10.20 07:37:40. 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
2020-10-21 07:37:42 No severe PSQL-related problems occured
21.10.20 07:37:43. End of report.