Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.10.2020 19: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 17499768 kB
MemAvailable 31587004 kB
Buffers 0 kB
Cached 14169420 kB
SwapCached 5424 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-14 19:26:58
DTS_END                         2020-10-14 19:26:58.2540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/48064
TRN_ID                          250830
ATT_ID                          698
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: 9477.10 23992 1137252
customer order: creation 173.63 367 20835
customer order: refuse 14.78 302 1773
order to supplier: creation 46.25 1179 5550
order to supplier: removal 3.84 82305 461
invoice (draft): creation 46.48 3077 5578
invoice (draft): removal 55.43 676 6652
invoice accept: apply 41.82 16129 5018
invoice accept: cancel 50.23 710 6028
customer reserve: creation 3821.93 161 458631
customer reserve: removal 4923.37 51 590804
realization accept: apply 83.96 169 10075
realization accept: cancel 83.86 97 10063
payment from customer: creation 60.38 105 7245
payment from customer: removal 5.93 76 712
payment to supplier: creation 49.15 128 5898
payment to supplier: removal 3.70 53 444
service: total inventory turnovers 4.95 1045 594
service: total monetary turnovers 6.54 21 785
service: aggregate perf. data 0.85 17464 102
service: refresh index statistics 0.03 355728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-10-2020 19:28:01 to 14-10-2020 19:28:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2789
TEST_TIME 2 2543
TEST_TIME 3 2967
TEST_TIME 4 3853
TEST_TIME 5 3702
TEST_TIME 6 4361
TEST_TIME 7 5008
TEST_TIME 8 5233
TEST_TIME 9 5472
TEST_TIME 10 6057
TEST_TIME 11 6362
TEST_TIME 12 6532
TEST_TIME 13 6978
TEST_TIME 14 7656
TEST_TIME 15 8382
TEST_TIME 16 8262
TEST_TIME 17 9229
TEST_TIME 18 9358
TEST_TIME 19 9512
TEST_TIME 20 9795
TEST_TIME 21 9938
TEST_TIME 22 10228
TEST_TIME 23 10870
TEST_TIME 24 10830
TEST_TIME 25 11330
TEST_TIME 26 11726
TEST_TIME 27 11969
TEST_TIME 28 11816
TEST_TIME 29 11627
TEST_TIME 30 11405
TEST_TIME 31 11173
TEST_TIME 32 11237
TEST_TIME 33 11036
TEST_TIME 34 10902
TEST_TIME 35 10749
TEST_TIME 36 10746
TEST_TIME 37 10651
TEST_TIME 38 10496
TEST_TIME 39 10422
TEST_TIME 40 10307
TEST_TIME 41 10151
TEST_TIME 42 10109
TEST_TIME 43 10017
TEST_TIME 44 9905
TEST_TIME 45 10008
TEST_TIME 46 10025
TEST_TIME 47 10145
TEST_TIME 48 10101
TEST_TIME 49 9993
TEST_TIME 50 9971
TEST_TIME 51 10090
TEST_TIME 52 10059
TEST_TIME 53 10001
TEST_TIME 54 10075
TEST_TIME 55 10091
TEST_TIME 56 10145
TEST_TIME 57 10028
TEST_TIME 58 9913
TEST_TIME 59 9807
TEST_TIME 60 9916
TEST_TIME 61 9809
TEST_TIME 62 9745
TEST_TIME 63 9749
TEST_TIME 64 9728
TEST_TIME 65 9657
TEST_TIME 66 9687
TEST_TIME 67 9628
TEST_TIME 68 9725
TEST_TIME 69 9756
TEST_TIME 70 9676
TEST_TIME 71 9683
TEST_TIME 72 9736
TEST_TIME 73 9713
TEST_TIME 74 9762
TEST_TIME 75 9757
TEST_TIME 76 9803
TEST_TIME 77 9836
TEST_TIME 78 9874
TEST_TIME 79 9855
TEST_TIME 80 9834
TEST_TIME 81 9759
TEST_TIME 82 9739
TEST_TIME 83 9751
TEST_TIME 84 9725
TEST_TIME 85 9678
TEST_TIME 86 9669
TEST_TIME 87 9662
TEST_TIME 88 9579
TEST_TIME 89 9526
TEST_TIME 90 9557
TEST_TIME 91 9512
TEST_TIME 92 9554
TEST_TIME 93 9601
TEST_TIME 94 9573
TEST_TIME 95 9588
TEST_TIME 96 9557
TEST_TIME 97 9634
TEST_TIME 98 9727
TEST_TIME 99 9677
TEST_TIME 100 9721
TEST_TIME 101 9745
TEST_TIME 102 9726
TEST_TIME 103 9756
TEST_TIME 104 9764
TEST_TIME 105 9688
TEST_TIME 106 9638
TEST_TIME 107 9603
TEST_TIME 108 9616
TEST_TIME 109 9558
TEST_TIME 110 9593
TEST_TIME 111 9564
TEST_TIME 112 9525
TEST_TIME 113 9500
TEST_TIME 114 9469
TEST_TIME 115 9451
TEST_TIME 116 9452
TEST_TIME 117 9430
TEST_TIME 118 9470
TEST_TIME 119 9483
TEST_TIME 120 9482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2020 19:28:04 to 14-10-2020 19:28:08.

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 1564705 1564705 0 0.00 0 19800 5 0 0
doc_list_biud 1564694 1564694 0 0.00 0 75981 24 0 0
sp_add_invoice_to_stock 5138 5018 120 2.33 20 173898 16130 0 120
sp_cancel_adding_invoice 6028 6028 0 0.00 5 94846 710 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 9181 303 0 0
sp_cancel_customer_reserve 590804 590804 0 0.00 1 75982 52 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 5957 77 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 2 976 54 0 0
sp_cancel_supplier_invoice 6660 6652 8 0.12 2 18624 676 0 8
sp_cancel_supplier_order 461 461 0 0.00 12 841948 82306 0 0
sp_cancel_write_off 10063 10063 0 0.00 1 12165 97 0 0
sp_client_order 20835 20835 0 0.00 3 27781 368 0 0
sp_customer_reserve 458631 458631 0 0.00 0 85740 162 0 0
sp_fill_shopping_cart 31953 31953 0 0.00 1 28001 38 0 0
sp_fill_shopping_cart_clo_res 458619 458619 0 0.00 0 11989 14 0 0
sp_get_clo_for_invoice 5005 5005 0 0.00 0 37251 337 0 0
sp_kill_cost_storno 17247 17247 0 0.00 0 12163 48 0 0
sp_kill_qstorno_ret_qs2qd 1198784 1198784 0 0.00 0 75978 28 0 0
sp_kill_qty_storno 630856 630856 0 0.00 0 75981 60 0 0
sp_lock_selected_doc 21023 21023 0 0.00 0 9006 5 0 0
sp_make_cost_storno 28223 28223 0 0.00 0 19787 47 0 0
sp_multiply_rows_for_pdistr 28215 28215 0 0.00 0 6506 24 0 0
sp_multiply_rows_for_qdistr 20834 20834 0 0.00 0 17092 175 0 0
sp_pay_from_customer 7245 7245 0 0.00 4 22254 105 0 0
sp_pay_to_supplier 5898 5898 0 0.00 4 17712 128 0 0
sp_payment_common 13140 13140 0 0.00 1 22248 32 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 9174 278 0 0
sp_qd_handle_on_invoice_upd_sts 11031 11031 0 0.00 0 22568 276 0 0
sp_qd_handle_on_reserve_upd_sts 10072 10072 0 0.00 0 18148 55 0 0
sp_reserve_write_off 10075 10075 0 0.00 6 19820 169 0 0
sp_supplier_invoice 5578 5578 0 0.00 5 79018 3077 0 0
sp_supplier_order 5550 5550 0 0.00 5 37149 1180 0 0
srv_aggregate_perf_data 102 102 0 0.00 3100 126167 17465 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2447 140298 52030 0 0
srv_find_qd_qs_mism 1090639 1090639 0 0.00 0 11043 4 0 0
srv_make_invnt_saldo 594 594 0 0.00 30 68736 1045 0 0
srv_make_money_saldo 785 785 0 0.00 1 886 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 188099 518195 355729 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5018 5018 0 0.00 0 15134 144 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 3921 95 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 8986 18 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 1000 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1422 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 4942 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 610 9 0 0
t$perf-norm:sp_cancel_supplier_order 461 461 0 0.00 0 18880 1289 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 5778 11 0 0
t$perf-norm:sp_client_order 20835 20835 0 0.00 0 15039 17 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 3421 7 0 0
t$perf-norm:sp_pay_from_customer 7245 7245 0 0.00 0 3501 8 0 0
t$perf-norm:sp_pay_to_supplier 5898 5898 0 0.00 0 7444 10 0 0
t$perf-norm:sp_reserve_write_off 10075 10075 0 0.00 0 8334 16 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 14546 16 0 0
t$perf-norm:sp_supplier_order 5550 5550 0 0.00 0 6808 15 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 228 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 4463 18 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 2731 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 4 0 0
v_add_invoice_to_stock 10246 10006 240 2.34 0 11843 20 0 240
v_all_customers 37976 37976 0 0.00 0 125 1 0 0
v_all_suppliers 30376 30376 0 0.00 0 157 1 0 0
v_all_wares 41662 41662 0 0.00 0 92 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 1699 7 0 0
v_cancel_client_order 3546 3546 0 0.00 0 2896 6 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2630 7 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 0 20300 19 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 291 3 0 0
v_cancel_supplier_invoice 782 766 16 2.04 0 4723 31 0 16
v_cancel_supplier_order 922 922 0 0.00 0 1264 5 0 0
v_cancel_write_off 2048 2048 0 0.00 0 2779 12 0 0
v_max_id_clo_ord 5549 5549 0 0.00 0 2128 1 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 2013 15 0 0
v_max_id_ord_sup 5572 5572 0 0.00 0 4113 5 0 0
v_max_non_paid_invoice 1827 1827 0 0.00 0 160 2 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 4383 5 0 0
v_min_id_clo_ord 5549 5549 0 0.00 0 2155 5 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 3704 10 0 0
v_min_id_ord_sup 5572 5572 0 0.00 0 6072 8 0 0
v_min_non_paid_invoice 5895 5895 0 0.00 0 17702 23 0 0
v_min_non_paid_realizn 7245 7245 0 0.00 0 11741 20 0 0
v_our_firm 10296 10296 0 0.00 0 124 1 0 0
v_reserve_write_off 20144 20144 0 0.00 0 8103 7 0 0
x_lock_depdocs_on_canc_invoice 12297 12297 0 0.00 0 15564 110 0 0
x_lock_depdocs_on_canc_sup_ord 461 461 0 0.00 0 6746 316 0 0
x_make_qstorno_1000_1200 5550 5550 0 0.00 1 37138 1116 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 2 76297 2933 0 0
x_make_qstorno_2100_3300 458631 458631 0 0.00 0 85740 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-10-2020 19:28:09 to 14-10-2020 19:28:13.

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
16:57:01 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:02 24667 8192000000 10381026880 10699911168 1428277888 14.846 100 93 186 0 1464908288 6273792 30356608
16:59:29 10152 8192000000 10414305856 10742325248 1473935600 15.248 100 88 177 0 1511304256 8508464 28860192
17:00:46 10525 8192000000 10462704640 10804166656 1518409376 15.636 100 75 150 1 1551789552 9610016 23770160
17:02:05 10667 8192000000 10550326736 10907025408 1603007264 16.365 100 74 149 0 1635780528 10656928 22116336
17:03:20 5406 8192000000 10631318544 10999717888 1691179280 17.111 100 76 152 1 1725269424 12030288 22059856
17:04:27 3404 8192000000 10703264032 11083014144 1770080896 17.768 100 86 169 3 1806522368 13056064 23385408
17:05:32 4504 8192000000 10764265392 11152621568 1823006992 18.202 100 80 160 0 1858134832 13142224 21985616
17:06:39 1972 8192000000 10811522128 11202154496 1871801152 18.599 100 93 186 1 1911552704 13451536 26300016
17:07:42 9810 8192000000 10818911648 11214217216 1876048896 18.633 100 85 175 0 1907214656 5926336 25239424
17:08:53 4491 8192000000 10789254720 11215269888 1875763520 18.631 100 40 82 0 1895548752 8783136 11002096
17:09:59 8989 8192000000 10795425680 11231920128 1892154304 18.763 100 19 36 2 1903082688 5362304 5566080
17:11:10 8498 8192000000 10814962240 11243786240 1901156256 18.836 100 15 31 0 1910307072 5219216 3931600
17:12:21 40848 8192000000 10816511232 11251507200 1912637744 18.928 100 44 86 1 1931400992 4647520 14115728
17:14:02 8748 8192000000 10822284432 11260547072 1912361920 18.926 100 19 38 0 1923204032 5369328 5472784
17:15:11 17829 8192000000 10799693792 11237462016 1847547808 18.402 97 11 22 0 1856039088 5450496 3040784
17:16:34 12818 8192000000 10732776720 11148103680 1761001952 17.693 95 31 61 2 1776075648 5220304 9853392
17:17:50 16424 8192000000 10724815264 11147673600 1726540288 17.407 94 32 64 1 1745347520 10190464 8616768
17:19:08 6996 8192000000 10697012848 11111546880 1708154800 17.253 95 18 35 2 1717925024 4581984 5188240
17:20:18 6076 8192000000 10711717552 11124645888 1763247808 17.711 98 28 56 0 1777022032 5602256 8171968
17:21:26 15035 8192000000 10747109904 11169824768 1830682912 18.265 100 10 20 2 1838884832 5588736 2613184
17:22:43 22128 8192000000 10769850400 11193057280 1847091760 18.398 100 23 46 1 1859136992 5563872 6481360
17:24:08 8755 8192000000 10780256304 11207712768 1863594160 18.532 100 28 56 1 1876468896 5307168 7567568
17:25:20 11862 8192000000 10784355456 11219116032 1874426256 18.620 100 20 40 2 1886038608 6022352 5590000
17:26:32 6742 8192000000 10787115840 11229007872 1881437952 18.677 100 13 25 2 1890192224 5101392 3652880
17:27:40 76635 8192000000 10829009168 11262574592 1899503088 18.822 100 34 66 1 1915330224 6262912 9564224
17:29:59 18349 8192000000 10878199936 11302580224 1942294576 19.165 100 57 113 3 1972415376 13733600 16387200
17:31:46 17831 8192000000 10884648048 11319042048 1943277616 19.173 99 49 97 3 1973059856 15949376 13832864
17:33:12 5221 8192000000 10915100256 11341697024 1967299184 19.364 100 53 106 3 2002926560 19702880 15924496
17:34:21 13866 8192000000 10900807456 11336146944 1956802576 19.281 99 40 80 1 1990004448 20796096 12405776
17:35:43 7025 8192000000 10918201008 11338645504 1950368912 19.229 99 71 138 0 1996706656 25184752 21152992
17:37:01 5387 8192000000 10922138112 11340304384 1959072576 19.299 100 61 122 1 2008444544 30938896 18433072
17:38:10 4705 8192000000 10894452880 11312623616 1929673328 19.064 100 60 121 1 1983488272 33690240 20124704
17:39:19 4811 8192000000 10919135664 11332435968 1939248560 19.141 99 80 160 1 2001783136 37552080 24982496
17:40:27 2446 8192000000 10912152064 11323129856 1910045920 18.907 98 75 147 3 1979941936 46960272 22935744
17:41:34 2935 8192000000 10917647616 11337879552 1940051760 19.147 100 60 120 3 2000066704 41136992 18877952
17:42:41 10146 8192000000 10916298144 11336495104 1892051472 18.762 97 46 92 2 1952830624 44952864 15826288
17:43:58 10337 8192000000 10928011920 11357446144 1950110768 19.227 100 35 70 2 2002628960 40642992 11875200
17:45:11 8798 8192000000 10937040800 11371786240 1964573696 19.342 100 41 82 2 2018940928 40510160 13857072
17:46:29 28135 8192000000 10923206240 11374006272 1944859408 19.186 99 22 44 3 1998193120 45752112 7581600
17:47:58 28258 8192000000 10934676016 11378143232 1944561328 19.183 99 31 62 3 1998297120 43409488 10326304
17:49:35 41334 8192000000 10896364896 11352936448 1930384144 19.070 99 21 42 1 1971088528 33930592 6773792
17:51:31 39291 8192000000 10908301296 11359031296 1933994400 19.099 99 17 34 0 1984504416 44588672 5921344
17:53:11 87096 8192000000 10878765408 11323846656 1915548704 18.951 99 16 32 1 1938251168 17949520 4752944
17:55:48 46859 8192000000 10862126208 11327209472 1920564688 18.991 99 26 52 2 1941780416 13628192 7587536
17:57:35 54002 8192000000 10866161696 11340828672 1941280416 19.157 100 17 35 1 1960600416 14735200 4584800
17:59:35 86827 8192000000 10899403872 11371249664 1971094704 19.394 100 15 30 1 1985535584 10460064 3980816
18:02:02 35526 8192000000 10913908832 11371360256 1978464896 19.453 100 17 35 1 1997339168 14392160 4482112
18:03:50 124826 8192000000 10891537232 11358875648 1923408832 19.014 97 11 22 0 1933103424 7087632 2606960
18:06:55 24438 8192000000 10901932576 11371671552 1981843152 19.479 100 17 34 0 1997977520 11612688 4521680
18:08:22 68536 8192000000 10920253520 11388989440 1992068368 19.560 100 20 40 1 2013804480 15736416 5999696
18:10:31 48885 8192000000 10915610032 11393036288 1988815168 19.534 100 12 25 0 2012418272 19765408 3837696
18:12:24 66641 8192000000 10908388352 11390664704 1976949872 19.441 99 35 71 0 2002365888 14545120 10870896
18:14:33 63254 8192000000 10930245296 11386630144 1963526592 19.334 98 19 38 1 1978489584 9664624 5298368
18:16:40 42181 8192000000 10914114688 11379818496 1966566320 19.358 99 32 65 1 1985037392 9234144 9236928
18:18:30 140298 8192000000 10867776448 11344113664 1757645040 17.665 89 12 24 0 1775679088 15023232 3010816
18:21:54 79870 8192000000 10862973600 11337863168 1893544048 18.774 97 9 18 0 1909205616 12733104 2928464
18:24:16 94951 8192000000 10913376128 11357122560 1957794288 19.289 100 30 60 3 1982184672 16122000 8268384
18:26:53 77856 8192000000 10862333520 11334029312 1894033840 18.778 97 22 44 2 1915194256 15230224 5930192
18:29:11 69417 8192000000 10827889568 11305009152 1867069760 18.561 96 12 24 0 1881250480 11033968 3146752
18:31:26 67939 8192000000 10846193904 11318743040 1896677456 18.800 98 12 23 2 1914648464 14632768 3338240
18:33:34 64469 8192000000 10881056464 11341459456 1913331648 18.933 98 18 36 0 1933344672 14485584 5527440
18:35:40 34138 8192000000 10854013520 11307098112 1842404224 18.360 94 22 44 0 1854624352 6390800 5829328
18:37:16 63763 8192000000 10835246720 11294060544 1835147520 18.301 95 29 58 0 1857580144 14355248 8077376
18:39:22 35154 8192000000 10862957184 11313983488 1915236240 18.949 100 33 66 2 1939534560 15136048 9162272
18:40:58 121910 8192000000 10861229008 11334283264 1884233952 18.699 97 12 23 0 1897264272 10119872 2910448
18:44:10 99442 8192000000 10881065392 11347718144 1892041648 18.762 97 19 38 2 1910568144 13017760 5508736
18:46:57 102003 8192000000 10880126592 11335589888 1934883872 19.106 99 27 54 0 1956355344 12843424 8628048
18:49:43 39741 8192000000 10871228400 11360350208 1942378672 19.166 100 5 10 0 1962104992 18565312 1161008
18:51:29 62901 8192000000 10871599408 11347640320 1938221568 19.133 99 10 20 0 1948103696 7178368 2703760
18:53:36 44542 8192000000 10886481568 11366375424 1970391680 19.389 100 12 24 0 1983767808 9893632 3482496
18:57:11 38979 8192000000 10923823008 11371589632 1958970736 19.298 99 36 72 1 1982119712 13021264 10127712
18:59:01 64345 8192000000 10907262016 11383463936 1927802752 19.049 97 12 24 0 1940509120 9533344 3173024
19:01:06 54182 8192000000 10905405248 11366162432 1923890448 19.018 97 12 24 1 1934931168 7991728 3048992
19:03:08 69184 8192000000 10900121184 11382321152 1961738768 19.320 99 16 32 0 1980199872 13494176 4966928
19:05:18 32722 8192000000 10937198448 11377762304 1978662832 19.454 100 32 63 1 1999420320 11843728 8913760
19:06:53 70296 8192000000 10895215280 11358457856 1879512704 18.661 95 22 44 0 1898036448 12635952 5887792
19:09:22 56065 8192000000 10871640624 11335688192 1903192624 18.852 97 17 34 0 1922413712 14795776 4425312
19:11:26 108757 8192000000 10891413632 11339603968 1861882256 18.519 94 42 82 1 1885008048 11921728 11204064
19:15:57 35691 8192000000 10861690080 11340148736 1927255920 19.045 99 17 34 2 1948544288 16189472 5098896
19:17:35 95397 8192000000 10861040080 11333201920 1909532896 18.903 98 12 24 1 1920356096 7516000 3307200
19:20:10 88111 8192000000 10873277312 11334750208 1905160352 18.868 98 20 41 0 1918212144 7158736 5893056
19:22:41 46400 8192000000 10848725664 11306704896 1866114864 18.553 96 35 69 1 1886284576 10321376 9848336
19:24:37 66496 8192000000 10833888864 11303456768 1870627952 18.589 98 20 40 1 1885763920 9384304 5751664
19:26:48 22165 8192000000 9106956976 9344172032 259128864 3.066 14 1 2 0 261411200 2220208 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-10-2020 19:28:14 to 14-10-2020 19:28:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 120 v_add_invoice_to_stock 335544517
user_exc 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-10-2020 19:28:30 to 14-10-2020 19:28:32.

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          251186
MON$OLDEST_ACTIVE               251187
MON$OLDEST_SNAPSHOT             251187
MON$NEXT_TRANSACTION            251187
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                       2032672
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.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 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 ---
:143: 
:144: mscs-test-161.budzdorov.ru    Wed Oct 14 19:27:17 2020
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
--- end of diff output ---

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

14.10.20 19:28:33. 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-14 19:28:35 No severe PSQL-related problems occured
14.10.20 19:28:36. End of report.