Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2020 04: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 16836168 kB
MemAvailable 31529760 kB
Buffers 20 kB
Cached 14813188 kB
SwapCached 3044 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-26 04:26:49
DTS_END                         2020-10-26 04:26:49.6310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(67:17)
IP                              ::1/39042
TRN_ID                          234606
ATT_ID                          671
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: 8827.14 21082 1059257
customer order: creation 172.38 410 20686
customer order: refuse 14.56 341 1747
order to supplier: creation 45.21 1337 5425
order to supplier: removal 3.08 123352 370
invoice (draft): creation 44.08 3581 5290
invoice (draft): removal 49.66 954 5959
invoice accept: apply 40.54 17610 4865
invoice accept: cancel 44.98 851 5398
customer reserve: creation 3706.57 173 444788
customer reserve: removal 4417.98 67 530157
realization accept: apply 82.66 164 9919
realization accept: cancel 79.88 109 9585
payment from customer: creation 57.98 116 6957
payment from customer: removal 5.67 66 680
payment to supplier: creation 47.85 126 5742
payment to supplier: removal 3.18 79 382
service: total inventory turnovers 3.93 3266 472
service: total monetary turnovers 6.01 590 721
service: aggregate perf. data 0.92 16149 110
service: refresh index statistics 0.03 252300 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2020 04:28:17 to 26-10-2020 04:28:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3869
TEST_TIME 2 6428
TEST_TIME 3 6264
TEST_TIME 4 6183
TEST_TIME 5 5673
TEST_TIME 6 5627
TEST_TIME 7 6180
TEST_TIME 8 6086
TEST_TIME 9 6243
TEST_TIME 10 6338
TEST_TIME 11 6551
TEST_TIME 12 6557
TEST_TIME 13 6979
TEST_TIME 14 7228
TEST_TIME 15 7640
TEST_TIME 16 8172
TEST_TIME 17 8518
TEST_TIME 18 8807
TEST_TIME 19 8725
TEST_TIME 20 8844
TEST_TIME 21 9042
TEST_TIME 22 9076
TEST_TIME 23 9248
TEST_TIME 24 9320
TEST_TIME 25 9845
TEST_TIME 26 9953
TEST_TIME 27 10782
TEST_TIME 28 11712
TEST_TIME 29 11468
TEST_TIME 30 11203
TEST_TIME 31 11204
TEST_TIME 32 11060
TEST_TIME 33 10930
TEST_TIME 34 10736
TEST_TIME 35 10569
TEST_TIME 36 10470
TEST_TIME 37 10496
TEST_TIME 38 10300
TEST_TIME 39 10218
TEST_TIME 40 10096
TEST_TIME 41 10007
TEST_TIME 42 9955
TEST_TIME 43 9809
TEST_TIME 44 9751
TEST_TIME 45 9711
TEST_TIME 46 9607
TEST_TIME 47 9463
TEST_TIME 48 9417
TEST_TIME 49 9474
TEST_TIME 50 9364
TEST_TIME 51 9284
TEST_TIME 52 9285
TEST_TIME 53 9274
TEST_TIME 54 9275
TEST_TIME 55 9206
TEST_TIME 56 9205
TEST_TIME 57 9267
TEST_TIME 58 9339
TEST_TIME 59 9472
TEST_TIME 60 9403
TEST_TIME 61 9452
TEST_TIME 62 9502
TEST_TIME 63 9517
TEST_TIME 64 9410
TEST_TIME 65 9287
TEST_TIME 66 9295
TEST_TIME 67 9218
TEST_TIME 68 9186
TEST_TIME 69 9165
TEST_TIME 70 9126
TEST_TIME 71 9096
TEST_TIME 72 9107
TEST_TIME 73 9140
TEST_TIME 74 9095
TEST_TIME 75 9073
TEST_TIME 76 9106
TEST_TIME 77 9146
TEST_TIME 78 9149
TEST_TIME 79 9106
TEST_TIME 80 9060
TEST_TIME 81 9106
TEST_TIME 82 9001
TEST_TIME 83 9125
TEST_TIME 84 9119
TEST_TIME 85 9083
TEST_TIME 86 9023
TEST_TIME 87 8985
TEST_TIME 88 8962
TEST_TIME 89 9000
TEST_TIME 90 9010
TEST_TIME 91 9035
TEST_TIME 92 9046
TEST_TIME 93 9007
TEST_TIME 94 9166
TEST_TIME 95 9162
TEST_TIME 96 9210
TEST_TIME 97 9179
TEST_TIME 98 9150
TEST_TIME 99 9100
TEST_TIME 100 9070
TEST_TIME 101 9024
TEST_TIME 102 8959
TEST_TIME 103 8920
TEST_TIME 104 8920
TEST_TIME 105 8917
TEST_TIME 106 8905
TEST_TIME 107 8904
TEST_TIME 108 8883
TEST_TIME 109 8921
TEST_TIME 110 8968
TEST_TIME 111 8972
TEST_TIME 112 8995
TEST_TIME 113 9001
TEST_TIME 114 8995
TEST_TIME 115 8978
TEST_TIME 116 8939
TEST_TIME 117 8892
TEST_TIME 118 8851
TEST_TIME 119 8854
TEST_TIME 120 8828

