Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.10.2020 13: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 16815872 kB
MemAvailable 31593280 kB
Buffers 0 kB
Cached 14824144 kB
SwapCached 5608 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-11 13:26:49
DTS_END                         2020-10-11 13:26:49.4110
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/56710
TRN_ID                          229648
ATT_ID                          621
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: 8831.42 23878 1059771
customer order: creation 157.97 402 18956
customer order: refuse 13.70 312 1644
order to supplier: creation 42.35 1364 5082
order to supplier: removal 3.15 124470 378
invoice (draft): creation 42.37 3866 5084
invoice (draft): removal 50.64 1029 6077
invoice accept: apply 39.00 16417 4680
invoice accept: cancel 45.95 867 5514
customer reserve: creation 3655.54 156 438665
customer reserve: removal 4501.82 67 540218
realization accept: apply 76.26 196 9151
realization accept: cancel 84.44 109 10133
payment from customer: creation 54.93 115 6591
payment from customer: removal 5.24 104 629
payment to supplier: creation 45.17 146 5420
payment to supplier: removal 3.06 75 367
service: total inventory turnovers 3.73 3101 448
service: total monetary turnovers 5.32 592 638
service: aggregate perf. data 0.77 18383 92
service: refresh index statistics 0.03 305796 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2020 13:28:09 to 11-10-2020 13:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2833
TEST_TIME 2 2924
TEST_TIME 3 2920
TEST_TIME 4 3922
TEST_TIME 5 5134
TEST_TIME 6 4998
TEST_TIME 7 5421
TEST_TIME 8 5232
TEST_TIME 9 5331
TEST_TIME 10 5812
TEST_TIME 11 6154
TEST_TIME 12 6228
TEST_TIME 13 6166
TEST_TIME 14 6701
TEST_TIME 15 6549
TEST_TIME 16 6606
TEST_TIME 17 6639
TEST_TIME 18 7020
TEST_TIME 19 7258
TEST_TIME 20 7396
TEST_TIME 21 7860
TEST_TIME 22 8032
TEST_TIME 23 8141
TEST_TIME 24 8997
TEST_TIME 25 8920
TEST_TIME 26 9237
TEST_TIME 27 9303
TEST_TIME 28 9278
TEST_TIME 29 9688
TEST_TIME 30 11076
TEST_TIME 31 10825
TEST_TIME 32 10746
TEST_TIME 33 10733
TEST_TIME 34 10547
TEST_TIME 35 10486
TEST_TIME 36 10342
TEST_TIME 37 10251
TEST_TIME 38 10231
TEST_TIME 39 10072
TEST_TIME 40 9990
TEST_TIME 41 9937
TEST_TIME 42 9964
TEST_TIME 43 9883
TEST_TIME 44 9918
TEST_TIME 45 9889
TEST_TIME 46 9829
TEST_TIME 47 9844
TEST_TIME 48 9840
TEST_TIME 49 9832
TEST_TIME 50 9817
TEST_TIME 51 9666
TEST_TIME 52 9488
TEST_TIME 53 9352
TEST_TIME 54 9359
TEST_TIME 55 9326
TEST_TIME 56 9221
TEST_TIME 57 9186
TEST_TIME 58 9182
TEST_TIME 59 9193
TEST_TIME 60 9110
TEST_TIME 61 8997
TEST_TIME 62 8988
TEST_TIME 63 9208
TEST_TIME 64 9223
TEST_TIME 65 9253
TEST_TIME 66 9267
TEST_TIME 67 9301
TEST_TIME 68 9278
TEST_TIME 69 9247
TEST_TIME 70 9340
TEST_TIME 71 9263
TEST_TIME 72 9244
TEST_TIME 73 9372
TEST_TIME 74 9363
TEST_TIME 75 9345
TEST_TIME 76 9263
TEST_TIME 77 9200
TEST_TIME 78 9132
TEST_TIME 79 9108
TEST_TIME 80 9044
TEST_TIME 81 9016
TEST_TIME 82 9023
TEST_TIME 83 8971
TEST_TIME 84 9009
TEST_TIME 85 8992
TEST_TIME 86 8978
TEST_TIME 87 8986
TEST_TIME 88 8992
TEST_TIME 89 8991
TEST_TIME 90 9040
TEST_TIME 91 9099
TEST_TIME 92 9163
TEST_TIME 93 9183
TEST_TIME 94 9137
TEST_TIME 95 9083
TEST_TIME 96 9060
TEST_TIME 97 9030
TEST_TIME 98 8989
TEST_TIME 99 9051
TEST_TIME 100 9036
TEST_TIME 101 9015
TEST_TIME 102 8993
TEST_TIME 103 8973
TEST_TIME 104 8988
TEST_TIME 105 9051
TEST_TIME 106 9066
TEST_TIME 107 9156
TEST_TIME 108 9141
TEST_TIME 109 9137
TEST_TIME 110 9059
TEST_TIME 111 9001
TEST_TIME 112 8992
TEST_TIME 113 8952
TEST_TIME 114 8896
TEST_TIME 115 8888
TEST_TIME 116 8905
TEST_TIME 117 8874
TEST_TIME 118 8857
TEST_TIME 119 8876
TEST_TIME 120 8837

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-10-2020 13:28:12 to 11-10-2020 13:28:16.

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 1465191 1465191 0 0.00 0 19766 6 0 0
doc_list_biud 1465184 1465184 0 0.00 0 31682 32 0 0
sp_add_invoice_to_stock 4791 4680 111 2.31 40 175079 16418 0 111
sp_cancel_adding_invoice 5514 5514 0 0.00 5 60586 868 0 0
sp_cancel_client_order 1644 1644 0 0.00 4 10934 312 0 0
sp_cancel_customer_reserve 540218 540218 0 0.00 1 24626 68 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 3 8048 105 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 4787 75 0 0
sp_cancel_supplier_invoice 6082 6077 5 0.08 2 31689 1029 0 5
sp_cancel_supplier_order 378 378 0 0.00 11 975562 124471 0 0
sp_cancel_write_off 10133 10133 0 0.00 1 12748 110 0 0
sp_client_order 18956 18956 0 0.00 4 20717 402 0 0
sp_customer_reserve 438665 438665 0 0.00 0 40321 157 0 0
sp_fill_shopping_cart 29112 29112 0 0.00 1 20332 48 0 0
sp_fill_shopping_cart_clo_res 438657 438657 0 0.00 0 21833 17 0 0
sp_get_clo_for_invoice 4670 4670 0 0.00 0 64368 432 0 0
sp_kill_cost_storno 16643 16643 0 0.00 0 12698 61 0 0
sp_kill_qstorno_ret_qs2qd 1097024 1097024 0 0.00 0 31680 37 0 0
sp_kill_qty_storno 577785 577785 0 0.00 0 31681 80 0 0
sp_lock_selected_doc 19083 19083 0 0.00 0 8830 5 0 0
sp_make_cost_storno 25832 25832 0 0.00 0 19730 65 0 0
sp_multiply_rows_for_pdistr 25832 25832 0 0.00 0 14659 36 0 0
sp_multiply_rows_for_qdistr 18956 18956 0 0.00 0 20376 207 0 0
sp_pay_from_customer 6591 6591 0 0.00 4 13727 115 0 0
sp_pay_to_supplier 5420 5420 0 0.00 5 15277 147 0 0
sp_payment_common 12011 12011 0 0.00 1 15247 27 0 0
sp_qd_handle_on_cancel_clo 1644 1644 0 0.00 0 10926 284 0 0
sp_qd_handle_on_invoice_upd_sts 10184 10184 0 0.00 0 24627 408 0 0
sp_qd_handle_on_reserve_upd_sts 9151 9151 0 0.00 0 9320 50 0 0
sp_reserve_write_off 9151 9151 0 0.00 7 16847 196 0 0
sp_supplier_invoice 5084 5084 0 0.00 5 99177 3867 0 0
sp_supplier_order 5082 5082 0 0.00 6 32231 1364 0 0
srv_aggregate_perf_data 92 92 0 0.00 2413 173004 18384 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1754 157544 50392 0 0
srv_find_qd_qs_mism 1014989 1014989 0 0.00 0 14488 5 0 0
srv_make_invnt_saldo 448 448 0 0.00 60 105786 3102 0 0
srv_make_money_saldo 638 638 0 0.00 1 13250 593 0 0
srv_recalc_idx_stat 4 4 0 0.00 102196 451829 305796 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4680 4680 0 0.00 0 5835 143 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 0 972 53 0 0
t$perf-norm:sp_cancel_client_order 1644 1644 0 0.00 0 4559 13 0 0
t$perf-norm:sp_cancel_customer_reserve 609 609 0 0.00 0 245 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 3167 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 3962 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1075 14 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 33252 1754 0 0
t$perf-norm:sp_cancel_write_off 858 858 0 0.00 0 1791 7 0 0
t$perf-norm:sp_client_order 18956 18956 0 0.00 0 31672 20 0 0
t$perf-norm:sp_customer_reserve 906 906 0 0.00 0 2177 9 0 0
t$perf-norm:sp_pay_from_customer 6591 6591 0 0.00 0 4817 8 0 0
t$perf-norm:sp_pay_to_supplier 5420 5420 0 0.00 0 9169 11 0 0
t$perf-norm:sp_reserve_write_off 9151 9151 0 0.00 0 21469 10 0 0
t$perf-norm:sp_supplier_invoice 5084 5084 0 0.00 0 2596 13 0 0
t$perf-norm:sp_supplier_order 5082 5082 0 0.00 0 18560 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 222 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 35 1 0 0
t$perf-norm:srv_make_invnt_saldo 448 448 0 0.00 0 498 6 0 0
t$perf-norm:srv_make_money_saldo 638 638 0 0.00 0 843 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9562 9340 222 2.32 0 6148 16 0 222
v_all_customers 34694 34694 0 0.00 0 23 1 0 0
v_all_suppliers 28152 28152 0 0.00 0 13 1 0 0
v_all_wares 37912 37912 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 3498 14 0 0
v_cancel_client_order 3288 3288 0 0.00 0 1592 4 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 1982 6 0 0
v_cancel_customer_reserve 1218 1218 0 0.00 0 200 3 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 414 4 0 0
v_cancel_supplier_invoice 740 730 10 1.35 0 1244 13 0 10
v_cancel_supplier_order 756 756 0 0.00 0 1115 5 0 0
v_cancel_write_off 1716 1716 0 0.00 0 4896 18 0 0
v_max_id_clo_ord 5077 5077 0 0.00 0 412 1 0 0
v_max_id_clo_res 906 906 0 0.00 0 5099 14 0 0
v_max_id_ord_sup 5078 5078 0 0.00 0 4747 5 0 0
v_max_non_paid_invoice 1499 1499 0 0.00 0 1929 5 0 0
v_max_non_paid_realizn 3506 3506 0 0.00 0 1097 3 0 0
v_min_id_clo_ord 5077 5077 0 0.00 0 2861 6 0 0
v_min_id_clo_res 906 906 0 0.00 0 17702 23 0 0
v_min_id_ord_sup 5078 5078 0 0.00 0 7225 9 0 0
v_min_non_paid_invoice 5420 5420 0 0.00 0 15244 21 0 0
v_min_non_paid_realizn 6591 6591 0 0.00 0 12606 19 0 0
v_our_firm 9388 9388 0 0.00 0 19 1 0 0
v_reserve_write_off 18302 18302 0 0.00 0 4549 7 0 0
x_lock_depdocs_on_canc_invoice 11226 11226 0 0.00 0 17313 119 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 5682 351 0 0
x_make_qstorno_1000_1200 5082 5082 0 0.00 2 32216 1288 0 0
x_make_qstorno_1200_2000 5084 5084 0 0.00 2 99150 3677 0 0
x_make_qstorno_2100_3300 438665 438665 0 0.00 0 40312 138 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2020 13:28:17 to 11-10-2020 13:28:21.

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
10:56:53 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
10:57:53 22802 8192000000 9714890976 10009899008 792361264 8.819 100 87 174 0 820954304 7383520 21209520
10:59:21 24105 8192000000 9765303296 10070917120 838278624 9.282 100 87 174 0 871740800 12683424 20778752
11:00:49 25778 8192000000 9827868064 10144972800 877382832 9.674 100 82 164 3 921469536 25218960 18867744
11:02:20 8723 8192000000 9878515392 10213822464 927486880 10.170 100 81 162 1 989512752 44319632 17706240
11:03:32 7253 8192000000 9935073264 10277863424 967257808 10.560 100 85 168 1 1041800064 56694912 17847344
11:04:42 4425 8192000000 9985207904 10343469056 1000210800 10.881 100 83 164 2 1099903008 82835024 16857184
11:05:48 4955 8192000000 10024214432 10382131200 1026279936 11.133 100 86 172 1 1128988400 86174512 16533952
11:06:55 1426 8192000000 10029983376 10396123136 1060802528 11.464 100 96 194 2 1131015872 50632944 19580400
11:07:57 14977 8192000000 9996680496 10391687168 1068900048 11.542 100 46 92 1 1131909120 52975312 10033760
11:09:16 5985 8192000000 9998642576 10392608768 1081988128 11.666 100 45 92 2 1102363792 9990400 10385264
11:10:24 5561 8192000000 10003474688 10397306880 1085211760 11.697 100 42 83 2 1116368992 21827360 9329872
11:11:32 74853 8192000000 9998165072 10399461376 1090978192 11.752 100 30 60 4 1111519040 14519936 6020912
11:13:47 7315 8192000000 10036088944 10447933440 1096590352 11.805 100 39 78 2 1136701504 31536928 8574224
11:14:55 6899 8192000000 10019913696 10413408256 1093120272 11.772 100 34 66 4 1121482848 21010944 7351632
11:16:04 10589 8192000000 9997166064 10406830080 1061409040 11.470 97 33 67 2 1091539152 22965264 7164848
11:17:15 35885 8192000000 9993836048 10394685440 1013665216 11.011 94 34 66 3 1050496480 30375680 6455584
11:18:52 6981 8192000000 9945911680 10327224320 1011848096 10.993 98 34 68 1 1041456576 22761024 6847456
11:20:01 5224 8192000000 9929100336 10307432448 985363664 10.736 97 45 90 1 1008650992 13517264 9770064
11:21:06 7590 8192000000 9948903808 10351849472 1027220320 11.142 100 24 48 1 1067756512 35598848 4937344
11:22:15 47125 8192000000 9949722144 10337345536 1042806224 11.292 100 38 73 1 1066683920 16011136 7866560
11:24:02 7992 8192000000 9979244176 10376237056 1057131040 11.429 100 41 82 0 1085096784 18191520 9774224
11:25:12 8865 8192000000 9977349264 10381627392 1070713072 11.559 100 32 64 1 1098659344 20709680 7236592
11:26:21 4808 8192000000 10007646832 10399379456 1088781456 11.731 100 41 84 1 1118035616 19924624 9329536
11:27:30 99840 8192000000 10047436816 10454409216 1104083696 11.876 100 42 84 1 1157551952 43476176 9992080
11:30:14 17575 8192000000 10101124688 10509836288 1121707664 12.043 100 42 84 0 1228327920 97858368 8761888
11:31:35 2397 8192000000 10106139152 10519621632 1118030832 12.008 99 69 136 3 1216429344 83764368 14634144
11:32:46 8635 8192000000 10135350592 10540859392 1129068080 12.113 99 52 103 1 1228640704 88397696 11174928
11:34:01 7417 8192000000 10119699456 10527588352 1142820848 12.242 100 56 112 0 1228451504 72982880 12647776
11:35:17 6834 8192000000 10182206528 10593492992 1158068912 12.385 100 65 127 3 1287359216 114748816 14541488
11:36:31 19619 8192000000 10212545984 10622132224 1149367024 12.304 99 69 138 0 1295476128 131499248 14609856
11:37:54 2145 8192000000 10279075744 10674913280 1165019360 12.450 100 88 176 0 1367796144 183316336 19460448
11:39:01 9085 8192000000 10288014048 10694316032 1161591104 12.418 99 59 116 1 1380695232 205286144 13817984
11:40:16 7556 8192000000 10278281584 10686750720 1158815072 12.392 100 75 150 0 1377982560 202272736 16894752
11:41:32 6768 8192000000 10290916864 10693455872 1139571456 12.211 99 55 110 2 1388466336 235320080 13574800
11:42:48 1751 8192000000 10332264032 10745065472 1154295584 12.350 100 75 149 0 1440780944 268795088 17690272
11:43:56 2547 8192000000 10385862032 10783477760 1154523344 12.352 100 86 173 1 1486341872 312235984 19582544
11:45:02 7402 8192000000 10356764912 10760101888 1128717152 12.109 100 47 93 2 1444645216 304674832 11253232
11:46:15 6565 8192000000 10379592464 10778234880 1118074400 12.009 99 67 134 2 1473816512 339240160 16501952
11:47:25 9935 8192000000 10367130256 10817200128 1129459520 12.116 99 34 68 1 1481086144 342975568 8651056
11:48:38 6724 8192000000 10413048688 10829668352 1140640272 12.222 100 46 92 0 1527320336 374799136 11880928
11:49:51 7242 8192000000 10451889696 10879680512 1151434224 12.323 100 56 112 0 1540829440 375499856 13895360
11:51:08 18988 8192000000 10378912176 10805014528 1127158832 12.095 98 32 64 1 1445597840 310292144 8146864
11:52:39 11383 8192000000 10445333504 10878836736 1145895088 12.271 100 34 69 0 1541754976 387101616 8758272
11:54:18 21881 8192000000 10407194448 10844229632 1095642224 11.796 98 22 43 1 1508545008 406552288 6350496
11:55:41 24990 8192000000 10361567936 10831716352 1096464240 11.804 99 23 46 0 1461906080 358921504 6520336
11:57:11 47409 8192000000 10102879664 10531258368 1119118144 12.019 100 22 44 2 1201022384 77154000 4750240
11:58:59 34805 8192000000 10065921504 10549379072 1123520848 12.060 100 13 26 0 1195143072 69089648 2532576
12:00:44 76400 8192000000 10064701424 10540138496 1130145488 12.123 100 15 30 1 1188926096 55843856 2936752
12:03:02 63770 8192000000 10092175792 10567270400 1136015728 12.178 100 10 20 0 1214796640 76511248 2269664
12:05:25 90838 8192000000 10108016480 10554208256 1103582576 11.872 97 22 44 1 1164388416 55989056 4816784
12:08:00 52980 8192000000 10082330720 10498854912 1130301968 12.124 99 35 68 2 1165397984 27391984 7704032
12:09:55 78482 8192000000 10088980784 10570866688 1125793536 12.082 99 21 41 1 1206496672 76074560 4628576
12:12:52 24522 8192000000 10111079536 10528759808 1148345280 12.294 100 35 69 2 1190807856 35211968 7250608
12:14:19 112379 8192000000 10104773872 10562195456 1131290672 12.134 99 10 20 0 1180525552 47238416 1996464
12:17:13 146642 8192000000 10114912464 10562088960 1151133328 12.320 100 16 32 0 1227483632 72994336 3355968
12:21:33 35018 8192000000 10089382544 10532364288 1145292672 12.265 100 20 38 2 1204364432 55259104 3812656
12:23:14 56173 8192000000 10158425168 10609823744 1140220720 12.218 99 20 39 1 1238878336 94340224 4317392
12:25:28 57877 8192000000 10125808416 10566746112 1134893552 12.167 99 16 32 0 1251712064 113644944 3173568
12:27:49 111740 8192000000 10161641856 10632204288 1117333648 12.002 98 18 36 0 1246111712 124903792 3874272
12:30:41 71712 8192000000 10081718480 10551332864 1086116784 11.706 95 12 24 0 1155159824 66517712 2525328
12:32:53 74098 8192000000 10063306256 10495401984 1095345056 11.793 96 21 42 0 1138438528 39043488 4049984
12:35:08 25997 8192000000 10075674816 10498506752 1085739872 11.702 97 41 82 1 1118213184 24642192 7831120
12:36:44 54750 8192000000 10058504544 10478505984 1081416464 11.661 96 28 57 2 1127722320 40721360 5584496
12:38:40 69888 8192000000 10074856576 10565906432 1077708656 11.626 97 16 32 0 1186822592 105600288 3513648
12:40:52 65236 8192000000 10075286624 10529288192 1083355472 11.679 98 33 67 1 1122536464 32401248 6779744
12:44:20 114668 8192000000 10022895776 10490245120 1076996432 11.619 97 7 14 0 1107270080 28745648 1528000
12:47:24 64194 8192000000 10063931248 10527510528 1103569648 11.871 99 13 26 0 1170702496 63725008 3407840
12:49:30 47016 8192000000 10093983520 10528804864 1129126688 12.113 100 31 63 1 1185379552 49749216 6503648
12:51:18 43496 8192000000 10069863664 10503790592 1101020112 11.847 98 32 64 0 1141308656 33177760 7110784
12:53:02 27174 8192000000 10111450224 10547724288 1113837968 11.969 99 21 41 0 1202067568 83603024 4626576
12:54:42 65325 8192000000 10099727840 10545287168 1084188048 11.687 97 23 45 1 1163708144 74835552 4684544
12:56:47 92205 8192000000 10101322272 10582441984 1094459152 11.785 98 14 29 0 1160271984 63257456 2555376
12:59:48 86037 8192000000 10054088688 10528415744 1124015760 12.065 100 19 38 4 1159477008 30498496 4962752
13:02:29 63899 8192000000 10058896064 10519810048 1100814288 11.845 97 9 17 1 1124268608 22000288 1454032
13:04:34 33330 8192000000 10110171680 10546995200 1123078176 12.056 99 34 66 4 1172974144 42224480 7671488
13:06:32 153524 8192000000 10108972016 10568462336 1102874432 11.865 97 12 24 0 1133254352 27743600 2636320
13:10:15 74445 8192000000 10087067680 10599878656 1112159920 11.953 97 19 38 1 1205440432 88734384 4546128
13:12:31 82970 8192000000 10134387328 10614513664 1123674800 12.062 98 15 30 1 1256844528 129465952 3703776
13:15:31 157544 8192000000 10094615152 10561785856 1126507920 12.088 98 14 28 0 1185532112 55958656 3065536
13:19:09 30536 8192000000 10087143872 10551877632 1134952736 12.168 99 9 18 0 1190655584 53505504 2197344
13:20:40 75543 8192000000 10079591440 10558607360 1119018576 12.018 98 14 28 1 1158598400 36691552 2888272
13:22:56 61047 8192000000 10080660448 10515288064 1114580800 11.976 98 18 36 0 1137389056 18962288 3845968
13:25:06 92472 8192000000 10117185792 10558636032 1101868112 11.855 97 14 28 0 1158373136 53754720 2750304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-10-2020 13:28:23 to 11-10-2020 13:28:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 111 v_add_invoice_to_stock 335544517
user_exc 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2020 13:28:38 to 11-10-2020 13:28:40.

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          226633
MON$OLDEST_ACTIVE               230837
MON$OLDEST_SNAPSHOT             230837
MON$NEXT_TRANSACTION            230837
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                       2118848
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             768
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 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.2225 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Sun Oct 11 13:26:52 2020
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sun Oct 11 13:27:02 2020
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

11.10.20 13:28:41. 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-11 13:28:44 No severe PSQL-related problems occured
11.10.20 13:28:45. End of report.