Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.10.2020 04:27
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 16585056 kB
MemAvailable 31544168 kB
Buffers 20 kB
Cached 15054552 kB
SwapCached 3048 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-25 04:26:52
DTS_END                         2020-10-25 04:26:52.9480
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(67:17)
IP                              ::1/50756
TRN_ID                          240018
ATT_ID                          626
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: 9107.08 21134 1092850
customer order: creation 176.11 384 21133
customer order: refuse 15.62 334 1874
order to supplier: creation 46.42 1363 5570
order to supplier: removal 3.29 114348 395
invoice (draft): creation 44.71 3763 5365
invoice (draft): removal 49.27 990 5912
invoice accept: apply 42.34 18109 5081
invoice accept: cancel 44.65 939 5358
customer reserve: creation 3970.28 173 476433
customer reserve: removal 4417.21 66 530065
realization accept: apply 83.94 191 10073
realization accept: cancel 82.54 122 9905
payment from customer: creation 60.18 114 7222
payment from customer: removal 6.01 99 721
payment to supplier: creation 49.48 119 5937
payment to supplier: removal 3.50 79 420
service: total inventory turnovers 3.99 3714 479
service: total monetary turnovers 6.73 589 808
service: aggregate perf. data 0.79 19695 95
service: refresh index statistics 0.03 257483 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-10-2020 04:28:17 to 25-10-2020 04:28:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3468
TEST_TIME 2 4390
TEST_TIME 3 5707
TEST_TIME 4 5060
TEST_TIME 5 5086
TEST_TIME 6 4932
TEST_TIME 7 5581
TEST_TIME 8 6008
TEST_TIME 9 6144
TEST_TIME 10 6241
TEST_TIME 11 6821
TEST_TIME 12 6867
TEST_TIME 13 6891
TEST_TIME 14 6790
TEST_TIME 15 6839
TEST_TIME 16 7140
TEST_TIME 17 7237
TEST_TIME 18 7352
TEST_TIME 19 7794
TEST_TIME 20 7974
TEST_TIME 21 8335
TEST_TIME 22 8819
TEST_TIME 23 8624
TEST_TIME 24 9151
TEST_TIME 25 9154
TEST_TIME 26 9552
TEST_TIME 27 9367
TEST_TIME 28 9463
TEST_TIME 29 10080
TEST_TIME 30 11264
TEST_TIME 31 11166
TEST_TIME 32 11199
TEST_TIME 33 11196
TEST_TIME 34 11192
TEST_TIME 35 11107
TEST_TIME 36 11066
TEST_TIME 37 10917
TEST_TIME 38 10897
TEST_TIME 39 10799
TEST_TIME 40 10751
TEST_TIME 41 10642
TEST_TIME 42 10645
TEST_TIME 43 10569
TEST_TIME 44 10533
TEST_TIME 45 10598
TEST_TIME 46 10604
TEST_TIME 47 10668
TEST_TIME 48 10462
TEST_TIME 49 10286
TEST_TIME 50 10113
TEST_TIME 51 10023
TEST_TIME 52 9966
TEST_TIME 53 9898
TEST_TIME 54 9851
TEST_TIME 55 9836
TEST_TIME 56 9809
TEST_TIME 57 9747
TEST_TIME 58 9724
TEST_TIME 59 9777
TEST_TIME 60 9651
TEST_TIME 61 9609
TEST_TIME 62 9553
TEST_TIME 63 9569
TEST_TIME 64 9565
TEST_TIME 65 9551
TEST_TIME 66 9494
TEST_TIME 67 9570
TEST_TIME 68 9494
TEST_TIME 69 9686
TEST_TIME 70 9644
TEST_TIME 71 9664
TEST_TIME 72 9690
TEST_TIME 73 9706
TEST_TIME 74 9679
TEST_TIME 75 9747
TEST_TIME 76 9674
TEST_TIME 77 9568
TEST_TIME 78 9537
TEST_TIME 79 9453
TEST_TIME 80 9446
TEST_TIME 81 9409
TEST_TIME 82 9370
TEST_TIME 83 9355
TEST_TIME 84 9362
TEST_TIME 85 9339
TEST_TIME 86 9383
TEST_TIME 87 9367
TEST_TIME 88 9420
TEST_TIME 89 9465
TEST_TIME 90 9490
TEST_TIME 91 9508
TEST_TIME 92 9465
TEST_TIME 93 9507
TEST_TIME 94 9498
TEST_TIME 95 9421
TEST_TIME 96 9368
TEST_TIME 97 9439
TEST_TIME 98 9369
TEST_TIME 99 9315
TEST_TIME 100 9333
TEST_TIME 101 9305
TEST_TIME 102 9320
TEST_TIME 103 9318
TEST_TIME 104 9298
TEST_TIME 105 9273
TEST_TIME 106 9319
TEST_TIME 107 9305
TEST_TIME 108 9303
TEST_TIME 109 9297
TEST_TIME 110 9340
TEST_TIME 111 9379
TEST_TIME 112 9321
TEST_TIME 113 9281
TEST_TIME 114 9267
TEST_TIME 115 9218
TEST_TIME 116 9171
TEST_TIME 117 9140
TEST_TIME 118 9157
TEST_TIME 119 9138
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-10-2020 04:28:20 to 25-10-2020 04: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 1531865 1531865 0 0.00 0 27724 7 0 0
doc_list_biud 1531865 1531865 0 0.00 0 28687 30 0 0
sp_add_invoice_to_stock 5263 5081 182 3.45 29 180833 18110 0 182
sp_cancel_adding_invoice 5358 5358 0 0.00 5 68551 940 0 0
sp_cancel_client_order 1874 1874 0 0.00 4 10633 334 0 0
sp_cancel_customer_reserve 530065 530065 0 0.00 1 28689 66 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 3 5926 100 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2383 80 0 0
sp_cancel_supplier_invoice 5927 5912 15 0.25 2 26527 990 0 15
sp_cancel_supplier_order 395 395 0 0.00 7 1197159 114349 0 0
sp_cancel_write_off 9905 9905 0 0.00 1 14945 123 0 0
sp_client_order 21133 21133 0 0.00 4 19940 385 0 0
sp_customer_reserve 476433 476433 0 0.00 0 51653 173 0 0
sp_fill_shopping_cart 32051 32051 0 0.00 1 18926 49 0 0
sp_fill_shopping_cart_clo_res 476431 476431 0 0.00 0 22314 16 0 0
sp_get_clo_for_invoice 5079 5079 0 0.00 0 59002 389 0 0
sp_kill_cost_storno 16404 16404 0 0.00 0 12616 63 0 0
sp_kill_qstorno_ret_qs2qd 1076342 1076342 0 0.00 0 28682 36 0 0
sp_kill_qty_storno 568661 568661 0 0.00 0 28687 78 0 0
sp_lock_selected_doc 21108 21108 0 0.00 0 10363 6 0 0
sp_make_cost_storno 28311 28311 0 0.00 0 27715 65 0 0
sp_multiply_rows_for_pdistr 28311 28311 0 0.00 0 9712 32 0 0
sp_multiply_rows_for_qdistr 21133 21133 0 0.00 0 19927 208 0 0
sp_pay_from_customer 7222 7222 0 0.00 4 14770 115 0 0
sp_pay_to_supplier 5937 5937 0 0.00 4 14046 119 0 0
sp_payment_common 13159 13159 0 0.00 1 10158 21 0 0
sp_qd_handle_on_cancel_clo 1874 1874 0 0.00 0 7901 304 0 0
sp_qd_handle_on_invoice_upd_sts 10437 10437 0 0.00 0 17527 423 0 0
sp_qd_handle_on_reserve_upd_sts 10073 10073 0 0.00 0 11085 53 0 0
sp_reserve_write_off 10073 10073 0 0.00 6 19977 191 0 0
sp_supplier_invoice 5365 5365 0 0.00 7 109812 3763 0 0
sp_supplier_order 5570 5570 0 0.00 6 37785 1363 0 0
srv_aggregate_perf_data 95 95 0 0.00 2630 226541 19696 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 983 182204 45044 0 0
srv_find_qd_qs_mism 1043121 1043121 0 0.00 0 20617 5 0 0
srv_make_invnt_saldo 479 479 0 0.00 74 137550 3715 0 0
srv_make_money_saldo 808 808 0 0.00 1 17532 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 54273 525944 257484 0 0
t$perf-abend:sp_add_invoice_to_stock 182 182 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5081 5081 0 0.00 0 8719 163 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 3300 116 0 0
t$perf-norm:sp_cancel_client_order 1874 1874 0 0.00 0 2143 14 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 6292 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 173 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 608 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 709 19 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 18423 1484 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 3268 8 0 0
t$perf-norm:sp_client_order 21133 21133 0 0.00 0 12641 19 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 903 4 0 0
t$perf-norm:sp_pay_from_customer 7222 7222 0 0.00 0 7484 11 0 0
t$perf-norm:sp_pay_to_supplier 5937 5937 0 0.00 0 2732 7 0 0
t$perf-norm:sp_reserve_write_off 10073 10073 0 0.00 0 7904 9 0 0
t$perf-norm:sp_supplier_invoice 5365 5365 0 0.00 0 4129 17 0 0
t$perf-norm:sp_supplier_order 5570 5570 0 0.00 0 8893 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 403 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 427 14 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 461 7 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1950 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10522 10158 364 3.45 0 5239 20 0 364
v_all_customers 38554 38554 0 0.00 0 254 1 0 0
v_all_suppliers 30534 30534 0 0.00 0 21 1 0 0
v_all_wares 42266 42266 0 0.00 0 254 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 688 7 0 0
v_cancel_client_order 3748 3748 0 0.00 0 7240 6 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 4408 11 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 1990 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2375 12 0 0
v_cancel_supplier_invoice 820 790 30 3.65 0 662 15 0 30
v_cancel_supplier_order 790 790 0 0.00 0 586 5 0 0
v_cancel_write_off 2068 2068 0 0.00 0 2132 14 0 0
v_max_id_clo_ord 5569 5569 0 0.00 0 4520 3 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 4697 19 0 0
v_max_id_ord_sup 5348 5348 0 0.00 0 5962 5 0 0
v_max_non_paid_invoice 1587 1587 0 0.00 0 108 2 0 0
v_max_non_paid_realizn 3759 3759 0 0.00 0 3439 4 0 0
v_min_id_clo_ord 5569 5569 0 0.00 0 10850 15 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 8461 22 0 0
v_min_id_ord_sup 5348 5348 0 0.00 0 8142 14 0 0
v_min_non_paid_invoice 5937 5937 0 0.00 0 4582 10 0 0
v_min_non_paid_realizn 7222 7222 0 0.00 0 5907 14 0 0
v_our_firm 10638 10638 0 0.00 0 17 1 0 0
v_reserve_write_off 20146 20146 0 0.00 0 3512 5 0 0
x_lock_depdocs_on_canc_invoice 10875 10875 0 0.00 0 15253 118 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 10546 361 0 0
x_make_qstorno_1000_1200 5570 5570 0 0.00 2 37352 1259 0 0
x_make_qstorno_1200_2000 5365 5365 0 0.00 2 90885 3579 0 0
x_make_qstorno_2100_3300 476433 476433 0 0.00 0 51651 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-10-2020 04:28:25 to 25-10-2020 04: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
01:56:56 11 8192000000 8798396432 8809598976 4436400 0.054 2 1 2 0 4535872 63616 35856
01:57:57 29891 8192000000 9724313392 10019729408 795542928 8.851 100 85 170 0 823965632 7681264 20741440
01:59:38 24411 8192000000 9776863296 10086326272 845892608 9.359 100 85 171 0 880207792 14679136 19636048
02:01:13 20188 8192000000 9833313888 10157498368 886822656 9.768 100 88 175 2 938699104 32464576 19411872
02:02:37 9533 8192000000 9885360032 10225352704 928309280 10.178 100 76 153 1 1000118944 55786272 16023392
02:03:49 9313 8192000000 9922697248 10270892032 970879376 10.595 100 77 152 0 1042560544 55734992 15946176
02:05:01 3842 8192000000 9979746432 10333409280 1000283616 10.881 100 86 171 0 1097956544 80726256 16946672
02:06:07 8020 8192000000 9993358432 10359312384 1033051696 11.198 100 79 158 0 1110289024 60997824 16239504
02:07:17 1593 8192000000 10021890848 10391539712 1053029856 11.390 100 93 186 0 1133958080 61510496 19417728
02:08:20 20440 8192000000 9989844048 10371551232 1069708848 11.549 100 64 134 0 1119390112 35605792 14075472
02:09:41 8681 8192000000 10004834064 10396188672 1073155952 11.582 100 37 74 3 1123254784 42288112 7810720
02:10:50 5717 8192000000 9983114800 10381234176 1084160224 11.687 100 24 47 1 1122738960 33629216 4949520
02:11:59 7787 8192000000 9999611888 10402168832 1093982368 11.781 100 30 61 0 1122979808 22890992 6106448
02:13:07 54268 8192000000 10020762480 10423308288 1096369152 11.803 100 49 99 0 1125249760 18178752 10701856
02:15:10 6580 8192000000 10026510400 10433413120 1103152912 11.868 100 47 93 4 1141752064 28775072 9824080
02:16:17 5635 8192000000 10009046080 10404069376 1089349152 11.736 99 41 82 1 1126394336 28240784 8804400
02:17:25 6418 8192000000 9985108032 10388287488 1071833184 11.570 100 30 60 1 1105987984 27452080 6702720
02:18:35 52563 8192000000 9969965200 10369368064 996114992 10.841 95 31 63 1 1036842176 34604032 6123152
02:20:31 8726 8192000000 9933171792 10316574720 963410560 10.522 94 36 72 4 988908688 17274288 8223840
02:21:43 12094 8192000000 9949460096 10339495936 1026436912 11.134 99 34 68 3 1055332992 21803504 7092576
02:22:56 8511 8192000000 9957373376 10358521856 1047178848 11.334 100 44 88 0 1067703440 9941920 10582672
02:24:08 38131 8192000000 9973208144 10362093568 1056076912 11.419 100 32 63 0 1081838352 19084144 6677296
02:25:46 6902 8192000000 9983478864 10383343616 1066017216 11.514 100 40 80 1 1103671008 28084336 9569456
02:26:58 10571 8192000000 10006243728 10399698944 1092997200 11.771 100 52 103 2 1112585392 7855856 11732336
02:28:16 15791 8192000000 10079374320 10474262528 1108326112 11.917 100 56 112 1 1178028752 58835984 10866656
02:29:34 21530 8192000000 10081715408 10484277248 1126749312 12.091 100 49 99 0 1183994944 46302864 10942768
02:32:09 1447 8192000000 10107121056 10504769536 1138121872 12.198 100 63 125 0 1212145600 61389056 12634672
02:33:16 3198 8192000000 10136585600 10544353280 1145013808 12.263 100 50 98 1 1252205776 96057696 11134272
02:34:24 11812 8192000000 10153287136 10562732032 1153242336 12.340 100 50 100 2 1264165600 100355712 10567552
02:35:40 3240 8192000000 10179940704 10582863872 1147532752 12.286 99 61 121 3 1268668880 107755744 13380384
02:36:47 6262 8192000000 10196863120 10633732096 1165100672 12.451 100 53 106 3 1306220080 129523408 11596000
02:37:59 11166 8192000000 10221284592 10630807552 1169191632 12.489 100 59 118 0 1323962832 141716112 13055088
02:39:15 7072 8192000000 10243669568 10650718208 1169727456 12.494 100 53 102 0 1344791184 162699536 12364192
02:40:27 4389 8192000000 10258091872 10665816064 1166775568 12.467 100 55 109 1 1356494304 177519808 12198928
02:41:34 2937 8192000000 10308765184 10711240704 1163120544 12.432 99 76 152 2 1405960800 224281968 18558288
02:42:44 4710 8192000000 10323694208 10723880960 1163269200 12.434 100 76 153 1 1428675488 248689184 16717104
02:43:54 4737 8192000000 10338009184 10734215168 1138865344 12.205 99 79 158 0 1431046608 273334352 18846912
02:45:06 980 8192000000 10394391632 10788687872 1142377472 12.238 100 85 169 1 1490543312 328397424 19768416
02:46:10 2768 8192000000 10362002944 10750251008 1123666832 12.062 99 84 168 1 1458835776 315767424 19401520
02:47:19 12149 8192000000 10357583072 10788909056 1130764416 12.129 100 42 82 3 1470258912 329112944 10381552
02:48:46 14703 8192000000 10394286736 10857558016 1143991408 12.253 100 38 74 1 1516455440 362361872 10102160
02:50:20 2312 8192000000 10479740624 10885111808 1157135376 12.376 100 90 180 0 1567942944 389330064 21477504
02:51:25 14382 8192000000 10435610896 10886909952 1157414288 12.379 100 37 73 1 1537709856 370686000 9609568
02:52:43 19195 8192000000 10419792768 10850586624 1162057184 12.423 100 28 56 1 1519254832 350427360 6770288
02:54:27 14895 8192000000 10506701664 10962665472 1139256448 12.209 100 26 52 0 1597949296 451090448 7602400
02:55:44 30171 8192000000 10312100512 10845458432 1140630896 12.221 100 30 60 1 1501118832 352704624 7783312
02:57:15 46208 8192000000 10117812832 10560884736 1132463008 12.145 98 35 69 1 1202932864 62624352 7845504
02:59:04 36501 8192000000 10103063392 10574041088 1139750992 12.213 99 17 34 1 1202023296 59135824 3136480
03:00:53 182175 8192000000 10051136688 10468601856 1055030032 11.409 92 15 29 3 1098005376 39941680 3033664
03:04:56 90701 8192000000 10099831936 10530168832 1126004208 12.084 98 18 32 1 1157108448 28082416 3021824
03:07:30 97792 8192000000 10113773632 10582151168 1143140608 12.245 99 20 39 0 1187089344 40153360 3795376
03:10:14 39406 8192000000 10097301200 10519027712 1139181600 12.208 99 32 64 0 1179763168 33694976 6886592
03:11:54 48335 8192000000 10118450384 10583146496 1153648912 12.344 100 6 12 0 1196380976 41560288 1171776
03:13:44 109978 8192000000 10090351872 10530754560 1155289312 12.359 100 24 46 2 1186175120 25640848 5244960
03:17:11 28184 8192000000 10114045360 10556420096 1152502112 12.333 100 40 80 1 1206223744 45383424 8338208
03:18:44 75435 8192000000 10129526032 10587959296 1152797360 12.336 99 18 34 0 1219589760 62908896 3883504
03:21:03 104341 8192000000 10120233216 10611240960 1153933680 12.346 99 14 28 0 1239079280 81727728 3417872
03:23:47 40196 8192000000 10116583072 10550837248 1129220288 12.114 98 15 30 0 1202370624 70137072 3013264
03:25:29 69566 8192000000 10050567920 10511810560 1120158064 12.028 98 17 34 2 1150429504 26750272 3521168
03:27:41 67227 8192000000 10078018976 10558177280 1085509408 11.700 95 10 20 0 1112282304 25062960 1709936
03:29:54 35697 8192000000 10049256704 10494631936 1086531088 11.710 96 16 30 2 1110789552 21467072 2791392
03:31:34 49452 8192000000 10082608864 10504589312 1059124672 11.448 94 30 59 1 1112096016 46915168 6056176
03:33:25 41136 8192000000 10093257216 10541731840 1089085392 11.734 98 19 39 4 1180529744 87478144 3966208
03:35:08 104068 8192000000 10102704000 10567909376 1084446816 11.690 98 11 22 1 1173323744 86556112 2320816
03:37:52 52394 8192000000 10069389504 10509893632 1096030304 11.800 98 35 69 2 1142048464 39635712 6382448
03:39:51 40517 8192000000 10048241728 10498277376 1110107872 11.933 99 26 52 1 1169608320 54680832 4819616
03:41:32 90394 8192000000 10067843120 10558398464 1068431984 11.537 96 11 22 1 1126219392 55723664 2063744
03:44:22 63898 8192000000 10071912704 10538967040 1118278704 12.011 100 34 68 0 1197698848 71792128 7628016
03:46:36 68508 8192000000 10079584880 10585100288 1116321280 11.992 99 14 28 2 1187245424 67784560 3139584
03:48:57 79972 8192000000 10054411408 10515300352 1063601888 11.491 95 17 34 0 1128786240 61359664 3824688
03:51:27 62742 8192000000 10106541632 10577588224 1102855776 11.865 98 21 43 1 1190826048 83355568 4614704
03:54:00 58131 8192000000 10155263136 10634039296 1130135232 12.123 100 38 77 0 1247154752 108983088 8036432
03:56:07 72561 8192000000 10076637760 10508091392 1096902976 11.808 97 16 33 0 1148009312 48225584 2880752
03:58:23 46946 8192000000 10095278896 10560278528 1145539600 12.268 100 41 82 0 1195144624 41030992 8574032
04:00:15 85874 8192000000 10162276288 10617667584 1151657152 12.325 100 27 52 2 1251086288 93416528 6012608
04:03:44 49473 8192000000 10074915600 10590175232 1130448784 12.126 99 2 4 0 1180444512 49682160 313568
04:05:33 80398 8192000000 10094600672 10570874880 1119942128 12.026 98 16 32 0 1190720864 67142304 3636432
04:07:55 68420 8192000000 10080389792 10525896704 1131204240 12.133 99 22 43 0 1173533392 37994240 4334912
04:10:12 50994 8192000000 10096108112 10531921920 1086292208 11.707 95 19 38 4 1133487536 43233904 3961424
04:12:04 54772 8192000000 10077316624 10531389440 1125579584 12.080 99 14 28 0 1218771728 89858576 3333568
04:14:00 36319 8192000000 10070971568 10517487616 1092041392 11.762 97 27 54 2 1137573584 40269328 5262864
04:15:39 43778 8192000000 10113075504 10533314560 1129686592 12.118 100 35 70 1 1226717680 89476848 7554240
04:17:28 45328 8192000000 10089150560 10520113152 1103157360 11.868 97 33 66 1 1145470976 35293088 7020528
04:19:14 89363 8192000000 10054658928 10546921472 1122945904 12.055 99 18 36 1 1179223152 52228864 4048384
04:21:46 49991 8192000000 10074508128 10569719808 1095083184 11.791 97 5 10 0 1137123392 41185392 854816
04:23:46 59859 8192000000 10061478992 10499993600 1071266704 11.564 95 19 38 0 1123113296 47469216 4377376
04:25:46 45217 8192000000 10086705376 10514440192 1046422144 11.326 94 24 48 1 1122438416 70960656 5055616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-10-2020 04:28:31 to 25-10-2020 04:28:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 182 v_add_invoice_to_stock 335544517
user_exc 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-10-2020 04:28:47 to 25-10-2020 04: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          234600
MON$OLDEST_ACTIVE               240895
MON$OLDEST_SNAPSHOT             240895
MON$NEXT_TRANSACTION            240895
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                       2133184
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             796
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 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.2235 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'

25.10.20 04:28:50. 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-25 04:28:52 No severe PSQL-related problems occured
25.10.20 04:28:53. End of report.