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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17216392 kB
MemAvailable 31611020 kB
Buffers 0 kB
Cached 14494108 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 19:26:57
DTS_END                         2020-10-17 19:26:57.3460
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/40090
TRN_ID                          261575
ATT_ID                          715
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: 9744.78 18782 1169374
customer order: creation 193.43 300 23212
customer order: refuse 16.48 277 1978
order to supplier: creation 50.97 1082 6116
order to supplier: removal 3.98 74449 478
invoice (draft): creation 51.12 2776 6134
invoice (draft): removal 54.03 669 6484
invoice accept: apply 45.98 15014 5518
invoice accept: cancel 49.28 681 5913
customer reserve: creation 4161.26 153 499351
customer reserve: removal 4790.43 49 574852
realization accept: apply 93.21 149 11185
realization accept: cancel 90.02 90 10802
payment from customer: creation 65.56 102 7867
payment from customer: removal 6.22 110 746
payment to supplier: creation 55.70 100 6684
payment to supplier: removal 3.91 93 469
service: total inventory turnovers 5.44 1033 653
service: total monetary turnovers 6.72 32 806
service: aggregate perf. data 1.02 19276 122
service: refresh index statistics 0.03 259195 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2020 19:28:39 to 17-10-2020 19:28:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5743
TEST_TIME 2 5819
TEST_TIME 3 4689
TEST_TIME 4 5680
TEST_TIME 5 6426
TEST_TIME 6 6104
TEST_TIME 7 6714
TEST_TIME 8 6648
TEST_TIME 9 6656
TEST_TIME 10 6944
TEST_TIME 11 7165
TEST_TIME 12 7656
TEST_TIME 13 7562
TEST_TIME 14 7990
TEST_TIME 15 8160
TEST_TIME 16 8348
TEST_TIME 17 8880
TEST_TIME 18 9109
TEST_TIME 19 8948
TEST_TIME 20 9074
TEST_TIME 21 9538
TEST_TIME 22 9916
TEST_TIME 23 11080
TEST_TIME 24 11403
TEST_TIME 25 11696
TEST_TIME 26 11896
TEST_TIME 27 11611
TEST_TIME 28 11525
TEST_TIME 29 11414
TEST_TIME 30 11327
TEST_TIME 31 11284
TEST_TIME 32 11426
TEST_TIME 33 11276
TEST_TIME 34 11312
TEST_TIME 35 11171
TEST_TIME 36 11106
TEST_TIME 37 11209
TEST_TIME 38 11156
TEST_TIME 39 11106
TEST_TIME 40 10921
TEST_TIME 41 10707
TEST_TIME 42 10676
TEST_TIME 43 10785
TEST_TIME 44 10656
TEST_TIME 45 10590
TEST_TIME 46 10464
TEST_TIME 47 10610
TEST_TIME 48 10675
TEST_TIME 49 10558
TEST_TIME 50 10512
TEST_TIME 51 10413
TEST_TIME 52 10353
TEST_TIME 53 10296
TEST_TIME 54 10196
TEST_TIME 55 10128
TEST_TIME 56 10169
TEST_TIME 57 10192
TEST_TIME 58 10196
TEST_TIME 59 10249
TEST_TIME 60 10210
TEST_TIME 61 10299
TEST_TIME 62 10349
TEST_TIME 63 10294
TEST_TIME 64 10315
TEST_TIME 65 10432
TEST_TIME 66 10401
TEST_TIME 67 10440
TEST_TIME 68 10396
TEST_TIME 69 10402
TEST_TIME 70 10437
TEST_TIME 71 10340
TEST_TIME 72 10284
TEST_TIME 73 10290
TEST_TIME 74 10237
TEST_TIME 75 10164
TEST_TIME 76 10104
TEST_TIME 77 10080
TEST_TIME 78 10059
TEST_TIME 79 10065
TEST_TIME 80 10064
TEST_TIME 81 10054
TEST_TIME 82 10032
TEST_TIME 83 10116
TEST_TIME 84 10099
TEST_TIME 85 10106
TEST_TIME 86 10148
TEST_TIME 87 10140
TEST_TIME 88 10140
TEST_TIME 89 10156
TEST_TIME 90 10185
TEST_TIME 91 10200
TEST_TIME 92 10118
TEST_TIME 93 10057
TEST_TIME 94 10056
TEST_TIME 95 9995
TEST_TIME 96 9976
TEST_TIME 97 9937
TEST_TIME 98 9921
TEST_TIME 99 9897
TEST_TIME 100 9911
TEST_TIME 101 9859
TEST_TIME 102 9966
TEST_TIME 103 10023
TEST_TIME 104 9990
TEST_TIME 105 10035
TEST_TIME 106 10023
TEST_TIME 107 9986
TEST_TIME 108 10032
TEST_TIME 109 10046
TEST_TIME 110 10010
TEST_TIME 111 9966
TEST_TIME 112 9934
TEST_TIME 113 9897
TEST_TIME 114 9908
TEST_TIME 115 9860
TEST_TIME 116 9832
TEST_TIME 117 9814
TEST_TIME 118 9792
TEST_TIME 119 9738
TEST_TIME 120 9751

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2020 19:28:42 to 17-10-2020 19:28:46.

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 1634800 1634800 0 0.00 0 23801 5 0 0
doc_list_biud 1634792 1634792 0 0.00 0 61617 22 0 0
sp_add_invoice_to_stock 5658 5518 140 2.47 43 135870 15014 0 140
sp_cancel_adding_invoice 5913 5913 0 0.00 5 57982 681 0 0
sp_cancel_client_order 1978 1978 0 0.00 4 15230 278 0 0
sp_cancel_customer_reserve 574852 574852 0 0.00 1 17971 49 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 9927 111 0 0
sp_cancel_pay_to_supplier 469 469 0 0.00 2 8279 93 0 0
sp_cancel_supplier_invoice 6497 6484 13 0.20 1 61625 669 0 13
sp_cancel_supplier_order 478 478 0 0.00 9 1011757 74450 0 0
sp_cancel_write_off 10802 10802 0 0.00 1 12739 90 0 0
sp_client_order 23212 23212 0 0.00 3 21271 301 0 0
sp_customer_reserve 499351 499351 0 0.00 0 102366 153 0 0
sp_fill_shopping_cart 35456 35456 0 0.00 1 15687 32 0 0
sp_fill_shopping_cart_clo_res 499345 499345 0 0.00 0 13732 11 0 0
sp_get_clo_for_invoice 5512 5512 0 0.00 0 18877 238 0 0
sp_kill_cost_storno 17930 17930 0 0.00 0 7093 43 0 0
sp_kill_qstorno_ret_qs2qd 1167468 1167468 0 0.00 0 61612 27 0 0
sp_kill_qty_storno 617202 617202 0 0.00 0 61615 58 0 0
sp_lock_selected_doc 23184 23184 0 0.00 0 8255 6 0 0
sp_make_cost_storno 31248 31248 0 0.00 0 23745 39 0 0
sp_multiply_rows_for_pdistr 31246 31246 0 0.00 0 11140 25 0 0
sp_multiply_rows_for_qdistr 23212 23212 0 0.00 0 16338 136 0 0
sp_pay_from_customer 7867 7867 0 0.00 4 11253 102 0 0
sp_pay_to_supplier 6684 6684 0 0.00 5 8441 101 0 0
sp_payment_common 14550 14550 0 0.00 1 11248 27 0 0
sp_qd_handle_on_cancel_clo 1978 1978 0 0.00 0 15219 246 0 0
sp_qd_handle_on_invoice_upd_sts 11424 11424 0 0.00 0 20400 263 0 0
sp_qd_handle_on_reserve_upd_sts 11184 11184 0 0.00 0 23330 52 0 0
sp_reserve_write_off 11185 11185 0 0.00 6 23363 149 0 0
sp_supplier_invoice 6134 6134 0 0.00 5 107451 2776 0 0
sp_supplier_order 6116 6116 0 0.00 6 26930 1082 0 0
srv_aggregate_perf_data 123 122 1 0.81 2956 373072 19277 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1018 110544 47808 0 0
srv_find_qd_qs_mism 1118119 1118119 0 0.00 0 7751 5 0 0
srv_make_invnt_saldo 653 653 0 0.00 25 87199 1034 0 0
srv_make_money_saldo 806 806 0 0.00 0 10835 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 181370 393131 259196 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 5518 5518 0 0.00 0 11104 140 0 0
t$perf-norm:sp_cancel_adding_invoice 480 480 0 0.00 1 3224 80 0 0
t$perf-norm:sp_cancel_client_order 1978 1978 0 0.00 0 3121 11 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 1876 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 4172 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 469 469 0 0.00 0 2513 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 477 477 0 0.00 0 1054 13 0 0
t$perf-norm:sp_cancel_supplier_order 478 478 0 0.00 0 17341 1140 0 0
t$perf-norm:sp_cancel_write_off 1117 1117 0 0.00 0 1766 7 0 0
t$perf-norm:sp_client_order 23212 23212 0 0.00 0 15856 18 0 0
t$perf-norm:sp_customer_reserve 1116 1116 0 0.00 0 5176 10 0 0
t$perf-norm:sp_pay_from_customer 7868 7868 0 0.00 0 3046 6 0 0
t$perf-norm:sp_pay_to_supplier 6684 6684 0 0.00 0 10128 10 0 0
t$perf-norm:sp_reserve_write_off 11186 11186 0 0.00 0 12177 11 0 0
t$perf-norm:sp_supplier_invoice 6134 6134 0 0.00 0 5077 15 0 0
t$perf-norm:sp_supplier_order 6117 6117 0 0.00 0 4255 13 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 143 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 813 14 0 0
t$perf-norm:srv_make_invnt_saldo 653 653 0 0.00 0 5285 22 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 1126 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 123 33 0 0
v_add_invoice_to_stock 11296 11016 280 2.47 0 4521 16 0 280
v_all_customers 42358 42358 0 0.00 0 16 0 0 0
v_all_suppliers 34018 34018 0 0.00 0 17 1 0 0
v_all_wares 46422 46422 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 960 960 0 0.00 0 1707 9 0 0
v_cancel_client_order 3956 3956 0 0.00 0 5120 6 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 4149 11 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 1159 4 0 0
v_cancel_payment_to_supplier 938 938 0 0.00 0 2118 5 0 0
v_cancel_supplier_invoice 980 954 26 2.65 0 2656 19 0 26
v_cancel_supplier_order 956 956 0 0.00 0 1054 6 0 0
v_cancel_write_off 2234 2234 0 0.00 0 1991 12 0 0
v_max_id_clo_ord 6114 6114 0 0.00 0 99 1 0 0
v_max_id_clo_res 1116 1116 0 0.00 0 1965 11 0 0
v_max_id_ord_sup 6131 6131 0 0.00 0 4778 3 0 0
v_max_non_paid_invoice 1919 1919 0 0.00 0 575 2 0 0
v_max_non_paid_realizn 4126 4126 0 0.00 0 2512 3 0 0
v_min_id_clo_ord 6114 6114 0 0.00 0 4519 6 0 0
v_min_id_clo_res 1116 1116 0 0.00 0 5480 7 0 0
v_min_id_ord_sup 6131 6131 0 0.00 0 6860 7 0 0
v_min_non_paid_invoice 6683 6683 0 0.00 0 7512 16 0 0
v_min_non_paid_realizn 7867 7867 0 0.00 0 11245 17 0 0
v_our_firm 11546 11546 0 0.00 0 20 1 0 0
v_reserve_write_off 22368 22368 0 0.00 0 5330 6 0 0
x_lock_depdocs_on_canc_invoice 11920 11920 0 0.00 0 15018 98 0 0
x_lock_depdocs_on_canc_sup_ord 478 478 0 0.00 0 11888 285 0 0
x_make_qstorno_1000_1200 6116 6116 0 0.00 2 26917 1015 0 0
x_make_qstorno_1200_2000 6134 6134 0 0.00 2 107439 2664 0 0
x_make_qstorno_2100_3300 499351 499351 0 0.00 0 102360 140 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2020 19:28:47 to 17-10-2020 19:28:51.

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 19179 8192000000 10379800048 10700881920 1438955744 14.940 100 91 182 0 1475239008 6497472 29785792
16:59:28 18836 8192000000 10449246512 10784792576 1502325728 15.496 100 86 172 1 1538230688 8335232 27569728
17:00:50 18088 8192000000 10487575392 10832363520 1542065856 15.841 100 83 166 0 1577885360 9606944 26212560
17:02:12 12699 8192000000 10577548416 10940059648 1633505136 16.625 100 77 154 1 1667250080 11045456 22699488
17:03:33 9394 8192000000 10661643280 11032502272 1718810096 17.342 100 86 172 4 1757020704 13895424 24315184
17:04:46 4000 8192000000 10707820880 11090821120 1769081376 17.759 100 69 136 7 1801490880 12844000 19565504
17:05:51 2293 8192000000 10774810672 11156144128 1831430080 18.271 100 86 173 1 1869088048 13082656 24575312
17:06:55 1497 8192000000 10817165392 11203284992 1874343648 18.619 100 94 188 2 1912128064 11907584 25876832
17:07:57 10604 8192000000 10787916528 11204894720 1868775968 18.574 100 36 72 1 1887438544 8554784 10107792
17:09:09 9002 8192000000 10784290544 11212406784 1877196224 18.642 100 17 34 1 1888006528 6294080 4516224
17:10:23 12893 8192000000 10791647232 11229696000 1892282192 18.764 100 19 37 2 1903545216 6323648 4939376
17:11:38 32028 8192000000 10809195520 11244417024 1895955136 18.794 100 19 38 0 1907522016 5045248 6521632
17:13:12 6875 8192000000 10816043632 11253661696 1905170928 18.868 100 20 40 2 1916535552 5693056 5671568
17:14:21 16008 8192000000 10823591616 11266945024 1912721744 18.928 100 15 30 0 1923132048 6100048 4310256
17:15:39 48771 8192000000 10820502752 11251449856 1904868016 18.865 100 44 86 3 1923842048 5670176 13303856
17:17:29 9735 8192000000 10750680288 11180703744 1824278096 18.213 97 22 44 0 1840291328 9725248 6287984
17:18:39 7918 8192000000 10642405264 11048198144 1676784224 16.990 96 19 38 1 1687453248 5060464 5608560
17:19:50 18836 8192000000 10705837488 11120328704 1728316400 17.421 96 17 34 0 1738821776 5423024 5082352
17:21:10 11955 8192000000 10740557120 11164049408 1823956800 18.210 100 25 50 4 1836107008 5472032 6678176
17:22:25 9570 8192000000 10749857776 11170086912 1834765600 18.298 100 26 50 3 1847091952 5006752 7319600
17:23:35 14375 8192000000 10771409904 11199967232 1856154896 18.472 100 20 40 0 1867115984 5265408 5695680
17:24:50 65828 8192000000 10787433712 11220471808 1875352320 18.628 100 17 34 0 1884874480 4686368 4835792
17:26:57 4026 8192000000 10825270576 11243094016 1893113056 18.771 100 40 80 4 1910535952 5218208 12204688
17:28:05 7277 8192000000 10873569984 11286724608 1937294608 19.125 100 73 146 1 1967854480 9666320 20893552
17:29:28 48936 8192000000 10890131104 11322773504 1956206720 19.276 100 57 114 0 1988796816 16573680 16016416
17:31:21 2616 8192000000 10898159120 11322265600 1962739104 19.328 100 52 106 1 1993844640 15463904 15641632
17:32:26 20972 8192000000 10906478768 11330584576 1959583344 19.303 100 56 112 1 1998249360 21926128 16739888
17:33:52 8299 8192000000 10923109488 11347001344 1973133168 19.410 100 53 106 2 2009866192 21695968 15037056
17:35:05 1015 8192000000 10944480816 11362512896 1980858016 19.471 100 86 169 2 2034371776 27354656 26159104
17:36:10 34541 8192000000 10932308560 11368644608 1959354400 19.301 99 47 95 1 2008665024 34950464 14360160
17:37:49 7207 8192000000 10883123440 11302133760 1913626928 18.936 99 70 140 1 1971447936 36108800 21712208
17:39:06 5030 8192000000 10896363136 11318947840 1935558976 19.111 100 59 117 1 1986904432 33355904 17989552
17:40:17 6295 8192000000 10903234352 11314544640 1909835488 18.905 99 67 134 6 1969062656 38483872 20743296
17:41:28 2986 8192000000 10905759664 11327221760 1929684432 19.064 100 49 97 0 1989959376 45036832 15238112
17:42:38 8173 8192000000 10903476928 11336892416 1937806800 19.129 100 41 82 0 1991581808 41005520 12769488
17:43:48 12732 8192000000 10913826000 11353260032 1954364592 19.261 100 29 58 0 2004540144 40346160 9829392
17:45:06 14466 8192000000 10895384896 11332083712 1907802080 18.889 98 39 78 1 1963548400 43432592 12313728
17:46:24 14179 8192000000 10938359056 11374772224 1950100800 19.227 100 38 74 1 2019349616 56217760 13031056
17:47:42 17945 8192000000 10931275792 11372670976 1942489264 19.167 100 39 78 3 2014645152 58186368 13969520
17:49:04 22984 8192000000 10887909776 11362627584 1933107280 19.092 100 25 50 1 1996707696 55024672 8575744
17:51:00 33534 8192000000 10846521056 11313737728 1904413424 18.862 99 26 52 1 1938498656 26418208 7667024
17:53:37 66716 8192000000 10849514784 11300790272 1878268928 18.651 97 26 53 1 1891190832 5992816 6929088
17:55:48 85964 8192000000 10873408224 11346325504 1923017728 19.011 99 12 22 0 1944160448 18308336 2834384
17:58:22 93618 8192000000 10914472960 11367673856 1972820704 19.408 100 16 30 0 1986747184 10211856 3714624
18:01:10 64189 8192000000 10925940544 11388829696 1976879248 19.440 99 9 18 1 1988151584 8993584 2278752
18:03:19 50357 8192000000 10923878112 11389333504 1974764720 19.423 99 11 20 0 1986737184 9446784 2525680
18:05:18 27038 8192000000 10941040016 11406012416 2010126624 19.703 100 8 16 0 2019553968 7282896 2144448
18:06:51 97122 8192000000 10965935984 11426639872 2014405360 19.736 100 46 92 3 2048320880 19367232 14548288
18:09:31 69659 8192000000 10920577680 11389775872 1987588432 19.525 99 22 42 2 2005165056 11676768 5899856
18:11:47 40101 8192000000 10932327520 11398148096 1977391600 19.444 99 23 47 0 1999158784 14889632 6877552
18:13:27 59667 8192000000 10905708640 11370921984 1923973392 19.019 97 17 33 0 1943640512 14794832 4872288
18:16:14 91049 8192000000 10883068448 11363389440 1954965280 19.266 99 11 22 0 1966776144 8554112 3256752
18:18:50 88283 8192000000 10871442768 11357208576 1949905056 19.226 99 15 30 1 1962511232 8230368 4375808
18:21:19 40431 8192000000 10879505184 11355107328 1954251360 19.260 100 11 22 0 1967648640 10241472 3155808
18:23:04 33716 8192000000 10875726272 11330703360 1879721840 18.663 96 19 38 1 1891730608 6144432 5864336
18:24:40 44370 8192000000 10826776016 11285463040 1806955312 18.071 94 19 38 0 1821425216 9270416 5199488
18:26:38 27781 8192000000 10860241440 11310366720 1908679232 18.896 99 28 56 0 1927605408 10492256 8433920
18:28:05 75692 8192000000 10855662624 11321278464 1831556864 18.272 94 15 28 1 1844023968 8712032 3755072
18:30:31 110544 8192000000 10861520112 11322183680 1810408928 18.099 93 17 34 1 1833493216 18410032 4674256
18:33:27 32541 8192000000 10851987680 11307024384 1864143744 18.537 96 17 34 2 1879742256 10268272 5330240
18:35:00 35864 8192000000 10912549008 11359330304 1936363392 19.118 99 43 86 0 1966398080 18685056 11349632
18:36:39 75035 8192000000 10882643328 11347566592 1952247456 19.244 100 25 50 0 1967114272 7784672 7082144
18:39:06 10739 8192000000 10886531808 11351314432 1949179616 19.220 100 19 38 0 1970020928 15073904 5767408
18:40:16 41965 8192000000 10880226608 11353530368 1959478944 19.302 100 12 23 2 1968912048 5653376 3779728
18:41:59 64408 8192000000 10896999680 11369467904 1974651456 19.422 100 15 30 2 1989697872 10267392 4779024
18:44:08 90862 8192000000 10888459936 11376205824 1974416496 19.420 100 20 40 1 1996361584 15453824 6491264
18:46:40 60649 8192000000 10897887392 11371831296 1962332160 19.325 99 21 43 0 1979244160 10835728 6076272
18:49:09 70598 8192000000 10910295408 11395624960 1978547616 19.453 100 9 18 0 2000130992 18308608 3274768
18:51:25 62500 8192000000 10909932768 11377496064 1933670192 19.096 97 23 46 0 1954742336 14588848 6483296
18:54:15 72685 8192000000 10907425728 11375943680 1928849936 19.058 97 22 44 1 1950192128 15445168 5897024
18:56:28 76997 8192000000 10900192480 11376271360 1879067872 18.658 95 14 28 1 1899804560 16234256 4502432
18:58:45 87259 8192000000 10908882208 11374010368 1951676320 19.240 98 31 62 2 1967318432 7295376 8346736
19:01:16 45674 8192000000 10871821056 11357343744 1943327616 19.173 99 7 14 0 1952214656 7251040 1636000
19:03:03 32391 8192000000 10880788256 11346448384 1933363072 19.094 99 17 33 0 1944291552 5817232 5111248
19:04:40 65383 8192000000 10876449968 11352219648 1867573680 18.565 95 26 51 1 1885497840 10777088 7147072
19:06:48 73772 8192000000 10891440240 11364552704 1934244656 19.101 98 21 41 2 1960306608 19538544 6523408
19:09:23 53188 8192000000 10858538432 11334123520 1889124480 18.739 97 11 22 0 1906331840 13958272 3249088
19:11:22 35632 8192000000 10819039872 11294855168 1855080832 18.463 97 27 54 0 1875486400 11669424 8736144
19:12:59 18078 8192000000 10847695168 11282300928 1880608096 18.670 99 49 96 2 1902497504 8613984 13275424
19:14:48 108156 8192000000 10852358992 11315748864 1848981952 18.414 96 12 24 1 1872704720 20426048 3296720
19:17:37 94042 8192000000 10867422256 11329179648 1909439072 18.902 99 24 48 0 1930764768 14279760 7045936
19:20:19 63200 8192000000 10861332000 11334287360 1923070224 19.011 99 11 22 0 1938777968 12177040 3530704
19:22:22 73715 8192000000 10887227312 11356459008 1947356128 19.205 100 10 20 0 1963761536 13515536 2889872
19:24:41 79223 8192000000 10890720592 11370037248 1966025280 19.354 100 24 47 0 1983586352 10847376 6713696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-10-2020 19:28:53 to 17-10-2020 19:29:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 1 srv_aggregate_perf_data 335544517
user_exc 140 v_add_invoice_to_stock 335544517
user_exc 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2020 19:29:08 to 17-10-2020 19:29:10.

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          260937
MON$OLDEST_ACTIVE               261779
MON$OLDEST_SNAPSHOT             261779
MON$NEXT_TRANSACTION            261779
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                       2062624
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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Oct 17 19:26:59 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Sat Oct 17 19:27:01 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Sat Oct 17 19:27:02 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Oct 17 19:27:14 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

17.10.20 19:29:11. 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 19:29:13 No severe PSQL-related problems occured
17.10.20 19:29:15. End of report.