Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.10.2020 16:28
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 17445416 kB
MemAvailable 31593508 kB
Buffers 0 kB
Cached 14287136 kB
SwapCached 3040 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 3.0.7
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-23 16:27:00
DTS_END                         2020-10-23 16:27:00.1470
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(56:17)
IP                              ::1/49772
TRN_ID                          252051
ATT_ID                          658
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: 9762.92 22168 1171551
customer order: creation 183.69 349 22043
customer order: refuse 15.77 261 1892
order to supplier: creation 48.78 1046 5853
order to supplier: removal 3.76 73182 451
invoice (draft): creation 47.86 2827 5743
invoice (draft): removal 53.98 614 6477
invoice accept: apply 45.03 15468 5404
invoice accept: cancel 48.72 651 5846
customer reserve: creation 4146.55 154 497586
customer reserve: removal 4859.90 44 583188
realization accept: apply 88.96 146 10675
realization accept: cancel 82.89 86 9947
payment from customer: creation 61.81 109 7417
payment from customer: removal 5.83 76 699
payment to supplier: creation 52.48 120 6297
payment to supplier: removal 3.86 71 463
service: total inventory turnovers 5.20 905 624
service: total monetary turnovers 6.92 28 830
service: aggregate perf. data 0.93 16985 112
service: refresh index statistics 0.03 330244 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-10-2020 16:28:43 to 23-10-2020 16:28:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8603
TEST_TIME 2 6186
TEST_TIME 3 6452
TEST_TIME 4 6417
TEST_TIME 5 6702
TEST_TIME 6 7354
TEST_TIME 7 8442
TEST_TIME 8 8189
TEST_TIME 9 8204
TEST_TIME 10 8219
TEST_TIME 11 8401
TEST_TIME 12 8846
TEST_TIME 13 9485
TEST_TIME 14 9673
TEST_TIME 15 9583
TEST_TIME 16 9903
TEST_TIME 17 10429
TEST_TIME 18 10706
TEST_TIME 19 11312
TEST_TIME 20 12518
TEST_TIME 21 12316
TEST_TIME 22 12051
TEST_TIME 23 11833
TEST_TIME 24 11860
TEST_TIME 25 11496
TEST_TIME 26 11553
TEST_TIME 27 11338
TEST_TIME 28 11216
TEST_TIME 29 11217
TEST_TIME 30 11000
TEST_TIME 31 10767
TEST_TIME 32 10672
TEST_TIME 33 10486
TEST_TIME 34 10710
TEST_TIME 35 10647
TEST_TIME 36 10814
TEST_TIME 37 10764
TEST_TIME 38 10700
TEST_TIME 39 10824
TEST_TIME 40 10797
TEST_TIME 41 10945
TEST_TIME 42 10901
TEST_TIME 43 10856
TEST_TIME 44 10914
TEST_TIME 45 10794
TEST_TIME 46 10842
TEST_TIME 47 10858
TEST_TIME 48 10902
TEST_TIME 49 10695
TEST_TIME 50 10543
TEST_TIME 51 10574
TEST_TIME 52 10486
TEST_TIME 53 10415
TEST_TIME 54 10311
TEST_TIME 55 10312
TEST_TIME 56 10270
TEST_TIME 57 10212
TEST_TIME 58 10191
TEST_TIME 59 10205
TEST_TIME 60 10210
TEST_TIME 61 10294
TEST_TIME 62 10350
TEST_TIME 63 10311
TEST_TIME 64 10329
TEST_TIME 65 10330
TEST_TIME 66 10392
TEST_TIME 67 10515
TEST_TIME 68 10446
TEST_TIME 69 10477
TEST_TIME 70 10517
TEST_TIME 71 10488
TEST_TIME 72 10376
TEST_TIME 73 10373
TEST_TIME 74 10347
TEST_TIME 75 10316
TEST_TIME 76 10294
TEST_TIME 77 10235
TEST_TIME 78 10201
TEST_TIME 79 10153
TEST_TIME 80 10118
TEST_TIME 81 10048
TEST_TIME 82 10054
TEST_TIME 83 10048
TEST_TIME 84 10106
TEST_TIME 85 10123
TEST_TIME 86 10112
TEST_TIME 87 10137
TEST_TIME 88 10082
TEST_TIME 89 10109
TEST_TIME 90 10174
TEST_TIME 91 10158
TEST_TIME 92 10173
TEST_TIME 93 10244
TEST_TIME 94 10245
TEST_TIME 95 10189
TEST_TIME 96 10102
TEST_TIME 97 10081
TEST_TIME 98 10064
TEST_TIME 99 10017
TEST_TIME 100 10049
TEST_TIME 101 9982
TEST_TIME 102 9983
TEST_TIME 103 9968
TEST_TIME 104 9988
TEST_TIME 105 10040
TEST_TIME 106 10033
TEST_TIME 107 10040
TEST_TIME 108 10034
TEST_TIME 109 10028
TEST_TIME 110 10059
TEST_TIME 111 10116
TEST_TIME 112 10097
TEST_TIME 113 10021
TEST_TIME 114 9988
TEST_TIME 115 9941
TEST_TIME 116 9948
TEST_TIME 117 9920
TEST_TIME 118 9875
TEST_TIME 119 9846

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-10-2020 16:28:46 to 23-10-2020 16:28:50.

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 1632025 1632025 0 0.00 0 19848 5 0 0
doc_list_biud 1632025 1632025 0 0.00 0 23944 20 0 0
sp_add_invoice_to_stock 5536 5404 132 2.38 39 155586 15468 0 132
sp_cancel_adding_invoice 5846 5846 0 0.00 4 47302 651 0 0
sp_cancel_client_order 1892 1892 0 0.00 4 9024 261 0 0
sp_cancel_customer_reserve 583188 583188 0 0.00 1 22102 44 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 6945 77 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 7591 71 0 0
sp_cancel_supplier_invoice 6484 6477 7 0.10 1 13859 615 0 7
sp_cancel_supplier_order 451 451 0 0.00 5 782187 73182 0 0
sp_cancel_write_off 9947 9947 0 0.00 1 19901 87 0 0
sp_client_order 22043 22043 0 0.00 3 39880 350 0 0
sp_customer_reserve 497586 497586 0 0.00 0 133639 155 0 0
sp_fill_shopping_cart 33617 33617 0 0.00 1 29513 30 0 0
sp_fill_shopping_cart_clo_res 497578 497578 0 0.00 0 18072 12 0 0
sp_get_clo_for_invoice 5393 5393 0 0.00 0 26762 277 0 0
sp_kill_cost_storno 16955 16955 0 0.00 0 12285 40 0 0
sp_kill_qstorno_ret_qs2qd 1183251 1183251 0 0.00 0 22084 24 0 0
sp_kill_qty_storno 623868 623868 0 0.00 0 23944 52 0 0
sp_lock_selected_doc 22267 22267 0 0.00 0 9730 7 0 0
sp_make_cost_storno 29781 29781 0 0.00 0 18433 42 0 0
sp_multiply_rows_for_pdistr 29781 29781 0 0.00 0 6560 22 0 0
sp_multiply_rows_for_qdistr 22043 22043 0 0.00 0 17532 155 0 0
sp_pay_from_customer 7417 7417 0 0.00 4 12265 110 0 0
sp_pay_to_supplier 6297 6297 0 0.00 5 14001 120 0 0
sp_payment_common 13714 13714 0 0.00 1 12257 24 0 0
sp_qd_handle_on_cancel_clo 1892 1892 0 0.00 0 9015 226 0 0
sp_qd_handle_on_invoice_upd_sts 11238 11238 0 0.00 0 23943 251 0 0
sp_qd_handle_on_reserve_upd_sts 10675 10675 0 0.00 0 14041 48 0 0
sp_reserve_write_off 10675 10675 0 0.00 6 14609 147 0 0
sp_supplier_invoice 5743 5743 0 0.00 5 76197 2827 0 0
sp_supplier_order 5853 5853 0 0.00 6 52271 1046 0 0
srv_aggregate_perf_data 112 112 0 0.00 2408 297078 16985 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 2014 149377 54084 0 0
srv_find_qd_qs_mism 1120519 1120519 0 0.00 0 19848 5 0 0
srv_make_invnt_saldo 624 624 0 0.00 35 39570 905 0 0
srv_make_money_saldo 830 830 0 0.00 1 6715 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 179920 558231 330245 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5404 5404 0 0.00 0 22123 139 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 6117 90 0 0
t$perf-norm:sp_cancel_client_order 1892 1892 0 0.00 0 9918 17 0 0
t$perf-norm:sp_cancel_customer_reserve 767 767 0 0.00 0 532 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 9086 40 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 2041 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 499 7 0 0
t$perf-norm:sp_cancel_supplier_order 451 451 0 0.00 0 33650 1326 0 0
t$perf-norm:sp_cancel_write_off 1085 1085 0 0.00 0 2177 6 0 0
t$perf-norm:sp_client_order 22043 22043 0 0.00 0 10223 14 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 4960 6 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 8476 10 0 0
t$perf-norm:sp_pay_to_supplier 6297 6297 0 0.00 0 4985 8 0 0
t$perf-norm:sp_reserve_write_off 10675 10675 0 0.00 0 8504 10 0 0
t$perf-norm:sp_supplier_invoice 5743 5743 0 0.00 0 10338 19 0 0
t$perf-norm:sp_supplier_order 5853 5853 0 0.00 0 7191 12 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 218 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 5418 101 0 0
t$perf-norm:srv_make_invnt_saldo 624 624 0 0.00 0 738 13 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 3703 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 7 0 0
v_add_invoice_to_stock 11046 10782 264 2.39 0 5116 20 0 264
v_all_customers 39928 39928 0 0.00 0 22 0 0 0
v_all_suppliers 32110 32110 0 0.00 0 69 1 0 0
v_all_wares 44086 44086 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 3718 11 0 0
v_cancel_client_order 3784 3784 0 0.00 0 5106 11 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 5241 14 0 0
v_cancel_customer_reserve 1534 1534 0 0.00 0 2477 8 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 3256 6 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 4034 22 0 14
v_cancel_supplier_order 902 902 0 0.00 0 3456 9 0 0
v_cancel_write_off 2170 2170 0 0.00 0 1593 12 0 0
v_max_id_clo_ord 5852 5852 0 0.00 0 2509 1 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 1657 16 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 4267 5 0 0
v_max_non_paid_invoice 1816 1816 0 0.00 0 1206 4 0 0
v_max_non_paid_realizn 4000 4000 0 0.00 0 6569 6 0 0
v_min_id_clo_ord 5852 5852 0 0.00 0 2508 6 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 2931 6 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 4545 4 0 0
v_min_non_paid_invoice 6297 6297 0 0.00 0 4617 14 0 0
v_min_non_paid_realizn 7417 7417 0 0.00 0 12254 15 0 0
v_our_firm 10992 10992 0 0.00 0 13 1 0 0
v_reserve_write_off 21350 21350 0 0.00 0 7544 8 0 0
x_lock_depdocs_on_canc_invoice 11915 11915 0 0.00 0 24049 117 0 0
x_lock_depdocs_on_canc_sup_ord 451 451 0 0.00 0 7393 248 0 0
x_make_qstorno_1000_1200 5853 5853 0 0.00 1 52258 979 0 0
x_make_qstorno_1200_2000 5743 5743 0 0.00 3 76177 2723 0 0
x_make_qstorno_2100_3300 497586 497586 0 0.00 0 133638 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-10-2020 16:28:51 to 23-10-2020 16:28:55.

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
13:57:03 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:04 34997 8192000000 10353701568 10672242688 1399221520 14.588 100 87 174 1 1434351200 6201504 28928176
13:59:43 15813 8192000000 10420351216 10748706816 1476923360 15.274 100 87 174 1 1513382336 8498592 27960384
14:01:09 17115 8192000000 10499813664 10846441472 1546055232 15.876 100 77 154 0 1579469008 9602624 23811152
14:02:30 8633 8192000000 10597328000 10954178560 1646112256 16.731 100 84 168 1 1683513584 12617456 24783872
14:03:42 7962 8192000000 10656049568 11033505792 1722966000 17.377 100 78 156 2 1755798768 10844640 21988128
14:04:54 4393 8192000000 10724805472 11104022528 1781580704 17.862 100 87 174 1 1819856720 13803648 24472368
14:06:00 2902 8192000000 10776975040 11159597056 1825228080 18.220 100 89 178 1 1864259344 13909328 25121936
14:07:04 2018 8192000000 10802084480 11190800384 1861233632 18.513 100 84 170 5 1896444176 11106464 24104080
14:08:07 11499 8192000000 10792780512 11208753152 1873888144 18.616 100 46 92 3 1894064384 7515552 12660688
14:09:19 8365 8192000000 10799000256 11222548480 1877346064 18.644 100 24 48 1 1891698928 7776576 6576288
14:10:31 9423 8192000000 10796980352 11231543296 1893503136 18.774 100 20 40 1 1905953408 6426080 6024192
14:11:43 55656 8192000000 10827062064 11253100544 1906212672 18.876 100 31 58 1 1921297360 4485680 10599008
14:13:44 5513 8192000000 10834917728 11265740800 1914800128 18.945 100 22 45 0 1927055152 6304224 5950800
14:14:50 4864 8192000000 10847233776 11274358784 1916253040 18.957 100 33 65 4 1932791456 7122464 9415952
14:15:56 9928 8192000000 10787099680 11217494016 1859907424 18.503 99 22 44 1 1872452080 5767504 6777152
14:17:10 18109 8192000000 10752233552 11181887488 1763828064 17.716 94 26 52 0 1777403040 6195376 7379600
14:18:31 6746 8192000000 10713082720 11125981184 1778907888 17.840 99 32 60 3 1798285568 10178640 9199040
14:19:39 14947 8192000000 10694003520 11098574848 1694835888 17.142 95 23 46 2 1706913232 5077872 6999472
14:20:54 35620 8192000000 10722942032 11151040512 1799206576 18.007 99 28 54 0 1812618720 5531488 7880656
14:22:47 4216 8192000000 10764646288 11176177664 1838516864 18.329 100 47 96 5 1857787680 4745952 14524864
14:23:53 7963 8192000000 10769538000 11191713792 1846523168 18.394 100 23 46 0 1858622176 5284288 6814720
14:25:04 11442 8192000000 10769706960 11195936768 1851787152 18.437 100 20 38 1 1862585440 5231376 5566912
14:26:17 89321 8192000000 10805514240 11232604160 1878021456 18.649 100 40 79 4 1895293632 5948832 11323344
14:28:54 2031 8192000000 10842348832 11266244608 1914124192 18.940 100 47 93 2 1934623968 7461824 13037952
14:30:00 12175 8192000000 10864960160 11290865664 1933569040 19.095 100 41 82 1 1955225952 9327744 12329168
14:31:32 6540 8192000000 10889146192 11319857152 1954052512 19.259 100 51 102 1 1980194560 11283440 14858608
14:32:44 5649 8192000000 10904762480 11329138688 1949626608 19.224 99 66 132 1 1986944240 18370832 18946800
14:33:53 13341 8192000000 10906275040 11340627968 1962363744 19.325 100 59 115 1 1999639408 19688816 17586848
14:35:27 20972 8192000000 10920399936 11358679040 1960363408 19.309 99 38 76 0 1992825680 20953456 11508816
14:36:51 3987 8192000000 10953328928 11376951296 1994948688 19.583 100 74 146 3 2043956144 27684480 21322976
14:37:58 4132 8192000000 10951462864 11370110976 1981836832 19.479 100 76 150 2 2032366064 27069712 23459520
14:39:08 2011 8192000000 10945942624 11358699520 1971142352 19.395 100 87 176 0 2031547440 33659744 26745344
14:40:12 5757 8192000000 10920354112 11343081472 1935285344 19.109 99 60 120 1 1989716128 35190960 19239824
14:41:22 12655 8192000000 10863532416 11298209792 1884766720 18.704 98 39 78 1 1931895120 34552192 12576208
14:42:37 3941 8192000000 10883485232 11308523520 1919002736 18.979 100 44 88 1 1969399568 36201232 14195600
14:43:45 11213 8192000000 10881984656 11321462784 1888637520 18.735 98 29 56 4 1940263888 42059344 9567024
14:45:20 21222 8192000000 10878379408 11321946112 1910870160 18.914 100 28 56 2 1957083248 37175840 9037248
14:46:44 55233 8192000000 10873317888 11338702848 1865677184 18.549 97 14 28 2 1911088256 40405760 5005312
14:48:40 32130 8192000000 10815363584 11261550592 1847200736 18.399 98 13 26 1 1860730112 9612736 3916640
14:50:23 65723 8192000000 10847615120 11289038848 1903199760 18.852 100 31 60 0 1926510384 14314512 8996112
14:52:31 124865 8192000000 10859737072 11300655104 1892226896 18.764 99 27 55 1 1909736560 9446864 8062800
14:55:40 25903 8192000000 10852438480 11322482688 1917796016 18.969 99 23 46 2 1936939488 12231264 6912208
14:57:06 114599 8192000000 10896013536 11349987328 1926203712 19.037 97 17 34 0 1944936544 13788480 4944352
15:00:31 92212 8192000000 10919255920 11394850816 1993006288 19.568 100 15 30 0 2013240032 15634480 4599264
15:03:04 53743 8192000000 10926896656 11394424832 1998903632 19.614 100 16 32 1 2013767952 9989184 4875136
15:04:59 36097 8192000000 10948865312 11400867840 2004773504 19.660 100 48 95 5 2033125712 13676336 14675872
15:06:41 29689 8192000000 10943874192 11404722176 1983339968 19.491 99 21 40 3 2002620912 14045088 5235856
15:08:11 88911 8192000000 10934294512 11413397504 1963176832 19.331 98 13 26 0 1984307424 17570464 3560128
15:10:40 46190 8192000000 10913742640 11381592064 1952147312 19.244 98 12 24 0 1967640448 12045984 3447152
15:13:12 35139 8192000000 10938062544 11400458240 1998264224 19.609 100 36 72 1 2021612672 12959312 10389136
15:14:55 149376 8192000000 10913713328 11386994688 1951621520 19.239 98 19 38 0 1969340336 12461840 5256976
15:18:28 31924 8192000000 10888898048 11371044864 1942518304 19.167 98 15 30 1 1958371280 11211152 4641824
15:21:08 50650 8192000000 10878156864 11344732160 1919342816 18.982 98 18 36 1 1936991920 12490992 5158112
15:23:00 29186 8192000000 10848812272 11316768768 1888482928 18.734 97 14 28 0 1900534720 7716176 4335616
15:24:29 69673 8192000000 10845487584 11310178304 1844582672 18.378 95 18 36 0 1858589744 9010512 4996560
15:27:15 51197 8192000000 10862057696 11317907456 1895575328 18.791 99 17 34 1 1912363360 12210016 4578016
15:29:20 62017 8192000000 10862428288 11318607872 1878008832 18.649 97 25 50 1 1893431392 8702112 6720448
15:31:28 50341 8192000000 10840225280 11301842944 1873140672 18.610 98 16 32 0 1889655408 12051536 4463200
15:33:21 99769 8192000000 10872471008 11356917760 1895924960 18.794 98 15 30 1 1923879984 23290624 4664400
15:36:01 35080 8192000000 10875735792 11340906496 1925112816 19.028 99 33 65 0 1945599568 11615408 8871344
15:37:42 42202 8192000000 10873811520 11326951424 1909731968 18.904 98 22 44 3 1927847728 12309312 5806448
15:39:24 89815 8192000000 10867934704 11343003648 1908677616 18.896 98 21 42 0 1925358368 10535744 6145008
15:42:14 58960 8192000000 10872584096 11348185088 1934749296 19.105 99 24 48 0 1953190592 11040080 7401216
15:44:25 52971 8192000000 10877415696 11356729344 1943483952 19.175 99 24 48 3 1963539712 13469808 6585952
15:46:18 85514 8192000000 10886631968 11369693184 1977869184 19.448 100 18 36 1 1994391728 11034656 5487888
15:48:45 75604 8192000000 10912963520 11393630208 1955921952 19.274 99 4 8 0 1966582880 9690384 970544
15:51:01 59997 8192000000 10902762528 11376533504 1959096208 19.299 99 24 47 2 1980235280 14197136 6941936
15:53:01 28058 8192000000 10930480832 11389325312 1977281248 19.443 99 49 94 0 1999310960 8582736 13446976
15:54:34 59119 8192000000 10950379200 11401494528 1976266944 19.435 99 34 68 0 1998822112 12304720 10250448
15:56:36 30856 8192000000 10951095376 11425157120 2004277968 19.656 100 33 66 1 2030047760 16669792 9100000
15:58:22 76861 8192000000 10928820304 11409289216 1948279152 19.213 97 11 21 1 1964618128 12926096 3412880
16:00:41 147857 8192000000 10953040704 11413180416 1934266464 19.101 96 13 26 1 1952647408 14709536 3671408
16:04:44 85446 8192000000 10930267520 11425460224 2004634192 19.659 100 10 20 1 2021390272 13959520 2796560
16:07:27 66164 8192000000 10911903040 11367583744 1857842736 18.486 93 24 48 0 1878661776 13679344 7139696
16:09:33 73543 8192000000 10863642080 11335258112 1871309152 18.595 95 15 30 1 1893435584 17452256 4674176
16:12:00 116678 8192000000 10880185408 11352522752 1874993728 18.625 96 4 8 1 1900182176 24088096 1100352
16:14:58 81319 8192000000 10842615424 11312082944 1839127056 18.334 95 16 31 0 1855442176 11998800 4316320
16:17:20 52578 8192000000 10866001600 11333140480 1884368432 18.700 97 29 58 2 1904598368 12233328 7996608
16:19:13 146883 8192000000 10850410176 11310047232 1909128912 18.900 99 26 52 0 1929225856 12449664 7647280
16:23:15 41968 8192000000 10859188432 11327447040 1938574720 19.135 100 12 25 2 1951029792 8429776 4025296
16:25:55 73958 8192000000 10232020736 10643484672 1306858736 13.758 68 12 22 1 1317785648 7246864 3680048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-10-2020 16:28:56 to 23-10-2020 16:29:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 132 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-10-2020 16:29:12 to 23-10-2020 16:29:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          252465
MON$OLDEST_ACTIVE               252664
MON$OLDEST_SNAPSHOT             252664
MON$NEXT_TRANSACTION            252664
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-03 16:41:55.3470
MON$PAGES                       2035680
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33379 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33379 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33379 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:70: 
:71: mscs-test-161.budzdorov.ru    Fri Oct 23 16:27:13 2020
:72:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:73: 
:74: 
:75: mscs-test-161.budzdorov.ru    Fri Oct 23 16:27:21 2020
:76:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:77: 
--- end of diff output ---

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

23.10.20 16:29:15. There are 3 logs in /var/tmp/logs-oltp30 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-23 16:29:17 No severe PSQL-related problems occured
23.10.20 16:29:18. End of report.