Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2020 01: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 17432804 kB
MemAvailable 31525212 kB
Buffers 0 kB
Cached 14248204 kB
SwapCached 3044 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-26 01:26:56
DTS_END                         2020-10-26 01:26:56.9270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/60368
TRN_ID                          252528
ATT_ID                          699
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: 9698.02 18313 1163762
customer order: creation 184.30 370 22116
customer order: refuse 15.61 285 1873
order to supplier: creation 49.08 1182 5889
order to supplier: removal 3.82 73150 458
invoice (draft): creation 49.29 3229 5915
invoice (draft): removal 54.61 654 6553
invoice accept: apply 44.43 16066 5331
invoice accept: cancel 49.16 691 5899
customer reserve: creation 4086.37 161 490364
customer reserve: removal 4850.09 44 582011
realization accept: apply 87.77 169 10532
realization accept: cancel 85.85 89 10302
payment from customer: creation 63.09 116 7571
payment from customer: removal 6.02 56 722
payment to supplier: creation 52.02 141 6242
payment to supplier: removal 3.85 79 462
service: total inventory turnovers 5.36 782 643
service: total monetary turnovers 6.34 33 761
service: aggregate perf. data 0.95 17123 114
service: refresh index statistics 0.03 251847 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3252
TEST_TIME 2 4609
TEST_TIME 3 4644
TEST_TIME 4 4628
TEST_TIME 5 4731
TEST_TIME 6 5123
TEST_TIME 7 5414
TEST_TIME 8 5839
TEST_TIME 9 5922
TEST_TIME 10 6755
TEST_TIME 11 7025
TEST_TIME 12 7325
TEST_TIME 13 7709
TEST_TIME 14 8053
TEST_TIME 15 9006
TEST_TIME 16 9816
TEST_TIME 17 9978
TEST_TIME 18 10773
TEST_TIME 19 10818
TEST_TIME 20 10965
TEST_TIME 21 10978
TEST_TIME 22 10683
TEST_TIME 23 10506
TEST_TIME 24 10446
TEST_TIME 25 10462
TEST_TIME 26 10317
TEST_TIME 27 10382
TEST_TIME 28 10556
TEST_TIME 29 10549
TEST_TIME 30 10477
TEST_TIME 31 10433
TEST_TIME 32 10395
TEST_TIME 33 10512
TEST_TIME 34 10547
TEST_TIME 35 10690
TEST_TIME 36 10690
TEST_TIME 37 10925
TEST_TIME 38 10840
TEST_TIME 39 10584
TEST_TIME 40 10500
TEST_TIME 41 10406
TEST_TIME 42 10441
TEST_TIME 43 10372
TEST_TIME 44 10275
TEST_TIME 45 10278
TEST_TIME 46 10317
TEST_TIME 47 10319
TEST_TIME 48 10170
TEST_TIME 49 10125
TEST_TIME 50 10042
TEST_TIME 51 10067
TEST_TIME 52 10041
TEST_TIME 53 10029
TEST_TIME 54 10076
TEST_TIME 55 10051
TEST_TIME 56 9987
TEST_TIME 57 10158
TEST_TIME 58 10215
TEST_TIME 59 10156
TEST_TIME 60 10113
TEST_TIME 61 10203
TEST_TIME 62 10283
TEST_TIME 63 10295
TEST_TIME 64 10255
TEST_TIME 65 10174
TEST_TIME 66 10125
TEST_TIME 67 10101
TEST_TIME 68 10061
TEST_TIME 69 10016
TEST_TIME 70 9975
TEST_TIME 71 9942
TEST_TIME 72 9857
TEST_TIME 73 9827
TEST_TIME 74 9759
TEST_TIME 75 9891
TEST_TIME 76 9872
TEST_TIME 77 9849
TEST_TIME 78 9821
TEST_TIME 79 9823
TEST_TIME 80 9849
TEST_TIME 81 9886
TEST_TIME 82 9932
TEST_TIME 83 9920
TEST_TIME 84 9958
TEST_TIME 85 9957
TEST_TIME 86 10078
TEST_TIME 87 9984
TEST_TIME 88 9914
TEST_TIME 89 9893
TEST_TIME 90 9884
TEST_TIME 91 9853
TEST_TIME 92 9810
TEST_TIME 93 9778
TEST_TIME 94 9766
TEST_TIME 95 9726
TEST_TIME 96 9786
TEST_TIME 97 9758
TEST_TIME 98 9774
TEST_TIME 99 9823
TEST_TIME 100 9828
TEST_TIME 101 9833
TEST_TIME 102 9780
TEST_TIME 103 9884
TEST_TIME 104 9845
TEST_TIME 105 9884
TEST_TIME 106 9873
TEST_TIME 107 9870
TEST_TIME 108 9869
TEST_TIME 109 9787
TEST_TIME 110 9767
TEST_TIME 111 9725
TEST_TIME 112 9743
TEST_TIME 113 9716
TEST_TIME 114 9675
TEST_TIME 115 9657
TEST_TIME 116 9648
TEST_TIME 117 9596
TEST_TIME 118 9662
TEST_TIME 119 9683
TEST_TIME 120 9700

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2020 01:28:07 to 26-10-2020 01:28:10.

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 1617499 1617499 0 0.00 0 21690 6 0 0
doc_list_biud 1617499 1617499 0 0.00 0 39253 21 0 0
sp_add_invoice_to_stock 5446 5331 115 2.11 39 182820 16066 0 115
sp_cancel_adding_invoice 5899 5899 0 0.00 5 48538 691 0 0
sp_cancel_client_order 1873 1873 0 0.00 4 10488 285 0 0
sp_cancel_customer_reserve 582011 582011 0 0.00 1 27984 45 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 3509 56 0 0
sp_cancel_pay_to_supplier 462 462 0 0.00 3 4500 79 0 0
sp_cancel_supplier_invoice 6565 6553 12 0.18 1 32170 654 0 12
sp_cancel_supplier_order 458 458 0 0.00 8 800774 73150 0 0
sp_cancel_write_off 10302 10302 0 0.00 1 12077 89 0 0
sp_client_order 22116 22116 0 0.00 3 33495 370 0 0
sp_customer_reserve 490364 490364 0 0.00 0 88337 162 0 0
sp_fill_shopping_cart 33892 33892 0 0.00 1 21969 38 0 0
sp_fill_shopping_cart_clo_res 490360 490360 0 0.00 0 13198 12 0 0
sp_get_clo_for_invoice 5324 5324 0 0.00 0 15730 252 0 0
sp_kill_cost_storno 17385 17385 0 0.00 0 11561 45 0 0
sp_kill_qstorno_ret_qs2qd 1181335 1181335 0 0.00 0 32145 25 0 0
sp_kill_qty_storno 622950 622950 0 0.00 0 39250 54 0 0
sp_lock_selected_doc 22089 22089 0 0.00 0 6851 5 0 0
sp_make_cost_storno 29667 29667 0 0.00 0 21683 52 0 0
sp_multiply_rows_for_pdistr 29667 29667 0 0.00 0 6768 27 0 0
sp_multiply_rows_for_qdistr 22116 22116 0 0.00 0 25455 163 0 0
sp_pay_from_customer 7571 7571 0 0.00 4 16615 116 0 0
sp_pay_to_supplier 6242 6242 0 0.00 5 14939 142 0 0
sp_payment_common 13813 13813 0 0.00 1 11366 27 0 0
sp_qd_handle_on_cancel_clo 1873 1873 0 0.00 0 10479 252 0 0
sp_qd_handle_on_invoice_upd_sts 11221 11221 0 0.00 0 39248 283 0 0
sp_qd_handle_on_reserve_upd_sts 10532 10532 0 0.00 0 11166 55 0 0
sp_reserve_write_off 10532 10532 0 0.00 6 16662 169 0 0
sp_supplier_invoice 5915 5915 0 0.00 7 84955 3229 0 0
sp_supplier_order 5889 5889 0 0.00 4 31141 1182 0 0
srv_aggregate_perf_data 114 114 0 0.00 2653 90105 17123 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2188 178667 47522 0 0
srv_find_qd_qs_mism 1112742 1112742 0 0.00 0 17030 5 0 0
srv_make_invnt_saldo 643 643 0 0.00 36 27297 783 0 0
srv_make_money_saldo 761 761 0 0.00 0 10139 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 130492 368918 251847 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5331 5331 0 0.00 0 10511 115 0 0
t$perf-norm:sp_cancel_adding_invoice 472 472 0 0.00 0 4638 81 0 0
t$perf-norm:sp_cancel_client_order 1873 1873 0 0.00 0 2767 14 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 3941 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 3620 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 462 462 0 0.00 0 2320 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 2463 18 0 0
t$perf-norm:sp_cancel_supplier_order 458 458 0 0.00 0 17514 1196 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 3070 6 0 0
t$perf-norm:sp_client_order 22116 22116 0 0.00 0 11598 16 0 0
t$perf-norm:sp_customer_reserve 1121 1121 0 0.00 0 1746 7 0 0
t$perf-norm:sp_pay_from_customer 7571 7571 0 0.00 0 5091 8 0 0
t$perf-norm:sp_pay_to_supplier 6242 6242 0 0.00 0 8994 11 0 0
t$perf-norm:sp_reserve_write_off 10532 10532 0 0.00 0 10019 13 0 0
t$perf-norm:sp_supplier_invoice 5915 5915 0 0.00 0 5332 14 0 0
t$perf-norm:sp_supplier_order 5889 5889 0 0.00 0 5610 12 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 267 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 163 6 0 0
t$perf-norm:srv_make_invnt_saldo 643 643 0 0.00 0 1930 16 0 0
t$perf-norm:srv_make_money_saldo 761 761 0 0.00 0 1060 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10874 10644 230 2.11 0 5584 21 0 230
v_all_customers 40170 40170 0 0.00 0 94 0 0 0
v_all_suppliers 32572 32572 0 0.00 0 94 1 0 0
v_all_wares 44232 44232 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 944 944 0 0.00 0 5801 19 0 0
v_cancel_client_order 3746 3746 0 0.00 0 3708 8 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 1584 6 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 3363 9 0 0
v_cancel_payment_to_supplier 924 924 0 0.00 0 2725 12 0 0
v_cancel_supplier_invoice 920 896 24 2.60 0 2884 21 0 24
v_cancel_supplier_order 916 916 0 0.00 0 1414 6 0 0
v_cancel_write_off 2116 2116 0 0.00 0 5191 17 0 0
v_max_id_clo_ord 5889 5889 0 0.00 0 12525 4 0 0
v_max_id_clo_res 1121 1121 0 0.00 0 2045 19 0 0
v_max_id_ord_sup 5887 5887 0 0.00 0 1502 2 0 0
v_max_non_paid_invoice 1732 1732 0 0.00 0 2514 4 0 0
v_max_non_paid_realizn 4067 4067 0 0.00 0 2659 5 0 0
v_min_id_clo_ord 5889 5889 0 0.00 0 1435 5 0 0
v_min_id_clo_res 1121 1121 0 0.00 0 6295 23 0 0
v_min_id_ord_sup 5887 5887 0 0.00 0 7699 9 0 0
v_min_non_paid_invoice 6242 6242 0 0.00 0 11361 15 0 0
v_min_non_paid_realizn 7571 7571 0 0.00 0 9835 20 0 0
v_our_firm 11070 11070 0 0.00 0 10 1 0 0
v_reserve_write_off 21064 21064 0 0.00 0 16481 12 0 0
x_lock_depdocs_on_canc_invoice 12004 12004 0 0.00 0 30555 96 0 0
x_lock_depdocs_on_canc_sup_ord 458 458 0 0.00 0 12460 302 0 0
x_make_qstorno_1000_1200 5889 5889 0 0.00 1 31133 1116 0 0
x_make_qstorno_1200_2000 5915 5915 0 0.00 3 77424 3077 0 0
x_make_qstorno_2100_3300 490364 490364 0 0.00 0 88336 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 01:28:11 to 26-10-2020 01:28:15.

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
22:57:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
22:58:01 18037 8192000000 10337145072 10655113216 1391576576 14.520 100 85 170 0 1425830704 5972704 28281424
22:59:24 8260 8192000000 10419719168 10747949056 1475365232 15.261 100 85 170 0 1511047952 7862800 27819920
23:00:37 21932 8192000000 10498551984 10843058176 1547181440 15.886 100 80 160 0 1581886528 9058432 25646656
23:02:02 19238 8192000000 10591556144 10951725056 1636925792 16.654 100 83 166 0 1671705984 9705712 25074480
23:03:27 8818 8192000000 10662141824 11030667264 1721538144 17.365 100 84 167 0 1758177632 12361344 24278144
23:04:40 8110 8192000000 10716618864 11098677248 1776604432 17.821 100 78 154 1 1811508528 12821232 22082864
23:05:51 3527 8192000000 10770888336 11169472512 1840686256 18.346 100 74 149 2 1877153456 15211600 21255600
23:06:56 1928 8192000000 10824733456 11216420864 1882417360 18.685 100 96 189 0 1923613760 13068800 28127600
23:07:58 3569 8192000000 10823627296 11228225536 1886846768 18.720 100 55 110 3 1911123552 8623456 15653328
23:09:03 7984 8192000000 10800765792 11234275328 1893434352 18.773 100 28 56 2 1912386672 10772464 8179856
23:10:16 35132 8192000000 10819671696 11249909760 1903056048 18.851 100 25 50 2 1915535168 5031424 7447696
23:12:20 9388 8192000000 10826099184 11259162624 1908779840 18.897 100 24 48 4 1921862528 5773664 7309024
23:13:31 8910 8192000000 10832704288 11268878336 1912286736 18.925 100 21 42 1 1924131504 6414304 5430464
23:14:40 7874 8192000000 10801536672 11240955904 1871051680 18.593 98 16 32 1 1881530736 5876160 4602896
23:15:50 73300 8192000000 10803912672 11237711872 1817872560 18.160 95 34 68 2 1833728112 5507968 10347584
23:18:04 6835 8192000000 10747299616 11169800192 1828472128 18.247 100 24 48 2 1842321376 6322784 7526464
23:19:11 8486 8192000000 10665718720 11082764288 1705165600 17.228 95 25 50 1 1718621600 5662960 7793040
23:20:20 8170 8192000000 10707310208 11132432384 1774011920 17.800 98 21 42 2 1786693376 6852128 5829328
23:21:29 47162 8192000000 10748648416 11167203328 1826599136 18.232 100 37 74 3 1842497616 5831360 10067120
23:23:18 9482 8192000000 10775110480 11197353984 1849028816 18.414 100 22 44 2 1860891056 5577728 6284512
23:24:30 8962 8192000000 10775651504 11207622656 1860959904 18.511 100 12 24 1 1869617680 5417984 3239792
23:25:46 18277 8192000000 10788913040 11221090304 1874738496 18.623 100 15 31 1 1884485328 5686992 4059840
23:27:05 43693 8192000000 10816625744 11248918528 1897725744 18.808 100 37 69 3 1913319648 5556976 10036928
23:28:52 21764 8192000000 10875655488 11299418112 1940543520 19.151 100 44 88 0 1961239072 8482208 12213344
23:30:21 15255 8192000000 10899547488 11330314240 1962941376 19.329 100 37 74 0 1988756880 14849408 10966096
23:31:43 12431 8192000000 10896597328 11328761856 1960208000 19.308 100 41 82 0 1990022896 17572176 12242720
23:32:59 11129 8192000000 10930153808 11354660864 1976954224 19.441 100 58 114 1 2013509680 19269232 17286224
23:34:14 6792 8192000000 10938973760 11370635264 1991675776 19.557 100 50 99 1 2030909840 23321568 15912496
23:35:28 5011 8192000000 10942894864 11375431680 1992872496 19.566 100 53 104 1 2034854608 25560032 16422080
23:36:37 6493 8192000000 10933250944 11370795008 1942756112 19.169 97 61 120 2 1992458736 30708304 18994320
23:37:47 2187 8192000000 10928272080 11350691840 1947397296 19.206 98 65 130 0 1997813360 30409184 20006880
23:38:59 3485 8192000000 10932328768 11342139392 1942067456 19.163 99 87 174 1 2002068880 33597840 26403584
23:40:06 2538 8192000000 10941246144 11359494144 1970727840 19.391 100 86 172 2 2027827936 30369568 26730528
23:41:15 2910 8192000000 10929517744 11341856768 1951140112 19.236 100 79 154 0 2011834128 35826224 24867792
23:42:24 32959 8192000000 10856437984 11305963520 1879671504 18.662 98 31 62 1 1922147536 31862096 10613936
23:43:59 40396 8192000000 10836940928 11292045312 1829730656 18.257 96 20 40 0 1866565792 30334736 6500400
23:45:40 15748 8192000000 10834553232 11270803456 1869452320 18.580 98 32 63 2 1894604448 16080624 9071504
23:47:01 21672 8192000000 10850023776 11297910784 1915593984 18.952 100 28 56 2 1939514528 14631504 9289040
23:48:23 29710 8192000000 10860545552 11311255552 1929316800 19.061 100 22 44 0 1948796704 12863088 6616816
23:49:57 21012 8192000000 10866564496 11326738432 1932559600 19.087 100 9 18 0 1952963376 17783616 2620160
23:51:18 30639 8192000000 10856468064 11325132800 1896395984 18.797 98 18 35 3 1925877680 24156016 5325680
23:52:49 28055 8192000000 10893050560 11335966720 1946478896 19.198 100 48 88 3 1976462496 17207616 12775984
23:54:18 61734 8192000000 10870718080 11327397888 1925730112 19.033 99 15 30 2 1944416400 13630608 5055680
23:56:22 39663 8192000000 10884027008 11342184448 1957694880 19.288 100 43 87 0 1981265216 11263600 12306736
23:58:03 32761 8192000000 10915124816 11368423424 1976161104 19.434 100 32 62 3 1994661136 8558400 9941632
23:59:44 136576 8192000000 10904197952 11374678016 1882038160 18.682 95 5 10 0 1903184480 20077248 1069072
00:03:01 93924 8192000000 10922909392 11392905216 1986362912 19.515 100 9 18 0 2006026928 17364624 2299392
00:05:37 65157 8192000000 10944350384 11393753088 2003827984 19.653 100 29 59 0 2021402320 9056640 8517696
00:07:45 52372 8192000000 10898451264 11362725888 1957465312 19.286 97 37 72 2 1980067616 11810624 10791680
00:09:55 18045 8192000000 10936380608 11397885952 1994079952 19.576 99 16 32 0 2008768064 9698112 4990000
00:11:16 26402 8192000000 10920852384 11388702720 1979955696 19.464 98 25 50 0 2000380128 13224368 7200064
00:12:47 178648 8192000000 10918374656 11398127616 1932760240 19.089 96 15 30 0 1948181488 10963056 4458192
00:17:01 28202 8192000000 10921810496 11392815104 1985641472 19.509 99 18 36 0 2000892864 9973824 5277568
00:18:29 40571 8192000000 10916084880 11392913408 1954245952 19.260 97 21 42 0 1968734112 8661856 5826304
00:20:14 35677 8192000000 10905075328 11378798592 1971490656 19.397 99 19 38 0 1984763728 7965872 5307200
00:21:50 42236 8192000000 10931108400 11396378624 1998864784 19.614 100 27 54 2 2023882224 15914608 9102832
00:23:34 97350 8192000000 10882751808 11370553344 1835819472 18.307 92 15 31 1 1858083376 17920560 4343344
00:26:12 43494 8192000000 10873708816 11312259072 1838380144 18.328 94 28 55 1 1856009952 10299552 7330256
00:27:56 68697 8192000000 10855097728 11321655296 1859198288 18.497 96 14 26 2 1875910096 13381616 3330192
00:30:05 44276 8192000000 10880735600 11336859648 1923712544 19.017 99 21 42 1 1945145984 15539488 5893952
00:31:49 115128 8192000000 10849947104 11317018624 1854809616 18.461 95 26 52 1 1873031472 10985856 7236000
00:34:58 70134 8192000000 10852656480 11325943808 1923511200 19.015 99 20 40 0 1937663088 8812448 5339440
00:37:34 68616 8192000000 10883707248 11350908928 1943010080 19.171 99 14 28 0 1960127424 12053920 5063424
00:39:43 64047 8192000000 10864070896 11330846720 1886249600 18.716 96 21 42 1 1905152048 12652432 6250016
00:41:47 44517 8192000000 10849113552 11318296576 1866756736 18.558 96 17 33 1 1879468944 7791072 4921136
00:43:37 27526 8192000000 10860084272 11314388992 1896924112 18.802 98 41 78 1 1918689840 10987216 10778512
00:45:05 61055 8192000000 10886068464 11351343104 1945132736 19.188 100 20 40 1 1965205968 14389856 5683376
00:47:08 32364 8192000000 10916520400 11359961088 1962596656 19.327 100 27 54 1 1980184960 9686080 7902224
00:48:42 74640 8192000000 10901976688 11374387200 1969407488 19.381 100 23 46 1 1987272704 11397904 6467312
00:50:56 24291 8192000000 10944601872 11381669888 1982094768 19.481 100 41 81 1 2005440000 11527472 11817760
00:52:22 81814 8192000000 10896754688 11375308800 1943440496 19.174 98 14 28 0 1964726496 17504848 3781152
00:55:21 48660 8192000000 10896156832 11374125056 1961229104 19.316 99 19 38 1 1976946272 10553248 5163920
00:57:13 51813 8192000000 10884489792 11367231488 1963669888 19.335 99 13 26 0 1975586448 8336176 3580384
00:59:13 73401 8192000000 10928240256 11408187392 1992950832 19.567 100 8 16 2 2001923152 7454736 1517584
01:01:26 59902 8192000000 10898118352 11367854080 1921975696 19.003 96 16 32 1 1934542592 8361280 4205616
01:03:26 47845 8192000000 10915382128 11390083072 1972870624 19.408 99 26 50 2 1991793200 11530784 7391792
01:05:22 64205 8192000000 10927493056 11388534784 1966990720 19.362 99 24 48 1 1986222976 12080832 7151424
01:07:28 46126 8192000000 10901914432 11367251968 1916885536 18.962 97 18 36 0 1933617552 11812544 4919472
01:09:15 56887 8192000000 10860269872 11335200768 1921218816 18.997 99 11 22 1 1936443120 12045664 3178640
01:11:12 39871 8192000000 10869454864 11328471040 1904056784 18.859 98 37 74 2 1927030304 12747120 10226400
01:12:52 47937 8192000000 10831231616 11284287488 1773004032 17.792 92 11 22 0 1785246160 9472176 2769952
01:14:40 110007 8192000000 10852574896 11316621312 1901748240 18.840 99 20 40 0 1915759024 8160288 5850496
01:17:36 76747 8192000000 10821197712 11296055296 1874451632 18.620 98 20 40 0 1894594432 14492160 5650640
01:20:00 77733 8192000000 10838329040 11304046592 1867294144 18.562 97 10 20 2 1885610816 15112336 3204336
01:22:18 69131 8192000000 10838197664 11318374400 1873082368 18.609 97 14 28 0 1892037744 15001520 3953856
01:24:27 52427 8192000000 10809892480 11292217344 1835503632 18.304 96 11 21 1 1856736096 17813360 3419104
01:26:23 42352 8192000000 9548241152 9866366976 690786272 7.776 36 7 14 0 698835216 6237824 1811120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2020 01:28:17 to 26-10-2020 01:28:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 115 v_add_invoice_to_stock 335544517
user_exc 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2020 01:28:33 to 26-10-2020 01:28:35.

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          251681
MON$OLDEST_ACTIVE               252787
MON$OLDEST_SNAPSHOT             252787
MON$NEXT_TRANSACTION            252787
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                       2029280
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Mon Oct 26 01:27:11 2020
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
--- end of diff output ---

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

26.10.20 01:28:36. 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-26 01:28:38 No severe PSQL-related problems occured
26.10.20 01:28:39. End of report.