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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17514728 kB
MemAvailable 31538984 kB
Buffers 0 kB
Cached 14149084 kB
SwapCached 5152 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-20 19:26:57
DTS_END                         2020-10-20 19:26:57.8260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/60420
TRN_ID                          262904
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: 9862.74 17205 1183529
customer order: creation 195.03 308 23404
customer order: refuse 16.40 229 1968
order to supplier: creation 50.99 1079 6119
order to supplier: removal 3.67 73475 440
invoice (draft): creation 51.42 2722 6170
invoice (draft): removal 53.89 615 6467
invoice accept: apply 46.29 15209 5555
invoice accept: cancel 48.33 689 5799
customer reserve: creation 4325.31 150 519037
customer reserve: removal 4742.12 45 569054
realization accept: apply 94.10 154 11292
realization accept: cancel 88.12 106 10574
payment from customer: creation 67.30 113 8076
payment from customer: removal 6.14 68 737
payment to supplier: creation 56.23 115 6748
payment to supplier: removal 3.88 48 465
service: total inventory turnovers 5.60 831 672
service: total monetary turnovers 6.97 33 836
service: aggregate perf. data 0.93 19046 112
service: refresh index statistics 0.03 229056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-10-2020 19:28:04 to 20-10-2020 19:28:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1940
TEST_TIME 2 4044
TEST_TIME 3 3935
TEST_TIME 4 3455
TEST_TIME 5 4213
TEST_TIME 6 4711
TEST_TIME 7 4929
TEST_TIME 8 4820
TEST_TIME 9 5646
TEST_TIME 10 6081
TEST_TIME 11 6782
TEST_TIME 12 6844
TEST_TIME 13 8291
TEST_TIME 14 8434
TEST_TIME 15 8706
TEST_TIME 16 9431
TEST_TIME 17 9495
TEST_TIME 18 9774
TEST_TIME 19 10372
TEST_TIME 20 11111
TEST_TIME 21 11819
TEST_TIME 22 12394
TEST_TIME 23 12152
TEST_TIME 24 11926
TEST_TIME 25 12022
TEST_TIME 26 11875
TEST_TIME 27 11819
TEST_TIME 28 11791
TEST_TIME 29 11422
TEST_TIME 30 11328
TEST_TIME 31 11126
TEST_TIME 32 11077
TEST_TIME 33 11173
TEST_TIME 34 11018
TEST_TIME 35 10850
TEST_TIME 36 10669
TEST_TIME 37 10594
TEST_TIME 38 10615
TEST_TIME 39 10801
TEST_TIME 40 10755
TEST_TIME 41 10766
TEST_TIME 42 10795
TEST_TIME 43 10746
TEST_TIME 44 10880
TEST_TIME 45 10871
TEST_TIME 46 10834
TEST_TIME 47 10833
TEST_TIME 48 10926
TEST_TIME 49 10998
TEST_TIME 50 10979
TEST_TIME 51 10881
TEST_TIME 52 10740
TEST_TIME 53 10677
TEST_TIME 54 10660
TEST_TIME 55 10605
TEST_TIME 56 10668
TEST_TIME 57 10567
TEST_TIME 58 10486
TEST_TIME 59 10411
TEST_TIME 60 10317
TEST_TIME 61 10316
TEST_TIME 62 10282
TEST_TIME 63 10268
TEST_TIME 64 10262
TEST_TIME 65 10438
TEST_TIME 66 10417
TEST_TIME 67 10395
TEST_TIME 68 10416
TEST_TIME 69 10394
TEST_TIME 70 10437
TEST_TIME 71 10412
TEST_TIME 72 10432
TEST_TIME 73 10476
TEST_TIME 74 10435
TEST_TIME 75 10588
TEST_TIME 76 10491
TEST_TIME 77 10401
TEST_TIME 78 10342
TEST_TIME 79 10364
TEST_TIME 80 10324
TEST_TIME 81 10255
TEST_TIME 82 10248
TEST_TIME 83 10196
TEST_TIME 84 10129
TEST_TIME 85 10087
TEST_TIME 86 10051
TEST_TIME 87 10065
TEST_TIME 88 10084
TEST_TIME 89 10103
TEST_TIME 90 10063
TEST_TIME 91 10089
TEST_TIME 92 10093
TEST_TIME 93 10133
TEST_TIME 94 10152
TEST_TIME 95 10147
TEST_TIME 96 10109
TEST_TIME 97 10151
TEST_TIME 98 10138
TEST_TIME 99 10165
TEST_TIME 100 10109
TEST_TIME 101 10051
TEST_TIME 102 10020
TEST_TIME 103 9980
TEST_TIME 104 9924
TEST_TIME 105 9907
TEST_TIME 106 9871
TEST_TIME 107 9887
TEST_TIME 108 9893
TEST_TIME 109 9934
TEST_TIME 110 9910
TEST_TIME 111 9902
TEST_TIME 112 9896
TEST_TIME 113 9869
TEST_TIME 114 9919
TEST_TIME 115 9902
TEST_TIME 116 9935
TEST_TIME 117 9950
TEST_TIME 118 9942
TEST_TIME 119 9934
TEST_TIME 120 9864
TEST_TIME 121 9783

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-10-2020 19:28:08 to 20-10-2020 19:28:11.

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 1664448 1664448 0 0.00 0 19545 6 0 0
doc_list_biud 1664444 1664444 0 0.00 0 28395 20 0 0
sp_add_invoice_to_stock 5679 5555 124 2.18 39 159990 15210 0 124
sp_cancel_adding_invoice 5799 5799 0 0.00 6 70218 689 0 0
sp_cancel_client_order 1968 1968 0 0.00 4 10470 230 0 0
sp_cancel_customer_reserve 569054 569054 0 0.00 1 28399 45 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 3 8383 69 0 0
sp_cancel_pay_to_supplier 465 465 0 0.00 3 2640 49 0 0
sp_cancel_supplier_invoice 6478 6467 11 0.16 2 18154 616 0 11
sp_cancel_supplier_order 440 440 0 0.00 8 843736 73476 0 0
sp_cancel_write_off 10574 10574 0 0.00 1 12485 106 0 0
sp_client_order 23404 23404 0 0.00 4 22552 309 0 0
sp_customer_reserve 519037 519037 0 0.00 0 83369 151 0 0
sp_fill_shopping_cart 35675 35675 0 0.00 1 11685 34 0 0
sp_fill_shopping_cart_clo_res 519034 519034 0 0.00 0 15845 12 0 0
sp_get_clo_for_invoice 5551 5551 0 0.00 0 26317 264 0 0
sp_kill_cost_storno 17575 17575 0 0.00 0 6135 43 0 0
sp_kill_qstorno_ret_qs2qd 1155589 1155589 0 0.00 0 28314 25 0 0
sp_kill_qty_storno 611144 611144 0 0.00 0 28394 53 0 0
sp_lock_selected_doc 23272 23272 0 0.00 0 9607 7 0 0
sp_make_cost_storno 31667 31667 0 0.00 0 19539 42 0 0
sp_multiply_rows_for_pdistr 31666 31666 0 0.00 0 9151 23 0 0
sp_multiply_rows_for_qdistr 23404 23404 0 0.00 0 11075 152 0 0
sp_pay_from_customer 8076 8076 0 0.00 4 22536 113 0 0
sp_pay_to_supplier 6748 6748 0 0.00 4 23992 116 0 0
sp_payment_common 14823 14823 0 0.00 1 12703 33 0 0
sp_qd_handle_on_cancel_clo 1968 1968 0 0.00 0 10404 211 0 0
sp_qd_handle_on_invoice_upd_sts 11350 11350 0 0.00 0 21164 248 0 0
sp_qd_handle_on_reserve_upd_sts 11291 11291 0 0.00 0 18477 45 0 0
sp_reserve_write_off 11292 11292 0 0.00 6 20512 154 0 0
sp_supplier_invoice 6170 6170 0 0.00 5 88094 2722 0 0
sp_supplier_order 6119 6119 0 0.00 5 64469 1079 0 0
srv_aggregate_perf_data 113 112 1 0.88 2429 280779 19047 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2333 159627 49234 0 0
srv_find_qd_qs_mism 1129790 1129790 0 0.00 0 12190 6 0 0
srv_make_invnt_saldo 672 672 0 0.00 41 23295 832 0 0
srv_make_money_saldo 836 836 0 0.00 0 13122 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 139887 325443 229056 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 5556 5556 0 0.00 0 22190 123 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 0 8957 87 0 0
t$perf-norm:sp_cancel_client_order 1968 1968 0 0.00 0 11188 25 0 0
t$perf-norm:sp_cancel_customer_reserve 753 753 0 0.00 0 2572 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 2764 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 465 465 0 0.00 0 3364 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 462 462 0 0.00 0 2307 15 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 26485 1257 0 0
t$perf-norm:sp_cancel_write_off 1156 1156 0 0.00 0 5317 13 0 0
t$perf-norm:sp_client_order 23404 23404 0 0.00 0 14424 16 0 0
t$perf-norm:sp_customer_reserve 1146 1146 0 0.00 0 1002 5 0 0
t$perf-norm:sp_pay_from_customer 8076 8076 0 0.00 0 7477 12 0 0
t$perf-norm:sp_pay_to_supplier 6748 6748 0 0.00 0 9318 10 0 0
t$perf-norm:sp_reserve_write_off 11292 11292 0 0.00 0 8381 11 0 0
t$perf-norm:sp_supplier_invoice 6170 6170 0 0.00 0 9948 17 0 0
t$perf-norm:sp_supplier_order 6119 6119 0 0.00 0 4894 10 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 66 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 353 9 0 0
t$perf-norm:srv_make_invnt_saldo 672 672 0 0.00 0 4209 23 0 0
t$perf-norm:srv_make_money_saldo 836 836 0 0.00 0 1964 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 11348 11100 248 2.18 0 18158 20 0 248
v_all_customers 42828 42828 0 0.00 0 60 0 0 0
v_all_suppliers 34202 34202 0 0.00 0 46 1 0 0
v_all_wares 46808 46808 0 0.00 0 141 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1181 7 0 0
v_cancel_client_order 3936 3936 0 0.00 0 2515 4 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 8356 9 0 0
v_cancel_customer_reserve 1506 1506 0 0.00 0 2749 4 0 0
v_cancel_payment_to_supplier 930 930 0 0.00 0 2210 5 0 0
v_cancel_supplier_invoice 946 924 22 2.32 0 4016 20 0 22
v_cancel_supplier_order 880 880 0 0.00 0 154 2 0 0
v_cancel_write_off 2312 2312 0 0.00 0 5385 13 0 0
v_max_id_clo_ord 6117 6117 0 0.00 0 330 1 0 0
v_max_id_clo_res 1146 1146 0 0.00 0 5834 16 0 0
v_max_id_ord_sup 6154 6154 0 0.00 0 3873 3 0 0
v_max_non_paid_invoice 1918 1918 0 0.00 0 692 3 0 0
v_max_non_paid_realizn 4219 4219 0 0.00 0 3550 4 0 0
v_min_id_clo_ord 6117 6117 0 0.00 0 5697 8 0 0
v_min_id_clo_res 1146 1146 0 0.00 0 2666 6 0 0
v_min_id_ord_sup 6154 6154 0 0.00 0 6783 8 0 0
v_min_non_paid_invoice 6748 6748 0 0.00 0 12699 22 0 0
v_min_non_paid_realizn 8075 8075 0 0.00 0 12664 23 0 0
v_our_firm 11692 11692 0 0.00 0 60 1 0 0
v_reserve_write_off 22580 22580 0 0.00 0 20351 9 0 0
x_lock_depdocs_on_canc_invoice 11804 11804 0 0.00 0 23097 97 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 14715 276 0 0
x_make_qstorno_1000_1200 6119 6119 0 0.00 1 64454 1014 0 0
x_make_qstorno_1200_2000 6170 6170 0 0.00 2 87942 2597 0 0
x_make_qstorno_2100_3300 519037 519037 0 0.00 0 83302 137 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-10-2020 19:28:12 to 20-10-2020 19:28:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
16:57:01 2 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
16:58:02 33481 8192000000 10363486032 10681978880 1411243520 14.695 100 92 183 2 1447360928 5542720 30574688
16:59:43 14112 8192000000 10436058512 10766778368 1492538240 15.411 100 91 182 0 1529996144 8639600 28818304
17:01:02 12236 8192000000 10495445520 10838560768 1548320848 15.895 100 87 174 1 1585739056 9877472 27540736
17:02:16 11048 8192000000 10568588320 10924822528 1629585264 16.591 100 82 164 1 1665491712 11116224 24790224
17:03:32 6187 8192000000 10654178720 11029446656 1719402576 17.347 100 79 158 2 1754501488 12275808 22823104
17:04:40 3211 8192000000 10715156080 11093868544 1771883824 17.783 100 71 141 2 1803433376 11529648 20019904
17:05:44 5329 8192000000 10759335072 11142651904 1813109856 18.121 100 84 169 0 1850631520 13762080 23759584
17:06:50 1558 8192000000 10810612720 11196100608 1865031680 18.544 100 96 195 1 1905789168 12742656 28014832
17:07:53 42091 8192000000 10770603072 11198148608 1868801984 18.575 100 51 104 0 1890596160 5696640 16097536
17:09:43 5691 8192000000 10783125952 11212627968 1875530704 18.629 100 23 46 0 1889102544 7310240 6261600
17:10:52 6402 8192000000 10801205456 11230556160 1888295344 18.732 100 23 44 1 1904816016 10132528 6388144
17:12:03 6736 8192000000 10808555696 11241418752 1897830592 18.809 100 18 36 0 1908893104 6390384 4672128
17:13:12 46806 8192000000 10831070000 11260018688 1916807904 18.961 100 42 84 3 1934110560 5085104 12217552
17:15:09 9945 8192000000 10821258048 11254558720 1903542912 18.855 100 20 40 0 1915638128 6010688 6084528
17:16:20 9032 8192000000 10761161328 11180961792 1814855296 18.136 98 22 44 1 1827054896 5601632 6597968
17:17:29 4937 8192000000 10718835040 11143315456 1782886208 17.873 98 25 50 0 1796895360 6553824 7455328
17:18:42 48366 8192000000 10702198528 11108556800 1655394528 16.810 91 29 58 2 1668637408 5385888 7856992
17:20:33 5238 8192000000 10714880208 11133239296 1768380400 17.754 98 19 38 2 1779026096 5182992 5462704
17:21:44 8949 8192000000 10753789648 11172753408 1831385744 18.271 100 18 36 0 1841745744 4966064 5393936
17:22:55 56120 8192000000 10777409600 11202998272 1856627936 18.476 100 32 65 0 1870858336 4708048 9522352
17:24:51 9218 8192000000 10782992112 11210412032 1863742960 18.534 100 28 56 2 1877452528 6191376 7518192
17:26:03 12023 8192000000 10779220800 11217231872 1871995120 18.600 100 12 24 0 1880966096 5141152 3829824
17:27:19 39454 8192000000 10842782640 11264049152 1907758672 18.889 100 45 89 3 1927585568 7371072 12455824
17:29:04 67708 8192000000 10886767600 11313676288 1931218576 19.077 99 51 102 2 1961043088 14746496 15078016
17:31:22 17541 8192000000 10873516144 11320254464 1950265424 19.229 100 26 52 3 1976460624 18340192 7855008
17:32:45 19976 8192000000 10927165616 11359105024 1980447088 19.468 100 44 85 0 2012994000 19820960 12725952
17:34:09 12002 8192000000 10963748800 11380236288 1993674384 19.573 100 56 110 1 2041450112 31200848 16574880
17:35:28 13495 8192000000 10946690192 11365720064 1978156064 19.450 100 56 112 0 2024180656 29301264 16723328
17:36:47 2333 8192000000 10956778672 11372658688 1964628576 19.343 99 74 149 2 2026424176 38490624 23304976
17:37:52 6492 8192000000 10913551024 11339960320 1908593520 18.895 97 56 111 1 1964769344 38968784 17207040
17:39:04 2418 8192000000 10948265296 11355742208 1942996016 19.171 99 87 173 1 2018305904 48491728 26818160
17:40:08 7169 8192000000 10940815552 11366092800 1969678976 19.383 100 53 103 1 2022344544 35448960 17216608
17:41:21 8612 8192000000 10925832064 11355332608 1950753296 19.232 100 39 78 1 2007624992 43736064 13135632
17:42:32 4913 8192000000 10942572160 11370164224 1965954320 19.353 100 50 99 1 2020725616 39418192 15353104
17:43:41 14116 8192000000 10905690064 11345793024 1940778672 19.153 100 29 57 2 1991117632 40738000 9600960
17:45:00 15685 8192000000 10896242848 11346132992 1936968528 19.123 100 28 56 1 1989570640 43165200 9436912
17:46:32 28042 8192000000 10852336000 11317084160 1870635200 18.589 98 25 50 4 1926065280 46702624 8727456
17:48:01 63824 8192000000 10813897040 11275612160 1844368384 18.376 97 11 23 2 1865998576 18632464 2997728
17:50:19 57962 8192000000 10845370032 11299823616 1892159104 18.763 99 13 26 0 1910631968 14601904 3870960
17:52:21 51312 8192000000 10839164640 11285708800 1865054160 18.544 98 16 29 2 1883024880 13677408 4293312
17:54:13 139116 8192000000 10869287152 11318489088 1924866080 19.026 100 20 41 0 1942296752 11995104 5435568
17:57:34 67913 8192000000 10882305568 11334451200 1920853360 18.994 98 30 60 3 1939387680 10471312 8063008
17:59:45 12980 8192000000 10876523024 11349893120 1954966672 19.266 100 13 26 2 1970676576 12305152 3404752
18:01:12 43145 8192000000 10865517952 11323555840 1933959520 19.099 98 10 20 0 1944917696 8501696 2456480
18:02:56 53412 8192000000 10903597648 11373449216 1981894384 19.480 100 27 53 1 2004338928 14626208 7818336
18:05:05 37254 8192000000 10898527680 11384152064 1960095488 19.307 99 13 26 1 1981175472 16901936 4178048
18:06:49 71361 8192000000 10926142368 11390464000 1966633040 19.359 99 27 54 0 1987344224 13345104 7366080
18:09:01 84129 8192000000 10924486912 11389599744 1987621328 19.525 100 14 28 0 2004195408 12774960 3799120
18:11:38 32054 8192000000 10917218528 11391393792 1988823504 19.534 100 21 42 2 2004108336 8967824 6317008
18:13:10 88491 8192000000 10906925824 11367596032 1912377872 18.926 96 13 26 0 1929622720 13944784 3300064
18:15:40 86751 8192000000 10900248800 11368189952 1891723744 18.760 96 12 24 0 1907627664 12958176 2945744
18:18:14 103562 8192000000 10900790928 11371765760 1951532656 19.239 99 25 50 0 1975909264 16489792 7886816
18:20:59 38546 8192000000 10891008272 11362848768 1964921408 19.345 100 15 30 1 1984572160 14836624 4814128
18:22:43 61735 8192000000 10889813344 11376689152 1937808048 19.129 98 11 22 0 1951969152 11201440 2959664
18:24:50 58493 8192000000 10873795904 11344289792 1903281568 18.853 97 17 34 1 1915567888 7555952 4730368
18:28:00 39344 8192000000 10843420016 11298787328 1819178976 18.171 93 14 28 1 1828924208 6193920 3551312
18:29:41 118309 8192000000 10872934048 11357949952 1895130416 18.787 96 12 24 0 1918607584 19913488 3563680
18:32:40 35208 8192000000 10847210384 11293302784 1816673920 18.150 94 35 71 2 1837126496 10577536 9875040
18:34:17 33518 8192000000 10861095856 11319775232 1891775952 18.760 98 26 50 0 1912961296 14389264 6796080
18:35:51 46099 8192000000 10872627376 11321012224 1909683248 18.904 99 46 91 2 1935443904 13019568 12741088
18:37:41 10036 8192000000 10898831392 11335106560 1929912304 19.066 99 53 106 1 1957420160 12659696 14848160
18:38:51 94033 8192000000 10893601728 11354243072 1922951232 19.010 99 21 42 2 1949934512 21284544 5698736
18:41:27 159626 8192000000 10869668384 11328118784 1841864944 18.356 95 24 48 0 1864589104 16256880 6467280
18:45:07 18463 8192000000 10874203920 11338645504 1931258864 19.077 100 11 19 0 1948002160 14320800 2422496
18:46:27 23927 8192000000 10846620848 11323785216 1921868400 19.002 99 8 16 0 1934232512 10014960 2349152
18:48:07 63655 8192000000 10861264368 11339743232 1913996208 18.939 98 7 14 1 1924017248 7502160 2518880
18:50:11 47164 8192000000 10864094288 11340124160 1947862112 19.209 100 14 28 0 1958437920 6570480 4005328
18:52:06 70175 8192000000 10883174496 11358867456 1925600880 19.032 98 16 32 1 1939545152 9530464 4413808
18:54:18 39417 8192000000 10921093232 11374014464 1962915888 19.329 100 22 44 0 1975708864 7153616 5639360
18:55:59 61126 8192000000 10915480992 11376574464 1943065984 19.171 98 26 52 1 1962759056 12976432 6716640
18:58:09 18670 8192000000 10925143248 11353874432 1923349728 19.014 97 56 112 2 1952622112 13672976 15599408
18:59:28 54736 8192000000 10876529440 11342102528 1894503680 18.782 96 16 32 2 1913744032 15064944 4175408
19:01:24 32045 8192000000 10901581072 11349078016 1939394976 19.142 99 36 72 3 1959015120 9614352 10005792
19:02:58 29809 8192000000 10908109088 11350757376 1927913296 19.050 98 52 104 1 1955689584 13829696 13946592
19:04:31 43202 8192000000 10893312144 11339247616 1878727072 18.655 96 22 44 0 1897326000 12673824 5925104
19:06:14 137649 8192000000 10877225136 11354087424 1933549408 19.095 99 21 42 1 1955408272 15474832 6384032
19:09:34 36581 8192000000 10816367232 11283193856 1871731936 18.598 96 10 19 1 1882077104 7803424 2541744
19:11:12 94688 8192000000 10868095584 11329744896 1878327856 18.652 97 6 12 0 1887293568 7341088 1624624
19:13:47 56092 8192000000 10870121152 11327451136 1890646384 18.751 98 14 28 0 1908380368 13787680 3946304
19:16:03 78347 8192000000 10834247440 11302293504 1824917520 18.218 94 24 48 1 1840959888 9538656 6503712
19:18:26 61800 8192000000 10868254048 11337621504 1930973792 19.075 99 18 36 2 1947374608 10903616 5497200
19:20:28 55075 8192000000 10870634384 11336724480 1894348512 18.781 97 17 34 0 1907146672 8113456 4684704
19:22:32 53166 8192000000 10881952304 11338170368 1931763856 19.081 100 27 54 2 1948255104 8529536 7961712
19:24:25 59871 8192000000 10873772640 11337072640 1894096976 18.779 97 21 42 1 1909055904 8973440 5985488
19:26:49 17862 8192000000 9074114752 9285562368 247885152 2.937 13 2 4 0 248900480 887984 127344

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 124 v_add_invoice_to_stock 335544517
user_exc 11 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          263389
MON$OLDEST_ACTIVE               263390
MON$OLDEST_SNAPSHOT             263390
MON$NEXT_TRANSACTION            263390
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                       2024800
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.33376 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33376 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33376 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33376 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Oct 20 19:27:09 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
--- end of diff output ---

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

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