Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.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 16893244 kB
MemAvailable 31527652 kB
Buffers 0 kB
Cached 14758988 kB
SwapCached 3048 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-24 10:26:53
DTS_END                         2020-10-24 10:26:53.6220
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/38728
TRN_ID                          239292
ATT_ID                          666
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: 9199.23 20473 1103907
customer order: creation 177.33 389 21280
customer order: refuse 14.32 318 1718
order to supplier: creation 46.39 1310 5567
order to supplier: removal 3.20 128848 384
invoice (draft): creation 47.35 3428 5682
invoice (draft): removal 51.14 966 6137
invoice accept: apply 42.69 17599 5123
invoice accept: cancel 46.82 978 5618
customer reserve: creation 3920.33 172 470440
customer reserve: removal 4549.46 72 545935
realization accept: apply 84.90 160 10188
realization accept: cancel 85.28 121 10233
payment from customer: creation 60.82 113 7298
payment from customer: removal 5.59 74 671
payment to supplier: creation 48.54 122 5825
payment to supplier: removal 3.62 83 434
service: total inventory turnovers 4.09 3109 491
service: total monetary turnovers 6.39 533 767
service: aggregate perf. data 0.93 16257 112
service: refresh index statistics 0.03 234800 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2020 10:27:56 to 24-10-2020 10:27:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4747
TEST_TIME 2 5175
TEST_TIME 3 5850
TEST_TIME 4 6504
TEST_TIME 5 7484
TEST_TIME 6 6728
TEST_TIME 7 6550
TEST_TIME 8 6589
TEST_TIME 9 6711
TEST_TIME 10 6858
TEST_TIME 11 7483
TEST_TIME 12 7553
TEST_TIME 13 7706
TEST_TIME 14 7719
TEST_TIME 15 7754
TEST_TIME 16 7816
TEST_TIME 17 8147
TEST_TIME 18 8047
TEST_TIME 19 8498
TEST_TIME 20 8456
TEST_TIME 21 8515
TEST_TIME 22 9139
TEST_TIME 23 9747
TEST_TIME 24 9862
TEST_TIME 25 10483
TEST_TIME 26 10912
TEST_TIME 27 12035
TEST_TIME 28 11883
TEST_TIME 29 11799
TEST_TIME 30 11627
TEST_TIME 31 11336
TEST_TIME 32 11035
TEST_TIME 33 10880
TEST_TIME 34 10806
TEST_TIME 35 10834
TEST_TIME 36 10902
TEST_TIME 37 10782
TEST_TIME 38 10597
TEST_TIME 39 10501
TEST_TIME 40 10551
TEST_TIME 41 10592
TEST_TIME 42 10631
TEST_TIME 43 10597
TEST_TIME 44 10502
TEST_TIME 45 10572
TEST_TIME 46 10633
TEST_TIME 47 10682
TEST_TIME 48 10639
TEST_TIME 49 10569
TEST_TIME 50 10583
TEST_TIME 51 10510
TEST_TIME 52 10379
TEST_TIME 53 10283
TEST_TIME 54 10242
TEST_TIME 55 10113
TEST_TIME 56 10030
TEST_TIME 57 9943
TEST_TIME 58 9921
TEST_TIME 59 9918
TEST_TIME 60 9934
TEST_TIME 61 9872
TEST_TIME 62 9966
TEST_TIME 63 9913
TEST_TIME 64 9830
TEST_TIME 65 9934
TEST_TIME 66 9920
TEST_TIME 67 9950
TEST_TIME 68 9968
TEST_TIME 69 9917
TEST_TIME 70 10007
TEST_TIME 71 10040
TEST_TIME 72 9943
TEST_TIME 73 9827
TEST_TIME 74 9784
TEST_TIME 75 9697
TEST_TIME 76 9672
TEST_TIME 77 9600
TEST_TIME 78 9608
TEST_TIME 79 9595
TEST_TIME 80 9578
TEST_TIME 81 9539
TEST_TIME 82 9517
TEST_TIME 83 9497
TEST_TIME 84 9531
TEST_TIME 85 9542
TEST_TIME 86 9529
TEST_TIME 87 9650
TEST_TIME 88 9622
TEST_TIME 89 9538
TEST_TIME 90 9474
TEST_TIME 91 9428
TEST_TIME 92 9370
TEST_TIME 93 9334
TEST_TIME 94 9319
TEST_TIME 95 9305
TEST_TIME 96 9285
TEST_TIME 97 9332
TEST_TIME 98 9284
TEST_TIME 99 9334
TEST_TIME 100 9313
TEST_TIME 101 9279
TEST_TIME 102 9257
TEST_TIME 103 9276
TEST_TIME 104 9318
TEST_TIME 105 9416
TEST_TIME 106 9442
TEST_TIME 107 9423
TEST_TIME 108 9386
TEST_TIME 109 9318
TEST_TIME 110 9293
TEST_TIME 111 9247
TEST_TIME 112 9189
TEST_TIME 113 9196
TEST_TIME 114 9172
TEST_TIME 115 9171
TEST_TIME 116 9145
TEST_TIME 117 9173
TEST_TIME 118 9185
TEST_TIME 119 9168
TEST_TIME 120 9206

