Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2020 22:28
Common Performance Final Results
  1. Hardware and OS info
  2. DB and test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database Statistics, full
  3. Ratio "Versions / Records" for tables
  4. Database Validation Results
  5. New in firebird.log while test was run
  6. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17391656 kB
MemAvailable 31597844 kB
Buffers 0 kB
Cached 14317612 kB
SwapCached 5448 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-18 22:26:56
DTS_END                         2020-10-18 22:26:56.9590
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/58216
TRN_ID                          253601
ATT_ID                          696
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: 9469.04 21832 1136285
customer order: creation 178.53 303 21424
customer order: refuse 15.44 260 1853
order to supplier: creation 47.34 1136 5681
order to supplier: removal 3.42 64790 410
invoice (draft): creation 47.14 2869 5657
invoice (draft): removal 53.87 510 6464
invoice accept: apply 44.23 15353 5307
invoice accept: cancel 48.55 644 5826
customer reserve: creation 3985.77 156 478292
customer reserve: removal 4726.66 36 567199
realization accept: apply 87.10 153 10452
realization accept: cancel 94.97 83 11396
payment from customer: creation 61.67 111 7400
payment from customer: removal 6.02 77 722
payment to supplier: creation 51.94 125 6233
payment to supplier: removal 3.90 63 468
service: total inventory turnovers 5.18 1087 622
service: total monetary turnovers 6.37 21 764
service: aggregate perf. data 0.93 14424 111
service: refresh index statistics 0.03 334434 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2020 22:28:33 to 18-10-2020 22:28:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4305
TEST_TIME 2 3401
TEST_TIME 3 4061
TEST_TIME 4 4965
TEST_TIME 5 5528
TEST_TIME 6 6314
TEST_TIME 7 6187
TEST_TIME 8 6906
TEST_TIME 9 7680
TEST_TIME 10 8074
TEST_TIME 11 8116
TEST_TIME 12 8142
TEST_TIME 13 7713
TEST_TIME 14 8084
TEST_TIME 15 8862
TEST_TIME 16 8786
TEST_TIME 17 9442
TEST_TIME 18 9781
TEST_TIME 19 10566
TEST_TIME 20 10532
TEST_TIME 21 10757
TEST_TIME 22 10564
TEST_TIME 23 10556
TEST_TIME 24 10663
TEST_TIME 25 10645
TEST_TIME 26 10399
TEST_TIME 27 10627
TEST_TIME 28 10672
TEST_TIME 29 10650
TEST_TIME 30 10642
TEST_TIME 31 10582
TEST_TIME 32 10793
TEST_TIME 33 10498
TEST_TIME 34 10234
TEST_TIME 35 10286
TEST_TIME 36 10149
TEST_TIME 37 10280
TEST_TIME 38 10174
TEST_TIME 39 10086
TEST_TIME 40 10132
TEST_TIME 41 10065
TEST_TIME 42 9869
TEST_TIME 43 9883
TEST_TIME 44 10108
TEST_TIME 45 10026
TEST_TIME 46 9887
TEST_TIME 47 9889
TEST_TIME 48 9916
TEST_TIME 49 9870
TEST_TIME 50 9907
TEST_TIME 51 9894
TEST_TIME 52 9868
TEST_TIME 53 9983
TEST_TIME 54 10015
TEST_TIME 55 10076
TEST_TIME 56 10011
TEST_TIME 57 10126
TEST_TIME 58 10120
TEST_TIME 59 10145
TEST_TIME 60 10184
TEST_TIME 61 10174
TEST_TIME 62 10047
TEST_TIME 63 10050
TEST_TIME 64 9961
TEST_TIME 65 9922
TEST_TIME 66 9832
TEST_TIME 67 9783
TEST_TIME 68 9724
TEST_TIME 69 9734
TEST_TIME 70 9647
TEST_TIME 71 9689
TEST_TIME 72 9663
TEST_TIME 73 9662
TEST_TIME 74 9714
TEST_TIME 75 9685
TEST_TIME 76 9776
TEST_TIME 77 9827
TEST_TIME 78 9856
TEST_TIME 79 9818
TEST_TIME 80 9826
TEST_TIME 81 9846
TEST_TIME 82 9901
TEST_TIME 83 9860
TEST_TIME 84 9790
TEST_TIME 85 9725
TEST_TIME 86 9667
TEST_TIME 87 9672
TEST_TIME 88 9625
TEST_TIME 89 9570
TEST_TIME 90 9523
TEST_TIME 91 9585
TEST_TIME 92 9608
TEST_TIME 93 9601
TEST_TIME 94 9573
TEST_TIME 95 9562
TEST_TIME 96 9613
TEST_TIME 97 9631
TEST_TIME 98 9648
TEST_TIME 99 9707
TEST_TIME 100 9716
TEST_TIME 101 9828
TEST_TIME 102 9781
TEST_TIME 103 9785
TEST_TIME 104 9931
TEST_TIME 105 9923
TEST_TIME 106 9863
TEST_TIME 107 9798
TEST_TIME 108 9763
TEST_TIME 109 9713
TEST_TIME 110 9679
TEST_TIME 111 9666
TEST_TIME 112 9620
TEST_TIME 113 9584
TEST_TIME 114 9590
TEST_TIME 115 9600
TEST_TIME 116 9575
TEST_TIME 117 9518
TEST_TIME 118 9507
TEST_TIME 119 9505
TEST_TIME 120 9470

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2020 22:28:37 to 18-10-2020 22:28:40.

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 1580358 1580358 0 0.00 0 31967 5 0 0
doc_list_biud 1580357 1580357 0 0.00 0 43810 17 0 0
sp_add_invoice_to_stock 5458 5307 151 2.76 19 156846 15353 0 151
sp_cancel_adding_invoice 5826 5826 0 0.00 5 61696 644 0 0
sp_cancel_client_order 1853 1853 0 0.00 4 11414 260 0 0
sp_cancel_customer_reserve 567199 567199 0 0.00 1 27151 37 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 4122 77 0 0
sp_cancel_pay_to_supplier 468 468 0 0.00 2 3683 64 0 0
sp_cancel_supplier_invoice 6480 6464 16 0.24 2 37405 510 0 16
sp_cancel_supplier_order 410 410 0 0.00 9 622734 64791 0 0
sp_cancel_write_off 11396 11396 0 0.00 1 10155 84 0 0
sp_client_order 21424 21424 0 0.00 3 19086 303 0 0
sp_customer_reserve 478292 478292 0 0.00 0 54778 157 0 0
sp_fill_shopping_cart 32762 32762 0 0.00 1 13936 33 0 0
sp_fill_shopping_cart_clo_res 478289 478289 0 0.00 0 12215 11 0 0
sp_get_clo_for_invoice 5303 5303 0 0.00 0 22627 256 0 0
sp_kill_cost_storno 18412 18412 0 0.00 0 6832 38 0 0
sp_kill_qstorno_ret_qs2qd 1152668 1152668 0 0.00 0 37374 20 0 0
sp_kill_qty_storno 608903 608903 0 0.00 0 43808 44 0 0
sp_lock_selected_doc 21845 21845 0 0.00 0 6611 4 0 0
sp_make_cost_storno 29388 29388 0 0.00 0 30470 49 0 0
sp_multiply_rows_for_pdistr 29388 29388 0 0.00 0 7967 26 0 0
sp_multiply_rows_for_qdistr 21424 21424 0 0.00 0 14719 129 0 0
sp_pay_from_customer 7400 7400 0 0.00 4 16377 111 0 0
sp_pay_to_supplier 6233 6233 0 0.00 4 9443 125 0 0
sp_payment_common 13633 13633 0 0.00 1 16370 38 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 0 11407 230 0 0
sp_qd_handle_on_invoice_upd_sts 11129 11129 0 0.00 0 43806 256 0 0
sp_qd_handle_on_reserve_upd_sts 10452 10452 0 0.00 0 9967 44 0 0
sp_reserve_write_off 10452 10452 0 0.00 6 14212 153 0 0
sp_supplier_invoice 5657 5657 0 0.00 6 77444 2869 0 0
sp_supplier_order 5681 5681 0 0.00 6 29883 1136 0 0
srv_aggregate_perf_data 112 111 1 0.89 2884 116825 14425 0 1
srv_fill_mon_cache_memory 60 60 0 0.00 1829 140290 53227 0 0
srv_find_qd_qs_mism 1087220 1087220 0 0.00 0 31966 5 0 0
srv_make_invnt_saldo 622 622 0 0.00 42 45433 1087 0 0
srv_make_money_saldo 764 764 0 0.00 1 4046 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 231268 442142 334435 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 12002 120 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 8986 113 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 1710 7 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 2035 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 382 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 468 468 0 0.00 0 5036 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 529 5 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 12863 1039 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 3672 11 0 0
t$perf-norm:sp_client_order 21424 21424 0 0.00 0 19601 14 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 4297 9 0 0
t$perf-norm:sp_pay_from_customer 7400 7400 0 0.00 0 11570 10 0 0
t$perf-norm:sp_pay_to_supplier 6233 6233 0 0.00 0 4499 6 0 0
t$perf-norm:sp_reserve_write_off 10452 10452 0 0.00 0 24402 11 0 0
t$perf-norm:sp_supplier_invoice 5657 5657 0 0.00 0 5463 13 0 0
t$perf-norm:sp_supplier_order 5681 5681 0 0.00 0 3754 10 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 390 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 97 4 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 1122 15 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 2245 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 113 33 0 0
v_add_invoice_to_stock 10908 10606 302 2.76 0 7110 18 0 302
v_all_customers 38798 38798 0 0.00 0 28 0 0 0
v_all_suppliers 31344 31344 0 0.00 0 18 1 0 0
v_all_wares 42848 42848 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 1373 7 0 0
v_cancel_client_order 3706 3706 0 0.00 0 7116 6 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 2565 6 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1858 6 0 0
v_cancel_payment_to_supplier 936 936 0 0.00 0 2161 7 0 0
v_cancel_supplier_invoice 906 874 32 3.53 0 727 14 0 32
v_cancel_supplier_order 820 820 0 0.00 0 1198 4 0 0
v_cancel_write_off 2082 2082 0 0.00 0 2866 14 0 0
v_max_id_clo_ord 5681 5681 0 0.00 0 408 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 2733 14 0 0
v_max_id_ord_sup 5657 5657 0 0.00 0 4918 4 0 0
v_max_non_paid_invoice 1899 1899 0 0.00 0 4013 4 0 0
v_max_non_paid_realizn 4087 4087 0 0.00 0 3314 4 0 0
v_min_id_clo_ord 5681 5681 0 0.00 0 4755 7 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 2998 7 0 0
v_min_id_ord_sup 5657 5657 0 0.00 0 3185 5 0 0
v_min_non_paid_invoice 6233 6233 0 0.00 0 9357 31 0 0
v_min_non_paid_realizn 7400 7400 0 0.00 0 8000 22 0 0
v_our_firm 10676 10676 0 0.00 0 14 1 0 0
v_reserve_write_off 20904 20904 0 0.00 0 2741 3 0 0
x_lock_depdocs_on_canc_invoice 11853 11853 0 0.00 0 20693 114 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 7796 294 0 0
x_make_qstorno_1000_1200 5681 5681 0 0.00 1 29875 1065 0 0
x_make_qstorno_1200_2000 5657 5657 0 0.00 3 77356 2758 0 0
x_make_qstorno_2100_3300 478292 478292 0 0.00 0 54776 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2020 22:28:41 to 18-10-2020 22:28:45.

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
19:57:00 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
19:58:01 24724 8192000000 10340735616 10658922496 1394822384 14.549 100 87 174 0 1428945200 5533696 28589120
19:59:30 6961 8192000000 10410664576 10736709632 1467255104 15.190 100 92 184 0 1504613936 7778368 29580464
20:00:39 16148 8192000000 10446812784 10789617664 1506239856 15.531 100 83 166 2 1541298832 8670208 26388768
20:01:59 11097 8192000000 10536206240 10892292096 1588124720 16.238 100 72 143 0 1619138192 10134448 20879024
20:03:15 8577 8192000000 10643980880 11014934528 1700805024 17.192 100 77 154 2 1733605792 11019936 21780832
20:04:27 4638 8192000000 10705658608 11087024128 1769901632 17.766 100 80 161 3 1806878736 14650160 22326944
20:05:34 3543 8192000000 10738859536 11130994688 1807944880 18.079 100 73 147 0 1841179072 12953440 20280752
20:06:40 1213 8192000000 10800819792 11187716096 1857543440 18.483 100 95 191 1 1899109600 14602720 26963440
20:07:42 7320 8192000000 10775435280 11200413696 1870241008 18.586 100 38 75 0 1887435280 6930608 10263664
20:08:52 8312 8192000000 10786851136 11215306752 1873590768 18.613 100 27 54 1 1888296080 6883632 7821680
20:10:00 7387 8192000000 10791263312 11223752704 1880326752 18.668 100 33 66 3 1901150416 11224480 9599184
20:11:10 6496 8192000000 10802522720 11237597184 1889222048 18.740 100 25 48 1 1903400608 6845536 7333024
20:12:17 53981 8192000000 10846450288 11267792896 1903340112 18.853 100 38 76 3 1920044960 5337536 11367312
20:14:14 8579 8192000000 10820840624 11254484992 1903563600 18.855 100 30 60 3 1917904896 5812800 8528496
20:15:25 7281 8192000000 10790419360 11230674944 1875073360 18.625 99 22 45 1 1887636832 5928208 6635264
20:16:34 11196 8192000000 10738991808 11166339072 1780821824 17.856 96 16 32 1 1790582816 5109984 4651008
20:17:46 35146 8192000000 10735756960 11156901888 1721667568 17.366 93 22 43 1 1733089360 5321184 6100608
20:19:23 5605 8192000000 10698511072 11104718848 1761926432 17.700 99 35 69 2 1778306320 5449440 10930448
20:20:30 8201 8192000000 10721311696 11137331200 1773814032 17.798 98 22 44 2 1785702736 4957744 6930960
20:21:39 21358 8192000000 10745038848 11173502976 1834436160 18.295 100 22 44 0 1845161120 4879808 5845152
20:23:38 8376 8192000000 10757941184 11189854208 1850548736 18.427 100 26 52 1 1863349776 5575776 7225264
20:24:47 6075 8192000000 10767463120 11197820928 1860437424 18.507 100 23 46 2 1872606832 5556432 6612976
20:25:54 21443 8192000000 10782998064 11217682432 1870215520 18.586 100 15 30 0 1880363744 5541984 4606240
20:27:20 26204 8192000000 10835813632 11255480320 1894516448 18.782 99 41 82 1 1912852368 6816704 11519216
20:28:49 81382 8192000000 10856526528 11299815424 1930113648 19.068 100 30 58 0 1946930928 8223664 8593616
20:31:15 8812 8192000000 10891085328 11318493184 1953392128 19.253 100 54 108 0 1978959936 10119792 15448016
20:32:28 4252 8192000000 10888425744 11321454592 1957069472 19.283 100 59 117 0 1983777568 10144640 16563456
20:33:36 13694 8192000000 10893035568 11320913920 1950479776 19.230 100 57 112 1 1982786992 15404448 16902768
20:34:54 2749 8192000000 10914546608 11335196672 1952127360 19.243 99 60 121 0 1988060816 18980816 16952640
20:35:59 4083 8192000000 10890310624 11309506560 1932508432 19.087 99 59 119 2 1969571024 19119488 17943104
20:37:07 1828 8192000000 10903212384 11309293568 1942407072 19.166 100 77 154 2 1983943072 19419328 22116672
20:38:15 10111 8192000000 10848544160 11274039296 1893178688 18.771 99 51 99 1 1930647952 22420704 15048560
20:39:34 14906 8192000000 10868948816 11296321536 1904710144 18.864 99 42 84 0 1944648144 26986144 12951856
20:40:52 3586 8192000000 10903837152 11316994048 1933215248 19.093 100 70 139 1 1986533056 32476096 20841712
20:42:01 6496 8192000000 10897570800 11323867136 1934464512 19.103 100 42 86 0 1974961088 26745232 13751344
20:43:15 14325 8192000000 10891441232 11334225920 1938535520 19.135 100 26 52 2 1978631296 31315968 8779808
20:44:34 24938 8192000000 10886362320 11341500416 1944616928 19.184 100 27 54 3 1984336512 30984672 8734912
20:46:03 33649 8192000000 10846387008 11288084480 1912077088 18.923 100 23 46 3 1938280256 19098288 7104880
20:47:38 27584 8192000000 10837179296 11291021312 1895760448 18.792 99 17 34 2 1915658208 15243200 4654560
20:49:10 27540 8192000000 10857376736 11302318080 1920374432 18.990 100 14 27 3 1941910144 17167760 4367952
20:50:38 56214 8192000000 10854776064 11304259584 1913266992 18.933 99 25 49 3 1928199920 7832048 7100880
20:52:44 31023 8192000000 10859647280 11315949568 1904356624 18.861 98 19 38 0 1921456672 11005424 6094624
20:54:26 68397 8192000000 10901604304 11339333632 1931541680 19.079 99 15 30 0 1947455824 11566880 4347264
20:56:40 70819 8192000000 10925336560 11377377280 1971777120 19.400 100 17 34 1 1985602672 8865152 4960400
20:58:52 97591 8192000000 10929262688 11378151424 1993755712 19.573 100 21 42 0 2009895664 9907824 6232128
21:01:38 32288 8192000000 10920604288 11374264320 1958034144 19.290 98 20 40 1 1968954672 5638080 5282448
21:03:11 60620 8192000000 10899936816 11377827840 1969823840 19.384 99 17 34 0 1990878096 16218336 4835920
21:05:32 50462 8192000000 10917920000 11390902272 1992955392 19.567 100 11 22 1 2007135200 11447424 2732384
21:07:23 54117 8192000000 10926454144 11387011072 1968063568 19.370 98 18 36 1 1987128208 13681776 5382864
21:09:17 63231 8192000000 10918966256 11387256832 1979144272 19.458 99 24 46 1 2010382240 24092784 7145184
21:11:36 66930 8192000000 10920000800 11380502528 1983391392 19.492 100 30 59 1 2003306144 11891136 8023616
21:14:05 60413 8192000000 10884543200 11339415552 1866056816 18.552 94 8 16 0 1884363360 15969824 2336720
21:16:19 43093 8192000000 10854359904 11292557312 1815498016 18.141 93 22 44 1 1831515584 9060448 6957120
21:18:04 30534 8192000000 10881425856 11330174976 1908301408 18.893 98 37 74 0 1933024224 14420240 10302576
21:19:36 89365 8192000000 10825806928 11302060032 1786421888 17.902 92 20 39 2 1812749744 20484624 5843232
21:22:06 21106 8192000000 10850018384 11289300992 1870298256 18.587 98 43 86 2 1892688544 9920192 12470096
21:23:33 57038 8192000000 10831529536 11284951040 1809212240 18.089 95 27 54 1 1832533008 15763248 7557520
21:25:31 133234 8192000000 10828086864 11282132992 1746763776 17.575 92 13 26 2 1769592720 19036880 3792064
21:28:57 90123 8192000000 10844917312 11301040128 1882480832 18.685 98 36 72 1 1902908512 11117744 9309936
21:31:31 62344 8192000000 10829111024 11295789056 1897854000 18.809 99 7 14 0 1906627840 6758464 2015376
21:33:39 55075 8192000000 10855146320 11334905856 1937552672 19.127 100 4 8 0 1951565584 12954000 1058912
21:35:41 77124 8192000000 10873883680 11362570240 1932063680 19.083 99 8 16 1 1953495776 19063920 2368176
21:37:58 70157 8192000000 10880375344 11353776128 1945946608 19.194 99 10 20 0 1962588224 13887296 2754320
21:40:21 53424 8192000000 10893742608 11377319936 1948275072 19.213 99 18 37 0 1965784320 12313136 5196112
21:42:50 59663 8192000000 10906714128 11375210496 1970731344 19.391 100 14 28 1 1986300528 12025456 3543728
21:44:50 124808 8192000000 10915117984 11390263296 1944181120 19.180 98 19 38 0 1964431808 15132944 5117744
21:47:55 111831 8192000000 10910000832 11385516032 1960511840 19.310 99 7 14 1 1977114480 14905840 1696800
21:50:53 97811 8192000000 10921212336 11391127552 1967783664 19.368 99 32 64 1 1985360096 8514256 9062176
21:53:32 39316 8192000000 10905386640 11392483328 1973571104 19.414 99 17 34 1 1990133008 11498336 5063568
21:55:44 42931 8192000000 10908753808 11389034496 1962652528 19.327 99 10 20 1 1981761808 16363472 2745808
21:58:25 121833 8192000000 10896294224 11376435200 1951742352 19.240 98 8 16 0 1976226592 22475072 2009168
22:01:27 28773 8192000000 10874672720 11336622080 1879312160 18.660 96 22 44 0 1894125504 8944832 5868512
22:02:56 57835 8192000000 10851906592 11324153856 1864711440 18.541 96 15 30 1 1879862400 10923536 4227424
22:04:56 93978 8192000000 10878586992 11332583424 1872016176 18.601 96 18 36 1 1901793712 24071680 5705856
22:07:39 85651 8192000000 10852741504 11307061248 1801455904 18.026 93 29 57 1 1824527744 15032768 8039072
22:10:05 32764 8192000000 10833530560 11300663296 1806682096 18.069 94 31 62 1 1832924272 17366352 8875824
22:11:53 140290 8192000000 10813580128 11273543680 1872899856 18.608 98 17 34 1 1887186304 9853392 4433056
22:15:23 35812 8192000000 10819891984 11294380032 1884733600 18.703 98 6 12 0 1895962432 8803248 2425584
22:16:59 58867 8192000000 10841974528 11312578560 1888096064 18.730 98 13 25 1 1903134624 11522384 3516176
22:19:17 75864 8192000000 10851773040 11324719104 1892433552 18.765 98 16 32 2 1907874224 10850864 4589808
22:21:47 88372 8192000000 10830499792 11308101632 1852808992 18.445 96 14 28 0 1866261152 9477040 3975120
22:24:19 89525 8192000000 10841566496 11316359168 1883265488 18.691 98 19 38 2 1898649952 10234992 5149472
22:26:56 6717 8192000000 9090728208 9310679040 256842832 3.039 14 2 3 0 263048096 5784912 420352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 18-10-2020 22:28:47 to 18-10-2020 22:29:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 151 v_add_invoice_to_stock 335544517
user_exc 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2020 22:29:02 to 18-10-2020 22:29:04.

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          253933
MON$OLDEST_ACTIVE               253934
MON$OLDEST_SNAPSHOT             253934
MON$NEXT_TRANSACTION            253934
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                       2045088
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.33375 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33375 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33375 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33375 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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Sun Oct 18 22:27:04 2020
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sun Oct 18 22:27:06 2020
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
--- end of diff output ---

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

18.10.20 22:29:05. 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-18 22:29:08 No severe PSQL-related problems occured
18.10.20 22:29:09. End of report.