Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2020 16:31
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 17331012 kB
MemAvailable 31553692 kB
Buffers 0 kB
Cached 14324660 kB
SwapCached 5432 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-17 16:30:37
DTS_END                         2020-10-17 16:30:37.7140
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/38046
TRN_ID                          251804
ATT_ID                          720
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: 9928.33 16531 1191399
customer order: creation 184.36 337 22123
customer order: refuse 15.70 222 1884
order to supplier: creation 50.02 1100 6002
order to supplier: removal 3.73 78230 448
invoice (draft): creation 48.48 2943 5817
invoice (draft): removal 57.74 597 6929
invoice accept: apply 44.38 15194 5325
invoice accept: cancel 52.32 671 6278
customer reserve: creation 4035.66 154 484279
customer reserve: removal 5109.73 45 613167
realization accept: apply 89.99 136 10799
realization accept: cancel 98.76 84 11851
payment from customer: creation 63.43 95 7612
payment from customer: removal 5.74 45 689
payment to supplier: creation 52.58 97 6309
payment to supplier: removal 3.68 42 441
service: total inventory turnovers 4.48 1127 538
service: total monetary turnovers 6.62 26 794
service: aggregate perf. data 0.92 18030 110
service: refresh index statistics 0.03 211445 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-10-2020 16:31:28 to 17-10-2020 16:31:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2719
TEST_TIME 2 3403
TEST_TIME 3 4490
TEST_TIME 4 6540
TEST_TIME 5 6806
TEST_TIME 6 7078
TEST_TIME 7 7106
TEST_TIME 8 7356
TEST_TIME 9 6981
TEST_TIME 10 7083
TEST_TIME 11 7392
TEST_TIME 12 7499
TEST_TIME 13 8044
TEST_TIME 14 8382
TEST_TIME 15 8561
TEST_TIME 16 9218
TEST_TIME 17 9034
TEST_TIME 18 9426
TEST_TIME 19 9595
TEST_TIME 20 9391
TEST_TIME 21 9767
TEST_TIME 22 10114
TEST_TIME 23 10394
TEST_TIME 24 10111
TEST_TIME 25 10754
TEST_TIME 26 11777
TEST_TIME 27 11851
TEST_TIME 28 11663
TEST_TIME 29 11560
TEST_TIME 30 11360
TEST_TIME 31 11322
TEST_TIME 32 11280
TEST_TIME 33 11334
TEST_TIME 34 11366
TEST_TIME 35 11099
TEST_TIME 36 11029
TEST_TIME 37 10982
TEST_TIME 38 10814
TEST_TIME 39 10839
TEST_TIME 40 10793
TEST_TIME 41 10792
TEST_TIME 42 10846
TEST_TIME 43 10739
TEST_TIME 44 10709
TEST_TIME 45 10717
TEST_TIME 46 10657
TEST_TIME 47 10466
TEST_TIME 48 10636
TEST_TIME 49 10548
TEST_TIME 50 10518
TEST_TIME 51 10505
TEST_TIME 52 10512
TEST_TIME 53 10535
TEST_TIME 54 10641
TEST_TIME 55 10521
TEST_TIME 56 10509
TEST_TIME 57 10570
TEST_TIME 58 10612
TEST_TIME 59 10649
TEST_TIME 60 10617
TEST_TIME 61 10698
TEST_TIME 62 10600
TEST_TIME 63 10436
TEST_TIME 64 10436
TEST_TIME 65 10381
TEST_TIME 66 10294
TEST_TIME 67 10286
TEST_TIME 68 10237
TEST_TIME 69 10168
TEST_TIME 70 10150
TEST_TIME 71 10208
TEST_TIME 72 10308
TEST_TIME 73 10325
TEST_TIME 74 10270
TEST_TIME 75 10263
TEST_TIME 76 10280
TEST_TIME 77 10307
TEST_TIME 78 10324
TEST_TIME 79 10320
TEST_TIME 80 10320
TEST_TIME 81 10222
TEST_TIME 82 10204
TEST_TIME 83 10124
TEST_TIME 84 10115
TEST_TIME 85 10081
TEST_TIME 86 10045
TEST_TIME 87 10032
TEST_TIME 88 9941
TEST_TIME 89 9965
TEST_TIME 90 9914
TEST_TIME 91 9941
TEST_TIME 92 9915
TEST_TIME 93 9930
TEST_TIME 94 9952
TEST_TIME 95 10046
TEST_TIME 96 10014
TEST_TIME 97 10018
TEST_TIME 98 10021
TEST_TIME 99 10012
TEST_TIME 100 10108
TEST_TIME 101 10102
TEST_TIME 102 10112
TEST_TIME 103 10084
TEST_TIME 104 10004
TEST_TIME 105 9966
TEST_TIME 106 9944
TEST_TIME 107 9938
TEST_TIME 108 9891
TEST_TIME 109 9882
TEST_TIME 110 9833
TEST_TIME 111 9880
TEST_TIME 112 9868
TEST_TIME 113 9858
TEST_TIME 114 9860
TEST_TIME 115 9876
TEST_TIME 116 9870
TEST_TIME 117 9922
TEST_TIME 118 9936
TEST_TIME 119 9906
TEST_TIME 120 9929

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2020 16:31:32 to 17-10-2020 16:31:35.

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 1642228 1642228 0 0.00 0 19795 5 0 0
doc_list_biud 1642227 1642227 0 0.00 0 47553 22 0 0
sp_add_invoice_to_stock 5448 5325 123 2.25 33 183401 15195 0 123
sp_cancel_adding_invoice 6278 6278 0 0.00 3 59524 671 0 0
sp_cancel_client_order 1884 1884 0 0.00 4 11649 223 0 0
sp_cancel_customer_reserve 613167 613167 0 0.00 1 47556 46 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 1029 46 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 1874 43 0 0
sp_cancel_supplier_invoice 6939 6929 10 0.14 1 12650 597 0 10
sp_cancel_supplier_order 448 448 0 0.00 5 763155 78231 0 0
sp_cancel_write_off 11851 11851 0 0.00 1 7922 84 0 0
sp_client_order 22123 22123 0 0.00 3 12008 337 0 0
sp_customer_reserve 484279 484279 0 0.00 0 59437 155 0 0
sp_fill_shopping_cart 33920 33920 0 0.00 1 22618 36 0 0
sp_fill_shopping_cart_clo_res 484278 484278 0 0.00 0 16065 12 0 0
sp_get_clo_for_invoice 5324 5324 0 0.00 0 25042 298 0 0
sp_kill_cost_storno 19259 19259 0 0.00 0 7269 38 0 0
sp_kill_qstorno_ret_qs2qd 1245562 1245562 0 0.00 0 44588 25 0 0
sp_kill_qty_storno 656679 656679 0 0.00 0 47553 53 0 0
sp_lock_selected_doc 22251 22251 0 0.00 0 8625 4 0 0
sp_make_cost_storno 30044 30044 0 0.00 0 19787 40 0 0
sp_multiply_rows_for_pdistr 30043 30043 0 0.00 0 6614 18 0 0
sp_multiply_rows_for_qdistr 22123 22123 0 0.00 0 12001 169 0 0
sp_pay_from_customer 7612 7612 0 0.00 4 15916 95 0 0
sp_pay_to_supplier 6309 6309 0 0.00 5 19815 97 0 0
sp_payment_common 13921 13921 0 0.00 1 15895 26 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 11642 206 0 0
sp_qd_handle_on_invoice_upd_sts 11601 11601 0 0.00 0 18465 264 0 0
sp_qd_handle_on_reserve_upd_sts 10799 10799 0 0.00 0 11991 42 0 0
sp_reserve_write_off 10799 10799 0 0.00 6 13109 136 0 0
sp_supplier_invoice 5817 5817 0 0.00 5 85015 2944 0 0
sp_supplier_order 6002 6002 0 0.00 5 21486 1100 0 0
srv_aggregate_perf_data 111 110 1 0.90 2372 228710 18031 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2180 158083 48194 0 0
srv_find_qd_qs_mism 1141936 1141936 0 0.00 0 7430 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 31 43852 1127 0 0
srv_make_money_saldo 794 794 0 0.00 1 6803 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 108963 390692 211445 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 5325 5325 0 0.00 0 15559 120 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 1585 62 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 5867 15 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 7199 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 3328 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1496 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 18516 59 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 21266 1237 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 1641 6 0 0
t$perf-norm:sp_client_order 22124 22124 0 0.00 0 11887 15 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 4053 8 0 0
t$perf-norm:sp_pay_from_customer 7612 7612 0 0.00 0 17674 14 0 0
t$perf-norm:sp_pay_to_supplier 6310 6310 0 0.00 0 9899 10 0 0
t$perf-norm:sp_reserve_write_off 10799 10799 0 0.00 0 16613 16 0 0
t$perf-norm:sp_supplier_invoice 5817 5817 0 0.00 0 19900 17 0 0
t$perf-norm:sp_supplier_order 6002 6002 0 0.00 0 10172 12 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 635 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 81 2 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 2833 16 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 734 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10892 10646 246 2.25 0 3846 15 0 246
v_all_customers 40266 40266 0 0.00 0 30 0 0 0
v_all_suppliers 32840 32840 0 0.00 0 15 1 0 0
v_all_wares 44246 44246 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 79 5 0 0
v_cancel_client_order 3768 3768 0 0.00 0 1269 3 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1016 6 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 2348 8 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1330 5 0 0
v_cancel_supplier_invoice 902 882 20 2.21 0 212 11 0 20
v_cancel_supplier_order 896 896 0 0.00 0 2703 9 0 0
v_cancel_write_off 2064 2064 0 0.00 0 3302 15 0 0
v_max_id_clo_ord 6002 6002 0 0.00 0 2961 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 1345 11 0 0
v_max_id_ord_sup 5793 5793 0 0.00 0 5330 4 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 3313 5 0 0
v_max_non_paid_realizn 4111 4111 0 0.00 0 854 3 0 0
v_min_id_clo_ord 6002 6002 0 0.00 0 5607 6 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 9587 14 0 0
v_min_id_ord_sup 5793 5793 0 0.00 0 7053 9 0 0
v_min_non_paid_invoice 6309 6309 0 0.00 0 7952 17 0 0
v_min_non_paid_realizn 7612 7612 0 0.00 0 4150 13 0 0
v_our_firm 10982 10982 0 0.00 0 18 1 0 0
v_reserve_write_off 21598 21598 0 0.00 0 5625 7 0 0
x_lock_depdocs_on_canc_invoice 12766 12766 0 0.00 0 11853 96 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 9471 265 0 0
x_make_qstorno_1000_1200 6002 6002 0 0.00 2 21477 1030 0 0
x_make_qstorno_1200_2000 5817 5817 0 0.00 2 78989 2804 0 0
x_make_qstorno_2100_3300 484279 484279 0 0.00 0 59435 141 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2020 16:31:36 to 17-10-2020 16:31:40.

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
14:00:41 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:43 38064 8192000000 10389494800 10709630976 1436931360 14.923 100 92 184 0 1472381296 6176688 29273248
14:03:30 18582 8192000000 10453482624 10789728256 1513316640 15.592 100 88 176 1 1549786368 8604896 27864832
14:04:52 16639 8192000000 10499329488 10846547968 1561229920 16.007 100 86 170 2 1598158128 10580528 26347680
14:06:11 8580 8192000000 10580825904 10940887040 1636857376 16.653 100 75 151 0 1670632160 11734512 22040272
14:07:24 7720 8192000000 10641743296 11015966720 1705895664 17.234 100 82 162 0 1742886592 13581280 23409648
14:08:34 3593 8192000000 10726401296 11101782016 1779282224 17.844 100 83 167 1 1815540816 13357440 22901152
14:09:40 2750 8192000000 10757943056 11141685248 1811754800 18.110 100 81 163 1 1848018416 13948784 22314832
14:10:45 1468 8192000000 10793678560 11183095808 1852930320 18.446 100 81 162 1 1889927920 14539024 22458576
14:11:48 21615 8192000000 10772532288 11195203584 1865026112 18.544 100 47 93 0 1888500048 10474064 12999872
14:13:10 6740 8192000000 10782763696 11205156864 1873688976 18.614 100 25 50 0 1887649808 6947424 7013408
14:14:19 14600 8192000000 10777831712 11215241216 1878271072 18.651 100 16 32 1 1888400224 5411936 4717216
14:15:40 26749 8192000000 10797387200 11238572032 1894385744 18.781 100 31 62 2 1910593712 6273376 9934592
14:17:27 7737 8192000000 10819350960 11249127424 1882107408 18.682 99 31 61 1 1896988912 6395600 8485904
14:18:35 5603 8192000000 10825784448 11253403648 1905387152 18.870 100 18 36 0 1916035888 5113088 5535648
14:19:45 10043 8192000000 10780875424 11204333568 1798329904 18.000 95 23 45 2 1810311440 4914592 7066944
14:20:56 55698 8192000000 10730082736 11155902464 1696964960 17.160 92 22 45 2 1708488480 4999808 6523712
14:22:53 4994 8192000000 10704924416 11124375552 1769810560 17.765 98 30 62 1 1789224176 10034992 9378624
14:24:00 4818 8192000000 10734715968 11147579392 1786429120 17.902 98 21 43 1 1798613616 5864512 6319984
14:25:13 25437 8192000000 10747431440 11174674432 1820822192 18.184 99 27 54 1 1833851568 5058400 7970976
14:26:53 6508 8192000000 10772515184 11198095360 1854884592 18.462 100 34 68 3 1869898480 5948640 9065248
14:28:02 8979 8192000000 10782223280 11210743808 1864893152 18.543 100 17 34 0 1875143360 5604784 4645424
14:29:15 9846 8192000000 10787655120 11226329088 1879365920 18.660 100 19 39 1 1890816992 5655664 5795408
14:30:27 67174 8192000000 10853614416 11275935744 1917908864 18.970 100 35 70 0 1932531728 4771456 9851408
14:32:44 29544 8192000000 10868785584 11305590784 1943140480 19.172 100 43 83 0 1964972256 9947424 11884352
14:34:24 6046 8192000000 10902063104 11341099008 1965187280 19.347 100 40 78 1 1991944800 15209776 11547744
14:35:33 11844 8192000000 10890019056 11315212288 1934018208 19.099 98 62 122 1 1965068064 13849200 17200656
14:36:48 8323 8192000000 10901734480 11336376320 1967413008 19.365 100 48 96 0 1994364032 12879440 14071584
14:38:28 2420 8192000000 10923521104 11356741632 1973217536 19.411 99 55 109 0 2003607792 13790112 16600144
14:39:33 12632 8192000000 10914834256 11353219072 1973212032 19.411 100 38 76 1 2005583024 20773760 11597232
14:40:49 6131 8192000000 10944572544 11376644096 1987418272 19.523 100 49 97 1 2025619424 23513088 14688064
14:41:59 2180 8192000000 10948836720 11367489536 1985164192 19.506 100 81 158 1 2033320368 24763024 23393152
14:43:04 3661 8192000000 10921613584 11353219072 1964851808 19.345 100 56 111 1 2012445952 30857376 16736768
14:44:10 6259 8192000000 10938567120 11372937216 1966262960 19.356 99 45 89 0 2008651168 28293264 14094944
14:45:24 9933 8192000000 10891658272 11318042624 1904292208 18.861 98 44 87 1 1949034672 30449488 14292976
14:46:37 8105 8192000000 10886438752 11321634816 1927016032 19.043 99 38 76 1 1966526640 27478768 12031840
14:47:52 2470 8192000000 10934982736 11351977984 1938796752 19.137 98 86 171 1 2008654176 43600512 26256912
14:48:56 10805 8192000000 10897304928 11333595136 1919444816 18.982 99 33 66 3 1976428960 45654336 11329808
14:50:15 26930 8192000000 10900708272 11337424896 1914938736 18.946 99 32 64 4 1975571264 49560000 11072528
14:52:07 19015 8192000000 10902282432 11356549120 1932149968 19.084 100 22 44 1 1991050672 51359728 7540976
14:53:26 26598 8192000000 10876108880 11330068480 1917213712 18.965 99 29 57 1 1964292384 37520416 9558256
14:55:12 52912 8192000000 10893438112 11362979840 1887965520 18.729 98 15 30 0 1954830608 61003360 5861728
14:57:20 65924 8192000000 10868671488 11313557504 1906691616 18.880 99 16 33 2 1922246432 11340048 4214768
14:59:31 75567 8192000000 10886648112 11318349824 1885481984 18.709 97 26 52 0 1905279600 13185216 6612400
15:01:49 11655 8192000000 10912088064 11341905920 1955920720 19.274 100 44 88 0 1976111360 6945952 13244688
15:03:03 135582 8192000000 10893673408 11357298688 1945131280 19.188 99 7 14 1 1967166080 19812112 2222688
15:06:25 63040 8192000000 10899540160 11344449536 1910998976 18.915 97 40 80 1 1933435264 9855712 12580576
15:08:28 32620 8192000000 10904641008 11368574976 1970550448 19.390 100 25 50 2 1995026816 17067488 7408880
15:10:07 21075 8192000000 10940477600 11378499584 1984341888 19.499 100 42 83 3 2009027152 12495264 12190000
15:11:33 62070 8192000000 10907684032 11380580352 1971041312 19.394 99 19 38 2 1992266896 15200608 6024976
15:13:35 40114 8192000000 10910143200 11387760640 1992148336 19.561 100 6 12 0 2005233296 11291088 1793872
15:15:16 60014 8192000000 10920863296 11393470464 1973432816 19.413 99 17 35 0 1990092736 11703808 4956112
15:17:21 76839 8192000000 10903767440 11400867840 1972447488 19.405 99 20 39 1 1998970784 20941632 5581664
15:20:15 64654 8192000000 10908067776 11390865408 1947682112 19.208 97 17 34 0 1961443984 8351184 5410688
15:22:20 96513 8192000000 10921058128 11395227648 1959711296 19.304 98 15 30 1 1974938320 10920304 4306720
15:24:57 36791 8192000000 10916636656 11356409856 1903152784 18.852 96 17 34 0 1915729888 7443504 5133600
15:26:36 57927 8192000000 10864629552 11324424192 1848738672 18.412 94 28 54 1 1868470928 12096528 7635728
15:28:34 50650 8192000000 10883490016 11356020736 1942685200 19.168 99 27 54 0 1962636400 12459808 7491392
15:30:27 16891 8192000000 10879716928 11325829120 1912452240 18.926 98 32 64 3 1929498704 8087632 8958832
15:31:47 158082 8192000000 10822465200 11282796544 1810257456 18.098 93 11 22 0 1826153424 13259152 2636816
15:35:28 55492 8192000000 10845604544 11318140928 1886034992 18.714 98 8 16 1 1899989424 12170736 1783696
15:37:24 64186 8192000000 10854373568 11330482176 1882551616 18.686 97 10 20 0 1894138880 8739184 2848080
15:39:28 64965 8192000000 10885529728 11348631552 1878929632 18.656 96 37 74 0 1905318272 15529632 10859008
15:41:50 69184 8192000000 10857567120 11332796416 1911615504 18.920 99 17 34 0 1931802800 15596864 4590432
15:44:06 28500 8192000000 10862424576 11321733120 1914353744 18.942 99 33 66 1 1931005504 7335472 9316288
15:45:38 32096 8192000000 10893125824 11347513344 1943303120 19.173 100 28 56 1 1964796544 12921408 8572016
15:47:12 29362 8192000000 10907965296 11352924160 1952582672 19.247 100 38 75 3 1974684160 11407472 10694016
15:48:44 53294 8192000000 10887041792 11352215552 1910295472 18.909 97 21 42 4 1925560256 9313312 5951472
15:50:48 67029 8192000000 10874682016 11336835072 1931141872 19.076 99 14 28 2 1949600816 14966832 3492112
15:53:14 43046 8192000000 10883592752 11358560256 1946522528 19.199 100 14 28 0 1965704368 15026416 4155424
15:55:00 89121 8192000000 10889391744 11368103936 1942569584 19.167 99 12 25 0 1958276768 12095920 3611264
15:57:30 73416 8192000000 10893932016 11369959424 1965902384 19.353 100 12 24 1 1979660080 10165504 3592192
15:59:45 62818 8192000000 10912646880 11371565056 1928344768 19.054 97 11 20 2 1937123152 5912048 2866336
16:01:50 76318 8192000000 10910460240 11387183104 1978283456 19.451 99 10 20 0 1994528192 13831616 2413120
16:04:06 60194 8192000000 10918769360 11408261120 1962822192 19.328 98 25 50 0 1987814928 18059504 6933232
16:06:12 38221 8192000000 10922688160 11373596672 1959070688 19.299 99 22 44 2 1973445440 8129200 6245552
16:07:53 23298 8192000000 10921630432 11376365568 1963884352 19.337 99 36 72 1 1984098848 10228272 9986224
16:09:19 39044 8192000000 10929108448 11384233984 1946159632 19.196 98 40 79 1 1970404096 13275568 10968896
16:11:13 106390 8192000000 10861583008 11314929664 1827818336 18.242 93 18 35 0 1841968832 9210528 4939968
16:14:02 98513 8192000000 10862695680 11332038656 1926790736 19.041 99 29 58 2 1944627472 9471584 8365152
16:16:41 79096 8192000000 10864649824 11345915904 1918953680 18.978 98 8 17 0 1932345216 10759328 2632208
16:19:06 77252 8192000000 10851974176 11329118208 1851575264 18.435 95 16 32 0 1872530752 16227248 4728240
16:21:27 86530 8192000000 10870380848 11345440768 1903700816 18.856 98 12 24 1 1918548240 11188432 3658992
16:23:57 75619 8192000000 10866290576 11328360448 1888809856 18.736 97 19 38 1 1910687056 16611232 5265968
16:26:18 53217 8192000000 10903826096 11360423936 1953713920 19.256 100 29 58 2 1974360320 12652400 7994000
16:28:12 58099 8192000000 10888702032 11364679680 1923589296 19.016 98 16 32 1 1945754480 17567664 4597520
16:30:10 41050 8192000000 9126108288 9356693504 286923024 3.383 15 4 8 0 294445888 6752128 770736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2020 16:31:42 to 17-10-2020 16:31:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 123 v_add_invoice_to_stock 335544517
user_exc 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2020 16:31:58 to 17-10-2020 16:32:00.

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          253929
MON$OLDEST_ACTIVE               253930
MON$OLDEST_SNAPSHOT             253930
MON$NEXT_TRANSACTION            253930
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                       2043424
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 ---
--- end of diff output ---

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

17.10.20 16:32:01. 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-17 16:32:03 No severe PSQL-related problems occured
17.10.20 16:32:04. End of report.