Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.10.2020 16:32
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 17583648 kB
MemAvailable 31596740 kB
Buffers 0 kB
Cached 14138352 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-21 16:30:43
DTS_END                         2020-10-21 16:30:43.0550
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/45584
TRN_ID                          241585
ATT_ID                          601
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: 9130.19 26832 1095623
customer order: creation 172.06 355 20647
customer order: refuse 14.63 335 1756
order to supplier: creation 47.43 1382 5691
order to supplier: removal 3.58 102810 429
invoice (draft): creation 46.75 3674 5610
invoice (draft): removal 51.48 956 6178
invoice accept: apply 41.90 16809 5028
invoice accept: cancel 46.18 917 5542
customer reserve: creation 3895.14 164 467417
customer reserve: removal 4520.68 63 542482
realization accept: apply 84.71 182 10165
realization accept: cancel 73.13 117 8776
payment from customer: creation 60.78 120 7294
payment from customer: removal 5.79 104 695
payment to supplier: creation 50.01 131 6001
payment to supplier: removal 3.68 59 441
service: total inventory turnovers 4.69 1186 563
service: total monetary turnovers 6.68 27 802
service: aggregate perf. data 0.85 19869 102
service: refresh index statistics 0.03 387384 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-10-2020 16:32:54 to 21-10-2020 16:32:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2230
TEST_TIME 2 2995
TEST_TIME 3 2588
TEST_TIME 4 2740
TEST_TIME 5 2879
TEST_TIME 6 3626
TEST_TIME 7 3852
TEST_TIME 8 4222
TEST_TIME 9 4195
TEST_TIME 10 4205
TEST_TIME 11 4722
TEST_TIME 12 4666
TEST_TIME 13 4859
TEST_TIME 14 4987
TEST_TIME 15 5103
TEST_TIME 16 5261
TEST_TIME 17 5737
TEST_TIME 18 6162
TEST_TIME 19 6427
TEST_TIME 20 6696
TEST_TIME 21 6627
TEST_TIME 22 6659
TEST_TIME 23 7074
TEST_TIME 24 7502
TEST_TIME 25 8138
TEST_TIME 26 8796
TEST_TIME 27 9315
TEST_TIME 28 9799
TEST_TIME 29 10221
TEST_TIME 30 10140
TEST_TIME 31 10360
TEST_TIME 32 10211
TEST_TIME 33 10126
TEST_TIME 34 9835
TEST_TIME 35 9865
TEST_TIME 36 9663
TEST_TIME 37 9640
TEST_TIME 38 9648
TEST_TIME 39 9741
TEST_TIME 40 9634
TEST_TIME 41 9543
TEST_TIME 42 9424
TEST_TIME 43 9457
TEST_TIME 44 9429
TEST_TIME 45 9428
TEST_TIME 46 9389
TEST_TIME 47 9314
TEST_TIME 48 9256
TEST_TIME 49 9243
TEST_TIME 50 9148
TEST_TIME 51 9210
TEST_TIME 52 9124
TEST_TIME 53 9061
TEST_TIME 54 9235
TEST_TIME 55 9188
TEST_TIME 56 9137
TEST_TIME 57 9229
TEST_TIME 58 9260
TEST_TIME 59 9209
TEST_TIME 60 9384
TEST_TIME 61 9373
TEST_TIME 62 9501
TEST_TIME 63 9558
TEST_TIME 64 9583
TEST_TIME 65 9539
TEST_TIME 66 9625
TEST_TIME 67 9636
TEST_TIME 68 9560
TEST_TIME 69 9497
TEST_TIME 70 9474
TEST_TIME 71 9435
TEST_TIME 72 9434
TEST_TIME 73 9407
TEST_TIME 74 9368
TEST_TIME 75 9337
TEST_TIME 76 9333
TEST_TIME 77 9229
TEST_TIME 78 9215
TEST_TIME 79 9187
TEST_TIME 80 9168
TEST_TIME 81 9157
TEST_TIME 82 9180
TEST_TIME 83 9219
TEST_TIME 84 9227
TEST_TIME 85 9216
TEST_TIME 86 9240
TEST_TIME 87 9202
TEST_TIME 88 9281
TEST_TIME 89 9308
TEST_TIME 90 9298
TEST_TIME 91 9388
TEST_TIME 92 9459
TEST_TIME 93 9380
TEST_TIME 94 9309
TEST_TIME 95 9262
TEST_TIME 96 9251
TEST_TIME 97 9256
TEST_TIME 98 9271
TEST_TIME 99 9244
TEST_TIME 100 9199
TEST_TIME 101 9200
TEST_TIME 102 9214
TEST_TIME 103 9247
TEST_TIME 104 9270
TEST_TIME 105 9315
TEST_TIME 106 9296
TEST_TIME 107 9351
TEST_TIME 108 9375
TEST_TIME 109 9355
TEST_TIME 110 9400
TEST_TIME 111 9360
TEST_TIME 112 9308
TEST_TIME 113 9298
TEST_TIME 114 9259
TEST_TIME 115 9280
TEST_TIME 116 9253
TEST_TIME 117 9211
TEST_TIME 118 9199
TEST_TIME 119 9159
TEST_TIME 120 9131

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-10-2020 16:32:58 to 21-10-2020 16:33:01.

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 1527444 1527444 0 0.00 0 25756 6 0 0
doc_list_biud 1527442 1527442 0 0.00 0 46562 29 0 0
sp_add_invoice_to_stock 5145 5028 117 2.27 35 216460 16809 0 117
sp_cancel_adding_invoice 5542 5542 0 0.00 4 105993 917 0 0
sp_cancel_client_order 1756 1756 0 0.00 4 11087 336 0 0
sp_cancel_customer_reserve 542482 542482 0 0.00 1 20776 64 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 2 12152 105 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 3074 59 0 0
sp_cancel_supplier_invoice 6189 6178 11 0.17 2 20689 956 0 11
sp_cancel_supplier_order 429 429 0 0.00 8 1244426 102810 0 0
sp_cancel_write_off 8776 8776 0 0.00 1 14232 117 0 0
sp_client_order 20647 20647 0 0.00 3 25771 355 0 0
sp_customer_reserve 467417 467417 0 0.00 0 126378 165 0 0
sp_fill_shopping_cart 31943 31943 0 0.00 1 17154 42 0 0
sp_fill_shopping_cart_clo_res 467414 467414 0 0.00 0 15252 14 0 0
sp_get_clo_for_invoice 5025 5025 0 0.00 0 24862 322 0 0
sp_kill_cost_storno 15454 15454 0 0.00 0 14188 60 0 0
sp_kill_qstorno_ret_qs2qd 1100347 1100347 0 0.00 0 20743 35 0 0
sp_kill_qty_storno 580353 580353 0 0.00 0 46562 76 0 0
sp_lock_selected_doc 21107 21107 0 0.00 0 8944 4 0 0
sp_make_cost_storno 28484 28484 0 0.00 0 18073 58 0 0
sp_multiply_rows_for_pdistr 28484 28484 0 0.00 0 19823 28 0 0
sp_multiply_rows_for_qdistr 20647 20647 0 0.00 0 13213 150 0 0
sp_pay_from_customer 7294 7294 0 0.00 4 21280 121 0 0
sp_pay_to_supplier 6001 6001 0 0.00 4 25697 131 0 0
sp_payment_common 13294 13294 0 0.00 1 19858 35 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 11080 309 0 0
sp_qd_handle_on_invoice_upd_sts 10567 10567 0 0.00 0 46561 345 0 0
sp_qd_handle_on_reserve_upd_sts 10165 10165 0 0.00 0 18422 58 0 0
sp_reserve_write_off 10165 10165 0 0.00 6 25795 182 0 0
sp_supplier_invoice 5610 5610 0 0.00 6 87562 3675 0 0
sp_supplier_order 5691 5691 0 0.00 6 42706 1383 0 0
srv_aggregate_perf_data 102 102 0 0.00 2448 325205 19870 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1412 150637 52001 0 0
srv_find_qd_qs_mism 1046686 1046686 0 0.00 0 10250 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 36 101396 1186 0 0
srv_make_money_saldo 802 802 0 0.00 1 3511 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 137706 600127 387384 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 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-norm:sp_add_invoice_to_stock 5028 5028 0 0.00 0 8729 173 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 3951 122 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 4804 14 0 0
t$perf-norm:sp_cancel_customer_reserve 772 772 0 0.00 0 5255 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 5471 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1033 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 513 8 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 27044 1502 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 3992 11 0 0
t$perf-norm:sp_client_order 20647 20647 0 0.00 0 11079 16 0 0
t$perf-norm:sp_customer_reserve 969 969 0 0.00 0 4874 13 0 0
t$perf-norm:sp_pay_from_customer 7294 7294 0 0.00 0 5196 8 0 0
t$perf-norm:sp_pay_to_supplier 6001 6001 0 0.00 0 1655 6 0 0
t$perf-norm:sp_reserve_write_off 10165 10165 0 0.00 0 6757 12 0 0
t$perf-norm:sp_supplier_invoice 5610 5610 0 0.00 0 5877 18 0 0
t$perf-norm:sp_supplier_order 5691 5691 0 0.00 0 5504 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 157 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 14380 48 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 3447 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10282 10048 234 2.27 0 7312 19 0 234
v_all_customers 38034 38034 0 0.00 0 20 1 0 0
v_all_suppliers 31214 31214 0 0.00 0 32 1 0 0
v_all_wares 41292 41292 0 0.00 0 139 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3746 10 0 0
v_cancel_client_order 3512 3512 0 0.00 0 3384 8 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 5275 16 0 0
v_cancel_customer_reserve 1544 1544 0 0.00 0 2144 8 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 3067 8 0 0
v_cancel_supplier_invoice 796 774 22 2.76 0 1015 14 0 22
v_cancel_supplier_order 858 858 0 0.00 0 3384 9 0 0
v_cancel_write_off 2034 2034 0 0.00 0 1576 13 0 0
v_max_id_clo_ord 5690 5690 0 0.00 0 3785 2 0 0
v_max_id_clo_res 969 969 0 0.00 0 3113 17 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 7497 6 0 0
v_max_non_paid_invoice 1690 1690 0 0.00 0 1574 3 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 6855 8 0 0
v_min_id_clo_ord 5690 5690 0 0.00 0 3941 7 0 0
v_min_id_clo_res 969 969 0 0.00 0 1268 5 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 6207 10 0 0
v_min_non_paid_invoice 6000 6000 0 0.00 0 13751 19 0 0
v_min_non_paid_realizn 7294 7294 0 0.00 0 18806 28 0 0
v_our_firm 10428 10428 0 0.00 0 18 1 0 0
v_reserve_write_off 20330 20330 0 0.00 0 6513 8 0 0
x_lock_depdocs_on_canc_invoice 11333 11333 0 0.00 0 22695 122 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 8941 362 0 0
x_make_qstorno_1000_1200 5691 5691 0 0.00 2 42701 1295 0 0
x_make_qstorno_1200_2000 5610 5610 0 0.00 2 86499 3523 0 0
x_make_qstorno_2100_3300 467417 467417 0 0.00 0 126376 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-10-2020 16:33:02 to 21-10-2020 16:33:06.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:00:46 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
14:01:47 34470 8192000000 10372121264 10691362816 1415743280 14.735 100 88 176 0 1449675648 5711200 28221168
14:03:26 20098 8192000000 10440610240 10773217280 1490021712 15.389 100 86 172 0 1525288352 7664176 27602464
14:04:50 15714 8192000000 10480534048 10825666560 1536744240 15.795 100 78 156 0 1570335280 9564768 24026272
14:06:11 12416 8192000000 10542978720 10901495808 1603167216 16.366 100 79 158 1 1638330064 11062832 24100016
14:07:25 12160 8192000000 10625357744 10994036736 1681256624 17.028 100 75 150 1 1714763984 11477168 22030192
14:08:40 6074 8192000000 10683742416 11060731904 1746454400 17.572 100 80 160 1 1780550496 11489200 22606896
14:09:48 4258 8192000000 10736046240 11115470848 1794741792 17.971 100 87 175 1 1833247216 13819184 24686240
14:10:55 1598 8192000000 10774433920 11163639808 1835199760 18.302 100 83 164 1 1872022720 13770640 23052320
14:11:59 1687 8192000000 10803203808 11190751232 1863540288 18.532 100 95 190 3 1903976400 13803184 26632928
14:13:02 23871 8192000000 10793482832 11206213632 1871884640 18.600 100 41 82 1 1892265696 8222608 12158448
14:14:27 4706 8192000000 10798889792 11220029440 1885297824 18.708 100 43 86 2 1905952368 7425152 13229392
14:15:33 9419 8192000000 10798311120 11232411648 1891239520 18.756 100 20 41 0 1915312432 18736304 5336608
14:16:44 24711 8192000000 10806622592 11244777472 1903775104 18.857 100 27 53 3 1917620432 5128896 8716432
14:18:09 11569 8192000000 10818694320 11256180736 1904219776 18.860 100 20 40 2 1915366064 5589136 5557152
14:19:23 3615 8192000000 10829613152 11259662336 1911332704 18.917 100 35 70 2 1928932336 7663824 9935808
14:20:27 13014 8192000000 10809646960 11250319360 1903061120 18.851 100 15 30 1 1912891872 6051504 3779248
14:21:41 18419 8192000000 10763505664 11174473728 1756810048 17.658 94 30 58 0 1770155552 5131040 8214464
14:23:01 6576 8192000000 10754334160 11180580864 1839666400 18.338 100 45 90 2 1860404784 7990192 12748192
14:24:09 5155 8192000000 10728944336 11154612224 1806328704 18.066 99 25 50 0 1820128816 6033904 7766208
14:25:17 12555 8192000000 10718531728 11135025152 1791592944 17.945 99 21 42 1 1803277424 5285744 6398736
14:26:32 9469 8192000000 10736882352 11154358272 1822267008 18.196 100 22 44 0 1834319680 5079872 6972800
14:27:42 22693 8192000000 10761264112 11185373184 1838697856 18.330 100 21 42 1 1854655392 10433360 5524176
14:29:08 6723 8192000000 10769564624 11196346368 1855794688 18.469 100 18 36 0 1865944704 5116432 5033584
14:30:20 7020 8192000000 10801061248 11227529216 1893209232 18.772 100 31 63 2 1911819728 8706448 9904048
14:31:32 17336 8192000000 10847454880 11271741440 1914894480 18.946 100 42 84 1 1934624448 8145024 11584944
14:32:53 86249 8192000000 10869063056 11305779200 1939591568 19.143 100 38 76 0 1961216752 10803104 10822080
14:35:26 13402 8192000000 10898874512 11329609728 1962049568 19.322 100 44 88 1 1988624144 13816624 12757952
14:36:55 17667 8192000000 10923505488 11353378816 1979613408 19.462 100 42 84 0 2009587568 17037504 12936656
14:38:30 9625 8192000000 10955231376 11378757632 1995769984 19.589 100 61 116 0 2033653664 21297104 16586576
14:39:52 19213 8192000000 10954985168 11389399040 2002693888 19.644 100 50 100 0 2045181968 27289328 15198752
14:41:16 5176 8192000000 10985673712 11413164032 2022497168 19.800 100 62 123 3 2073167440 32596736 18073536
14:42:25 18935 8192000000 10976625296 11412414464 2012978752 19.725 100 49 96 2 2064181008 36017808 15184448
14:43:50 6517 8192000000 10965751600 11401138176 1996203472 19.593 99 45 90 1 2049307296 38524720 14579104
14:45:03 3184 8192000000 10969389312 11397001216 1993795648 19.574 99 61 121 1 2054015072 40636368 19583056
14:46:17 9031 8192000000 10988520112 11426365440 2016122496 19.750 100 62 125 2 2080820624 45162464 19535664
14:47:29 3447 8192000000 10995694176 11417837568 2008523600 19.690 100 78 153 1 2076841712 45227008 23091104
14:48:36 3594 8192000000 10941237120 11358011392 1954026592 19.259 99 71 141 0 2023950992 47266512 22657888
14:49:44 6122 8192000000 10946545520 11381968896 1978980656 19.457 100 45 89 2 2037750912 44003376 14766880
14:50:56 7151 8192000000 10963448576 11392303104 1972206544 19.403 99 52 102 1 2034169232 45487808 16474880
14:52:12 1411 8192000000 10982602368 11396820992 1982053552 19.481 100 83 165 1 2062949056 54553984 26341520
14:53:19 11476 8192000000 10958098720 11387760640 1935094784 19.108 98 55 108 2 2017207840 62605776 19507280
14:54:37 7187 8192000000 10962220496 11394392064 1958830208 19.297 100 58 117 0 2042722512 63930240 19962064
14:55:53 32363 8192000000 10930845568 11382661120 1945488272 19.191 100 28 55 1 2018575232 62253296 10833664
14:57:41 41203 8192000000 10877567600 11335667712 1928014816 19.051 100 11 21 1 1966364720 34476288 3873616
14:59:34 80365 8192000000 10872632176 11333541888 1900483088 18.830 98 9 18 0 1922684976 19744032 2457856
15:01:54 125816 8192000000 10871580976 11337764864 1873548256 18.613 96 18 35 1 1896093888 16747632 5798000
15:05:32 35428 8192000000 10854313504 11319566336 1924701920 19.024 99 15 30 0 1938690240 10031344 3956976
15:07:09 72431 8192000000 10874784352 11330629632 1922789152 19.009 99 22 44 1 1937922128 9043472 6089504
15:09:29 19464 8192000000 10876806736 11339272192 1930528352 19.071 98 17 35 0 1942426592 7495392 4402848
15:11:27 142244 8192000000 10901781424 11365978112 1955530832 19.271 99 21 42 1 1973508624 11837856 6139936
15:14:50 58349 8192000000 10917117488 11365634048 1961795680 19.320 99 15 29 1 1976389072 10374112 4219280
15:16:54 34881 8192000000 10891093408 11345100800 1938268544 19.133 98 34 69 1 1955101648 7100848 9732256
15:18:31 56758 8192000000 10897962912 11363979264 1959311008 19.301 99 29 58 0 1978984160 11004944 8668208
15:20:30 53987 8192000000 10904764704 11377565696 1984179696 19.498 100 12 24 0 1999952400 11457280 4315424
15:22:24 68310 8192000000 10916047744 11379564544 1967541744 19.366 99 25 49 0 1980356928 6422736 6392448
15:24:35 74735 8192000000 10883200240 11366580224 1935381136 19.110 98 6 12 0 1948117376 11345040 1391200
15:26:50 102100 8192000000 10876179200 11345457152 1892816048 18.768 96 17 34 1 1914891536 16705760 5369728
15:29:42 46508 8192000000 10902360672 11365101568 1936956960 19.122 98 33 66 0 1963404224 17363696 9083568
15:31:29 114179 8192000000 10908801856 11393110016 1931462272 19.079 97 14 28 1 1956563264 21691328 3409664
15:34:23 93297 8192000000 10923358688 11372994560 1852633040 18.444 93 31 62 0 1871263408 10434560 8195808
15:36:57 59311 8192000000 10884294912 11330912256 1925417696 19.030 99 25 49 0 1944277360 12056416 6803248
15:38:58 96593 8192000000 10880214304 11343958016 1877356992 18.644 96 18 36 0 1889389376 7095264 4937120
15:41:46 14753 8192000000 10849199504 11311845376 1923221008 19.013 100 18 35 1 1937939216 9700640 5017568
15:43:02 119617 8192000000 10808527712 11274412032 1802336048 18.033 93 12 24 0 1821770768 15657856 3776864
15:46:02 67598 8192000000 10822705456 11288236032 1894609616 18.783 99 14 28 0 1906900240 8282416 4008208
15:48:10 78536 8192000000 10828377104 11308265472 1908565840 18.895 99 13 27 0 1919155008 7249936 3339232
15:50:28 41574 8192000000 10865706784 11330179072 1923260224 19.013 99 20 40 3 1935308416 6397472 5650720
15:52:26 31355 8192000000 10836399536 11303800832 1871428992 18.596 97 9 18 0 1882620192 8761376 2429824
15:53:59 56714 8192000000 10859819920 11320369152 1878615136 18.654 97 17 34 1 1891754176 8603792 4535248
15:56:04 31479 8192000000 10885554640 11334660096 1927106144 19.044 99 18 36 2 1943412448 10403488 5902816
15:57:37 55232 8192000000 10887048304 11353882624 1940319248 19.149 99 31 62 0 1962957520 13638592 8999680
15:59:47 56832 8192000000 10883829184 11349766144 1925519552 19.031 98 19 38 1 1944321040 13976240 4825248
16:01:45 150637 8192000000 10898112544 11350601728 1905069920 18.867 97 22 44 0 1923473200 12746608 5656672
16:05:23 68405 8192000000 10901698832 11382480896 1956747040 19.280 99 16 32 1 1976486208 15330000 4409168
16:08:13 50214 8192000000 10893671248 11377049600 1955998992 19.274 99 39 78 0 1980021120 12795408 11226720
16:10:04 25214 8192000000 10926336176 11372175360 1978661376 19.454 100 44 88 1 2002379760 10210592 13507792
16:11:34 82135 8192000000 10919755840 11394940928 1961887744 19.321 99 11 22 0 1976117984 10961280 3268960
16:14:05 124548 8192000000 10852631072 11321065472 1801678640 18.028 91 8 16 1 1815082896 11165824 2238432
16:17:10 38766 8192000000 10880099376 11353624576 1935255328 19.109 99 45 90 1 1960683872 12220176 13208368
16:18:58 64185 8192000000 10885822144 11341058048 1922777616 19.009 98 34 66 0 1940952624 8578640 9596368
16:21:12 115609 8192000000 10825619328 11283918848 1831661792 18.273 94 28 56 0 1848302576 8568000 8072784
16:24:46 20904 8192000000 10839793824 11308367872 1877873360 18.648 97 41 82 3 1901318640 11358960 12086320
16:26:38 62190 8192000000 10836299536 11303211008 1881902624 18.680 97 12 24 0 1895603808 10033040 3668144
16:28:44 108447 8192000000 10860471744 11337011200 1910684320 18.912 98 11 22 0 1927708336 13861408 3162608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-10-2020 16:33:08 to 21-10-2020 16:33:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 117 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 21-10-2020 16:33:23 to 21-10-2020 16:33:25.

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          237937
MON$OLDEST_ACTIVE               241716
MON$OLDEST_SNAPSHOT             241716
MON$NEXT_TRANSACTION            241716
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                       2026208
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.33378 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33378 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33378 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33378 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Wed Oct 21 16:30:45 2020
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Wed Oct 21 16:30:59 2020
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

21.10.20 16:33:26. 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-21 16:33:29 No severe PSQL-related problems occured
21.10.20 16:33:30. End of report.