Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2020 07: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 16968060 kB
MemAvailable 31518656 kB
Buffers 0 kB
Cached 14718832 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 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-31 07:26:53
DTS_END                         2020-10-31 07:26:53.2170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/56362
TRN_ID                          227722
ATT_ID                          623
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: 8519.48 22875 1022337
customer order: creation 166.28 383 19954
customer order: refuse 14.13 402 1696
order to supplier: creation 42.61 1312 5113
order to supplier: removal 3.19 124552 383
invoice (draft): creation 44.20 3989 5304
invoice (draft): removal 47.98 1074 5757
invoice accept: apply 39.48 18970 4737
invoice accept: cancel 43.43 954 5212
customer reserve: creation 3632.51 184 435901
customer reserve: removal 4211.65 73 505398
realization accept: apply 80.22 201 9627
realization accept: cancel 73.08 131 8770
payment from customer: creation 55.69 109 6683
payment from customer: removal 5.69 112 683
payment to supplier: creation 46.37 140 5564
payment to supplier: removal 3.48 105 418
service: total inventory turnovers 3.13 4807 375
service: total monetary turnovers 5.43 777 652
service: aggregate perf. data 0.88 19927 105
service: refresh index statistics 0.04 279299 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2020 07:28:36 to 31-10-2020 07:28:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4009
TEST_TIME 2 4296
TEST_TIME 3 3581
TEST_TIME 4 4108
TEST_TIME 5 3863
TEST_TIME 6 4015
TEST_TIME 7 4581
TEST_TIME 8 4864
TEST_TIME 9 5015
TEST_TIME 10 5121
TEST_TIME 11 5489
TEST_TIME 12 5598
TEST_TIME 13 5582
TEST_TIME 14 5755
TEST_TIME 15 5975
TEST_TIME 16 6205
TEST_TIME 17 6243
TEST_TIME 18 6673
TEST_TIME 19 7060
TEST_TIME 20 7273
TEST_TIME 21 7258
TEST_TIME 22 7601
TEST_TIME 23 7553
TEST_TIME 24 8012
TEST_TIME 25 8021
TEST_TIME 26 8054
TEST_TIME 27 8340
TEST_TIME 28 8357
TEST_TIME 29 8373
TEST_TIME 30 8693
TEST_TIME 31 8838
TEST_TIME 32 9383
TEST_TIME 33 10414
TEST_TIME 34 10435
TEST_TIME 35 10235
TEST_TIME 36 9956
TEST_TIME 37 9814
TEST_TIME 38 9746
TEST_TIME 39 9687
TEST_TIME 40 9581
TEST_TIME 41 9496
TEST_TIME 42 9370
TEST_TIME 43 9443
TEST_TIME 44 9343
TEST_TIME 45 9237
TEST_TIME 46 9132
TEST_TIME 47 8999
TEST_TIME 48 8909
TEST_TIME 49 8786
TEST_TIME 50 8793
TEST_TIME 51 8925
TEST_TIME 52 8948
TEST_TIME 53 8853
TEST_TIME 54 8899
TEST_TIME 55 8843
TEST_TIME 56 8876
TEST_TIME 57 8934
TEST_TIME 58 8913
TEST_TIME 59 8890
TEST_TIME 60 8938
TEST_TIME 61 8949
TEST_TIME 62 8961
TEST_TIME 63 9002
TEST_TIME 64 9108
TEST_TIME 65 9041
TEST_TIME 66 8931
TEST_TIME 67 8867
TEST_TIME 68 8791
TEST_TIME 69 8765
TEST_TIME 70 8773
TEST_TIME 71 8748
TEST_TIME 72 8718
TEST_TIME 73 8704
TEST_TIME 74 8730
TEST_TIME 75 8681
TEST_TIME 76 8642
TEST_TIME 77 8778
TEST_TIME 78 8822
TEST_TIME 79 8783
TEST_TIME 80 8868
TEST_TIME 81 8845
TEST_TIME 82 8879
TEST_TIME 83 8881
TEST_TIME 84 8826
TEST_TIME 85 8792
TEST_TIME 86 8715
TEST_TIME 87 8728
TEST_TIME 88 8678
TEST_TIME 89 8629
TEST_TIME 90 8575
TEST_TIME 91 8522
TEST_TIME 92 8483
TEST_TIME 93 8504
TEST_TIME 94 8561
TEST_TIME 95 8667
TEST_TIME 96 8684
TEST_TIME 97 8659
TEST_TIME 98 8702
TEST_TIME 99 8721
TEST_TIME 100 8729
TEST_TIME 101 8764
TEST_TIME 102 8825
TEST_TIME 103 8798
TEST_TIME 104 8823
TEST_TIME 105 8857
TEST_TIME 106 8849
TEST_TIME 107 8800
TEST_TIME 108 8740
TEST_TIME 109 8686
TEST_TIME 110 8647
TEST_TIME 111 8620
TEST_TIME 112 8585
TEST_TIME 113 8581
TEST_TIME 114 8553
TEST_TIME 115 8534
TEST_TIME 116 8514
TEST_TIME 117 8500
TEST_TIME 118 8512
TEST_TIME 119 8498
TEST_TIME 120 8520

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-10-2020 07:28:39 to 31-10-2020 07:28:43.

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 1425538 1425538 0 0.00 0 25975 7 0 0
doc_list_biud 1425537 1425537 0 0.00 0 69872 33 0 0
sp_add_invoice_to_stock 4842 4737 105 2.16 48 212277 18970 0 105
sp_cancel_adding_invoice 5212 5212 0 0.00 5 91186 955 0 0
sp_cancel_client_order 1696 1696 0 0.00 5 11407 403 0 0
sp_cancel_customer_reserve 505398 505398 0 0.00 1 29944 73 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 6465 113 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 6311 105 0 0
sp_cancel_supplier_invoice 5766 5757 9 0.15 1 69950 1074 0 9
sp_cancel_supplier_order 383 383 0 0.00 7 1266295 124552 0 0
sp_cancel_write_off 8770 8770 0 0.00 1 12795 132 0 0
sp_client_order 19954 19954 0 0.00 4 27048 383 0 0
sp_customer_reserve 435901 435901 0 0.00 0 36146 185 0 0
sp_fill_shopping_cart 30351 30351 0 0.00 1 13639 46 0 0
sp_fill_shopping_cart_clo_res 435893 435893 0 0.00 0 15710 16 0 0
sp_get_clo_for_invoice 4729 4729 0 0.00 0 35946 433 0 0
sp_kill_cost_storno 15083 15083 0 0.00 0 12676 72 0 0
sp_kill_qstorno_ret_qs2qd 1025706 1025706 0 0.00 0 69871 40 0 0
sp_kill_qty_storno 541569 541569 0 0.00 0 69872 87 0 0
sp_lock_selected_doc 19940 19940 0 0.00 0 5509 4 0 0
sp_make_cost_storno 26600 26600 0 0.00 0 25969 60 0 0
sp_multiply_rows_for_pdistr 26600 26600 0 0.00 0 12252 33 0 0
sp_multiply_rows_for_qdistr 19954 19954 0 0.00 0 26622 191 0 0
sp_pay_from_customer 6683 6683 0 0.00 4 12930 109 0 0
sp_pay_to_supplier 5564 5564 0 0.00 4 26105 141 0 0
sp_payment_common 12247 12247 0 0.00 1 26093 30 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 11399 377 0 0
sp_qd_handle_on_invoice_upd_sts 9938 9938 0 0.00 0 27342 432 0 0
sp_qd_handle_on_reserve_upd_sts 9627 9627 0 0.00 0 11029 55 0 0
sp_reserve_write_off 9627 9627 0 0.00 7 26006 201 0 0
sp_supplier_invoice 5304 5304 0 0.00 9 108227 3990 0 0
sp_supplier_order 5113 5113 0 0.00 6 40513 1312 0 0
srv_aggregate_perf_data 105 105 0 0.00 2621 487836 19927 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1056 144488 48989 0 0
srv_find_qd_qs_mism 976888 976888 0 0.00 0 15043 6 0 0
srv_make_invnt_saldo 375 375 0 0.00 60 142656 4808 0 0
srv_make_money_saldo 652 652 0 0.00 1 17185 777 0 0
srv_recalc_idx_stat 5 5 0 0.00 54859 527690 279299 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4738 4738 0 0.00 0 12981 186 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 1984 109 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 6581 15 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 875 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 640 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 726 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 3448 21 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 35211 1761 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 1953 9 0 0
t$perf-norm:sp_client_order 19954 19954 0 0.00 0 16895 23 0 0
t$perf-norm:sp_customer_reserve 956 956 0 0.00 0 1448 6 0 0
t$perf-norm:sp_pay_from_customer 6683 6683 0 0.00 0 6831 9 0 0
t$perf-norm:sp_pay_to_supplier 5564 5564 0 0.00 0 9331 10 0 0
t$perf-norm:sp_reserve_write_off 9627 9627 0 0.00 0 7477 10 0 0
t$perf-norm:sp_supplier_invoice 5304 5304 0 0.00 0 7737 19 0 0
t$perf-norm:sp_supplier_order 5113 5113 0 0.00 0 2640 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 360 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 375 375 0 0.00 0 967 11 0 0
t$perf-norm:srv_make_money_saldo 652 652 0 0.00 0 290 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 4 2 0 0
v_add_invoice_to_stock 9662 9452 210 2.17 0 13823 22 0 210
v_all_customers 35672 35672 0 0.00 0 19 1 0 0
v_all_suppliers 28758 28758 0 0.00 0 15 1 0 0
v_all_wares 39904 39904 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 780 9 0 0
v_cancel_client_order 3392 3392 0 0.00 0 2297 5 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 6435 22 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 398 4 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 6305 15 0 0
v_cancel_supplier_invoice 796 778 18 2.26 0 2970 19 0 18
v_cancel_supplier_order 766 766 0 0.00 0 9781 22 0 0
v_cancel_write_off 1910 1910 0 0.00 0 3762 17 0 0
v_max_id_clo_ord 5108 5108 0 0.00 0 522 1 0 0
v_max_id_clo_res 956 956 0 0.00 0 4358 18 0 0
v_max_id_ord_sup 5288 5288 0 0.00 0 3203 4 0 0
v_max_non_paid_invoice 1580 1580 0 0.00 0 3228 5 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 1656 3 0 0
v_min_id_clo_ord 5108 5108 0 0.00 0 1643 6 0 0
v_min_id_clo_res 956 956 0 0.00 0 4873 9 0 0
v_min_id_ord_sup 5288 5288 0 0.00 0 8064 7 0 0
v_min_non_paid_invoice 5564 5564 0 0.00 0 26088 29 0 0
v_min_non_paid_realizn 6683 6683 0 0.00 0 12452 18 0 0
v_our_firm 9960 9960 0 0.00 0 18 1 0 0
v_reserve_write_off 19254 19254 0 0.00 0 13550 9 0 0
x_lock_depdocs_on_canc_invoice 10580 10580 0 0.00 0 13813 143 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 27812 465 0 0
x_make_qstorno_1000_1200 5113 5113 0 0.00 2 40507 1220 0 0
x_make_qstorno_1200_2000 5304 5304 0 0.00 5 108184 3819 0 0
x_make_qstorno_2100_3300 435901 435901 0 0.00 0 36145 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2020 07:28:44 to 31-10-2020 07:28:48.

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
04:56:56 1 8192000000 8796633136 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
04:57:58 21686 8192000000 9712978800 10004533248 791239984 8.807 100 86 172 0 820143056 7831232 21071840
04:59:31 25255 8192000000 9749023360 10051096576 824073168 9.140 100 87 174 0 856864176 11727040 21063968
05:00:59 12186 8192000000 9776369040 10086694912 840657696 9.306 100 88 176 1 887040928 25289744 21093488
05:02:26 15469 8192000000 9831792592 10164764672 897758976 9.876 100 67 134 4 948849984 36770560 14320448
05:03:44 10798 8192000000 9896986912 10236870656 945464224 10.347 100 74 148 1 1004409792 43281984 15663584
05:05:00 5451 8192000000 9959984656 10312769536 989888816 10.780 100 81 161 1 1068564480 62592336 16083328
05:06:09 3398 8192000000 9989233232 10351513600 1020576720 11.078 100 89 177 1 1108013936 69751344 17685872
05:07:13 2601 8192000000 10012620064 10376904704 1042073056 11.285 100 89 178 0 1121479040 61169296 18236688
05:08:18 1717 8192000000 10033394672 10408640512 1055444112 11.413 100 87 174 1 1154296000 81597728 17254160
05:09:22 2730 8192000000 10044644464 10418835456 1065091760 11.505 100 88 176 1 1161275088 78490224 17693104
05:10:26 4060 8192000000 10014343040 10399621120 1071976192 11.571 100 75 152 1 1130025584 41358912 16690480
05:11:32 4613 8192000000 9995525184 10383527936 1076931872 11.618 100 46 92 1 1101798336 15365744 9500720
05:12:37 7603 8192000000 9988722256 10393600000 1081045280 11.657 100 29 56 5 1118795712 32441120 5309312
05:13:46 49436 8192000000 10003422192 10394394624 1083748320 11.683 100 50 100 1 1105576912 10677184 11151408
05:15:36 10209 8192000000 9999735280 10410610688 1088698416 11.730 100 35 70 5 1114642896 18328336 7616144
05:16:46 7115 8192000000 10014199632 10410528768 1088906640 11.732 100 45 90 3 1127368096 28354432 10107024
05:17:54 10565 8192000000 9995169472 10406834176 1078796288 11.636 99 32 64 1 1108148592 22563888 6788416
05:19:06 45716 8192000000 9976075552 10355867648 1040897440 11.273 97 51 102 1 1073490224 20606176 11986608
05:20:52 6898 8192000000 9934160880 10315206656 1016211312 11.035 98 23 44 0 1049009040 28557840 4239888
05:22:00 7069 8192000000 9921070000 10303856640 940965664 10.302 92 34 66 3 967083072 19112256 7005152
05:23:08 9680 8192000000 9946250560 10330673152 1031630464 11.184 100 36 71 3 1057592976 17646608 8315904
05:24:26 24804 8192000000 9958775392 10358734848 1049707216 11.358 100 40 80 1 1077726384 18298928 9720240
05:25:52 4719 8192000000 9989978256 10375389184 1069111392 11.544 100 53 103 2 1099072656 18418608 11542656
05:26:59 9909 8192000000 10010953712 10403307520 1090395536 11.746 100 38 75 2 1118498352 19929600 8173216
05:28:17 17602 8192000000 10038545392 10436493312 1108786992 11.921 100 46 92 3 1147577696 28652000 10138704
05:29:41 66792 8192000000 10071238336 10490757120 1120871632 12.035 100 35 70 3 1191762800 63502288 7388880
05:31:56 15769 8192000000 10101873440 10507214848 1130209712 12.123 100 44 88 1 1206664224 67223856 9230656
05:33:17 3207 8192000000 10113504032 10520547328 1143813664 12.251 100 59 118 0 1219226192 62238000 13174528
05:34:24 14662 8192000000 10146257488 10553851904 1143419376 12.248 99 57 113 1 1251535440 96349968 11766096
05:35:46 21462 8192000000 10151196048 10572959744 1158326784 12.388 100 30 59 2 1269976608 104832192 6817632
05:37:12 8005 8192000000 10202473376 10610130944 1169863296 12.496 100 67 133 0 1308283840 124475376 13945168
05:38:25 10412 8192000000 10214955072 10626306048 1167535008 12.474 100 57 113 0 1316905536 137391680 11978848
05:39:52 5094 8192000000 10251374752 10658811904 1175516128 12.548 100 69 138 1 1353328960 162266816 15546016
05:41:06 4266 8192000000 10281468640 10690363392 1192242256 12.704 100 74 146 0 1386837536 178894800 15700480
05:42:14 2031 8192000000 10313074368 10716483584 1166950432 12.468 100 86 172 3 1418079232 231254256 19874544
05:43:22 11506 8192000000 10342306496 10761547776 1171901888 12.515 100 68 134 2 1441402432 253642336 15858208
05:44:54 3114 8192000000 10324967936 10749177856 1182449904 12.613 100 81 160 1 1423067264 222284016 18333344
05:46:05 1055 8192000000 10301834848 10715320320 1158976080 12.394 99 86 169 0 1400353728 222852288 18525360
05:47:14 2018 8192000000 10333253056 10732335104 1145523168 12.267 99 85 167 3 1426050176 260837872 19689136
05:48:23 2067 8192000000 10373199888 10765561856 1141932304 12.234 100 88 174 3 1467092464 304833616 20326544
05:49:29 2371 8192000000 10379058528 10779631616 1154795472 12.354 100 76 151 0 1476484480 304625152 17063856
05:50:35 9045 8192000000 10375843936 10792177664 1161906928 12.421 100 41 79 0 1480579568 308601488 10071152
05:51:51 8432 8192000000 10404947392 10833727488 1154179392 12.349 100 37 73 0 1501402592 337621728 9601472
05:53:05 13676 8192000000 10434449264 10863325184 1149721792 12.307 100 38 76 3 1541047472 381754336 9571344
05:54:32 19592 8192000000 10434303504 10866569216 1136663328 12.184 99 24 48 0 1517461248 374519536 6278384
05:56:05 13840 8192000000 10469584688 10897162240 1139489392 12.211 100 32 64 2 1566966464 419555312 7921760
05:57:47 23986 8192000000 10394044048 10869055488 1129200768 12.114 100 30 60 1 1517757536 380332016 8224752
05:59:15 26681 8192000000 10117189856 10714120192 1134892912 12.167 100 16 32 1 1340967936 202396208 3678816
06:00:44 132257 8192000000 10075666064 10501419008 1102399072 11.860 97 21 41 0 1161604624 54952032 4253520
06:04:12 37243 8192000000 10107146960 10561150976 1129536608 12.117 99 40 80 1 1197225520 58806992 8881920
06:05:56 71759 8192000000 10091562112 10568818688 1135249040 12.171 99 20 42 0 1214132384 73986640 4896704
06:08:09 58457 8192000000 10047804912 10529820672 1115329344 11.983 99 22 44 0 1180529136 60283120 4916672
06:10:13 60929 8192000000 10053566304 10490507264 1122020928 12.046 99 16 31 1 1145627984 20600768 3006288
06:12:20 63550 8192000000 10114381776 10554757120 1133866912 12.158 99 21 42 2 1210148896 71553328 4728656
06:14:25 91708 8192000000 10165165424 10614587392 1140791008 12.223 99 28 56 0 1288247792 140477440 6979344
06:17:05 80309 8192000000 10064108240 10615537664 1141323344 12.228 100 10 20 0 1215377904 72129856 1924704
06:19:54 75495 8192000000 10067031056 10531139584 1126988816 12.093 99 21 42 0 1171973648 40887104 4097728
06:22:10 76229 8192000000 10082181120 10601398272 1138575696 12.202 99 24 48 2 1219263168 75429168 5258304
06:24:29 59501 8192000000 10067473504 10490576896 1102904112 11.865 97 30 60 1 1138470832 29505904 6060816
06:26:29 104333 8192000000 10062691456 10520948736 1082975984 11.676 95 21 42 0 1144248704 57248016 4024704
06:29:15 73262 8192000000 10102655024 10554150912 1088392896 11.727 96 17 34 2 1143438160 51874176 3171088
06:31:31 50068 8192000000 10027647168 10458509312 1092769152 11.769 97 20 39 3 1118396704 21782064 3845488
06:33:55 50921 8192000000 10047600096 10492137472 1109225488 11.925 100 7 14 0 1138810288 28306352 1278448
06:35:51 72041 8192000000 10032700000 10497163264 1094969344 11.790 98 9 18 0 1132628912 36093968 1565600
06:38:10 105568 8192000000 10055759424 10514391040 1027090288 11.140 92 15 30 0 1088592288 57888320 3613680
06:41:08 51498 8192000000 10094770352 10514685952 1100012976 11.838 98 20 38 1 1156511888 52380096 4118816
06:43:00 114489 8192000000 10065068320 10538131456 1086022624 11.705 97 16 32 1 1160874304 71836144 3015536
06:45:59 70317 8192000000 10047266448 10461196288 1064838896 11.503 96 10 20 0 1094848864 28005424 2004544
06:48:12 57757 8192000000 10045462064 10488512512 1073995920 11.590 97 19 38 0 1114753504 37244608 3512976
06:50:11 144487 8192000000 10025532352 10451320832 1110637168 11.938 100 18 35 3 1159062432 44258480 4166784
06:53:37 46592 8192000000 10034880736 10504130560 1113286816 11.964 100 8 16 0 1152389120 37766800 1335504
06:55:24 62286 8192000000 10102008816 10546458624 1119349440 12.021 99 13 26 0 1172479168 49875632 3254096
06:57:26 94121 8192000000 10200143872 10654404608 1137747008 12.194 100 18 36 2 1284574112 142755744 4071360
07:00:20 62860 8192000000 10103431744 10648387584 1144227488 12.255 100 16 32 1 1281639456 133918960 3493008
07:02:38 65639 8192000000 10095286912 10529247232 1101479808 11.852 96 23 45 1 1149783968 43928432 4375728
07:04:48 94775 8192000000 10114674416 10592743424 1145485216 12.267 100 19 38 0 1238334368 88795536 4053616
07:07:31 68121 8192000000 10103869680 10564755456 1114491024 11.975 98 15 30 0 1165120640 47550464 3079152
07:09:41 42742 8192000000 10125226560 10576191488 1118517504 12.013 98 27 53 0 1164208400 40483392 5207504
07:11:33 103149 8192000000 10074739136 10557407232 1129768976 12.119 99 13 26 1 1180521056 47975376 2776704
07:14:46 58497 8192000000 10063704800 10520342528 1139077024 12.207 99 7 14 0 1171488288 31451568 959696
07:16:51 88765 8192000000 10090054688 10556624896 1152649904 12.334 100 5 10 0 1184445168 30558864 1236400
07:19:20 74171 8192000000 10057447472 10525970432 1110030432 11.933 97 9 18 0 1136864800 24713840 2120528
07:21:35 44735 8192000000 10101569312 10539991040 1136289776 12.181 99 26 50 1 1177740928 36560064 4891088
07:23:23 29931 8192000000 10146074032 10577129472 1118736992 12.015 98 28 56 1 1214797280 89754368 6305920
07:24:56 82078 8192000000 10192682896 10634465280 1093837776 11.779 96 20 40 0 1232963792 134517232 4608784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 31-10-2020 07:28:50 to 31-10-2020 07:29:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 105 v_add_invoice_to_stock 335544517
user_exc 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-10-2020 07:29:05 to 31-10-2020 07:29:07.

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          222442
MON$OLDEST_ACTIVE               227832
MON$OLDEST_SNAPSHOT             227832
MON$NEXT_TRANSACTION            227832
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                       2092480
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             768
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2242 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2242 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.2242 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sat Oct 31 07:27:01 2020
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Oct 31 07:27:30 2020
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
--- end of diff output ---

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

31.10.20 07:29:08. 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-31 07:29:11 No severe PSQL-related problems occured
31.10.20 07:29:12. End of report.