Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.03.2021 10: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 df9f488d08424df294c459d6c283e9c3
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 2021-03-04 16:07
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.26
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 16947564 kB
MemAvailable 31569112 kB
Buffers 0 kB
Cached 14746056 kB
SwapCached 0 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-03-21 10:27:29
DTS_END                         2021-03-21 10:26:57.3190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35124
TRN_ID                          222039
ATT_ID                          259
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: 8731.88 22642 1047825
customer order: creation 158.93 431 19071
customer order: refuse 13.58 306 1630
order to supplier: creation 42.25 1373 5070
order to supplier: removal 3.14 115885 377
invoice (draft): creation 42.40 3706 5088
invoice (draft): removal 50.38 936 6046
invoice accept: apply 38.73 17141 4647
invoice accept: cancel 45.41 882 5449
customer reserve: creation 3572.02 166 428642
customer reserve: removal 4489.08 64 538689
realization accept: apply 76.55 166 9186
realization accept: cancel 82.12 121 9854
payment from customer: creation 55.16 112 6619
payment from customer: removal 5.10 96 612
payment to supplier: creation 44.15 145 5298
payment to supplier: removal 3.34 72 401
service: total inventory turnovers 3.51 3399 421
service: total monetary turnovers 5.25 577 630
service: aggregate perf. data 0.76 20555 91
service: refresh index statistics 0.03 286709 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-03-2021 10:29:04 to 21-03-2021 10:29:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2285
TEST_TIME 2 2405
TEST_TIME 3 2681
TEST_TIME 4 2911
TEST_TIME 5 3081
TEST_TIME 6 3212
TEST_TIME 7 4100
TEST_TIME 8 4340
TEST_TIME 9 4333
TEST_TIME 10 4498
TEST_TIME 11 5114
TEST_TIME 12 5555
TEST_TIME 13 5993
TEST_TIME 14 6330
TEST_TIME 15 6586
TEST_TIME 16 6885
TEST_TIME 17 7616
TEST_TIME 18 7873
TEST_TIME 19 8346
TEST_TIME 20 8527
TEST_TIME 21 8684
TEST_TIME 22 8981
TEST_TIME 23 9306
TEST_TIME 24 9256
TEST_TIME 25 9281
TEST_TIME 26 9584
TEST_TIME 27 9576
TEST_TIME 28 10766
TEST_TIME 29 10624
TEST_TIME 30 10551
TEST_TIME 31 10540
TEST_TIME 32 10405
TEST_TIME 33 10267
TEST_TIME 34 10107
TEST_TIME 35 10046
TEST_TIME 36 10025
TEST_TIME 37 9886
TEST_TIME 38 9672
TEST_TIME 39 9681
TEST_TIME 40 9600
TEST_TIME 41 9569
TEST_TIME 42 9577
TEST_TIME 43 9421
TEST_TIME 44 9449
TEST_TIME 45 9426
TEST_TIME 46 9250
TEST_TIME 47 9279
TEST_TIME 48 9213
TEST_TIME 49 9155
TEST_TIME 50 9190
TEST_TIME 51 9082
TEST_TIME 52 9083
TEST_TIME 53 9105
TEST_TIME 54 9010
TEST_TIME 55 9112
TEST_TIME 56 9194
TEST_TIME 57 9244
TEST_TIME 58 9179
TEST_TIME 59 9125
TEST_TIME 60 9190
TEST_TIME 61 9267
TEST_TIME 62 9261
TEST_TIME 63 9120
TEST_TIME 64 9046
TEST_TIME 65 8961
TEST_TIME 66 8997
TEST_TIME 67 8933
TEST_TIME 68 8897
TEST_TIME 69 8894
TEST_TIME 70 8856
TEST_TIME 71 8832
TEST_TIME 72 8865
TEST_TIME 73 8916
TEST_TIME 74 8963
TEST_TIME 75 8979
TEST_TIME 76 8942
TEST_TIME 77 8985
TEST_TIME 78 9080
TEST_TIME 79 9101
TEST_TIME 80 9036
TEST_TIME 81 8934
TEST_TIME 82 8940
TEST_TIME 83 8884
TEST_TIME 84 8812
TEST_TIME 85 8803
TEST_TIME 86 8783
TEST_TIME 87 8795
TEST_TIME 88 8765
TEST_TIME 89 8785
TEST_TIME 90 8760
TEST_TIME 91 8767
TEST_TIME 92 8733
TEST_TIME 93 8724
TEST_TIME 94 8714
TEST_TIME 95 8980
TEST_TIME 96 8946
TEST_TIME 97 8966
TEST_TIME 98 8984
TEST_TIME 99 8963
TEST_TIME 100 8899
TEST_TIME 101 8874
TEST_TIME 102 8867
TEST_TIME 103 8871
TEST_TIME 104 8850
TEST_TIME 105 8837
TEST_TIME 106 8854
TEST_TIME 107 8840
TEST_TIME 108 8885
TEST_TIME 109 8887
TEST_TIME 110 8907
TEST_TIME 111 8896
TEST_TIME 112 8907
TEST_TIME 113 8941
TEST_TIME 114 8948
TEST_TIME 115 8906
TEST_TIME 116 8880
TEST_TIME 117 8829
TEST_TIME 118 8792
TEST_TIME 119 8773
TEST_TIME 120 8733

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-03-2021 10:29:08 to 21-03-2021 10:29:11.

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 1445181 1445181 0 0.00 0 29945 6 0 0
doc_list_biud 1445180 1445180 0 0.00 0 73956 30 0 0
sp_add_invoice_to_stock 4769 4647 122 2.55 33 176328 17141 0 122
sp_cancel_adding_invoice 5449 5449 0 0.00 6 73793 882 0 0
sp_cancel_client_order 1630 1630 0 0.00 5 10396 307 0 0
sp_cancel_customer_reserve 538689 538689 0 0.00 1 21386 64 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 3 7442 96 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 1532 72 0 0
sp_cancel_supplier_invoice 6050 6046 4 0.06 2 73963 936 0 4
sp_cancel_supplier_order 377 377 0 0.00 6 970827 115885 0 0
sp_cancel_write_off 9854 9854 0 0.00 1 12583 121 0 0
sp_client_order 19071 19071 0 0.00 4 57086 431 0 0
sp_customer_reserve 428642 428642 0 0.00 0 54399 166 0 0
sp_fill_shopping_cart 29216 29216 0 0.00 1 22917 46 0 0
sp_fill_shopping_cart_clo_res 428639 428639 0 0.00 0 19156 18 0 0
sp_get_clo_for_invoice 4644 4644 0 0.00 0 55258 508 0 0
sp_kill_cost_storno 16316 16316 0 0.00 0 20412 58 0 0
sp_kill_qstorno_ret_qs2qd 1093655 1093655 0 0.00 0 73955 35 0 0
sp_kill_qty_storno 575874 575874 0 0.00 0 73956 75 0 0
sp_lock_selected_doc 19150 19150 0 0.00 0 7081 5 0 0
sp_make_cost_storno 25747 25747 0 0.00 0 29929 64 0 0
sp_multiply_rows_for_pdistr 25746 25746 0 0.00 0 7714 29 0 0
sp_multiply_rows_for_qdistr 19071 19071 0 0.00 0 57032 243 0 0
sp_pay_from_customer 6619 6619 0 0.00 4 15912 113 0 0
sp_pay_to_supplier 5298 5298 0 0.00 5 23122 145 0 0
sp_payment_common 11917 11917 0 0.00 2 9000 20 0 0
sp_qd_handle_on_cancel_clo 1630 1630 0 0.00 0 10388 272 0 0
sp_qd_handle_on_invoice_upd_sts 10092 10092 0 0.00 0 52302 384 0 0
sp_qd_handle_on_reserve_upd_sts 9186 9186 0 0.00 0 12564 50 0 0
sp_reserve_write_off 9186 9186 0 0.00 7 18097 166 0 0
sp_supplier_invoice 5088 5088 0 0.00 7 103683 3707 0 0
sp_supplier_order 5070 5070 0 0.00 6 62796 1373 0 0
srv_aggregate_perf_data 91 91 0 0.00 2639 145564 20555 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1532 205912 53770 0 0
srv_find_qd_qs_mism 1004067 1004067 0 0.00 0 12543 5 0 0
srv_make_invnt_saldo 421 421 0 0.00 56 102332 3400 0 0
srv_make_money_saldo 630 630 0 0.00 0 13429 577 0 0
srv_recalc_idx_stat 4 4 0 0.00 177800 419989 286710 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4647 4647 0 0.00 0 15744 182 0 0
t$perf-norm:sp_cancel_adding_invoice 348 348 0 0.00 0 3451 124 0 0
t$perf-norm:sp_cancel_client_order 1630 1630 0 0.00 0 10628 22 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 1831 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 1637 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 239 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 2257 19 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 19346 1636 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 7030 14 0 0
t$perf-norm:sp_client_order 19071 19071 0 0.00 0 10197 19 0 0
t$perf-norm:sp_customer_reserve 886 886 0 0.00 0 3501 9 0 0
t$perf-norm:sp_pay_from_customer 6619 6619 0 0.00 0 6058 9 0 0
t$perf-norm:sp_pay_to_supplier 5298 5298 0 0.00 0 4806 8 0 0
t$perf-norm:sp_reserve_write_off 9186 9186 0 0.00 0 4963 9 0 0
t$perf-norm:sp_supplier_invoice 5088 5088 0 0.00 0 5881 18 0 0
t$perf-norm:sp_supplier_order 5070 5070 0 0.00 0 8466 16 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 1150 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 149 3 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 277 5 0 0
t$perf-norm:srv_make_money_saldo 630 630 0 0.00 0 202 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9528 9284 244 2.56 0 9029 17 0 244
v_all_customers 34900 34900 0 0.00 0 19 1 0 0
v_all_suppliers 27916 27916 0 0.00 0 18 1 0 0
v_all_wares 38140 38140 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 696 696 0 0.00 0 1825 10 0 0
v_cancel_client_order 3260 3260 0 0.00 0 1708 5 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 3857 10 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 1680 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 524 4 0 0
v_cancel_supplier_invoice 772 764 8 1.03 0 637 15 0 8
v_cancel_supplier_order 754 754 0 0.00 0 667 4 0 0
v_cancel_write_off 1854 1854 0 0.00 0 1707 13 0 0
v_max_id_clo_ord 5066 5066 0 0.00 0 2006 2 0 0
v_max_id_clo_res 886 886 0 0.00 0 6761 24 0 0
v_max_id_ord_sup 5080 5080 0 0.00 0 2788 2 0 0
v_max_non_paid_invoice 1486 1486 0 0.00 0 4485 5 0 0
v_max_non_paid_realizn 3421 3421 0 0.00 0 5684 5 0 0
v_min_id_clo_ord 5066 5066 0 0.00 0 10243 13 0 0
v_min_id_clo_res 886 886 0 0.00 0 4055 16 0 0
v_min_id_ord_sup 5080 5080 0 0.00 0 6119 10 0 0
v_min_non_paid_invoice 5298 5298 0 0.00 0 7226 12 0 0
v_min_non_paid_realizn 6619 6619 0 0.00 0 8997 14 0 0
v_our_firm 9636 9636 0 0.00 0 15 1 0 0
v_reserve_write_off 18372 18372 0 0.00 0 2595 5 0 0
x_lock_depdocs_on_canc_invoice 11113 11113 0 0.00 0 14949 97 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 9327 371 0 0
x_make_qstorno_1000_1200 5070 5070 0 0.00 2 62786 1291 0 0
x_make_qstorno_1200_2000 5088 5088 0 0.00 4 103675 3534 0 0
x_make_qstorno_2100_3300 428642 428642 0 0.00 0 54399 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-03-2021 10:29:12 to 21-03-2021 10:29:16.

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:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 32890 8192000000 9734406272 10030354432 801792576 8.914 100 83 166 0 829887360 7264640 20830144
07:59:42 21229 8192000000 9769364256 10075967488 842565040 9.326 100 90 180 1 876294608 12699248 21030320
08:01:12 18258 8192000000 9814366816 10130055168 870421904 9.604 100 84 168 1 912959072 23283472 19253696
08:02:55 11674 8192000000 9895748336 10231021568 940454672 10.297 100 83 166 0 1002063840 44214864 17394304
08:04:11 5848 8192000000 9956472128 10303340544 980088384 10.685 100 81 162 0 1070419376 73177040 17153952
08:05:20 4339 8192000000 9987924096 10340511744 1007623616 10.952 100 84 167 0 1097571600 73033792 16914192
08:06:26 3783 8192000000 10020153472 10387226624 1030436624 11.173 100 80 160 1 1130811472 84326304 16048544
08:07:32 2972 8192000000 10008297680 10374500352 1053763648 11.397 100 85 170 1 1124462928 53466400 17232880
08:08:38 1686 8192000000 10038065888 10408980480 1070133168 11.553 100 90 179 0 1146015008 57556416 18325424
08:09:41 7826 8192000000 9989754128 10382991360 1069385440 11.546 100 32 64 0 1123983440 48185568 6412432
08:10:49 4779 8192000000 9995217920 10389995520 1077816944 11.627 100 43 86 0 1128958080 42575824 8565312
08:11:55 5117 8192000000 9999197296 10392584192 1080645712 11.654 100 44 88 2 1120736416 30485184 9605520
08:13:03 53089 8192000000 10007739872 10396549120 1086022768 11.705 100 39 78 0 1114956112 19934144 8999200
08:14:57 2892 8192000000 10038403216 10434236416 1090853232 11.751 100 65 130 1 1141782768 37444736 13484800
08:16:01 7008 8192000000 10019861808 10425434112 1090281552 11.745 100 28 56 0 1131096640 35196624 5618464
08:17:12 6203 8192000000 10007490848 10409291776 1077726880 11.626 99 40 80 0 1116808736 30459824 8622032
08:18:22 55888 8192000000 9985924160 10399727616 1028933632 11.158 96 41 82 0 1060656912 22850432 8872848
08:20:23 8303 8192000000 9971614976 10361638912 1010602464 10.981 97 44 88 2 1054922352 34238080 10081808
08:21:34 9092 8192000000 9961539280 10353958912 1025136832 11.122 99 27 55 1 1062403520 31082480 6184208
08:22:45 11484 8192000000 9945043104 10348457984 1027026704 11.140 99 35 70 3 1065979312 31341424 7611184
08:23:56 18832 8192000000 9936070528 10332844032 1018137824 11.054 98 15 29 3 1048242768 27067424 3037520
08:25:17 6391 8192000000 9970540976 10365378560 1056913232 11.427 100 49 98 2 1080152032 12680096 10558704
08:26:24 3062 8192000000 9980251840 10369503232 1062957424 11.485 100 41 78 2 1084928000 12373232 9597344
08:27:35 7045 8192000000 10007763248 10398568448 1084070464 11.686 100 50 95 5 1108452208 14030528 10351216
08:28:52 46630 8192000000 10056047664 10454310912 1102898432 11.865 100 46 89 0 1167425248 55466528 9060288
08:30:50 11732 8192000000 10077018288 10484961280 1119787488 12.025 100 35 70 0 1186227232 59152560 7287184
08:32:07 25607 8192000000 10121104608 10531381248 1132081072 12.141 100 43 87 1 1227880416 86353520 9445824
08:33:35 13760 8192000000 10163331312 10572320768 1137760112 12.194 100 55 110 0 1270267680 120309488 12198080
08:35:17 11024 8192000000 10183454720 10597023744 1156009408 12.366 100 46 92 0 1290608384 124468736 10130240
08:36:33 6463 8192000000 10256570096 10664243200 1171599680 12.512 100 64 126 0 1361063312 175864928 13598704
08:37:42 5962 8192000000 10296073984 10703495168 1176156992 12.554 100 71 142 0 1400949776 208407344 16385440
08:38:52 3130 8192000000 10286154528 10712416256 1164568112 12.446 99 88 174 1 1399978928 215326768 20084048
08:40:08 1530 8192000000 10309081616 10719870976 1189985808 12.683 100 89 173 1 1409929584 201007696 18936080
08:41:14 4724 8192000000 10308500704 10723356672 1174366976 12.538 100 74 149 1 1410489904 219325968 16796960
08:42:24 2906 8192000000 10344094336 10759393280 1149578544 12.306 99 80 160 0 1432124704 264208672 18337488
08:43:32 1846 8192000000 10334675856 10744520704 1164827424 12.448 100 82 163 0 1434187328 250533264 18826640
08:44:37 6223 8192000000 10272028976 10705510400 1152830656 12.336 100 57 110 0 1371272384 205092976 13348752
08:45:47 5943 8192000000 10264567104 10699669504 1139684848 12.213 99 44 88 0 1354054528 203508672 10861008
08:46:54 3826 8192000000 10268641120 10676744192 1135941696 12.177 99 70 140 1 1370137712 217917264 16278752
08:48:02 14372 8192000000 10293522528 10738364416 1140879888 12.224 99 32 63 0 1407426224 258543520 8002816
08:49:20 9288 8192000000 10294617648 10738728960 1147141584 12.283 100 36 71 1 1392084208 236230352 8712272
08:50:36 12475 8192000000 10260027536 10700148736 1117656256 12.005 98 27 54 0 1346286576 221974592 6655728
08:51:56 22769 8192000000 10290358160 10782842880 1121968368 12.046 99 25 50 1 1422793808 294512992 6312448
08:53:39 27350 8192000000 10314079600 10805673984 1106016112 11.895 99 18 36 1 1432192704 321074032 5102560
08:55:09 99331 8192000000 10119470352 10560192512 1094582368 11.786 98 49 98 0 1183899200 77690976 11625856
08:58:02 58038 8192000000 10058760656 10512367616 1091600144 11.758 97 11 22 0 1149294560 55796448 1897968
09:00:08 92162 8192000000 10133949104 10586259456 1133402208 12.153 100 21 42 2 1226736352 88980624 4353520
09:03:00 140499 8192000000 10146374800 10606804992 1114967600 11.979 98 33 63 2 1197276256 75260320 7048336
09:06:33 24078 8192000000 10100958992 10569912320 1120814960 12.035 99 21 43 1 1200025280 74514528 4695792
09:08:45 56307 8192000000 10097943072 10540019712 1133895568 12.158 100 26 52 1 1204010832 64752096 5363168
09:10:48 68959 8192000000 10065466336 10533220352 1096117280 11.801 98 11 22 0 1160186000 61807456 2261264
09:12:57 105563 8192000000 10055092192 10565877760 1111680768 11.948 99 8 16 1 1162755472 49412096 1662608
09:15:44 89433 8192000000 10055391264 10553647104 1104055232 11.876 98 10 20 0 1175050496 68819472 2175792
09:18:13 72169 8192000000 10096164544 10561818624 1122204304 12.048 99 27 55 1 1217518272 89055472 6258496
09:20:34 88128 8192000000 10107045328 10576961536 1122976768 12.055 99 16 32 0 1210883504 84181904 3724832
09:23:03 67786 8192000000 10060999136 10525802496 1115092272 11.981 98 18 36 0 1184358976 65324656 3942048
09:25:15 27036 8192000000 10098809104 10528129024 1132691712 12.147 99 51 102 3 1186163808 42857872 10614224
09:26:43 205911 8192000000 10068987856 10507280384 1077985344 11.628 94 19 38 0 1110148352 28372768 3790240
09:31:33 49836 8192000000 10069743600 10571857920 1135390128 12.172 100 5 10 0 1185587184 49093024 1104032
09:33:27 83862 8192000000 10065564912 10532851712 1120586208 12.033 99 16 32 1 1169953200 46098304 3268688
09:35:59 38822 8192000000 10098052320 10546925568 1097446352 11.813 96 38 76 0 1144799360 39043632 8309376
09:37:44 106714 8192000000 10140530784 10602061824 1063624080 11.491 93 21 42 0 1174166752 105813888 4728784
09:40:31 86358 8192000000 10091968016 10561126400 1085521968 11.700 96 24 48 0 1147275632 56449776 5303888
09:43:12 126948 8192000000 10086618192 10537148416 1056179056 11.420 94 11 22 0 1107670176 49469568 2021552
09:46:20 131826 8192000000 10060035536 10522603520 1087048144 11.715 98 32 64 0 1147996864 54342208 6606512
09:49:39 53282 8192000000 10059568752 10503102464 1071149504 11.563 96 13 26 2 1134767936 60738880 2879552
09:51:41 58012 8192000000 10055209392 10491113472 1085278848 11.698 98 10 20 0 1144119744 56558032 2282864
09:53:40 31895 8192000000 10078666192 10543210496 1111921376 11.951 100 19 38 0 1170483872 54368880 4193616
09:55:21 46622 8192000000 10119475968 10587922432 1103998400 11.876 98 29 59 0 1203733200 92431712 7303088
09:57:20 38117 8192000000 10148420496 10585833472 1115218720 11.982 98 31 62 0 1219704688 97703920 6782048
09:59:01 45779 8192000000 10182536752 10623127552 1123302016 12.058 98 22 44 0 1271353888 143072000 4979872
10:00:47 104214 8192000000 10078404848 10550415360 1107626592 11.910 98 10 20 0 1189296688 79505200 2164896
10:03:31 32058 8192000000 10082235632 10512297984 1119569136 12.023 99 31 60 2 1163656704 38494752 5592816
10:05:18 75261 8192000000 10069806112 10586603520 1121305232 12.039 99 8 16 0 1175453184 52763984 1383968
10:07:33 69411 8192000000 10058838176 10535813120 1120201040 12.029 99 11 22 0 1162801888 40482480 2118368
10:09:43 98971 8192000000 10116398416 10556424192 1111209456 11.944 97 15 30 0 1171509968 57370224 2930288
10:12:22 81802 8192000000 10160155744 10603679744 1136462720 12.182 99 23 46 0 1217841968 76907088 4472160
10:14:45 87093 8192000000 10119518688 10587090944 1149344992 12.303 100 17 34 1 1228009600 74722528 3942080
10:17:24 57653 8192000000 10110716320 10538110976 1107779952 11.911 97 37 73 0 1158948592 44009456 7159184
10:19:28 75553 8192000000 10092920864 10567266304 1094789856 11.788 96 18 34 3 1132906608 34509120 3607632
10:21:44 109689 8192000000 10071779824 10544930816 1129128144 12.113 100 11 22 0 1168411040 36314048 2968848
10:24:48 42517 8192000000 10066595504 10529832960 1129069472 12.113 100 6 12 0 1164641088 34370016 1201600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 21-03-2021 10:29:18 to 21-03-2021 10:29:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

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

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          225012
MON$OLDEST_ACTIVE               225013
MON$OLDEST_SNAPSHOT             225013
MON$NEXT_TRANSACTION            225013
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                       2095552
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

21.03.21 10:29:36. 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-03-21 10:29:39 No severe PSQL-related problems occured
21.03.21 10:29:40. End of report.