Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.02.2021 07:28
Common Performance Final Results
  1. Hardware and OS info
  2. 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 was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. 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 16605756 kB
MemAvailable 31262732 kB
Buffers 0 kB
Cached 15095860 kB
SwapCached 4876 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 2021-02-03 07:26:53
DTS_END                         2021-02-03 07:26:53.5020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45144
TRN_ID                          222530
ATT_ID                          643
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: 8761.52 25888 1051382
customer order: creation 148.53 414 17823
customer order: refuse 12.72 303 1526
order to supplier: creation 40.60 1466 4872
order to supplier: removal 3.33 118868 400
invoice (draft): creation 39.96 4115 4795
invoice (draft): removal 52.62 874 6314
invoice accept: apply 36.64 18571 4397
invoice accept: cancel 47.23 828 5667
customer reserve: creation 3474.66 177 416959
customer reserve: removal 4641.15 67 556938
realization accept: apply 72.97 185 8757
realization accept: cancel 79.15 130 9498
payment from customer: creation 51.00 115 6120
payment from customer: removal 5.11 86 613
payment to supplier: creation 43.28 140 5194
payment to supplier: removal 3.01 102 361
service: total inventory turnovers 3.43 3350 411
service: total monetary turnovers 5.21 589 625
service: aggregate perf. data 0.89 13897 107
service: refresh index statistics 0.04 353483 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-02-2021 07:28:52 to 03-02-2021 07:28:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2021
TEST_TIME 2 3767
TEST_TIME 3 3528
TEST_TIME 4 3871
TEST_TIME 5 4027
TEST_TIME 6 4769
TEST_TIME 7 4855
TEST_TIME 8 5109
TEST_TIME 9 5345
TEST_TIME 10 5499
TEST_TIME 11 5335
TEST_TIME 12 5549
TEST_TIME 13 5688
TEST_TIME 14 5758
TEST_TIME 15 5956
TEST_TIME 16 6067
TEST_TIME 17 6519
TEST_TIME 18 6940
TEST_TIME 19 7563
TEST_TIME 20 8132
TEST_TIME 21 8487
TEST_TIME 22 8493
TEST_TIME 23 8692
TEST_TIME 24 9004
TEST_TIME 25 8844
TEST_TIME 26 8879
TEST_TIME 27 9978
TEST_TIME 28 10785
TEST_TIME 29 10785
TEST_TIME 30 10672
TEST_TIME 31 11211
TEST_TIME 32 10980
TEST_TIME 33 10734
TEST_TIME 34 10635
TEST_TIME 35 10424
TEST_TIME 36 10431
TEST_TIME 37 10296
TEST_TIME 38 10172
TEST_TIME 39 10001
TEST_TIME 40 10090
TEST_TIME 41 9941
TEST_TIME 42 9759
TEST_TIME 43 9708
TEST_TIME 44 9677
TEST_TIME 45 9609
TEST_TIME 46 9440
TEST_TIME 47 9385
TEST_TIME 48 9329
TEST_TIME 49 9202
TEST_TIME 50 9190
TEST_TIME 51 9147
TEST_TIME 52 9365
TEST_TIME 53 9314
TEST_TIME 54 9316
TEST_TIME 55 9318
TEST_TIME 56 9247
TEST_TIME 57 9353
TEST_TIME 58 9338
TEST_TIME 59 9299
TEST_TIME 60 9357
TEST_TIME 61 9431
TEST_TIME 62 9472
TEST_TIME 63 9370
TEST_TIME 64 9480
TEST_TIME 65 9335
TEST_TIME 66 9273
TEST_TIME 67 9203
TEST_TIME 68 9125
TEST_TIME 69 9082
TEST_TIME 70 9056
TEST_TIME 71 9045
TEST_TIME 72 9166
TEST_TIME 73 9133
TEST_TIME 74 9088
TEST_TIME 75 9061
TEST_TIME 76 9146
TEST_TIME 77 9183
TEST_TIME 78 9212
TEST_TIME 79 9174
TEST_TIME 80 9158
TEST_TIME 81 9265
TEST_TIME 82 9182
TEST_TIME 83 9092
TEST_TIME 84 9046
TEST_TIME 85 9021
TEST_TIME 86 8961
TEST_TIME 87 8934
TEST_TIME 88 8943
TEST_TIME 89 8934
TEST_TIME 90 8928
TEST_TIME 91 8887
TEST_TIME 92 8957
TEST_TIME 93 8975
TEST_TIME 94 8953
TEST_TIME 95 8958
TEST_TIME 96 9035
TEST_TIME 97 9017
TEST_TIME 98 9056
TEST_TIME 99 9102
TEST_TIME 100 9022
TEST_TIME 101 8955
TEST_TIME 102 8930
TEST_TIME 103 8891
TEST_TIME 104 8841
TEST_TIME 105 8792
TEST_TIME 106 8819
TEST_TIME 107 8803
TEST_TIME 108 8814
TEST_TIME 109 8792
TEST_TIME 110 8817
TEST_TIME 111 8818
TEST_TIME 112 8838
TEST_TIME 113 8832
TEST_TIME 114 8866
TEST_TIME 115 8858
TEST_TIME 116 8896
TEST_TIME 117 8897
TEST_TIME 118 8868
TEST_TIME 119 8810
TEST_TIME 120 8764

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-02-2021 07:28:55 to 03-02-2021 07:28:59.

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 1437172 1437172 0 0.00 0 25581 7 0 0
doc_list_biud 1437172 1437172 0 0.00 0 80155 32 0 0
sp_add_invoice_to_stock 4508 4397 111 2.46 41 165591 18571 0 111
sp_cancel_adding_invoice 5667 5667 0 0.00 5 86771 828 0 0
sp_cancel_client_order 1526 1526 0 0.00 4 5517 304 0 0
sp_cancel_customer_reserve 556938 556938 0 0.00 1 24002 67 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 3 2571 87 0 0
sp_cancel_pay_to_supplier 361 361 0 0.00 3 11113 102 0 0
sp_cancel_supplier_invoice 6323 6314 9 0.14 2 80159 874 0 9
sp_cancel_supplier_order 400 400 0 0.00 10 1060093 118868 0 0
sp_cancel_write_off 9498 9498 0 0.00 1 23207 130 0 0
sp_client_order 17823 17823 0 0.00 4 20090 415 0 0
sp_customer_reserve 416959 416959 0 0.00 0 39578 177 0 0
sp_fill_shopping_cart 27484 27484 0 0.00 1 20895 51 0 0
sp_fill_shopping_cart_clo_res 416956 416956 0 0.00 0 20244 18 0 0
sp_get_clo_for_invoice 4394 4394 0 0.00 0 29995 484 0 0
sp_kill_cost_storno 16139 16139 0 0.00 0 22243 66 0 0
sp_kill_qstorno_ret_qs2qd 1130088 1130088 0 0.00 0 80154 36 0 0
sp_kill_qty_storno 593493 593493 0 0.00 0 80155 77 0 0
sp_lock_selected_doc 18239 18239 0 0.00 0 9222 6 0 0
sp_make_cost_storno 24464 24464 0 0.00 0 25344 54 0 0
sp_multiply_rows_for_pdistr 24464 24464 0 0.00 0 25564 35 0 0
sp_multiply_rows_for_qdistr 17823 17823 0 0.00 0 20078 219 0 0
sp_pay_from_customer 6120 6120 0 0.00 4 25823 116 0 0
sp_pay_to_supplier 5194 5194 0 0.00 5 25822 141 0 0
sp_payment_common 11314 11314 0 0.00 1 23439 26 0 0
sp_qd_handle_on_cancel_clo 1526 1526 0 0.00 0 5420 273 0 0
sp_qd_handle_on_invoice_upd_sts 10060 10060 0 0.00 0 16411 387 0 0
sp_qd_handle_on_reserve_upd_sts 8757 8757 0 0.00 0 11177 54 0 0
sp_reserve_write_off 8757 8757 0 0.00 7 23175 186 0 0
sp_supplier_invoice 4795 4795 0 0.00 7 89792 4116 0 0
sp_supplier_order 4872 4872 0 0.00 5 41674 1467 0 0
srv_aggregate_perf_data 107 107 0 0.00 2990 143124 13898 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 865 129145 44117 0 0
srv_find_qd_qs_mism 1009193 1009193 0 0.00 0 9548 6 0 0
srv_make_invnt_saldo 411 411 0 0.00 81 90621 3350 0 0
srv_make_money_saldo 625 625 0 0.00 1 14855 590 0 0
srv_recalc_idx_stat 5 5 0 0.00 156932 602645 353483 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 4398 4398 0 0.00 0 13736 190 0 0
t$perf-norm:sp_cancel_adding_invoice 326 326 0 0.00 0 4746 110 0 0
t$perf-norm:sp_cancel_client_order 1526 1526 0 0.00 0 6606 21 0 0
t$perf-norm:sp_cancel_customer_reserve 609 609 0 0.00 0 1762 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 214 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 361 361 0 0.00 0 2320 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 347 347 0 0.00 0 602 9 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 17709 1498 0 0
t$perf-norm:sp_cancel_write_off 907 907 0 0.00 0 2405 7 0 0
t$perf-norm:sp_client_order 17823 17823 0 0.00 0 7677 18 0 0
t$perf-norm:sp_customer_reserve 911 911 0 0.00 0 470 3 0 0
t$perf-norm:sp_pay_from_customer 6120 6120 0 0.00 0 4262 9 0 0
t$perf-norm:sp_pay_to_supplier 5194 5194 0 0.00 0 2810 8 0 0
t$perf-norm:sp_reserve_write_off 8757 8757 0 0.00 0 6505 12 0 0
t$perf-norm:sp_supplier_invoice 4795 4795 0 0.00 0 10857 25 0 0
t$perf-norm:sp_supplier_order 4872 4872 0 0.00 0 3313 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 195 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 128 8 0 0
t$perf-norm:srv_make_invnt_saldo 411 411 0 0.00 0 3601 19 0 0
t$perf-norm:srv_make_money_saldo 625 625 0 0.00 0 1531 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9008 8786 222 2.46 0 7617 22 0 222
v_all_customers 32604 32604 0 0.00 0 18 1 0 0
v_all_suppliers 27100 27100 0 0.00 0 16 1 0 0
v_all_wares 35644 35644 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 652 652 0 0.00 0 3048 11 0 0
v_cancel_client_order 3052 3052 0 0.00 0 4606 10 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 2436 11 0 0
v_cancel_customer_reserve 1218 1218 0 0.00 0 2864 5 0 0
v_cancel_payment_to_supplier 722 722 0 0.00 0 6857 21 0 0
v_cancel_supplier_invoice 712 694 18 2.52 0 2891 19 0 18
v_cancel_supplier_order 800 800 0 0.00 0 3372 13 0 0
v_cancel_write_off 1814 1814 0 0.00 0 3211 16 0 0
v_max_id_clo_ord 4871 4871 0 0.00 0 238 1 0 0
v_max_id_clo_res 911 911 0 0.00 0 4359 20 0 0
v_max_id_ord_sup 4791 4791 0 0.00 0 8098 7 0 0
v_max_non_paid_invoice 1306 1306 0 0.00 0 85 2 0 0
v_max_non_paid_realizn 3207 3207 0 0.00 0 699 3 0 0
v_min_id_clo_ord 4871 4871 0 0.00 0 2688 5 0 0
v_min_id_clo_res 911 911 0 0.00 0 5412 20 0 0
v_min_id_ord_sup 4791 4791 0 0.00 0 4272 8 0 0
v_min_non_paid_invoice 5194 5194 0 0.00 0 23199 24 0 0
v_min_non_paid_realizn 6120 6120 0 0.00 0 23433 16 0 0
v_our_firm 8866 8866 0 0.00 0 16 1 0 0
v_reserve_write_off 17514 17514 0 0.00 0 7253 9 0 0
x_lock_depdocs_on_canc_invoice 11634 11634 0 0.00 0 10600 114 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 14118 418 0 0
x_make_qstorno_1000_1200 4872 4872 0 0.00 2 41665 1382 0 0
x_make_qstorno_1200_2000 4795 4795 0 0.00 4 88896 3919 0 0
x_make_qstorno_2100_3300 416959 416959 0 0.00 0 39576 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-02-2021 07:29:00 to 03-02-2021 07:29:04.

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:57 1 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
04:57:58 26241 8192000000 9718208192 10009554944 793718416 8.833 100 88 176 0 822612016 7472560 21421040
04:59:32 17528 8192000000 9757204448 10063912960 830850512 9.208 100 82 163 0 863704944 12817376 20037056
05:00:56 16954 8192000000 9810652544 10128691200 875266880 9.653 100 81 163 0 918330384 24383936 18679568
05:02:16 15052 8192000000 9874339648 10207887360 926520976 10.160 100 77 153 0 980095728 37678464 15896288
05:03:36 9195 8192000000 9943812784 10290540544 971122544 10.598 100 79 158 0 1045939744 58805712 16011488
05:04:49 4349 8192000000 9981058880 10339594240 1008557008 10.961 100 78 152 1 1091709920 67854064 15298848
05:05:55 2429 8192000000 10014757328 10375942144 1030251824 11.171 100 91 181 3 1126300592 77889632 18159136
05:07:00 2033 8192000000 10040566240 10408787968 1054376912 11.403 100 87 173 0 1152260400 79700240 18183248
05:08:03 40156 8192000000 9996410592 10395566080 1060499936 11.461 100 41 83 0 1122918848 53725200 8693712
05:09:45 4895 8192000000 10002370880 10386726912 1069655840 11.549 100 48 96 1 1110135680 30592720 9887120
05:10:51 7093 8192000000 9994925312 10383851520 1080044448 11.648 100 28 56 0 1111365632 24745008 6576176
05:11:58 11418 8192000000 10000438400 10412937216 1089173840 11.735 100 24 48 2 1129360704 35070064 5116800
05:13:13 27030 8192000000 10023717568 10407899136 1090184912 11.744 100 54 111 2 1111040704 9350336 11505456
05:14:41 10542 8192000000 10008339872 10418200576 1096817408 11.807 100 38 77 2 1127488752 21739568 8931776
05:15:55 7993 8192000000 10006027728 10417549312 1094273376 11.783 100 25 50 2 1118313488 18794160 5245952
05:17:07 8686 8192000000 9993132176 10392625152 1066715696 11.521 98 14 28 0 1094773376 25358592 2699088
05:18:15 32499 8192000000 9941831680 10340114432 986970896 10.752 92 27 54 1 1007059248 14304576 5783776
05:19:52 5625 8192000000 9950848304 10326630400 1022224432 11.093 98 41 80 3 1048956112 17609568 9122112
05:20:59 9910 8192000000 9914694144 10297954304 993793136 10.818 97 24 46 0 1030167376 31033392 5340848
05:22:11 64532 8192000000 9946551568 10351132672 1027943312 11.149 99 48 99 0 1060682704 21847808 10891584
05:24:20 4414 8192000000 9968590608 10353045504 1050290896 11.363 100 49 97 1 1074153120 12140096 11722128
05:25:25 6346 8192000000 9976304352 10363060224 1064937808 11.504 100 48 95 3 1083913168 8366912 10608448
05:26:32 12457 8192000000 10020858288 10426224640 1086689808 11.711 100 26 52 0 1121627104 29998672 4938624
05:27:53 53191 8192000000 10044099904 10442633216 1106824096 11.902 100 35 73 1 1147227664 33499856 6903712
05:29:51 8437 8192000000 10084817632 10486571008 1125110336 12.075 100 48 96 1 1195136368 59545616 10480416
05:31:03 4835 8192000000 10123028240 10524508160 1132567040 12.146 100 67 129 3 1233130368 87131984 13431344
05:32:13 2072 8192000000 10172649824 10564907008 1147657248 12.288 100 63 126 0 1271356368 110423360 13275760
05:33:18 13661 8192000000 10214143136 10621763584 1169205728 12.489 100 57 114 0 1326330544 144890304 12234512
05:34:41 14420 8192000000 10259950672 10671886336 1174059600 12.535 100 53 103 2 1368501440 182680144 11761696
05:36:11 6303 8192000000 10252381200 10661015552 1148366352 12.294 99 53 105 2 1347946784 187408640 12171792
05:37:20 12561 8192000000 10281857440 10686795776 1159856256 12.402 99 54 108 0 1377594640 205197632 12540752
05:38:41 10002 8192000000 10310889600 10715127808 1162201984 12.424 100 66 130 2 1413189712 236165872 14821856
05:39:58 1575 8192000000 10344465136 10744238080 1164480240 12.445 100 82 159 0 1444607328 261785152 18341936
05:41:04 1746 8192000000 10391923408 10784772096 1152630256 12.334 99 79 150 1 1478515056 308134416 17750384
05:42:10 3254 8192000000 10401182864 10814291968 1154606448 12.353 100 61 122 2 1503099952 333193200 15300304
05:43:16 863 8192000000 10458456960 10853875712 1163996656 12.441 100 89 179 0 1554208384 369167168 21044560
05:44:23 1123 8192000000 10451428224 10864005120 1153603952 12.343 100 90 180 0 1545523376 369731728 22187696
05:45:27 7381 8192000000 10440936352 10851471360 1136575184 12.183 99 61 117 0 1536436848 385632576 14229088
05:46:43 22776 8192000000 10316699648 10851004416 1141046864 12.225 100 26 52 1 1476685680 328526912 7111904
05:48:36 14147 8192000000 10331699520 10774519808 1134523712 12.164 99 31 61 1 1416271504 274554224 7193568
05:49:51 48368 8192000000 10335771280 10789707776 1105232176 11.887 97 25 50 2 1407721680 296121056 6368448
05:51:42 33561 8192000000 10402697328 10848460800 1099601904 11.834 99 25 50 1 1477095184 370432688 7060592
05:53:23 11239 8192000000 10261146736 10764132352 1114770704 11.978 99 30 60 0 1380101728 257276064 8054960
05:54:37 30284 8192000000 10148547216 10645622784 1133447616 12.154 100 16 32 2 1284187088 146683824 4055648
05:56:44 42051 8192000000 10145596224 10627809280 1073948624 11.590 96 9 18 0 1248584880 172540480 2095776
05:58:31 38091 8192000000 10036277968 10468859904 1087993200 11.724 99 20 39 1 1139758464 47098912 4666352
06:00:29 45638 8192000000 10073247168 10528477184 1113573104 11.966 100 13 27 0 1176173168 60088832 2511232
06:02:18 42682 8192000000 10066135088 10543419392 1123793872 12.063 100 12 24 0 1168663152 42481664 2387616
06:04:07 54000 8192000000 10095384976 10555518976 1135560208 12.174 100 18 36 2 1176735792 37495104 3680480
06:06:10 129145 8192000000 10070731424 10506113024 1133383392 12.153 100 24 48 0 1215717968 76995552 5339024
06:10:57 78863 8192000000 10084334768 10558189568 1140957824 12.225 100 14 29 1 1200855104 56903008 2994272
06:13:29 58966 8192000000 10125875488 10593755136 1152911136 12.337 100 12 24 0 1234887184 79129344 2846704
06:16:02 83267 8192000000 10176817760 10681049088 1165862432 12.458 100 22 45 0 1281454592 110435776 5156384
06:19:52 61810 8192000000 10094728720 10572025856 1115896896 11.988 96 15 30 1 1170243504 51114384 3232224
06:21:56 25279 8192000000 10101446544 10537451520 1152652016 12.334 100 35 70 0 1195342752 35217744 7472992
06:23:25 44236 8192000000 10090916832 10570485760 1115061696 11.980 97 28 56 0 1176269584 55579664 5628224
06:25:10 39638 8192000000 10111244784 10540498944 1155120880 12.358 100 37 73 1 1205820304 43057376 7642048
06:26:55 48967 8192000000 10158674992 10611064832 1160500992 12.408 100 13 26 0 1255899984 92776656 2622336
06:28:52 75067 8192000000 10130611168 10590187520 1107572464 11.909 95 27 54 0 1184092000 71109872 5409664
06:31:13 76450 8192000000 10084947824 10522648576 1149884896 12.308 100 25 50 0 1193769392 39069168 4815328
06:33:34 49883 8192000000 10091775648 10551181312 1159361632 12.397 100 9 18 0 1187086256 25887184 1837440
06:35:31 58128 8192000000 10121710832 10595430400 1139504976 12.211 98 14 28 1 1207716432 64839696 3371760
06:37:30 112319 8192000000 10098133648 10564120576 1107343072 11.907 96 19 38 3 1184478640 72969824 4165744
06:40:22 32621 8192000000 10103298784 10527846400 1125506576 12.079 99 30 61 1 1185519056 53115600 6896880
06:41:57 52060 8192000000 10121231248 10594336768 1131772688 12.138 99 32 64 0 1220072672 81616032 6683952
06:43:50 38769 8192000000 10082453648 10503827456 1101763056 11.854 97 38 76 0 1141924176 31706640 8454480
06:45:31 28697 8192000000 10065995536 10502795264 1104238336 11.878 98 46 92 0 1127013472 13052224 9722912
06:47:08 52456 8192000000 10079776176 10597351424 1119999504 12.027 99 11 22 0 1180081792 58009280 2073008
06:49:00 90248 8192000000 10065987760 10526679040 1123496176 12.060 99 23 46 0 1167188816 38357232 5335408
06:51:35 44012 8192000000 10051802864 10525798400 1072949104 11.580 95 4 8 0 1113527712 39798352 780256
06:53:27 69377 8192000000 10047509760 10555072512 1120252400 12.029 100 4 8 0 1158830640 37807328 770912
06:55:38 50841 8192000000 10065124736 10503925760 1077855600 11.627 97 25 50 1 1119387664 35592960 5939104
06:57:44 63866 8192000000 10085405616 10585890816 1109105184 11.924 99 22 44 1 1188729936 74939824 4684928
06:59:54 36416 8192000000 10076501456 10508021760 1092144576 11.763 97 15 30 1 1152449376 57521072 2783728
07:01:31 59453 8192000000 10070773968 10518556672 1085390816 11.699 97 23 46 0 1146620624 56725648 4504160
07:04:01 51700 8192000000 10068970208 10538319872 1081150800 11.658 96 12 24 0 1138891488 55179168 2561520
07:06:04 126171 8192000000 10054106944 10507993088 1110200592 11.934 99 15 30 0 1158537552 45481840 2855120
07:09:18 69121 8192000000 10050651312 10505945088 1113003952 11.961 99 8 16 0 1152597936 37969120 1624864
07:11:27 50415 8192000000 10106818288 10572922880 1132390432 12.144 100 11 21 0 1195566592 60816432 2359728
07:13:18 62805 8192000000 10084674896 10536689664 1134517680 12.164 100 13 26 0 1193268592 56068432 2682480
07:15:31 23959 8192000000 10098719072 10525224960 1125628560 12.080 99 40 80 0 1178753616 43697440 9427616
07:17:09 29118 8192000000 10073685888 10507386880 1114646400 11.976 98 28 56 0 1149925920 29383328 5896192
07:18:45 68413 8192000000 10089369664 10520723456 1124293040 12.068 99 34 66 0 1172830512 41287456 7250016
07:20:54 79134 8192000000 10108231600 10573836288 1127716928 12.100 99 25 50 1 1175384928 42632816 5035184
07:23:17 113405 8192000000 10084773296 10567131136 1145285728 12.265 100 19 38 0 1216898336 67824592 3788016
07:26:46 19942 8192000000 9520901136 9875378176 652733584 7.379 57 1 2 0 658517552 5748560 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-02-2021 07:29:06 to 03-02-2021 07:29:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-02-2021 07:29:21 to 03-02-2021 07:29:23.

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          222756
MON$OLDEST_ACTIVE               222757
MON$OLDEST_SNAPSHOT             222757
MON$NEXT_TRANSACTION            222757
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                       2100864
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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'

03.02.21 07:29:24. 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
2021-02-03 07:29:27 No severe PSQL-related problems occured
03.02.21 07:29:28. End of report.