Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.10.2020 16: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 17561884 kB
MemAvailable 31557536 kB
Buffers 0 kB
Cached 14095612 kB
SwapCached 5440 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-15 16:26:57
DTS_END                         2020-10-15 16:26:57.0650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/34256
TRN_ID                          252444
ATT_ID                          731
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: 9755.03 22607 1170603
customer order: creation 182.39 353 21887
customer order: refuse 16.13 273 1935
order to supplier: creation 50.30 1157 6036
order to supplier: removal 3.78 72765 454
invoice (draft): creation 48.52 2899 5822
invoice (draft): removal 56.36 623 6763
invoice accept: apply 44.37 15225 5324
invoice accept: cancel 50.88 673 6106
customer reserve: creation 4041.60 152 484992
customer reserve: removal 4944.56 44 593347
realization accept: apply 89.07 152 10688
realization accept: cancel 89.51 88 10741
payment from customer: creation 63.96 98 7675
payment from customer: removal 6.37 52 764
payment to supplier: creation 51.09 114 6131
payment to supplier: removal 3.83 46 460
service: total inventory turnovers 4.80 1023 576
service: total monetary turnovers 6.45 22 774
service: aggregate perf. data 1.03 12019 124
service: refresh index statistics 0.03 344367 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1489
TEST_TIME 2 2107
TEST_TIME 3 3303
TEST_TIME 4 3938
TEST_TIME 5 4022
TEST_TIME 6 4536
TEST_TIME 7 5058
TEST_TIME 8 5736
TEST_TIME 9 5910
TEST_TIME 10 6181
TEST_TIME 11 6796
TEST_TIME 12 7112
TEST_TIME 13 8204
TEST_TIME 14 8707
TEST_TIME 15 8885
TEST_TIME 16 9128
TEST_TIME 17 9922
TEST_TIME 18 10514
TEST_TIME 19 11724
TEST_TIME 20 11854
TEST_TIME 21 11864
TEST_TIME 22 12101
TEST_TIME 23 11834
TEST_TIME 24 11866
TEST_TIME 25 11657
TEST_TIME 26 11310
TEST_TIME 27 11212
TEST_TIME 28 11287
TEST_TIME 29 11085
TEST_TIME 30 10955
TEST_TIME 31 11056
TEST_TIME 32 10836
TEST_TIME 33 10847
TEST_TIME 34 10746
TEST_TIME 35 10601
TEST_TIME 36 10635
TEST_TIME 37 10608
TEST_TIME 38 10713
TEST_TIME 39 10609
TEST_TIME 40 10747
TEST_TIME 41 10707
TEST_TIME 42 10753
TEST_TIME 43 10642
TEST_TIME 44 10707
TEST_TIME 45 10753
TEST_TIME 46 10865
TEST_TIME 47 10873
TEST_TIME 48 10822
TEST_TIME 49 10702
TEST_TIME 50 10645
TEST_TIME 51 10486
TEST_TIME 52 10425
TEST_TIME 53 10331
TEST_TIME 54 10258
TEST_TIME 55 10157
TEST_TIME 56 10184
TEST_TIME 57 10283
TEST_TIME 58 10179
TEST_TIME 59 10192
TEST_TIME 60 10284
TEST_TIME 61 10265
TEST_TIME 62 10296
TEST_TIME 63 10317
TEST_TIME 64 10382
TEST_TIME 65 10356
TEST_TIME 66 10434
TEST_TIME 67 10410
TEST_TIME 68 10403
TEST_TIME 69 10384
TEST_TIME 70 10276
TEST_TIME 71 10199
TEST_TIME 72 10243
TEST_TIME 73 10169
TEST_TIME 74 10133
TEST_TIME 75 10052
TEST_TIME 76 10020
TEST_TIME 77 10047
TEST_TIME 78 10058
TEST_TIME 79 10021
TEST_TIME 80 10029
TEST_TIME 81 10037
TEST_TIME 82 10037
TEST_TIME 83 10063
TEST_TIME 84 10043
TEST_TIME 85 10116
TEST_TIME 86 10098
TEST_TIME 87 10054
TEST_TIME 88 10172
TEST_TIME 89 10144
TEST_TIME 90 10153
TEST_TIME 91 10063
TEST_TIME 92 10057
TEST_TIME 93 10031
TEST_TIME 94 10030
TEST_TIME 95 9960
TEST_TIME 96 9924
TEST_TIME 97 9918
TEST_TIME 98 9878
TEST_TIME 99 9918
TEST_TIME 100 9969
TEST_TIME 101 9970
TEST_TIME 102 9937
TEST_TIME 103 9956
TEST_TIME 104 9993
TEST_TIME 105 10004
TEST_TIME 106 10020
TEST_TIME 107 9982
TEST_TIME 108 9975
TEST_TIME 109 10009
TEST_TIME 110 9940
TEST_TIME 111 9887
TEST_TIME 112 9868
TEST_TIME 113 9831
TEST_TIME 114 9789
TEST_TIME 115 9765
TEST_TIME 116 9741
TEST_TIME 117 9769
TEST_TIME 118 9770
TEST_TIME 119 9763
TEST_TIME 120 9755
TEST_TIME 121 9675

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-10-2020 16:28:05 to 15-10-2020 16:28:08.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1620270 1620270 0 0.00 0 16201 5 0 0
doc_list_biud 1620262 1620262 0 0.00 0 83580 21 0 0
sp_add_invoice_to_stock 5435 5324 111 2.04 39 175039 15226 0 111
sp_cancel_adding_invoice 6106 6106 0 0.00 6 101524 674 0 0
sp_cancel_client_order 1935 1935 0 0.00 4 10091 274 0 0
sp_cancel_customer_reserve 593347 593347 0 0.00 1 83582 44 0 0
sp_cancel_pay_from_customer 764 764 0 0.00 2 7006 52 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 1871 47 0 0
sp_cancel_supplier_invoice 6770 6763 7 0.10 2 18355 624 0 7
sp_cancel_supplier_order 454 454 0 0.00 6 951614 72766 0 0
sp_cancel_write_off 10741 10741 0 0.00 1 10710 89 0 0
sp_client_order 21887 21887 0 0.00 3 28622 354 0 0
sp_customer_reserve 484992 484992 0 0.00 0 39871 153 0 0
sp_fill_shopping_cart 33720 33720 0 0.00 1 10664 30 0 0
sp_fill_shopping_cart_clo_res 484987 484987 0 0.00 0 21994 14 0 0
sp_get_clo_for_invoice 5319 5319 0 0.00 0 23883 296 0 0
sp_kill_cost_storno 18071 18071 0 0.00 0 11272 36 0 0
sp_kill_qstorno_ret_qs2qd 1204652 1204652 0 0.00 0 83572 24 0 0
sp_kill_qty_storno 635353 635353 0 0.00 0 83578 52 0 0
sp_lock_selected_doc 22268 22268 0 0.00 0 10593 8 0 0
sp_make_cost_storno 29813 29813 0 0.00 0 13890 42 0 0
sp_multiply_rows_for_pdistr 29813 29813 0 0.00 0 6462 20 0 0
sp_multiply_rows_for_qdistr 21887 21887 0 0.00 0 28614 169 0 0
sp_pay_from_customer 7675 7675 0 0.00 4 18185 98 0 0
sp_pay_to_supplier 6131 6131 0 0.00 4 13937 114 0 0
sp_payment_common 13802 13802 0 0.00 1 18180 25 0 0
sp_qd_handle_on_cancel_clo 1935 1935 0 0.00 0 10083 228 0 0
sp_qd_handle_on_invoice_upd_sts 11425 11425 0 0.00 0 44152 255 0 0
sp_qd_handle_on_reserve_upd_sts 10688 10688 0 0.00 0 24440 52 0 0
sp_reserve_write_off 10688 10688 0 0.00 6 24480 152 0 0
sp_supplier_invoice 5822 5822 0 0.00 6 95881 2900 0 0
sp_supplier_order 6036 6036 0 0.00 5 27143 1158 0 0
srv_aggregate_perf_data 125 124 1 0.80 2757 86975 12019 0 1
srv_fill_mon_cache_memory 59 59 0 0.00 2054 122109 56014 0 0
srv_find_qd_qs_mism 1120304 1120304 0 0.00 0 16200 4 0 0
srv_make_invnt_saldo 576 576 0 0.00 37 49908 1023 0 0
srv_make_money_saldo 774 774 0 0.00 1 2414 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 227482 407272 344368 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5324 5324 0 0.00 0 17279 141 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 8074 96 0 0
t$perf-norm:sp_cancel_client_order 1935 1935 0 0.00 0 10452 15 0 0
t$perf-norm:sp_cancel_customer_reserve 761 761 0 0.00 0 1768 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 764 764 0 0.00 0 1618 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 357 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 9333 32 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 17419 1208 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 5501 9 0 0
t$perf-norm:sp_client_order 21889 21889 0 0.00 0 23017 18 0 0
t$perf-norm:sp_customer_reserve 1114 1114 0 0.00 0 10998 14 0 0
t$perf-norm:sp_pay_from_customer 7676 7676 0 0.00 0 18650 15 0 0
t$perf-norm:sp_pay_to_supplier 6131 6131 0 0.00 0 14901 16 0 0
t$perf-norm:sp_reserve_write_off 10688 10688 0 0.00 0 7780 15 0 0
t$perf-norm:sp_supplier_invoice 5822 5822 0 0.00 0 10426 22 0 0
t$perf-norm:sp_supplier_order 6036 6036 0 0.00 0 8089 17 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 428 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 6666 114 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 2897 14 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 2539 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10859 10637 222 2.04 0 10782 20 0 222
v_all_customers 40062 40062 0 0.00 0 21 0 0 0
v_all_suppliers 32188 32188 0 0.00 0 24 1 0 0
v_all_wares 43766 43766 0 0.00 0 248 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 2380 11 0 0
v_cancel_client_order 3870 3870 0 0.00 0 9350 9 0 0
v_cancel_customer_prepayment 1528 1528 0 0.00 0 6968 10 0 0
v_cancel_customer_reserve 1522 1522 0 0.00 0 2399 7 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 1246 3 0 0
v_cancel_supplier_invoice 848 834 14 1.65 0 3623 18 0 14
v_cancel_supplier_order 908 908 0 0.00 0 3240 10 0 0
v_cancel_write_off 2030 2030 0 0.00 0 2293 12 0 0
v_max_id_clo_ord 6030 6030 0 0.00 0 291 1 0 0
v_max_id_clo_res 1114 1114 0 0.00 0 2107 16 0 0
v_max_id_ord_sup 5807 5807 0 0.00 0 4043 5 0 0
v_max_non_paid_invoice 1872 1872 0 0.00 0 400 2 0 0
v_max_non_paid_realizn 4087 4087 0 0.00 0 5340 4 0 0
v_min_id_clo_ord 6030 6030 0 0.00 0 3555 4 0 0
v_min_id_clo_res 1114 1114 0 0.00 0 3573 13 0 0
v_min_id_ord_sup 5807 5807 0 0.00 0 7487 8 0 0
v_min_non_paid_invoice 6129 6129 0 0.00 0 9849 12 0 0
v_min_non_paid_realizn 7673 7673 0 0.00 0 18176 14 0 0
v_our_firm 10876 10876 0 0.00 0 209 1 0 0
v_reserve_write_off 21376 21376 0 0.00 0 9402 6 0 0
x_lock_depdocs_on_canc_invoice 12452 12452 0 0.00 0 15131 93 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 14035 303 0 0
x_make_qstorno_1000_1200 6036 6036 0 0.00 1 27136 1096 0 0
x_make_qstorno_1200_2000 5822 5822 0 0.00 3 95850 2793 0 0
x_make_qstorno_2100_3300 484992 484992 0 0.00 0 39869 138 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
13:57:00 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:01 29821 8192000000 10385042016 10707865600 1433204496 14.890 100 88 177 0 1469359184 7011952 29142736
13:59:37 13830 8192000000 10429800992 10760384512 1480197120 15.303 100 89 178 0 1518075216 8592016 29286080
14:00:53 17678 8192000000 10468213312 10809520128 1520717040 15.656 100 82 164 0 1556292416 9843168 25732208
14:02:17 13320 8192000000 10566335392 10923888640 1614550784 16.464 100 72 144 0 1646435168 10206336 21678048
14:03:33 9350 8192000000 10650602624 11026378752 1709591008 17.265 100 70 140 1 1741865568 12125312 20149248
14:04:49 4328 8192000000 10706256736 11090415616 1772633120 17.789 100 85 170 2 1810686592 13977760 24075712
14:05:56 3309 8192000000 10774347504 11162755072 1834553664 18.296 100 84 166 2 1870264816 12172720 23538432
14:07:00 3297 8192000000 10808690256 11196649472 1865056576 18.544 100 86 172 0 1901835648 12760704 24018368
14:08:04 3020 8192000000 10782209792 11203166208 1871277856 18.595 100 30 60 0 1887020064 7662224 8079984
14:09:07 11034 8192000000 10796019168 11220594688 1878671632 18.654 100 14 29 2 1887180032 4871856 3636544
14:10:19 3284 8192000000 10799666480 11231420416 1892131952 18.763 100 25 50 3 1905997040 6969856 6895232
14:11:26 70003 8192000000 10816464832 11244384256 1897635280 18.807 100 28 56 0 1910726960 5193184 7898496
14:13:37 8799 8192000000 10817398048 11250540544 1899161376 18.820 100 22 42 2 1911066208 5918608 5986224
14:14:48 10402 8192000000 10814260192 11253174272 1901538704 18.839 100 18 36 0 1912494512 6107248 4848560
14:16:02 54144 8192000000 10773201344 11182465024 1821567744 18.190 97 38 74 2 1837645648 5109568 10968336
14:17:56 4798 8192000000 10717572176 11132104704 1772550624 17.788 98 32 64 2 1787969584 6746960 8672000
14:19:02 7206 8192000000 10689140912 11106729984 1719647728 17.349 96 12 24 0 1727418368 4667536 3103104
14:20:12 10383 8192000000 10713026048 11127062528 1752355328 17.621 97 25 48 1 1765489712 5618512 7515872
14:21:23 58931 8192000000 10743005792 11169574912 1828723328 18.249 100 30 61 0 1843016384 5792480 8500576
14:23:23 4146 8192000000 10770389344 11197378560 1857355760 18.482 100 31 62 1 1874567056 7619552 9591744
14:24:28 10835 8192000000 10779765744 11208740864 1868845152 18.575 100 20 40 1 1879688896 5328192 5515552
14:25:39 15146 8192000000 10786791392 11223797760 1872380784 18.604 100 7 14 1 1879740336 5171296 2188256
14:26:58 50815 8192000000 10832328864 11255734272 1900401808 18.830 100 51 102 1 1922968496 7808368 14758320
14:28:58 19834 8192000000 10862989280 11287965696 1931360832 19.078 100 42 82 1 1955215696 13104608 10750256
14:30:19 12645 8192000000 10882125696 11309785088 1948999792 19.219 100 46 92 0 1974936224 13131472 12804960
14:31:35 24701 8192000000 10923335088 11345043456 1974832624 19.424 100 53 106 1 2007528816 16643632 16052560
14:33:02 12179 8192000000 10930789664 11372552192 1993261920 19.570 100 37 74 1 2025272672 19728736 12282016
14:34:17 8575 8192000000 10956574272 11388579840 1995026624 19.583 99 59 118 2 2039544992 26316480 18201888
14:35:33 5231 8192000000 10976251248 11396583424 2000255840 19.625 99 63 126 3 2046595744 27681824 18658080
14:36:44 14138 8192000000 10980330208 11407175680 2013724672 19.731 100 65 128 1 2064914208 31944608 19244928
14:38:01 2054 8192000000 10969231152 11385319424 1994570032 19.580 99 84 168 2 2054687568 35118528 24999008
14:39:05 2574 8192000000 10982519760 11398893568 1993348368 19.570 100 81 162 0 2063936512 45189648 25398496
14:40:12 4160 8192000000 10925339152 11354402816 1932776464 19.089 98 46 92 0 1983124544 35654160 14693920
14:41:17 11869 8192000000 10883468112 11326296064 1904262336 18.861 98 26 52 2 1948025232 34519536 9243360
14:42:37 24942 8192000000 10869754032 11315625984 1906852672 18.881 100 29 58 0 1963156656 45726448 10577536
14:44:07 37465 8192000000 10878609408 11340972032 1902993824 18.850 99 20 40 3 1951160016 40898832 7267360
14:45:49 66431 8192000000 10861961248 11329363968 1894441744 18.782 98 5 10 0 1923718320 27546864 1729712
14:47:56 48676 8192000000 10820499952 11272302592 1801008864 18.022 94 16 32 0 1821686032 15789280 4887888
14:49:59 33959 8192000000 10818024752 11273281536 1862302896 18.522 98 19 38 2 1877826896 10191808 5332192
14:51:35 120574 8192000000 10843928048 11280117760 1857512816 18.483 97 28 56 0 1880983968 14178752 9292400
14:54:44 58866 8192000000 10829697152 11289931776 1882161680 18.683 98 21 42 0 1896820192 7985520 6672992
14:56:47 32113 8192000000 10862690576 11320565760 1934647104 19.104 100 33 66 3 1954251616 10693328 8911184
14:58:36 30452 8192000000 10881130880 11338379264 1955083712 19.267 100 17 34 1 1980131984 21029280 4018992
15:00:06 45550 8192000000 10888114800 11351781376 1959953712 19.306 100 14 27 1 1977947536 14074384 3919440
15:01:52 30423 8192000000 10890706464 11359924224 1966290864 19.356 100 13 26 1 1981614368 11301376 4022128
15:03:32 93361 8192000000 10883110560 11359846400 1934856752 19.106 98 12 24 1 1958320960 19767472 3696736
15:06:11 48395 8192000000 10913798880 11366010880 1980275888 19.467 100 26 51 0 1996202768 8830720 7096160
15:08:04 47891 8192000000 10909127376 11383472128 1987087792 19.521 100 18 36 1 2004095216 12068704 4938720
15:09:57 68523 8192000000 10916722784 11381264384 1929462480 19.063 97 16 32 0 1952046656 17630272 4953904
15:12:27 109700 8192000000 10920277328 11382226944 1966172304 19.355 99 16 32 0 1983989520 13214992 4602224
15:15:27 111130 8192000000 10899075872 11375579136 1988780208 19.534 100 20 40 2 2001813968 7120176 5913584
15:18:42 68065 8192000000 10886988528 11361300480 1945132096 19.188 97 15 30 0 1961995392 12404032 4459264
15:20:51 66206 8192000000 10889017984 11355488256 1912324176 18.925 96 18 37 1 1927467440 9956688 5186576
15:23:07 61579 8192000000 10854335312 11337809920 1862303872 18.522 95 12 24 0 1879632144 13648016 3680256
15:25:09 82514 8192000000 10870246128 11339685888 1896118496 18.795 97 13 26 0 1910371168 10774272 3478400
15:27:44 75640 8192000000 10835638448 11294633984 1784532240 17.887 92 23 46 2 1806202416 15224368 6445808
15:30:07 92720 8192000000 10849177392 11310415872 1873168832 18.610 97 11 22 0 1889136208 12836032 3131344
15:32:40 71296 8192000000 10831823664 11277099008 1770366064 17.770 92 18 36 1 1789393456 14466272 4561120
15:34:51 34837 8192000000 10882439072 11319959552 1879346768 18.660 97 36 72 0 1905404992 15510224 10548000
15:36:32 105171 8192000000 10855359632 11324772352 1935976048 19.115 100 29 58 0 1958984480 14127280 8881152
15:39:47 73763 8192000000 10855531408 11333468160 1902526304 18.847 98 9 18 0 1914368976 9139504 2703168
15:42:07 108735 8192000000 10854271104 11339321344 1917153584 18.964 99 11 22 2 1934993920 14902304 2938032
15:44:56 36745 8192000000 10872701024 11327205376 1925648944 19.032 99 31 62 1 1944904720 10602992 8652784
15:46:39 62313 8192000000 10868266720 11326574592 1930848688 19.074 99 16 31 1 1944213536 9058352 4306496
15:48:57 81257 8192000000 10924374368 11391078400 1976327104 19.436 100 25 50 1 1995018864 11464736 7227024
15:51:23 54183 8192000000 10908202768 11381432320 1959210880 19.300 99 13 26 0 1974430576 11794000 3425696
15:53:21 73160 8192000000 10898677440 11376427008 1922516736 19.007 97 27 54 0 1948582976 18578624 7487616
15:55:57 62593 8192000000 10921710496 11383578624 1969849376 19.384 99 30 61 1 1989636144 11514160 8272608
15:58:00 76469 8192000000 10913970448 11382685696 1955309552 19.269 98 17 34 1 1970855184 11053488 4492144
16:00:22 66872 8192000000 10894603696 11378360320 1953566800 19.255 98 13 26 1 1965910576 8880304 3463472
16:02:29 31039 8192000000 10901159072 11375792128 1971870592 19.400 99 22 42 3 1989416064 11710240 5835232
16:04:02 59546 8192000000 10904158880 11376103424 1918674256 18.976 96 16 31 2 1935265856 11557712 5033888
16:06:05 81802 8192000000 10890897376 11375689728 1929154048 19.060 98 3 6 0 1940433168 10557968 721152
16:08:27 67461 8192000000 10905701392 11376586752 1953526144 19.255 99 30 60 0 1977167008 15246432 8394432
16:11:20 112337 8192000000 10847652752 11308736512 1792165488 17.950 91 14 28 1 1806949552 10922032 3862032
16:14:13 22067 8192000000 10882120336 11330449408 1937656960 19.128 100 31 63 3 1957424048 10745440 9021648
16:15:40 122108 8192000000 10854262576 11314212864 1883642272 18.695 97 17 34 0 1900604032 12606688 4355072
16:18:45 77606 8192000000 10821897568 11292569600 1835513744 18.304 95 5 10 0 1844444176 7491168 1439264
16:21:15 87381 8192000000 10845752848 11321262080 1892906816 18.769 98 12 24 3 1913787920 17093568 3787536
16:23:45 77536 8192000000 10844777296 11306807296 1893012384 18.770 98 18 36 2 1909255392 10980928 5262080
16:26:23 34483 8192000000 10804031888 11258003456 1868579648 18.573 96 27 54 0 1886084128 9126576 8377904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 111 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          251809
MON$OLDEST_ACTIVE               253336
MON$OLDEST_SNAPSHOT             253336
MON$NEXT_TRANSACTION            253336
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                       2016704
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.7.33374 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33374 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33374 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:178: 
:179: mscs-test-161.budzdorov.ru    Thu Oct 15 16:27:06 2020
:180:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:181: 
--- end of diff output ---

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

15.10.20 16:28:33. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2020-10-15 16:28:36 No severe PSQL-related problems occured
15.10.20 16:28:37. End of report.