Performance per minute, chart


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

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 1538280 1538280 0 0.00 0 22372 7 0 0
doc_list_biud 1538280 1538280 0 0.00 0 72886 32 0 0
sp_add_invoice_to_stock 5193 5123 70 1.34 37 194383 17599 0 70
sp_cancel_adding_invoice 5618 5618 0 0.00 6 106234 979 0 0
sp_cancel_client_order 1718 1718 0 0.00 5 12738 318 0 0
sp_cancel_customer_reserve 545935 545935 0 0.00 1 51324 73 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 4729 75 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 3033 84 0 0
sp_cancel_supplier_invoice 6140 6137 3 0.04 2 72892 967 0 3
sp_cancel_supplier_order 384 384 0 0.00 9 1273350 128848 0 0
sp_cancel_write_off 10233 10233 0 0.00 1 10333 121 0 0
sp_client_order 21280 21280 0 0.00 4 34359 390 0 0
sp_customer_reserve 470440 470440 0 0.00 0 118684 172 0 0
sp_fill_shopping_cart 32519 32519 0 0.00 1 36899 42 0 0
sp_fill_shopping_cart_clo_res 470435 470435 0 0.00 0 20642 16 0 0
sp_get_clo_for_invoice 5101 5101 0 0.00 0 37478 375 0 0
sp_kill_cost_storno 16956 16956 0 0.00 0 22363 60 0 0
sp_kill_qstorno_ret_qs2qd 1108624 1108624 0 0.00 0 72884 39 0 0
sp_kill_qty_storno 585314 585314 0 0.00 0 72885 83 0 0
sp_lock_selected_doc 21107 21107 0 0.00 0 10404 6 0 0
sp_make_cost_storno 28412 28412 0 0.00 0 11534 46 0 0
sp_multiply_rows_for_pdistr 28412 28412 0 0.00 0 7175 28 0 0
sp_multiply_rows_for_qdistr 21280 21280 0 0.00 0 34303 215 0 0
sp_pay_from_customer 7298 7298 0 0.00 4 25899 114 0 0
sp_pay_to_supplier 5825 5825 0 0.00 5 30764 123 0 0
sp_payment_common 13123 13123 0 0.00 1 5747 17 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 12732 284 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 37143 397 0 0
sp_qd_handle_on_reserve_upd_sts 10188 10188 0 0.00 0 9288 46 0 0
sp_reserve_write_off 10188 10188 0 0.00 7 17523 160 0 0
sp_supplier_invoice 5682 5682 0 0.00 6 168034 3429 0 0
sp_supplier_order 5567 5567 0 0.00 5 49958 1310 0 0
srv_aggregate_perf_data 112 112 0 0.00 2995 150323 16258 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1218 161583 49831 0 0
srv_find_qd_qs_mism 1054690 1054690 0 0.00 0 17169 6 0 0
srv_make_invnt_saldo 491 491 0 0.00 48 98483 3109 0 0
srv_make_money_saldo 767 767 0 0.00 1 13621 533 0 0
srv_recalc_idx_stat 4 4 0 0.00 138162 388054 234801 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5123 5123 0 0.00 0 17051 158 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 1 12059 114 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 4491 15 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 2466 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 3085 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 363 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1077 20 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 27143 1430 0 0
t$perf-norm:sp_cancel_write_off 1059 1059 0 0.00 0 3093 10 0 0
t$perf-norm:sp_client_order 21280 21280 0 0.00 0 4982 14 0 0
t$perf-norm:sp_customer_reserve 1029 1029 0 0.00 0 3846 9 0 0
t$perf-norm:sp_pay_from_customer 7298 7298 0 0.00 0 3564 8 0 0
t$perf-norm:sp_pay_to_supplier 5825 5825 0 0.00 0 6380 10 0 0
t$perf-norm:sp_reserve_write_off 10188 10188 0 0.00 0 5044 10 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 3945 16 0 0
t$perf-norm:sp_supplier_order 5567 5567 0 0.00 0 6334 17 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 500 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 491 491 0 0.00 0 600 4 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 4326 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10342 10202 140 1.35 0 8851 22 0 140
v_all_customers 38648 38648 0 0.00 0 16 1 0 0
v_all_suppliers 30930 30930 0 0.00 0 82 1 0 0
v_all_wares 42560 42560 0 0.00 0 97 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 2362 12 0 0
v_cancel_client_order 3436 3436 0 0.00 0 4643 6 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 2244 6 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 1435 5 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2972 8 0 0
v_cancel_supplier_invoice 814 808 6 0.73 0 4344 19 0 6
v_cancel_supplier_order 768 768 0 0.00 0 3496 16 0 0
v_cancel_write_off 2118 2118 0 0.00 0 2065 14 0 0
v_max_id_clo_ord 5567 5567 0 0.00 0 5312 3 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 3212 22 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 5669 5 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 528 2 0 0
v_max_non_paid_realizn 3863 3863 0 0.00 0 3202 4 0 0
v_min_id_clo_ord 5567 5567 0 0.00 0 3513 5 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 4150 20 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 5715 5 0 0
v_min_non_paid_invoice 5825 5825 0 0.00 0 4542 8 0 0
v_min_non_paid_realizn 7298 7298 0 0.00 0 5741 13 0 0
v_our_firm 10782 10782 0 0.00 0 16 1 0 0
v_reserve_write_off 20376 20376 0 0.00 0 6377 6 0 0
x_lock_depdocs_on_canc_invoice 11351 11351 0 0.00 0 29741 114 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 11415 407 0 0
x_make_qstorno_1000_1200 5567 5567 0 0.00 2 49952 1234 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 3 167782 3264 0 0
x_make_qstorno_2100_3300 470440 470440 0 0.00 0 118682 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2020 10:28:04 to 24-10-2020 10:28:08.

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:57 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
07:57:58 27988 8192000000 9738091744 10036826112 810777520 9.005 100 89 178 0 839917040 7939424 21200096
07:59:33 24652 8192000000 9777170176 10086887424 849079872 9.391 100 92 182 1 884461216 13732944 21648400
08:01:01 15731 8192000000 9819334400 10143944704 882732912 9.727 100 79 158 0 925903888 25138960 18032016
08:02:20 9461 8192000000 9871659632 10206097408 926455568 10.160 100 79 156 1 982217936 38579840 17182528
08:03:31 7625 8192000000 9905809872 10252574720 964290672 10.531 100 76 152 1 1016942400 36820256 15831472
08:04:41 5420 8192000000 9967358288 10322411520 1003408752 10.912 100 77 153 3 1080384512 62045808 14929952
08:05:47 2696 8192000000 10016413312 10380009472 1023720224 11.108 100 88 177 0 1128613408 87109904 17783280
08:06:51 2438 8192000000 10029744272 10396454912 1044920352 11.312 100 87 176 1 1147387584 84480912 17986320
08:07:55 10744 8192000000 10021365424 10400161792 1061332672 11.469 100 70 140 2 1136558992 61065904 14160416
08:09:08 6180 8192000000 9985208384 10376429568 1070564048 11.557 100 38 77 2 1108033632 29195248 8274336
08:10:15 4279 8192000000 9991953856 10380005376 1075360672 11.603 100 46 92 0 1105027568 18273216 11393680
08:11:20 9260 8192000000 9984893152 10384551936 1082231520 11.669 100 54 108 2 1119292784 24373584 12687680
08:12:32 30601 8192000000 10011635504 10406285312 1086092096 11.705 100 52 106 0 1117692368 18545952 13054320
08:14:03 7888 8192000000 10017934880 10411978752 1093688880 11.778 100 37 74 3 1141637568 39058992 8889696
08:15:13 5935 8192000000 10013378768 10412781568 1095785792 11.798 100 45 89 2 1122808592 16785088 10237712
08:16:22 5755 8192000000 9970592864 10357661696 1043760656 11.301 97 28 56 1 1073602304 23216416 6625232
08:17:28 28633 8192000000 9954328992 10351820800 976047776 10.646 91 32 63 2 1007750336 24595584 7106976
08:18:57 12439 8192000000 9931454720 10325438464 985857280 10.741 95 30 60 1 1020054080 27164096 7032704
08:20:10 5780 8192000000 9926568032 10306125824 1004393616 10.921 98 51 102 1 1031568320 15136864 12037840
08:21:18 69923 8192000000 9942913680 10337968128 998163072 10.861 96 35 70 1 1028495776 22396048 7936656
08:23:29 5309 8192000000 9954291200 10331041792 1026050976 11.130 99 33 66 1 1062773616 29282032 7440608
08:24:37 9171 8192000000 9968521696 10363211776 1048927440 11.350 100 29 56 2 1068520368 12750912 6842016
08:25:48 3214 8192000000 9979841488 10364375040 1073232880 11.583 100 35 68 2 1096121120 13068400 9819840
08:26:53 46635 8192000000 10010941824 10404806656 1091422816 11.756 100 36 72 2 1114055280 15298080 7334384
08:28:44 21623 8192000000 10096242496 10488283136 1118064768 12.009 100 71 142 2 1195684864 63949904 13670192
08:30:10 24075 8192000000 10106072608 10512453632 1137735536 12.194 100 42 84 1 1228954368 82199840 9018992
08:31:37 1668 8192000000 10158650736 10562015232 1151751216 12.326 100 75 150 3 1273151616 104683104 16717296
08:32:42 18473 8192000000 10190297392 10593263616 1155328960 12.359 100 58 113 2 1290260352 122765680 12165712
08:34:04 12749 8192000000 10222114592 10628984832 1160509664 12.408 100 55 110 3 1328358224 155458608 12389952
08:35:22 1216 8192000000 10251946768 10652766208 1160540208 12.408 100 82 162 2 1359495152 180368592 18586352
08:36:29 1993 8192000000 10306921296 10706735104 1177276320 12.565 100 77 155 2 1414369584 220522928 16570336
08:37:34 10457 8192000000 10271116864 10689667072 1168894080 12.486 99 58 116 1 1389655520 207655792 13105648
08:38:51 4950 8192000000 10290424832 10692939776 1173397664 12.529 100 66 131 0 1394169552 205805840 14966048
08:40:01 1677 8192000000 10320605824 10711650304 1158807328 12.392 99 88 175 1 1414412176 235621792 19983056
08:41:07 1759 8192000000 10393718208 10793803776 1168868368 12.486 99 76 152 1 1492899232 306172528 17858336
08:42:20 3177 8192000000 10389771600 10776662016 1148541280 12.296 99 86 173 0 1488864512 320420528 19902704
08:43:29 1741 8192000000 10457985120 10851467264 1151072688 12.320 100 80 159 1 1550382496 380578784 18731024
08:44:37 1620 8192000000 10504051632 10897309696 1146528800 12.277 99 87 174 1 1599559120 432277120 20753200
08:45:44 9386 8192000000 10512106928 10942271488 1158053392 12.385 99 43 84 0 1604817424 436074512 10689520
08:47:09 12126 8192000000 10564785648 11005321216 1148200496 12.293 99 47 94 2 1662514224 501670704 12643024
08:48:30 2763 8192000000 10508972864 10930634752 1154548448 12.352 100 49 98 1 1610932256 444066928 12316880
08:49:47 19727 8192000000 10385526272 10918854656 1143158112 12.245 100 28 56 2 1554301232 403443776 7699344
08:51:16 28025 8192000000 10371395216 10870394880 1114896672 11.979 99 20 40 1 1519979200 399142272 5940256
08:52:45 33520 8192000000 10133519408 10725273600 1120407120 12.031 100 16 32 2 1349900848 225412048 4081680
08:54:21 79394 8192000000 10083438000 10537242624 1107352048 11.907 99 17 35 0 1183431184 72485008 3594128
08:56:43 161582 8192000000 10103255968 10543558656 1108332528 11.917 98 31 62 1 1206065104 90875664 6856912
09:00:51 40668 8192000000 10130785200 10591756288 1111047824 11.942 98 33 66 0 1233493104 114785776 7659504
09:02:33 80810 8192000000 10069976576 10530480128 1127101296 12.094 99 12 24 0 1182669232 52829072 2738864
09:05:58 73732 8192000000 10136661136 10643046400 1140549984 12.221 99 15 29 0 1241472992 97729808 3193200
09:08:12 57935 8192000000 10094614384 10564665344 1129429072 12.116 98 18 37 2 1183029696 49472464 4128160
09:10:11 35260 8192000000 10140659824 10589147136 1157175072 12.377 100 28 56 1 1233541808 70219936 6146800
09:11:49 37227 8192000000 10142090736 10589650944 1145947536 12.271 99 30 60 3 1233194336 80519632 6727168
09:13:35 77057 8192000000 10086462272 10536349696 1129483680 12.116 98 15 30 0 1163902768 31431968 2987120
09:16:01 79898 8192000000 10117340400 10576027648 1143468736 12.248 99 14 28 2 1196426640 50180080 2777824
09:18:21 89979 8192000000 10107752240 10570149888 1155557056 12.362 100 19 38 0 1217613984 57969088 4087840
09:20:51 38904 8192000000 10084594832 10556145664 1133545200 12.155 98 12 24 0 1181574032 45660448 2368384
09:22:34 52842 8192000000 10104438128 10574712832 1153045776 12.338 100 9 18 0 1218118624 63528256 1544592
09:24:55 66500 8192000000 10075780320 10516996096 1081711696 11.664 94 18 36 2 1133747264 48126176 3909392
09:27:09 29942 8192000000 10070962208 10499055616 1117905280 12.007 98 26 51 0 1157386976 34381920 5099776
09:28:40 75107 8192000000 10082900368 10522488832 1077485456 11.624 94 16 32 0 1134007424 53268288 3253680
09:30:56 43363 8192000000 10078330864 10557829120 1096587792 11.805 97 36 72 2 1174813104 70890848 7334464
09:32:47 48352 8192000000 10081244784 10533933056 1070284128 11.555 95 27 54 0 1130805136 55272800 5248208
09:34:36 69041 8192000000 10098281808 10534109184 1090420080 11.747 97 20 40 0 1141147424 46535008 4192336
09:36:55 59908 8192000000 10054238864 10498478080 1090156848 11.744 98 15 30 0 1149753792 56720768 2876176
09:38:56 128632 8192000000 10045422176 10498658304 1110100416 11.933 99 28 56 0 1144743872 28751248 5892208
09:42:59 54463 8192000000 10036876144 10490654720 1067326144 11.527 96 8 15 2 1106822368 37392912 2103312
09:44:54 84730 8192000000 10058378544 10487910400 1106144576 11.896 99 14 27 1 1133717744 23697824 3875344
09:47:23 42724 8192000000 10098774384 10535944192 1124122320 12.066 99 28 55 1 1194515744 64656096 5737328
09:49:10 50772 8192000000 10145299136 10586427392 1110039136 11.933 98 21 41 0 1218836592 104000208 4797248
09:51:08 74717 8192000000 10150354128 10581549056 1120700848 12.034 99 30 58 3 1246256336 118925264 6630224
09:53:23 46692 8192000000 10043532128 10465128448 1081465056 11.661 96 24 48 0 1108199296 22116000 4618240
09:55:12 96118 8192000000 10060097904 10498793472 1115681312 11.986 99 18 36 0 1153968864 34986752 3300800
09:57:48 51435 8192000000 10047474400 10505711616 1101795152 11.855 98 6 12 0 1132462544 29610080 1057312
09:59:40 58860 8192000000 10067528816 10546081792 1123007648 12.055 99 7 14 0 1155399040 31033296 1358096
10:01:39 48498 8192000000 10128284016 10573455360 1145423024 12.267 100 29 58 3 1197334912 45962560 5949328
10:03:38 59532 8192000000 10098763936 10538041344 1107765024 11.911 96 14 28 1 1162996288 52236688 2994576
10:05:38 85427 8192000000 10086975808 10520891392 1127157664 12.095 98 14 28 3 1162619088 32857744 2603680
10:08:04 78074 8192000000 10144530704 10606661632 1106780304 11.902 97 21 42 1 1208152960 97266416 4106240
10:10:22 84144 8192000000 10092271408 10596376576 1143518784 12.249 100 15 28 1 1232724272 86107408 3098080
10:12:46 141346 8192000000 10034818832 10477101056 1066014112 11.514 94 7 14 0 1096979984 29870976 1094896
10:16:41 78839 8192000000 10051345920 10509422592 1102811600 11.864 98 12 24 2 1143720864 38443536 2465728
10:19:01 82406 8192000000 10059497392 10515169280 1103030384 11.866 98 11 22 0 1145074992 39476352 2568256
10:21:33 67912 8192000000 10067808528 10499756032 1067865760 11.532 95 22 44 2 1122694256 49905200 4923296
10:24:01 62857 8192000000 10084219584 10546536448 1103313312 11.869 98 25 50 1 1169816848 61354896 5148640
10:26:22 54420 8192000000 8941980048 9183637504 96778784 1.167 9 2 4 0 123318208 26227248 312176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 70 v_add_invoice_to_stock 335544517
user_exc 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2020 10:28:26 to 24-10-2020 10:28:28.

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          239583
MON$OLDEST_ACTIVE               239584
MON$OLDEST_SNAPSHOT             239584
MON$NEXT_TRANSACTION            239584
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                       2099648
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             788
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2235 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2235 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.2235 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 ---
--- end of diff output ---

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

24.10.20 10:28:29. 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-24 10:28:31 No severe PSQL-related problems occured
24.10.20 10:28:33. End of report.