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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16796340 kB
MemAvailable 31554456 kB
Buffers 0 kB
Cached 14824596 kB
SwapCached 5424 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-14 10:26:51
DTS_END                         2020-10-14 10:26:51.3530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/46654
TRN_ID                          238401
ATT_ID                          624
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: 9033.88 21897 1084066
customer order: creation 176.73 338 21207
customer order: refuse 14.65 321 1758
order to supplier: creation 47.91 1228 5749
order to supplier: removal 3.50 101727 420
invoice (draft): creation 47.63 3254 5716
invoice (draft): removal 50.85 888 6102
invoice accept: apply 42.25 15530 5070
invoice accept: cancel 45.58 826 5469
customer reserve: creation 3861.60 153 463392
customer reserve: removal 4456.67 63 534800
realization accept: apply 84.77 157 10172
realization accept: cancel 73.88 105 8866
payment from customer: creation 58.31 99 6997
payment from customer: removal 5.99 94 719
payment to supplier: creation 49.72 107 5966
payment to supplier: removal 3.45 99 414
service: total inventory turnovers 3.78 3392 454
service: total monetary turnovers 5.82 448 698
service: aggregate perf. data 0.78 21299 93
service: refresh index statistics 0.03 287808 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-10-2020 10:28:08 to 14-10-2020 10:28:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6744
TEST_TIME 2 4840
TEST_TIME 3 4140
TEST_TIME 4 3834
TEST_TIME 5 3934
TEST_TIME 6 4268
TEST_TIME 7 4553
TEST_TIME 8 4836
TEST_TIME 9 4959
TEST_TIME 10 5091
TEST_TIME 11 5214
TEST_TIME 12 5519
TEST_TIME 13 5814
TEST_TIME 14 6000
TEST_TIME 15 6187
TEST_TIME 16 6836
TEST_TIME 17 7167
TEST_TIME 18 7514
TEST_TIME 19 8192
TEST_TIME 20 8131
TEST_TIME 21 8818
TEST_TIME 22 8762
TEST_TIME 23 9360
TEST_TIME 24 9584
TEST_TIME 25 10141
TEST_TIME 26 10657
TEST_TIME 27 10680
TEST_TIME 28 10790
TEST_TIME 29 10665
TEST_TIME 30 10462
TEST_TIME 31 10278
TEST_TIME 32 10286
TEST_TIME 33 10311
TEST_TIME 34 10383
TEST_TIME 35 10266
TEST_TIME 36 10241
TEST_TIME 37 10251
TEST_TIME 38 10237
TEST_TIME 39 10129
TEST_TIME 40 10293
TEST_TIME 41 10259
TEST_TIME 42 10240
TEST_TIME 43 10267
TEST_TIME 44 10160
TEST_TIME 45 9958
TEST_TIME 46 9770
TEST_TIME 47 9824
TEST_TIME 48 9750
TEST_TIME 49 9781
TEST_TIME 50 9773
TEST_TIME 51 9729
TEST_TIME 52 9621
TEST_TIME 53 9591
TEST_TIME 54 9653
TEST_TIME 55 9610
TEST_TIME 56 9483
TEST_TIME 57 9492
TEST_TIME 58 9511
TEST_TIME 59 9534
TEST_TIME 60 9541
TEST_TIME 61 9470
TEST_TIME 62 9481
TEST_TIME 63 9598
TEST_TIME 64 9682
TEST_TIME 65 9665
TEST_TIME 66 9651
TEST_TIME 67 9685
TEST_TIME 68 9669
TEST_TIME 69 9704
TEST_TIME 70 9687
TEST_TIME 71 9801
TEST_TIME 72 9829
TEST_TIME 73 9700
TEST_TIME 74 9627
TEST_TIME 75 9566
TEST_TIME 76 9552
TEST_TIME 77 9488
TEST_TIME 78 9469
TEST_TIME 79 9395
TEST_TIME 80 9355
TEST_TIME 81 9316
TEST_TIME 82 9354
TEST_TIME 83 9424
TEST_TIME 84 9422
TEST_TIME 85 9441
TEST_TIME 86 9457
TEST_TIME 87 9458
TEST_TIME 88 9444
TEST_TIME 89 9413
TEST_TIME 90 9428
TEST_TIME 91 9443
TEST_TIME 92 9587
TEST_TIME 93 9551
TEST_TIME 94 9460
TEST_TIME 95 9442
TEST_TIME 96 9403
TEST_TIME 97 9353
TEST_TIME 98 9344
TEST_TIME 99 9325
TEST_TIME 100 9270
TEST_TIME 101 9286
TEST_TIME 102 9241
TEST_TIME 103 9227
TEST_TIME 104 9246
TEST_TIME 105 9249
TEST_TIME 106 9269
TEST_TIME 107 9318
TEST_TIME 108 9287
TEST_TIME 109 9313
TEST_TIME 110 9371
TEST_TIME 111 9290
TEST_TIME 112 9229
TEST_TIME 113 9204
TEST_TIME 114 9146
TEST_TIME 115 9109
TEST_TIME 116 9120
TEST_TIME 117 9090
TEST_TIME 118 9063
TEST_TIME 119 9032
TEST_TIME 120 9034

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-10-2020 10:28:12 to 14-10-2020 10:28:16.

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 1513913 1513913 0 0.00 0 28281 6 0 0
doc_list_biud 1513907 1513907 0 0.00 0 54054 28 0 0
sp_add_invoice_to_stock 5144 5070 74 1.43 40 225694 15530 0 74
sp_cancel_adding_invoice 5469 5469 0 0.00 6 60976 826 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 12330 321 0 0
sp_cancel_customer_reserve 534800 534800 0 0.00 1 54056 64 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 3 9155 95 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 3 10391 100 0 0
sp_cancel_supplier_invoice 6113 6102 11 0.17 2 46386 888 0 11
sp_cancel_supplier_order 420 420 0 0.00 5 1152916 101727 0 0
sp_cancel_write_off 8866 8866 0 0.00 1 6023 106 0 0
sp_client_order 21207 21207 0 0.00 4 20055 338 0 0
sp_customer_reserve 463392 463392 0 0.00 0 97306 153 0 0
sp_fill_shopping_cart 32659 32659 0 0.00 1 27097 42 0 0
sp_fill_shopping_cart_clo_res 463391 463391 0 0.00 0 26184 16 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 36962 345 0 0
sp_kill_cost_storno 15468 15468 0 0.00 0 7996 54 0 0
sp_kill_qstorno_ret_qs2qd 1084988 1084988 0 0.00 0 54047 34 0 0
sp_kill_qty_storno 572656 572656 0 0.00 0 54053 74 0 0
sp_lock_selected_doc 21190 21190 0 0.00 0 9563 4 0 0
sp_make_cost_storno 28204 28204 0 0.00 0 13804 56 0 0
sp_multiply_rows_for_pdistr 28204 28204 0 0.00 0 12497 28 0 0
sp_multiply_rows_for_qdistr 21205 21205 0 0.00 0 19809 182 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 10372 99 0 0
sp_pay_to_supplier 5966 5966 0 0.00 5 12841 107 0 0
sp_payment_common 12963 12963 0 0.00 1 7754 18 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 12319 290 0 0
sp_qd_handle_on_invoice_upd_sts 10538 10538 0 0.00 0 27219 347 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 10341 46 0 0
sp_reserve_write_off 10172 10172 0 0.00 7 14911 158 0 0
sp_supplier_invoice 5716 5716 0 0.00 8 114947 3255 0 0
sp_supplier_order 5749 5749 0 0.00 5 73994 1228 0 0
srv_aggregate_perf_data 93 93 0 0.00 2968 554314 21299 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1007 137742 50706 0 0
srv_find_qd_qs_mism 1036242 1036242 0 0.00 0 28281 5 0 0
srv_make_invnt_saldo 454 454 0 0.00 58 131019 3393 0 0
srv_make_money_saldo 698 698 0 0.00 1 8964 449 0 0
srv_recalc_idx_stat 4 4 0 0.00 60149 477934 287809 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 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 5070 5070 0 0.00 0 11483 171 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 0 8672 89 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 5269 15 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 2687 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 5040 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 4145 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1491 23 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 19141 1488 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 2418 9 0 0
t$perf-norm:sp_client_order 21207 21207 0 0.00 0 7240 21 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 1762 8 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 6280 11 0 0
t$perf-norm:sp_pay_to_supplier 5966 5966 0 0.00 0 5912 13 0 0
t$perf-norm:sp_reserve_write_off 10172 10172 0 0.00 0 5038 10 0 0
t$perf-norm:sp_supplier_invoice 5716 5716 0 0.00 0 5460 17 0 0
t$perf-norm:sp_supplier_order 5749 5749 0 0.00 0 9513 23 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 408 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 78 5 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 2967 20 0 0
t$perf-norm:srv_make_money_saldo 698 698 0 0.00 0 1571 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10286 10138 148 1.43 0 8299 14 0 148
v_all_customers 38144 38144 0 0.00 0 17 1 0 0
v_all_suppliers 31674 31674 0 0.00 0 14 1 0 0
v_all_wares 42408 42408 0 0.00 0 177 1 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 227 5 0 0
v_cancel_client_order 3516 3516 0 0.00 0 2726 4 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 635 6 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 837 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 10350 20 0 0
v_cancel_supplier_invoice 834 812 22 2.63 0 1376 13 0 22
v_cancel_supplier_order 840 840 0 0.00 0 99 2 0 0
v_cancel_write_off 2124 2124 0 0.00 0 816 11 0 0
v_max_id_clo_ord 5746 5746 0 0.00 0 1192 1 0 0
v_max_id_clo_res 975 975 0 0.00 0 1661 10 0 0
v_max_id_ord_sup 5709 5709 0 0.00 0 7942 8 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 1577 5 0 0
v_max_non_paid_realizn 3901 3901 0 0.00 0 1783 3 0 0
v_min_id_clo_ord 5746 5746 0 0.00 0 2349 7 0 0
v_min_id_clo_res 975 975 0 0.00 0 4611 16 0 0
v_min_id_ord_sup 5709 5709 0 0.00 0 6867 8 0 0
v_min_non_paid_invoice 5966 5966 0 0.00 0 6562 11 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 7749 12 0 0
v_our_firm 10456 10456 0 0.00 0 33 1 0 0
v_reserve_write_off 20343 20343 0 0.00 0 1975 3 0 0
x_lock_depdocs_on_canc_invoice 11165 11165 0 0.00 0 11537 101 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 14105 312 0 0
x_make_qstorno_1000_1200 5749 5749 0 0.00 2 73977 1150 0 0
x_make_qstorno_1200_2000 5716 5716 0 0.00 3 114919 3100 0 0
x_make_qstorno_2100_3300 463392 463392 0 0.00 0 97298 135 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-10-2020 10:28:16 to 14-10-2020 10:28:21.

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
07:56:55 1 8192000000 8796624000 8807108608 3156752 0.038 1 1 2 0 3254112 61504 35856
07:57:57 32067 8192000000 9735270400 10034294784 811994432 9.018 100 87 174 0 840456144 7781664 20680048
07:59:32 23253 8192000000 9767067728 10073894912 838075776 9.280 100 91 182 0 873321056 13712720 21532560
08:01:06 17690 8192000000 9823167040 10143264768 876934416 9.669 100 90 178 0 922259200 25328096 19996688
08:02:27 12355 8192000000 9889318000 10222891008 925428912 10.150 100 83 165 1 988628960 45760832 17439216
08:03:43 7282 8192000000 9929802272 10275766272 964837056 10.536 100 89 178 0 1046314016 62995264 18481696
08:04:52 2629 8192000000 9952400304 10308251648 994537312 10.826 100 74 149 3 1074159120 65038960 14582848
08:05:56 6772 8192000000 9994845312 10357493760 1022910592 11.100 100 76 154 1 1107141248 69391056 14839600
08:07:04 1401 8192000000 10010970240 10377527296 1053167712 11.391 100 90 180 1 1123282448 51598080 18516656
08:08:07 4687 8192000000 9998987488 10389172224 1063576064 11.491 100 42 82 2 1109230016 37277728 8376224
08:09:12 7436 8192000000 9984292000 10368385024 1068096208 11.534 100 59 115 4 1094390976 13467792 12826976
08:10:21 5211 8192000000 9997156320 10402152448 1068989104 11.542 100 23 45 1 1100450352 26544288 4916960
08:11:27 6792 8192000000 10004026240 10397933568 1080681408 11.654 100 38 76 2 1106299904 17450928 8167568
08:12:39 9866 8192000000 10013628720 10408841216 1090327440 11.746 100 34 68 1 1116894752 19416416 7150896
08:13:51 49234 8192000000 9997580784 10395910144 1082012880 11.667 99 41 80 1 1107882976 16049952 9820144
08:16:02 7417 8192000000 10028740352 10437062656 1092921056 11.770 100 44 88 2 1136309696 33745168 9643472
08:17:10 4216 8192000000 9974789344 10370662400 1076933088 11.618 100 33 66 0 1093575520 8825584 7816848
08:18:20 76448 8192000000 9944365568 10339893248 912464864 10.022 86 24 46 1 939979184 22811536 4702784
08:20:40 5867 8192000000 9929600912 10303049728 1010361136 10.979 99 47 94 2 1044558464 24565232 9632096
08:21:47 7354 8192000000 9947498016 10342096896 1031534592 11.183 100 34 67 0 1056803920 17773104 7496224
08:22:56 50682 8192000000 9948513616 10338717696 1036060240 11.227 99 46 92 1 1058478336 11987040 10431056
08:24:56 4657 8192000000 9970455280 10361868288 1058736624 11.444 100 23 47 1 1080329600 16269024 5323952
08:26:03 7957 8192000000 9996710032 10382655488 1080226000 11.650 100 39 77 0 1103872544 14755136 8891408
08:27:11 6775 8192000000 10033559232 10423992320 1098703088 11.825 100 53 102 4 1137764384 28584224 10477072
08:28:26 97760 8192000000 10060070464 10468605952 1114702784 11.977 100 35 70 1 1175703760 52870240 8130736
08:31:10 18065 8192000000 10110883664 10517274624 1128946288 12.111 100 41 82 4 1227682944 89931136 8805520
08:32:31 13405 8192000000 10148715120 10571943936 1136736720 12.185 100 40 80 0 1260656992 115223696 8696576
08:33:50 4270 8192000000 10179552944 10582151168 1147479184 12.286 100 67 132 1 1284942544 123248608 14214752
08:35:02 12430 8192000000 10237936400 10648109056 1165170944 12.452 100 59 117 2 1353708064 175417360 13119760
08:36:27 11597 8192000000 10251324432 10660163584 1170932944 12.506 100 56 111 3 1359661488 176483152 12245392
08:37:42 2993 8192000000 10292157520 10694021120 1155989872 12.366 100 60 117 3 1393841248 224417568 13433808
08:38:53 8529 8192000000 10324650752 10721087488 1156347664 12.369 99 70 141 1 1420190048 247351424 16490960
08:40:10 1005 8192000000 10376191264 10771132416 1160635824 12.409 100 83 169 1 1479031184 297906384 20488976
08:41:18 3356 8192000000 10390751888 10793730048 1159940800 12.403 99 78 156 0 1489267168 311065072 18261296
08:42:24 1758 8192000000 10397302272 10803240960 1161600992 12.418 100 91 176 1 1492798352 310725984 20471376
08:43:29 5796 8192000000 10400195088 10817093632 1152504032 12.333 100 57 114 4 1496075328 329963120 13608176
08:44:38 2020 8192000000 10418318352 10846035968 1162085808 12.423 100 65 130 0 1517868096 339950624 15831664
08:45:46 9268 8192000000 10365236896 10785615872 1124969904 12.074 99 46 92 0 1472027632 335703424 11354304
08:47:03 6852 8192000000 10396440848 10811453440 1127395152 12.097 100 44 86 0 1496741712 358278704 11067856
08:48:52 19951 8192000000 10374295136 10791006208 1125987120 12.084 99 44 88 1 1443839904 306838080 11014704
08:50:17 32539 8192000000 10262661968 10712465408 1122620464 12.052 99 25 50 0 1386203088 256916096 6666528
08:51:54 29009 8192000000 10197786368 10642317312 1115542480 11.985 100 12 23 1 1301374816 182922416 2909920
08:53:30 51866 8192000000 10055084368 10529140736 1087456960 11.718 98 11 22 0 1136232336 46567632 2207744
08:56:02 72872 8192000000 10066127264 10499698688 1111938000 11.951 99 17 35 0 1152713456 37004080 3771376
08:58:20 108840 8192000000 10071543376 10552659968 1090345376 11.746 96 9 18 0 1147572128 55668912 1557840
09:01:15 91891 8192000000 10071099952 10523484160 1118925856 12.017 98 20 40 1 1164682864 41429376 4327632
09:03:51 100906 8192000000 10115449984 10581745664 1125827264 12.082 98 19 37 2 1171867648 42022208 4018176
09:06:34 100989 8192000000 10079589776 10535497728 1124887856 12.073 98 10 20 0 1169276592 41754992 2633744
09:09:15 105001 8192000000 10108230976 10563780608 1125821936 12.082 97 13 26 1 1178254304 48750368 3682000
09:13:11 66421 8192000000 10097657552 10544410624 1115217344 11.982 96 24 49 1 1169604976 49663424 4724208
09:15:18 88197 8192000000 10077283104 10543243264 1112604336 11.957 97 16 32 0 1185178416 69081168 3492912
09:17:50 56464 8192000000 10133088608 10580344832 1156887424 12.374 100 20 40 1 1230428368 68835040 4705904
09:19:47 96263 8192000000 10106042976 10617520128 1142435648 12.238 99 11 22 1 1203488656 58851968 2201040
09:22:25 96129 8192000000 10086217504 10572095488 1104765184 11.883 96 16 32 0 1150873616 42885632 3222800
09:25:05 55378 8192000000 10053763072 10523475968 1093669936 11.778 96 22 44 2 1130238496 32014112 4554448
09:27:02 35518 8192000000 10104470160 10533470208 1104446608 11.880 97 28 55 2 1150625184 40987360 5191216
09:29:04 61361 8192000000 10099498128 10547560448 1109817072 11.931 98 12 24 2 1177889744 65678016 2394656
09:31:08 43906 8192000000 10062611920 10495188992 1086036880 11.705 96 29 58 1 1121392336 29511792 5843664
09:33:01 17311 8192000000 10099965488 10524635136 1108671408 11.920 99 55 107 2 1157458800 37402544 11384848
09:34:22 40970 8192000000 10070773648 10491465728 1080505040 11.652 96 40 80 3 1140263008 51299920 8458048
09:36:03 72613 8192000000 10130662736 10575499264 1086290640 11.707 97 11 22 0 1178707776 89910240 2506896
09:38:52 137742 8192000000 10047627264 10482610176 1108213120 11.915 99 32 64 1 1132376400 17794832 6368448
09:42:45 70595 8192000000 10071685440 10528591872 1127269312 12.096 100 7 14 1 1177255984 48297984 1688688
09:44:56 52676 8192000000 10114021248 10585858048 1100450416 11.842 97 15 30 0 1177983840 74102992 3430432
09:46:54 67280 8192000000 10160643056 10625638400 1118539584 12.013 99 18 35 0 1244547168 121914624 4092960
09:49:11 65946 8192000000 10072541968 10511552512 1108044976 11.914 98 14 28 2 1156006944 45178224 2783744
09:52:07 66942 8192000000 10068400816 10500042752 1108820976 11.921 98 29 58 0 1143358544 28143552 6394016
09:54:18 25904 8192000000 10072795152 10508316672 1121332768 12.040 99 24 47 0 1159821056 33374064 5114224
09:55:45 30041 8192000000 10117263600 10554232832 1135054704 12.169 100 46 92 1 1198600304 54457088 9088512
09:57:17 45934 8192000000 10137974608 10588975104 1130763552 12.129 99 23 46 0 1191873232 56673440 4436240
09:59:05 47623 8192000000 10070468528 10514006016 1094948032 11.790 96 10 20 1 1154054192 57341376 1764784
10:00:54 78116 8192000000 10055842656 10549448704 1134892000 12.167 100 13 26 0 1188683088 51189344 2601744
10:03:13 58949 8192000000 10065934416 10546987008 1125353136 12.078 99 6 12 0 1168765936 42386768 1026032
10:05:12 39896 8192000000 10077573120 10521911296 1138848336 12.205 99 19 38 0 1163820496 21073872 3898288
10:07:01 57751 8192000000 10056791328 10508804096 1119649344 12.024 98 26 52 1 1170013600 44583824 5780432
10:09:01 44490 8192000000 10094714848 10532384768 1122705168 12.053 98 23 46 1 1170243168 42729392 4808608
10:10:52 63089 8192000000 10108575232 10546368512 1113496208 11.966 97 15 30 0 1155049904 38272624 3281072
10:12:55 99607 8192000000 10053742544 10476032000 1053118864 11.391 93 23 45 1 1093668016 35047232 5501920
10:15:38 73339 8192000000 10067145312 10604085248 1094207600 11.783 98 7 14 0 1187625200 91829440 1588160
10:17:52 92875 8192000000 10035752144 10490150912 1120511648 12.032 100 19 37 2 1152562624 28001280 4049696
10:20:31 91329 8192000000 10084376256 10568458240 1106355712 11.898 98 7 14 0 1157939456 49996464 1587280
10:24:33 57252 8192000000 10069216208 10504073216 1066430336 11.518 95 24 48 0 1113385920 41879616 5075968
10:26:37 24650 8192000000 9050189552 9301475328 200329072 2.387 19 4 8 0 228649056 27512032 807952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 74 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 14-10-2020 10:28:38 to 14-10-2020 10:28:40.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242070
MON$OLDEST_ACTIVE               242071
MON$OLDEST_SNAPSHOT             242071
MON$NEXT_TRANSACTION            242071
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-30 07:30:08.6030 GMT
MON$PAGES                       2117120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             797
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2225 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:802: 
:803: mscs-test-161.budzdorov.ru    Wed Oct 14 10:27:10 2020
:804:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:805: 
--- end of diff output ---

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

14.10.20 10:28:41. There are 3 logs in /var/tmp/logs-oltp40 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-14 10:28:43 No severe PSQL-related problems occured
14.10.20 10:28:45. End of report.