Performance per minute, chart


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

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 1471902 1471902 0 0.00 0 21109 7 0 0
doc_list_biud 1471899 1471899 0 0.00 0 79980 31 0 0
sp_add_invoice_to_stock 4945 4865 80 1.61 29 175499 17610 0 80
sp_cancel_adding_invoice 5398 5398 0 0.00 5 84190 851 0 0
sp_cancel_client_order 1747 1747 0 0.00 5 12696 341 0 0
sp_cancel_customer_reserve 530157 530157 0 0.00 1 79981 68 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 6756 67 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 5962 80 0 0
sp_cancel_supplier_invoice 5966 5959 7 0.11 1 63176 954 0 7
sp_cancel_supplier_order 370 370 0 0.00 8 963350 123352 0 0
sp_cancel_write_off 9585 9585 0 0.00 1 10231 110 0 0
sp_client_order 20686 20686 0 0.00 4 59875 411 0 0
sp_customer_reserve 444788 444788 0 0.00 0 35520 174 0 0
sp_fill_shopping_cart 31372 31372 0 0.00 1 53205 47 0 0
sp_fill_shopping_cart_clo_res 444784 444784 0 0.00 0 22965 19 0 0
sp_get_clo_for_invoice 4861 4861 0 0.00 0 39450 514 0 0
sp_kill_cost_storno 16045 16045 0 0.00 0 7448 58 0 0
sp_kill_qstorno_ret_qs2qd 1076228 1076228 0 0.00 0 68262 37 0 0
sp_kill_qty_storno 567994 567994 0 0.00 0 79980 79 0 0
sp_lock_selected_doc 20351 20351 0 0.00 0 10218 6 0 0
sp_make_cost_storno 27479 27479 0 0.00 0 10413 51 0 0
sp_multiply_rows_for_pdistr 27476 27476 0 0.00 0 8707 29 0 0
sp_multiply_rows_for_qdistr 20686 20686 0 0.00 0 59866 236 0 0
sp_pay_from_customer 6957 6957 0 0.00 4 20313 116 0 0
sp_pay_to_supplier 5742 5742 0 0.00 5 10755 126 0 0
sp_payment_common 12698 12698 0 0.00 1 20307 24 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 12687 310 0 0
sp_qd_handle_on_invoice_upd_sts 10259 10259 0 0.00 0 23626 381 0 0
sp_qd_handle_on_reserve_upd_sts 9917 9917 0 0.00 0 19092 54 0 0
sp_reserve_write_off 9919 9919 0 0.00 6 19154 165 0 0
sp_supplier_invoice 5290 5290 0 0.00 7 93149 3582 0 0
sp_supplier_order 5425 5425 0 0.00 5 23471 1337 0 0
srv_aggregate_perf_data 110 110 0 0.00 2717 122489 16150 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1816 138119 45963 0 0
srv_find_qd_qs_mism 1013077 1013077 0 0.00 0 21109 6 0 0
srv_make_invnt_saldo 472 472 0 0.00 40 93508 3266 0 0
srv_make_money_saldo 721 721 0 0.00 1 18720 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 122371 412963 252301 0 0
t$perf-abend:sp_add_invoice_to_stock 80 80 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4865 4865 0 0.00 0 14268 154 0 0
t$perf-norm:sp_cancel_adding_invoice 362 362 0 0.00 0 1890 85 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 1286 9 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 979 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 2163 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 489 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 6781 29 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 16225 1639 0 0
t$perf-norm:sp_cancel_write_off 977 977 0 0.00 0 498 3 0 0
t$perf-norm:sp_client_order 20687 20687 0 0.00 0 7938 16 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 4409 13 0 0
t$perf-norm:sp_pay_from_customer 6957 6957 0 0.00 0 5331 10 0 0
t$perf-norm:sp_pay_to_supplier 5743 5743 0 0.00 0 7203 10 0 0
t$perf-norm:sp_reserve_write_off 9919 9919 0 0.00 0 4159 9 0 0
t$perf-norm:sp_supplier_invoice 5290 5290 0 0.00 0 4772 15 0 0
t$perf-norm:sp_supplier_order 5425 5425 0 0.00 0 3582 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 138 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 45 2 0 0
t$perf-norm:srv_make_invnt_saldo 472 472 0 0.00 0 416 5 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 1036 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9882 9722 160 1.61 0 4508 16 0 160
v_all_customers 37224 37224 0 0.00 0 96 1 0 0
v_all_suppliers 29454 29454 0 0.00 0 34 1 0 0
v_all_wares 41372 41372 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 724 724 0 0.00 0 3333 12 0 0
v_cancel_client_order 3494 3494 0 0.00 0 2548 6 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 1205 5 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 1677 5 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 1593 8 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 2067 17 0 14
v_cancel_supplier_order 740 740 0 0.00 0 976 8 0 0
v_cancel_write_off 1954 1954 0 0.00 0 1316 13 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 238 1 0 0
v_max_id_clo_res 928 928 0 0.00 0 3653 12 0 0
v_max_id_ord_sup 5264 5264 0 0.00 0 6182 6 0 0
v_max_non_paid_invoice 1701 1701 0 0.00 0 6055 8 0 0
v_max_non_paid_realizn 3855 3855 0 0.00 0 2376 2 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 2787 8 0 0
v_min_id_clo_res 928 928 0 0.00 0 6881 19 0 0
v_min_id_ord_sup 5264 5264 0 0.00 0 11914 9 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 10439 14 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 20302 17 0 0
v_our_firm 10350 10350 0 0.00 0 12 1 0 0
v_reserve_write_off 19834 19834 0 0.00 0 2037 5 0 0
x_lock_depdocs_on_canc_invoice 10958 10958 0 0.00 0 10075 97 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 2937 305 0 0
x_make_qstorno_1000_1200 5425 5425 0 0.00 1 23457 1257 0 0
x_make_qstorno_1200_2000 5290 5290 0 0.00 3 76469 3382 0 0
x_make_qstorno_2100_3300 444788 444788 0 0.00 0 34663 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2020 04:28:25 to 26-10-2020 04:28:29.

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
01:56:53 2 8192000000 8796633056 8807174144 3163600 0.038 1 1 2 0 3261296 61840 35856
01:57:55 27345 8192000000 9726152080 10024820736 804748656 8.944 100 87 174 1 833479184 7721552 21008976
01:59:27 14799 8192000000 9770431456 10081259520 842954272 9.329 100 87 174 0 876292736 12589104 20749360
02:00:48 14337 8192000000 9816204400 10137092096 876333760 9.663 100 82 163 0 918620688 23222624 19064304
02:02:09 10768 8192000000 9867576192 10201292800 922232720 10.118 100 79 156 1 979399936 39621984 17545232
02:03:23 8486 8192000000 9941066288 10287091712 968305680 10.570 100 82 163 1 1047038064 62013616 16718768
02:04:37 4677 8192000000 9962919488 10322059264 997685648 10.856 100 75 150 1 1078190608 65246112 15258848
02:05:43 2674 8192000000 9989311664 10355367936 1034941664 11.216 100 85 168 0 1106122160 53470336 17710160
02:06:47 1163 8192000000 10032047888 10398887936 1052351600 11.383 100 93 184 0 1137683904 66556048 18776256
02:07:48 9035 8192000000 9975806736 10363342848 1060391600 11.460 100 32 64 2 1096469488 28111200 7966688
02:08:59 6126 8192000000 9968898352 10372534272 1068103648 11.534 100 31 60 4 1085490160 10784672 6601840
02:10:07 4740 8192000000 9994020288 10380681216 1077962976 11.628 100 49 100 1 1106715056 18689984 10062096
02:11:16 73700 8192000000 10000392304 10405736448 1085521232 11.700 100 25 49 1 1101980752 11249808 5209712
02:13:31 7214 8192000000 10020881680 10410749952 1096673184 11.806 100 38 79 2 1128428976 22962656 8793136
02:14:39 5728 8192000000 10007358144 10408800256 1098748304 11.826 100 29 58 3 1141978528 37421424 5808800
02:15:50 8747 8192000000 10004591552 10402996224 1075518688 11.605 99 34 66 2 1097153776 14575552 7059536
02:16:59 45232 8192000000 9969956128 10371477504 1008229776 10.958 93 16 30 1 1031961232 20350768 3380688
02:18:46 5568 8192000000 9929488144 10303971328 981912896 10.703 95 34 67 1 1006785200 17189808 7682496
02:19:52 7725 8192000000 9901407760 10273849344 961365104 10.502 95 30 60 1 985924656 17782464 6777088
02:21:01 6674 8192000000 9950366816 10322845696 1005453680 10.931 98 37 73 3 1042201536 28879712 7868144
02:22:10 24622 8192000000 9938272672 10334740480 1004816736 10.925 97 20 40 0 1030273040 20703472 4752832
02:23:37 4006 8192000000 9974286720 10355011584 1052667120 11.386 100 50 96 1 1075549680 12803680 10078880
02:24:43 6157 8192000000 9985733648 10373492736 1066802992 11.522 100 41 81 2 1100534576 25235152 8496432
02:25:51 9878 8192000000 10010229088 10408759296 1072966448 11.580 100 42 85 0 1094850016 13104544 8779024
02:27:04 26665 8192000000 10024942672 10413346816 1098774736 11.826 100 51 102 3 1129823424 20199200 10849488
02:28:32 13309 8192000000 10097500784 10491101184 1114201632 11.972 100 61 119 2 1202059360 75300688 12557040
02:29:50 8298 8192000000 10097958832 10501070848 1129872544 12.120 100 55 110 1 1212634928 71284896 11477488
02:31:02 28779 8192000000 10124178976 10526838784 1143410960 12.248 100 44 88 0 1224065968 70873584 9781424
02:32:33 11363 8192000000 10156737776 10555756544 1149370656 12.304 99 62 124 0 1268653936 105330112 13953168
02:33:46 7858 8192000000 10190324864 10599636992 1164255536 12.443 100 51 101 2 1305839696 130691248 10892912
02:35:00 5854 8192000000 10233195184 10645598208 1166280128 12.462 100 56 112 2 1336423760 157810944 12332688
02:36:09 3031 8192000000 10259249856 10656419840 1153202976 12.340 98 68 131 2 1347032384 178991632 14837776
02:37:19 3703 8192000000 10287645024 10693263360 1169056000 12.488 99 67 135 2 1382402896 198803184 14543712
02:38:29 5616 8192000000 10302896480 10713399296 1156807904 12.373 100 49 96 3 1405075024 237224224 11042896
02:39:41 3273 8192000000 10306423648 10706178048 1152462800 12.333 100 76 151 1 1403018752 233730480 16825472
02:40:48 1814 8192000000 10335247872 10743947264 1162379568 12.426 100 80 160 0 1435054272 253786768 18887936
02:41:53 4461 8192000000 10310214768 10728779776 1126232960 12.086 100 77 154 0 1406218912 262159440 17826512
02:43:02 8013 8192000000 10246027104 10651652096 1106044928 11.895 98 46 90 2 1337536224 220762240 10729056
02:44:15 3662 8192000000 10267528768 10681049088 1126938736 12.092 99 56 108 3 1350856928 212509488 11408704
02:45:23 2795 8192000000 10279628624 10689404928 1136619856 12.184 100 58 116 0 1376382544 226402240 13360448
02:46:31 4536 8192000000 10301639376 10699538432 1133663984 12.156 100 63 124 1 1399601088 251520688 14416416
02:47:39 10840 8192000000 10377986256 10799411200 1144102416 12.254 99 40 80 0 1464416848 310243744 10070688
02:48:53 9341 8192000000 10429817216 10862645248 1144063392 12.254 100 34 68 0 1522580560 369611616 8905552
02:50:10 14121 8192000000 10441702864 10870837248 1163546016 12.436 100 35 70 2 1545018560 372461824 9010720
02:51:43 9855 8192000000 10427498992 10884038656 1151446736 12.323 100 30 60 1 1516506592 356762096 8297760
02:52:59 20270 8192000000 10368830544 10855870464 1124569648 12.070 99 20 38 3 1471299536 341277664 5452224
02:55:20 55780 8192000000 10077117952 10500227072 1150291584 12.312 100 23 44 0 1179421776 24393248 4736944
02:57:16 60616 8192000000 10103608368 10579374080 1134767888 12.166 99 12 24 0 1221831728 84761504 2302336
02:59:20 93996 8192000000 10106952976 10535927808 1114118880 11.971 97 17 34 2 1167615520 50585872 2910768
03:02:12 78510 8192000000 10068655376 10559213568 1123083472 12.056 99 18 36 3 1213366208 86207520 4075216
03:05:18 50556 8192000000 10083627328 10519343104 1145787936 12.270 100 43 84 4 1199685328 44657152 9240240
03:07:10 30321 8192000000 10090560064 10529722368 1150257024 12.312 100 45 89 1 1189823008 29049424 10516560
03:08:46 65328 8192000000 10114846640 10557681664 1141461792 12.229 99 13 26 1 1193500336 49411184 2627360
03:11:10 65072 8192000000 10151163920 10626953216 1158567888 12.390 100 11 22 2 1257734992 96418800 2748304
03:14:19 66021 8192000000 10079588832 10558636032 1117601888 12.004 97 14 28 1 1189674256 69150000 2922368
03:16:26 62666 8192000000 10061004784 10513240064 1121043312 12.037 98 7 14 2 1155426448 32820384 1562752
03:18:38 38002 8192000000 10079453520 10505134080 1131360304 12.134 99 30 60 1 1190385472 52456272 6568896
03:20:17 55581 8192000000 10072632336 10520989696 1144912416 12.262 100 16 32 1 1181875664 33761264 3201984
03:22:21 84665 8192000000 10102114544 10535895040 1115002848 11.980 97 17 35 0 1220995520 102199648 3793024
03:24:54 101849 8192000000 10083656432 10555379712 1067354944 11.527 94 20 40 1 1136250832 64803936 4091952
03:28:00 125655 8192000000 10051558576 10502492160 1012171680 10.996 89 15 30 0 1069660192 54577712 2910800
03:31:06 89972 8192000000 10040127152 10489376768 1081203984 11.659 97 17 34 1 1125205216 40254272 3746960
03:33:39 45313 8192000000 10047441296 10527715328 1110052288 11.933 100 4 8 0 1168644512 57574640 1017584
03:36:14 59938 8192000000 10037294688 10472476672 1084076352 11.686 97 9 18 0 1124617456 38599520 1941584
03:38:15 46166 8192000000 10061860256 10477633536 1087289760 11.717 97 13 26 1 1126588064 36334032 2964272
03:40:03 39449 8192000000 10032956752 10452332544 1068361776 11.536 96 21 42 3 1104290368 31403600 4524992
03:41:49 64708 8192000000 10098588320 10562445312 1114017232 11.970 100 22 44 1 1205087936 85757456 5313248
03:43:54 59200 8192000000 10061684048 10499723264 1122174096 12.048 100 20 40 2 1159992192 33712800 4105296
03:45:57 21544 8192000000 10175267808 10587779072 1139865936 12.214 100 61 122 1 1239071872 85639200 13566736
03:47:22 138119 8192000000 10088406560 10628878336 1115975824 11.989 99 15 30 0 1309456688 189663824 3817040
03:50:46 64513 8192000000 10038655136 10489413632 1108638224 11.920 99 4 8 1 1135589904 26268608 683072
03:52:51 99781 8192000000 10123084784 10554671104 1131627472 12.137 100 18 36 1 1228878192 93385744 3864976
03:55:34 52257 8192000000 10105991440 10553602048 1120425232 12.031 99 17 34 0 1197244656 73242384 3577040
03:57:37 64578 8192000000 10118589120 10577104896 1120751344 12.034 98 20 39 1 1190009808 64980896 4277568
04:00:27 68060 8192000000 10098283456 10561945600 1134623840 12.165 100 11 22 0 1187874080 51071440 2178800
04:02:36 72054 8192000000 10087482448 10521169920 1112341456 11.955 97 24 48 1 1148239776 30783968 5114352
04:04:52 113199 8192000000 10073822608 10539720704 1141415168 12.229 100 11 22 0 1191985456 48488448 2081840
04:07:45 22587 8192000000 10063426672 10533511168 1135760016 12.176 99 8 16 3 1160955936 23780208 1415712
04:09:08 44747 8192000000 10108091088 10555904000 1136211760 12.180 99 10 20 1 1192077392 53920080 1945552
04:10:55 58539 8192000000 10063236672 10534354944 1095574528 11.796 96 9 18 0 1134753616 37261488 1917600
04:12:54 81253 8192000000 10104663264 10547245056 1111934080 11.951 98 20 40 1 1180434320 64348288 4151952
04:15:45 81691 8192000000 10100557248 10587357184 1126649008 12.090 99 10 20 0 1204397920 75631584 2117328
04:18:19 32329 8192000000 10129837280 10565533696 1140062928 12.216 100 38 76 0 1200710624 53024384 7623312
04:19:53 13448 8192000000 10080568448 10497208320 1138251952 12.199 100 52 103 1 1175304512 24929984 12122576
04:21:12 110822 8192000000 10059713280 10526400512 1119829072 12.025 99 26 52 0 1167769936 42812304 5128560
04:24:06 70819 8192000000 10061556016 10522030080 1119165440 12.019 99 5 10 0 1158913136 38796592 951104
04:26:17 38418 8192000000 9085719856 9345675264 246007792 2.915 22 1 2 0 251972608 5894480 70336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-10-2020 04:28:31 to 26-10-2020 04:28:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
user_exc 80 v_add_invoice_to_stock 335544517
user_exc 7 v_cancel_supplier_invoice 335544517

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

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          234913
MON$OLDEST_ACTIVE               234914
MON$OLDEST_SNAPSHOT             234914
MON$NEXT_TRANSACTION            234914
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                       2104064
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             783
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'

26.10.20 04:28:50. 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-26 04:28:52 No severe PSQL-related problems occured
26.10.20 04:28:54. End of report.