Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.10.2020 16: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 17507744 kB
MemAvailable 31551180 kB
Buffers 0 kB
Cached 14216932 kB
SwapCached 3064 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-28 16:26:58
DTS_END                         2020-10-28 16:26:58.7720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(56:17)
IP                              ::1/38304
TRN_ID                          243730
ATT_ID                          654
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: 9278.17 26212 1113380
customer order: creation 174.56 320 20947
customer order: refuse 15.56 340 1867
order to supplier: creation 47.48 1198 5697
order to supplier: removal 3.65 91836 438
invoice (draft): creation 46.98 3005 5638
invoice (draft): removal 52.76 764 6331
invoice accept: apply 42.13 16180 5056
invoice accept: cancel 46.90 814 5628
customer reserve: creation 3887.17 159 466460
customer reserve: removal 4662.98 56 559557
realization accept: apply 84.80 182 10176
realization accept: cancel 79.29 125 9515
payment from customer: creation 61.24 128 7349
payment from customer: removal 5.93 78 711
payment to supplier: creation 50.53 149 6063
payment to supplier: removal 3.30 96 396
service: total inventory turnovers 5.13 1033 616
service: total monetary turnovers 6.88 26 826
service: aggregate perf. data 0.88 16890 105
service: refresh index statistics 0.03 390860 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-10-2020 16:28:42 to 28-10-2020 16:28:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2385
TEST_TIME 2 3027
TEST_TIME 3 4077
TEST_TIME 4 3988
TEST_TIME 5 3408
TEST_TIME 6 3677
TEST_TIME 7 4336
TEST_TIME 8 4588
TEST_TIME 9 4812
TEST_TIME 10 5195
TEST_TIME 11 5652
TEST_TIME 12 6137
TEST_TIME 13 6091
TEST_TIME 14 6359
TEST_TIME 15 6446
TEST_TIME 16 6545
TEST_TIME 17 7601
TEST_TIME 18 7682
TEST_TIME 19 8388
TEST_TIME 20 8617
TEST_TIME 21 8822
TEST_TIME 22 8865
TEST_TIME 23 9233
TEST_TIME 24 9235
TEST_TIME 25 9489
TEST_TIME 26 9631
TEST_TIME 27 10329
TEST_TIME 28 10758
TEST_TIME 29 10582
TEST_TIME 30 10564
TEST_TIME 31 10390
TEST_TIME 32 10409
TEST_TIME 33 10315
TEST_TIME 34 10332
TEST_TIME 35 10254
TEST_TIME 36 10234
TEST_TIME 37 10218
TEST_TIME 38 10078
TEST_TIME 39 9926
TEST_TIME 40 9943
TEST_TIME 41 9812
TEST_TIME 42 9769
TEST_TIME 43 9729
TEST_TIME 44 9742
TEST_TIME 45 9670
TEST_TIME 46 9877
TEST_TIME 47 9816
TEST_TIME 48 9717
TEST_TIME 49 9723
TEST_TIME 50 9613
TEST_TIME 51 9589
TEST_TIME 52 9625
TEST_TIME 53 9557
TEST_TIME 54 9655
TEST_TIME 55 9668
TEST_TIME 56 9690
TEST_TIME 57 9672
TEST_TIME 58 9784
TEST_TIME 59 9687
TEST_TIME 60 9696
TEST_TIME 61 9857
TEST_TIME 62 9810
TEST_TIME 63 9815
TEST_TIME 64 9774
TEST_TIME 65 9651
TEST_TIME 66 9591
TEST_TIME 67 9543
TEST_TIME 68 9494
TEST_TIME 69 9451
TEST_TIME 70 9428
TEST_TIME 71 9475
TEST_TIME 72 9446
TEST_TIME 73 9392
TEST_TIME 74 9402
TEST_TIME 75 9425
TEST_TIME 76 9405
TEST_TIME 77 9435
TEST_TIME 78 9501
TEST_TIME 79 9518
TEST_TIME 80 9535
TEST_TIME 81 9590
TEST_TIME 82 9578
TEST_TIME 83 9625
TEST_TIME 84 9586
TEST_TIME 85 9505
TEST_TIME 86 9463
TEST_TIME 87 9406
TEST_TIME 88 9445
TEST_TIME 89 9399
TEST_TIME 90 9360
TEST_TIME 91 9303
TEST_TIME 92 9234
TEST_TIME 93 9258
TEST_TIME 94 9340
TEST_TIME 95 9316
TEST_TIME 96 9353
TEST_TIME 97 9345
TEST_TIME 98 9356
TEST_TIME 99 9299
TEST_TIME 100 9357
TEST_TIME 101 9457
TEST_TIME 102 9468
TEST_TIME 103 9444
TEST_TIME 104 9453
TEST_TIME 105 9572
TEST_TIME 106 9529
TEST_TIME 107 9506
TEST_TIME 108 9506
TEST_TIME 109 9446
TEST_TIME 110 9417
TEST_TIME 111 9435
TEST_TIME 112 9440
TEST_TIME 113 9432
TEST_TIME 114 9417
TEST_TIME 115 9381
TEST_TIME 116 9340
TEST_TIME 117 9335
TEST_TIME 118 9299
TEST_TIME 119 9282
TEST_TIME 120 9279

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-10-2020 16:28:46 to 28-10-2020 16:28:49.

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 1546541 1546541 0 0.00 0 22638 6 0 0
doc_list_biud 1546540 1546540 0 0.00 0 51998 26 0 0
sp_add_invoice_to_stock 5185 5056 129 2.48 31 153151 16180 0 129
sp_cancel_adding_invoice 5628 5628 0 0.00 4 59431 815 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 13766 340 0 0
sp_cancel_customer_reserve 559557 559557 0 0.00 1 42552 56 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 3 5437 79 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 7902 97 0 0
sp_cancel_supplier_invoice 6342 6331 11 0.17 1 20869 764 0 11
sp_cancel_supplier_order 438 438 0 0.00 7 795957 91837 0 0
sp_cancel_write_off 9515 9515 0 0.00 1 18297 126 0 0
sp_client_order 20947 20947 0 0.00 3 18664 321 0 0
sp_customer_reserve 466460 466460 0 0.00 0 92795 159 0 0
sp_fill_shopping_cart 32264 32264 0 0.00 1 15317 39 0 0
sp_fill_shopping_cart_clo_res 466453 466453 0 0.00 0 18362 13 0 0
sp_get_clo_for_invoice 5049 5049 0 0.00 0 24989 281 0 0
sp_kill_cost_storno 16250 16250 0 0.00 0 18290 61 0 0
sp_kill_qstorno_ret_qs2qd 1135398 1135398 0 0.00 0 42546 31 0 0
sp_kill_qty_storno 598560 598560 0 0.00 0 51996 67 0 0
sp_lock_selected_doc 21277 21277 0 0.00 0 8449 5 0 0
sp_make_cost_storno 28636 28636 0 0.00 0 22633 55 0 0
sp_multiply_rows_for_pdistr 28636 28636 0 0.00 0 20532 35 0 0
sp_multiply_rows_for_qdistr 20947 20947 0 0.00 0 17671 148 0 0
sp_pay_from_customer 7349 7349 0 0.00 4 16242 129 0 0
sp_pay_to_supplier 6063 6063 0 0.00 5 20539 149 0 0
sp_payment_common 13412 13412 0 0.00 1 17287 46 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 13758 306 0 0
sp_qd_handle_on_invoice_upd_sts 10676 10676 0 0.00 0 51992 347 0 0
sp_qd_handle_on_reserve_upd_sts 10176 10176 0 0.00 0 21661 62 0 0
sp_reserve_write_off 10176 10176 0 0.00 6 22651 183 0 0
sp_supplier_invoice 5638 5638 0 0.00 5 96699 3006 0 0
sp_supplier_order 5697 5697 0 0.00 4 27522 1198 0 0
srv_aggregate_perf_data 105 105 0 0.00 2407 141404 16890 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1809 139796 51082 0 0
srv_find_qd_qs_mism 1065303 1065303 0 0.00 0 15000 5 0 0
srv_make_invnt_saldo 616 616 0 0.00 20 66049 1034 0 0
srv_make_money_saldo 826 826 0 0.00 1 3218 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 269400 574148 390861 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 5056 5056 0 0.00 0 20713 147 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3800 104 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 5210 17 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 3053 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 15616 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 441 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 654 11 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 26101 1457 0 0
t$perf-norm:sp_cancel_write_off 1093 1093 0 0.00 0 3817 9 0 0
t$perf-norm:sp_client_order 20947 20947 0 0.00 0 6421 15 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 2042 7 0 0
t$perf-norm:sp_pay_from_customer 7349 7349 0 0.00 0 3607 7 0 0
t$perf-norm:sp_pay_to_supplier 6063 6063 0 0.00 0 8471 12 0 0
t$perf-norm:sp_reserve_write_off 10176 10176 0 0.00 0 9442 11 0 0
t$perf-norm:sp_supplier_invoice 5638 5638 0 0.00 0 8399 16 0 0
t$perf-norm:sp_supplier_order 5697 5697 0 0.00 0 15980 24 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 215 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 60 1 0 0
t$perf-norm:srv_make_invnt_saldo 616 616 0 0.00 0 4366 30 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 8303 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 14 6 0 0
v_add_invoice_to_stock 10354 10096 258 2.49 0 8548 17 0 258
v_all_customers 38150 38150 0 0.00 0 17 1 0 0
v_all_suppliers 31620 31620 0 0.00 0 18 1 0 0
v_all_wares 41894 41894 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1793 10 0 0
v_cancel_client_order 3734 3734 0 0.00 0 4249 7 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1331 3 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 1219 4 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 3773 11 0 0
v_cancel_supplier_invoice 880 858 22 2.50 0 4545 17 0 22
v_cancel_supplier_order 876 876 0 0.00 0 5764 9 0 0
v_cancel_write_off 2186 2186 0 0.00 0 4200 15 0 0
v_max_id_clo_ord 5697 5697 0 0.00 0 1983 2 0 0
v_max_id_clo_res 964 964 0 0.00 0 2129 21 0 0
v_max_id_ord_sup 5620 5620 0 0.00 0 5225 5 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 3105 5 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 1688 4 0 0
v_min_id_clo_ord 5697 5697 0 0.00 0 2982 7 0 0
v_min_id_clo_res 964 964 0 0.00 0 4089 10 0 0
v_min_id_ord_sup 5620 5620 0 0.00 0 4520 5 0 0
v_min_non_paid_invoice 6063 6063 0 0.00 0 17278 24 0 0
v_min_non_paid_realizn 7349 7349 0 0.00 0 14890 38 0 0
v_our_firm 10674 10674 0 0.00 0 15 1 0 0
v_reserve_write_off 20352 20352 0 0.00 0 4555 5 0 0
x_lock_depdocs_on_canc_invoice 11530 11530 0 0.00 0 22756 121 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 26701 399 0 0
x_make_qstorno_1000_1200 5697 5697 0 0.00 1 27517 1120 0 0
x_make_qstorno_1200_2000 5638 5638 0 0.00 2 96686 2867 0 0
x_make_qstorno_2100_3300 466460 466460 0 0.00 0 92792 143 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-10-2020 16:28:50 to 28-10-2020 16:28:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
13:57:02 1 8192000000 8798422528 8808923136 5084624 0.062 1 1 2 0 5202672 55488 62560
13:58:03 23179 8192000000 10355542560 10675077120 1414535760 14.724 100 88 176 0 1450219776 6407904 29276112
13:59:30 23489 8192000000 10428163824 10757902336 1476487344 15.271 100 86 172 1 1512518752 7948928 28082480
14:00:58 14262 8192000000 10466501856 10810646528 1527143488 15.712 100 85 170 0 1563507168 9152640 27211040
14:02:16 12555 8192000000 10559286288 10915835904 1608525712 16.412 100 79 157 2 1642038656 9592192 23920752
14:03:32 9197 8192000000 10614822480 10988122112 1680701216 17.023 100 72 145 0 1712413664 10968352 20744096
14:04:44 6061 8192000000 10682280304 11059785728 1738707376 17.508 100 72 143 0 1770188720 10789296 20692048
14:05:52 6732 8192000000 10736841296 11114860544 1796112832 17.982 100 90 179 2 1835771824 14840560 24818432
14:07:01 2589 8192000000 10772902480 11160813568 1834447616 18.296 100 89 180 0 1873607472 13927840 25232016
14:08:06 1556 8192000000 10811964096 11199401984 1864495216 18.540 100 88 176 1 1903064144 13677088 24891840
14:09:08 25649 8192000000 10784623584 11201683456 1869433328 18.580 100 36 73 2 1888005552 6136992 12435232
14:10:35 9919 8192000000 10795808864 11220799488 1881759632 18.679 100 28 57 0 1895243632 5984240 7499760
14:11:45 8898 8192000000 10790432256 11224883200 1885794912 18.712 100 21 39 3 1896992960 5367584 5830464
14:12:55 8786 8192000000 10795386432 11236085760 1896716784 18.800 100 25 50 2 1910249840 6361008 7172048
14:14:05 7526 8192000000 10807373008 11235618816 1893851296 18.777 100 23 47 1 1905421264 5759232 5810736
14:15:13 60151 8192000000 10853571968 11254554624 1909306160 18.901 100 68 140 0 1936866944 5710448 21850336
14:17:13 5992 8192000000 10797267920 11231719424 1886136240 18.715 100 30 60 0 1904917568 10359632 8421696
14:18:22 6834 8192000000 10748010576 11167088640 1771851664 17.782 96 22 44 1 1784134080 5245616 7036800
14:19:29 28077 8192000000 10717905424 11113312256 1702168400 17.203 93 51 103 0 1722577328 4603280 15805648
14:20:58 8615 8192000000 10690033696 11112509440 1728053248 17.419 96 20 40 2 1738943072 4967904 5921920
14:22:07 4829 8192000000 10739065248 11154251776 1803614928 18.044 99 28 57 1 1817828208 6089136 8124144
14:23:15 16553 8192000000 10746093504 11175460864 1825514000 18.223 99 24 48 1 1838078112 5664480 6899632
14:24:33 22679 8192000000 10763698768 11195256832 1851798272 18.437 100 20 40 0 1862710624 5717792 5194560
14:26:00 8983 8192000000 10781681840 11211759616 1870664144 18.590 100 33 67 2 1885038848 5050592 9324112
14:27:10 16898 8192000000 10828084544 11245109248 1896904880 18.801 100 45 91 1 1915869440 6036272 12928288
14:28:36 23686 8192000000 10869105248 11292098560 1936136752 19.116 100 42 84 0 1958259280 9685120 12437408
14:30:15 89507 8192000000 10913850640 11336732672 1960141360 19.307 100 36 72 1 1981761104 11019248 10600496
14:32:58 50016 8192000000 10884570672 11334672384 1961605456 19.319 100 27 54 0 1988394304 18465728 8323120
14:35:14 5125 8192000000 10939423184 11366981632 1984698144 19.502 100 49 98 0 2016888368 17885968 14304256
14:36:25 4815 8192000000 10943713248 11371974656 1991259632 19.554 100 65 130 1 2032902304 22399776 19242896
14:37:34 8782 8192000000 10953221408 11387547648 2005037824 19.662 100 54 106 0 2045793152 23273216 17482112
14:38:49 4322 8192000000 10947375136 11385778176 2002408496 19.642 100 46 90 1 2036542816 20980000 13154320
14:40:00 2023 8192000000 11002462064 11425247232 2030698432 19.864 100 85 170 1 2084782800 28944368 25140000
14:41:08 2501 8192000000 10991473792 11414650880 2012703792 19.723 100 81 162 0 2072049104 34578048 24767264
14:42:17 3716 8192000000 11013523824 11439136768 2032642544 19.879 100 80 160 2 2097828784 39844976 25341264
14:43:27 2740 8192000000 10990197504 11402457088 1987484544 19.524 100 84 167 3 2062086448 47730288 26871616
14:44:35 1808 8192000000 10954673008 11377823744 1968688608 19.375 99 75 150 0 2035430784 42834720 23907456
14:45:41 8369 8192000000 10927121184 11374428160 1947966784 19.210 98 46 92 2 2001205280 38008176 15230320
14:46:54 13230 8192000000 10943614416 11383521280 1972425408 19.405 100 32 64 3 2023208432 40258112 10524912
14:48:20 7832 8192000000 10929307376 11368898560 1962798272 19.328 100 37 74 2 2013111040 38150416 12162352
14:49:36 57165 8192000000 10933273552 11390107648 1963815120 19.336 99 35 70 2 2011257072 35818176 11623776
14:51:35 14772 8192000000 10910247872 11367723008 1957178960 19.284 100 23 46 0 2003925712 38768720 7978032
14:52:55 37491 8192000000 10862961280 11321094144 1884493488 18.701 97 15 30 4 1934119360 44211040 5414832
14:54:40 51048 8192000000 10874350512 11305365504 1913491552 18.935 99 32 62 2 1935063808 11843648 9728608
14:56:35 121153 8192000000 10848655728 11305775104 1828254112 18.245 94 12 24 0 1847273728 15724816 3294800
14:59:39 43686 8192000000 10861215728 11322019840 1931255856 19.077 100 9 18 1 1942768656 9519424 1993376
15:01:25 34255 8192000000 10870761664 11319484416 1906547568 18.879 98 15 28 2 1920625424 9485664 4592192
15:03:02 71797 8192000000 10894131504 11338039296 1935935296 19.114 99 25 50 1 1959472800 15876400 7661104
15:05:18 83863 8192000000 10896169760 11366346752 1965645872 19.351 100 32 64 1 1987714960 13183424 8885664
15:07:51 15798 8192000000 10904320224 11362398208 1966495760 19.358 100 22 44 1 1984190400 11113712 6580928
15:09:08 72949 8192000000 10919888720 11373092864 1962972480 19.330 99 41 82 1 1989378192 14450352 11955360
15:11:23 28501 8192000000 10907418208 11376566272 1977439440 19.444 100 24 48 2 2001646432 16627296 7579696
15:13:17 61020 8192000000 10898001104 11367809024 1948739600 19.216 98 19 38 0 1961784384 7780624 5264160
15:15:19 93192 8192000000 10901992608 11379093504 1962885344 19.329 99 13 26 1 1976612944 9891376 3836224
15:17:56 46173 8192000000 10904855248 11390861312 1979195664 19.458 100 14 28 1 1994777952 11624384 3957904
15:19:44 20734 8192000000 10909845888 11360743424 1950144336 19.228 99 38 76 3 1972247888 10919472 11184080
15:21:09 84337 8192000000 10893309488 11365421056 1893933904 18.777 96 16 33 2 1907239200 8881008 4424288
15:23:43 54430 8192000000 10895097712 11380998144 1950442144 19.230 99 13 26 1 1974523792 19803696 4277952
15:25:38 24513 8192000000 10911987680 11366961152 1956461472 19.278 99 37 74 2 1977555696 10337072 10757152
15:27:06 76246 8192000000 10885703744 11360051200 1881638960 18.678 95 14 28 1 1898423232 12911696 3872576
15:29:23 60800 8192000000 10889615920 11343208448 1839996816 18.341 93 32 64 1 1858451232 10007328 8447088
15:31:25 97506 8192000000 10883072480 11353948160 1944599488 19.183 99 25 50 0 1960145008 8139088 7406432
15:34:11 100851 8192000000 10879904144 11362582528 1936424928 19.118 99 12 24 0 1955108656 15095760 3587968
15:36:55 88853 8192000000 10845971072 11325034496 1859098432 18.496 95 13 26 0 1872579408 9365200 4115776
15:39:24 16623 8192000000 10879019488 11310604288 1904721168 18.864 99 61 121 1 1933749136 12252768 16775200
15:40:41 91020 8192000000 10852042432 11314049024 1845405712 18.385 95 20 40 2 1858921648 8414784 5101152
15:43:17 93698 8192000000 10844499904 11327041536 1835147200 18.301 94 11 22 3 1857824240 19119472 3557568
15:46:47 83176 8192000000 10852141936 11326722048 1864504704 18.540 96 19 38 0 1881404272 11993424 4906144
15:49:11 139790 8192000000 10879756576 11344596992 1921072704 18.995 98 22 44 1 1937998832 10794768 6131360
15:52:31 53050 8192000000 10882346720 11354456064 1955006960 19.266 100 14 28 2 1973062624 14138256 3917408
15:54:27 33470 8192000000 10868115248 11352739840 1961794288 19.320 100 7 14 0 1969388272 5896048 1697936
15:56:06 70590 8192000000 10869568800 11340877824 1925475232 19.031 98 9 18 2 1937350528 9013360 2861936
15:58:28 89863 8192000000 10900169088 11392978944 1965381424 19.349 99 11 22 0 1983052016 14746592 2924000
16:02:11 50517 8192000000 10890017920 11367075840 1926806016 19.041 97 21 42 0 1942055472 9413808 5835648
16:04:02 38072 8192000000 10916266624 11388575744 1969928592 19.385 99 25 49 1 1989904320 13134192 6841536
16:05:53 55984 8192000000 10917205616 11385540608 1953063104 19.251 98 32 64 0 1983448448 20268368 10116976
16:07:49 89635 8192000000 10873977360 11339620352 1837679504 18.322 93 11 22 0 1848857008 8195792 2981712
16:10:23 51838 8192000000 10898693136 11369553920 1938392464 19.134 99 20 40 1 1963434992 19364736 5677792
16:12:23 64675 8192000000 10909152048 11364081664 1948967008 19.218 99 19 38 0 1964833728 11068048 4798672
16:14:28 131914 8192000000 10900751520 11367751680 1961980208 19.322 99 18 36 1 1979621616 11748048 5893360
16:17:49 24132 8192000000 10891674128 11382407168 1951382576 19.237 99 26 52 1 1979289744 20670160 7237008
16:20:44 62939 8192000000 10858416816 11331923968 1880002560 18.665 96 13 25 0 1891396560 8126896 3267104
16:22:47 76772 8192000000 10838544528 11310739456 1896418080 18.797 98 24 48 0 1916438496 13092368 6928048
16:25:05 60554 8192000000 10845808688 11324297216 1896349568 18.797 98 15 30 0 1912402656 11751136 4301952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 129 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 28-10-2020 16:29:11 to 28-10-2020 16:29:13.

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          243729
MON$OLDEST_ACTIVE               244911
MON$OLDEST_SNAPSHOT             244911
MON$NEXT_TRANSACTION            244911
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.33380 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.7.33380 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.7.33380 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.7.33380 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 ---
:217: 
:218: mscs-test-161.budzdorov.ru    Wed Oct 28 16:27:00 2020
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Wed Oct 28 16:27:03 2020
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
--- end of diff output ---

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

28.10.20 16:29:14. 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-28 16:29:16 No severe PSQL-related problems occured
28.10.20 16:29:18. End of report.