Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2021 13: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 16742736 kB
MemAvailable 31346808 kB
Buffers 0 kB
Cached 14874180 kB
SwapCached 4864 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-01-05 13:26:54
DTS_END                         2021-01-05 13:26:54.8820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57902
TRN_ID                          225990
ATT_ID                          623
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8883.11 20913 1065973
customer order: creation 166.34 424 19961
customer order: refuse 13.76 334 1651
order to supplier: creation 42.47 1361 5096
order to supplier: removal 3.36 118008 403
invoice (draft): creation 43.52 3537 5222
invoice (draft): removal 51.41 990 6169
invoice accept: apply 39.59 17726 4751
invoice accept: cancel 45.85 855 5502
customer reserve: creation 3750.88 169 450106
customer reserve: removal 4442.49 70 533099
realization accept: apply 79.45 174 9534
realization accept: cancel 82.84 118 9941
payment from customer: creation 56.94 90 6833
payment from customer: removal 5.66 68 679
payment to supplier: creation 45.04 126 5405
payment to supplier: removal 3.26 66 391
service: total inventory turnovers 3.53 3869 424
service: total monetary turnovers 5.96 665 715
service: aggregate perf. data 0.73 21652 87
service: refresh index statistics 0.03 247962 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2021 13:28:35 to 05-01-2021 13:28:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3084
TEST_TIME 2 3045
TEST_TIME 3 4122
TEST_TIME 4 4282
TEST_TIME 5 4755
TEST_TIME 6 4762
TEST_TIME 7 5017
TEST_TIME 8 5775
TEST_TIME 9 5854
TEST_TIME 10 5864
TEST_TIME 11 6008
TEST_TIME 12 5854
TEST_TIME 13 6281
TEST_TIME 14 6693
TEST_TIME 15 6642
TEST_TIME 16 6786
TEST_TIME 17 6941
TEST_TIME 18 7448
TEST_TIME 19 7483
TEST_TIME 20 7621
TEST_TIME 21 8106
TEST_TIME 22 8295
TEST_TIME 23 8448
TEST_TIME 24 8845
TEST_TIME 25 9059
TEST_TIME 26 9496
TEST_TIME 27 11031
TEST_TIME 28 10988
TEST_TIME 29 11323
TEST_TIME 30 11121
TEST_TIME 31 10918
TEST_TIME 32 11045
TEST_TIME 33 10955
TEST_TIME 34 11005
TEST_TIME 35 10746
TEST_TIME 36 10516
TEST_TIME 37 10345
TEST_TIME 38 10301
TEST_TIME 39 10306
TEST_TIME 40 10224
TEST_TIME 41 10115
TEST_TIME 42 10029
TEST_TIME 43 9981
TEST_TIME 44 9935
TEST_TIME 45 9926
TEST_TIME 46 9739
TEST_TIME 47 9858
TEST_TIME 48 9873
TEST_TIME 49 9857
TEST_TIME 50 9794
TEST_TIME 51 9802
TEST_TIME 52 9915
TEST_TIME 53 9932
TEST_TIME 54 9829
TEST_TIME 55 9786
TEST_TIME 56 9922
TEST_TIME 57 9864
TEST_TIME 58 10024
TEST_TIME 59 10029
TEST_TIME 60 9928
TEST_TIME 61 9829
TEST_TIME 62 9766
TEST_TIME 63 9644
TEST_TIME 64 9589
TEST_TIME 65 9485
TEST_TIME 66 9482
TEST_TIME 67 9484
TEST_TIME 68 9428
TEST_TIME 69 9401
TEST_TIME 70 9411
TEST_TIME 71 9401
TEST_TIME 72 9388
TEST_TIME 73 9398
TEST_TIME 74 9403
TEST_TIME 75 9386
TEST_TIME 76 9415
TEST_TIME 77 9403
TEST_TIME 78 9531
TEST_TIME 79 9558
TEST_TIME 80 9497
TEST_TIME 81 9397
TEST_TIME 82 9333
TEST_TIME 83 9325
TEST_TIME 84 9281
TEST_TIME 85 9267
TEST_TIME 86 9250
TEST_TIME 87 9204
TEST_TIME 88 9196
TEST_TIME 89 9199
TEST_TIME 90 9234
TEST_TIME 91 9204
TEST_TIME 92 9196
TEST_TIME 93 9244
TEST_TIME 94 9290
TEST_TIME 95 9288
TEST_TIME 96 9323
TEST_TIME 97 9281
TEST_TIME 98 9220
TEST_TIME 99 9202
TEST_TIME 100 9154
TEST_TIME 101 9110
TEST_TIME 102 9103
TEST_TIME 103 9075
TEST_TIME 104 9072
TEST_TIME 105 9056
TEST_TIME 106 9034
TEST_TIME 107 9067
TEST_TIME 108 9047
TEST_TIME 109 9063
TEST_TIME 110 9141
TEST_TIME 111 9097
TEST_TIME 112 9150
TEST_TIME 113 9161
TEST_TIME 114 9169
TEST_TIME 115 9097
TEST_TIME 116 9042
TEST_TIME 117 9006
TEST_TIME 118 8954
TEST_TIME 119 8923
TEST_TIME 120 8888

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-01-2021 13:28:38 to 05-01-2021 13:28:42.

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 1480793 1480793 0 0.00 0 15311 6 0 0
doc_list_biud 1480791 1480791 0 0.00 0 60839 32 0 0
sp_add_invoice_to_stock 4863 4751 112 2.30 25 173354 17727 0 112
sp_cancel_adding_invoice 5502 5502 0 0.00 6 67870 855 0 0
sp_cancel_client_order 1651 1651 0 0.00 4 9861 335 0 0
sp_cancel_customer_reserve 533099 533099 0 0.00 1 60841 71 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 2397 68 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 3 3256 66 0 0
sp_cancel_supplier_invoice 6180 6169 11 0.17 1 20384 990 0 11
sp_cancel_supplier_order 403 403 0 0.00 7 1315357 118009 0 0
sp_cancel_write_off 9941 9941 0 0.00 1 7629 118 0 0
sp_client_order 19961 19961 0 0.00 4 60766 424 0 0
sp_customer_reserve 450106 450106 0 0.00 0 88519 170 0 0
sp_fill_shopping_cart 30276 30276 0 0.00 1 13006 42 0 0
sp_fill_shopping_cart_clo_res 450100 450100 0 0.00 0 53819 18 0 0
sp_get_clo_for_invoice 4744 4744 0 0.00 0 59460 426 0 0
sp_kill_cost_storno 16513 16513 0 0.00 0 12050 56 0 0
sp_kill_qstorno_ret_qs2qd 1082711 1082711 0 0.00 0 60829 39 0 0
sp_kill_qty_storno 571043 571043 0 0.00 0 60839 83 0 0
sp_lock_selected_doc 19802 19802 0 0.00 0 10208 7 0 0
sp_make_cost_storno 26516 26516 0 0.00 0 15202 51 0 0
sp_multiply_rows_for_pdistr 26516 26516 0 0.00 0 9785 28 0 0
sp_multiply_rows_for_qdistr 19961 19961 0 0.00 0 60758 266 0 0
sp_pay_from_customer 6833 6833 0 0.00 4 8940 91 0 0
sp_pay_to_supplier 5405 5405 0 0.00 5 10053 127 0 0
sp_payment_common 12238 12238 0 0.00 1 8811 21 0 0
sp_qd_handle_on_cancel_clo 1651 1651 0 0.00 0 9856 308 0 0
sp_qd_handle_on_invoice_upd_sts 10246 10246 0 0.00 0 23148 373 0 0
sp_qd_handle_on_reserve_upd_sts 9534 9534 0 0.00 0 18697 52 0 0
sp_reserve_write_off 9534 9534 0 0.00 6 19344 175 0 0
sp_supplier_invoice 5222 5222 0 0.00 5 75596 3538 0 0
sp_supplier_order 5096 5096 0 0.00 5 22814 1362 0 0
srv_aggregate_perf_data 87 87 0 0.00 2756 339300 21653 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2942 193583 44880 0 0
srv_find_qd_qs_mism 1019265 1019265 0 0.00 0 15310 6 0 0
srv_make_invnt_saldo 424 424 0 0.00 49 113939 3870 0 0
srv_make_money_saldo 715 715 0 0.00 1 18591 666 0 0
srv_recalc_idx_stat 4 4 0 0.00 67739 377776 247962 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4751 4751 0 0.00 0 9850 161 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 1323 57 0 0
t$perf-norm:sp_cancel_client_order 1651 1651 0 0.00 0 2494 10 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 2497 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 2977 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 31 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1726 19 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 25414 1590 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 3631 13 0 0
t$perf-norm:sp_client_order 19961 19961 0 0.00 0 5357 16 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 6216 14 0 0
t$perf-norm:sp_pay_from_customer 6833 6833 0 0.00 0 8939 12 0 0
t$perf-norm:sp_pay_to_supplier 5405 5405 0 0.00 0 7015 7 0 0
t$perf-norm:sp_reserve_write_off 9534 9534 0 0.00 0 4669 10 0 0
t$perf-norm:sp_supplier_invoice 5222 5222 0 0.00 0 4566 16 0 0
t$perf-norm:sp_supplier_order 5096 5096 0 0.00 0 2825 14 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 36 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 195 3 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 365 5 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 5602 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9712 9488 224 2.30 0 3717 16 0 224
v_all_customers 36338 36338 0 0.00 0 39 1 0 0
v_all_suppliers 28352 28352 0 0.00 0 19 1 0 0
v_all_wares 39922 39922 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 2731 10 0 0
v_cancel_client_order 3302 3302 0 0.00 0 2034 5 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 2194 7 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 2747 8 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 3205 11 0 0
v_cancel_supplier_invoice 812 790 22 2.70 0 1015 13 0 22
v_cancel_supplier_order 806 806 0 0.00 0 3162 10 0 0
v_cancel_write_off 1914 1914 0 0.00 0 656 12 0 0
v_max_id_clo_ord 5096 5096 0 0.00 0 201 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 2068 13 0 0
v_max_id_ord_sup 5218 5218 0 0.00 0 3925 7 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 3632 5 0 0
v_max_non_paid_realizn 3589 3589 0 0.00 0 1348 3 0 0
v_min_id_clo_ord 5096 5096 0 0.00 0 4185 6 0 0
v_min_id_clo_res 934 934 0 0.00 0 1234 3 0 0
v_min_id_ord_sup 5218 5218 0 0.00 0 5514 6 0 0
v_min_non_paid_invoice 5405 5405 0 0.00 0 8807 19 0 0
v_min_non_paid_realizn 6833 6833 0 0.00 0 7544 11 0 0
v_our_firm 10072 10072 0 0.00 0 21 1 0 0
v_reserve_write_off 19068 19068 0 0.00 0 3163 5 0 0
x_lock_depdocs_on_canc_invoice 11276 11276 0 0.00 0 12362 103 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 3556 334 0 0
x_make_qstorno_1000_1200 5096 5096 0 0.00 2 22426 1287 0 0
x_make_qstorno_1200_2000 5222 5222 0 0.00 2 75570 3376 0 0
x_make_qstorno_2100_3300 450106 450106 0 0.00 0 88517 149 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 05-01-2021 13:28:42 to 05-01-2021 13:28:47.

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
10:56:58 7 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:58:00 28173 8192000000 9715002512 10009821184 790774384 8.803 100 86 172 1 819508816 7646960 21087472
10:59:31 18606 8192000000 9754945376 10059677696 833144288 9.231 100 89 178 0 865973088 11504672 21324128
11:00:53 12800 8192000000 9800049760 10112811008 865133520 9.551 100 85 170 0 901300912 16422768 19744624
11:02:12 13331 8192000000 9852482560 10183528448 906140656 9.959 100 77 152 3 962422560 39068784 17213120
11:03:37 12106 8192000000 9947688784 10291085312 957547984 10.465 100 85 170 0 1046541184 71265904 17727296
11:04:52 3942 8192000000 9958868592 10311045120 995789216 10.838 100 84 168 0 1078580960 65348640 17443104
11:05:58 3650 8192000000 9981638176 10342219776 1014513296 11.019 100 84 168 2 1096939936 65236112 17190528
11:07:02 2166 8192000000 10020059008 10386161664 1035898816 11.225 100 92 186 2 1133554512 78215440 19440256
11:08:07 3232 8192000000 10045596400 10414219264 1050476240 11.365 100 92 185 1 1157251024 88513648 18261136
11:09:12 20883 8192000000 9993176736 10369044480 1056411120 11.422 100 59 118 4 1097973856 28871520 12691216
11:10:34 5153 8192000000 9978042944 10362527744 1063792992 11.493 100 35 70 0 1106392576 34767520 7832064
11:11:40 9752 8192000000 9992603952 10398375936 1067934928 11.532 100 40 82 4 1102307840 24997040 9375872
11:12:53 4303 8192000000 10021846736 10426544128 1070123968 11.553 100 45 90 1 1127916752 47630656 10162128
11:13:59 6178 8192000000 10011040224 10412302336 1078929184 11.637 100 32 64 1 1132296096 46478048 6888864
11:15:05 5566 8192000000 9996284592 10389368832 1084055376 11.686 100 36 72 1 1110527040 19106640 7365024
11:16:12 52921 8192000000 9989988720 10386976768 1060606224 11.462 98 33 65 2 1079748768 12275040 6867504
11:18:07 5060 8192000000 9989880176 10387484672 1055531136 11.414 98 45 89 4 1085984800 20821280 9632384
11:19:12 8739 8192000000 9946776608 10344054784 1016032096 11.034 98 33 66 1 1043348896 20283584 7033216
11:20:22 3097 8192000000 9925458416 10295214080 1006438384 10.941 99 38 74 4 1030715808 15783312 8494112
11:21:25 9085 8192000000 9964069216 10328834048 1021337152 11.085 99 74 147 1 1048923264 11486832 16099280
11:22:34 5150 8192000000 9945014384 10319650816 1017563264 11.048 99 45 91 2 1057107376 29902448 9641664
11:23:41 14528 8192000000 9949539712 10338332672 1029372416 11.162 99 44 88 1 1056450736 16749840 10328480
11:24:56 67551 8192000000 9977901920 10380853248 1064476448 11.499 100 27 54 1 1100138576 30202048 5460080
11:27:05 12528 8192000000 10008590272 10404024320 1090779680 11.750 100 34 68 1 1115245104 17080304 7385120
11:28:27 12630 8192000000 10042745360 10444390400 1102712736 11.863 100 49 98 2 1150269088 37052752 10503600
11:29:48 8801 8192000000 10101886592 10503749632 1121723920 12.043 100 48 94 0 1204045088 72422752 9898416
11:31:02 9392 8192000000 10151840528 10548916224 1131920688 12.139 100 59 116 4 1248070816 103538368 12611760
11:32:15 15258 8192000000 10166799360 10584494080 1142500864 12.239 100 47 94 0 1276609184 124028592 10079728
11:33:46 6455 8192000000 10226575136 10630569984 1163336464 12.435 100 60 120 0 1336820448 160386528 13097456
11:34:55 8240 8192000000 10237118464 10646802432 1170711392 12.503 100 61 122 3 1341850400 157933632 13205376
11:36:08 11462 8192000000 10297222144 10711121920 1181601184 12.605 100 61 119 1 1400502752 206047696 12853872
11:37:25 4515 8192000000 10360266928 10778570752 1190711280 12.690 100 65 130 1 1462494656 257175872 14607504
11:38:36 5464 8192000000 10374496624 10789134336 1193845872 12.719 100 63 126 1 1468577760 260043136 14688752
11:39:45 2942 8192000000 10425405584 10847432704 1192176192 12.704 100 85 170 1 1518614192 306640144 19797856
11:40:53 7586 8192000000 10406894048 10833330176 1194351296 12.724 100 57 112 0 1517613904 310379408 12883200
11:42:08 4954 8192000000 10470710800 10887995392 1185831904 12.645 100 66 132 0 1574189872 373195056 15162912
11:43:20 10225 8192000000 10447992320 10862788608 1169183008 12.489 99 52 102 2 1538678672 357045792 12449872
11:44:37 9495 8192000000 10427373888 10863132672 1179650880 12.587 100 35 70 0 1544682480 356279504 8752096
11:45:51 5290 8192000000 10486732288 10906206208 1163307600 12.434 100 58 114 0 1581145568 404035824 13802144
11:47:04 4996 8192000000 10464313312 10901544960 1143793728 12.251 99 62 123 0 1561271856 402530336 14947792
11:48:14 7357 8192000000 10421592272 10839654400 1137847488 12.195 99 42 84 2 1518987552 370917072 10222992
11:49:28 18748 8192000000 10480322736 10941145088 1150169872 12.311 100 36 72 1 1594443456 434565232 9708352
11:50:55 15810 8192000000 10457102192 10911924224 1136586880 12.183 100 30 60 0 1554448512 409687248 8174384
11:52:19 25513 8192000000 10416380592 10879455232 1113472400 11.965 99 19 38 0 1523615184 404716896 5425888
11:53:46 18746 8192000000 10089990576 10552270848 1120253088 12.029 100 17 34 1 1179983360 56364912 3365360
11:55:08 56050 8192000000 10053228016 10583556096 1099533648 11.833 98 5 11 0 1161870784 61161152 1175984
11:57:04 41935 8192000000 10078760976 10508251136 1053265120 11.392 94 24 49 0 1117971968 59840208 4866640
11:59:33 33721 8192000000 10062326128 10491871232 1088372384 11.727 97 20 38 6 1117770208 25650208 3747616
12:01:07 193583 8192000000 10060458144 10504286208 1082704720 11.673 97 34 68 1 1126646080 36845088 7096272
12:05:52 4179 8192000000 10068675744 10523508736 1121439456 12.041 100 49 98 0 1170060832 38458464 10162912
12:06:58 36296 8192000000 10078035856 10511900672 1100214976 11.840 98 24 48 0 1156857776 51592688 5050112
12:08:40 20262 8192000000 10093338240 10541699072 1124570128 12.070 100 27 54 0 1198124416 67608608 5945680
12:10:52 35366 8192000000 10117842608 10557743104 1133155760 12.151 100 25 49 1 1218740976 80043712 5541504
12:12:36 82930 8192000000 10059472512 10558959616 1130874432 12.130 100 16 32 1 1192355408 57942912 3538064
12:15:08 47488 8192000000 10077123440 10539298816 1132828336 12.148 100 19 39 1 1180159696 42742880 4588480
12:17:02 33838 8192000000 10137910672 10566733824 1142425392 12.238 100 29 58 1 1230081488 81619824 6036272
12:18:39 82371 8192000000 10109292832 10573312000 1152252528 12.331 100 11 22 1 1205368304 51095824 2019952
12:21:02 52002 8192000000 10175665008 10622447616 1127170000 12.095 97 21 42 2 1219308640 87846864 4291776
12:23:18 80716 8192000000 10152541328 10701889536 1140959664 12.225 98 18 36 2 1265496736 120399312 4137760
12:25:45 101006 8192000000 10109606640 10578923520 1133822208 12.157 98 29 58 1 1182111424 42178528 6110688
12:28:29 50317 8192000000 10069715680 10556186624 1126696432 12.090 98 7 14 0 1155028464 27042080 1289952
12:30:20 68613 8192000000 10080404784 10566713344 1135796912 12.176 99 8 16 0 1183412576 45448544 2167120
12:32:51 87297 8192000000 10058838960 10524012544 1102831824 11.865 96 18 36 0 1134174432 27723152 3619456
12:35:18 82343 8192000000 10064461232 10528600064 1069243024 11.545 94 20 40 1 1117519056 43704880 4571152
12:37:41 50389 8192000000 10068211488 10531307520 1094816352 11.788 98 16 32 0 1165987232 67799232 3371648
12:39:44 36570 8192000000 10104181600 10522099712 1097912656 11.818 98 52 104 2 1164888672 56397456 10578560
12:41:21 44991 8192000000 10094273712 10547032064 1078133024 11.630 96 31 62 2 1154210736 69740016 6337696
12:43:08 109389 8192000000 10104142064 10630422528 1076639184 11.615 96 4 8 0 1122118816 44890592 589040
12:45:57 122604 8192000000 10041443568 10496688128 1087069552 11.715 98 25 47 2 1138625536 47022432 4533552
12:49:06 75704 8192000000 10043736192 10543243264 1093746704 11.778 98 5 10 0 1134990992 40210784 1033504
12:51:23 87753 8192000000 10046932176 10503761920 1091537296 11.757 98 20 40 0 1120616016 24937184 4141536
12:53:51 45272 8192000000 10075851952 10522341376 1083239616 11.678 97 30 60 0 1152278192 62623040 6415536
12:55:54 74641 8192000000 10092570832 10555572224 1124987648 12.074 100 23 46 0 1200661616 70877280 4796688
12:58:10 42134 8192000000 10120314752 10565853184 1100534864 11.843 97 23 46 0 1212297536 107015408 4747264
13:00:15 31618 8192000000 10061017344 10483253248 1116116304 11.990 100 17 34 1 1138296064 18770624 3409136
13:01:48 30561 8192000000 10105398016 10534748160 1129765680 12.119 100 33 66 1 1192584928 54883920 7935328
13:03:21 103528 8192000000 10082763616 10551472128 1130045648 12.122 100 15 30 1 1190768672 57142944 3580080
13:06:21 59238 8192000000 10056714256 10521034752 1109006528 11.923 98 8 16 2 1141667856 30831696 1829632
13:08:21 94363 8192000000 10094340992 10563723264 1125909888 12.083 99 29 58 2 1193587792 61583392 6094512
13:10:55 43808 8192000000 10102551200 10552152064 1146336672 12.275 100 20 40 2 1187103360 36784864 3981824
13:12:44 48744 8192000000 10114918496 10587799552 1113085760 11.962 97 22 44 0 1178753376 61118880 4548736
13:14:51 84184 8192000000 10105728272 10607968256 1101603584 11.853 96 24 47 3 1203379568 96753360 5022624
13:17:15 43439 8192000000 10111131184 10534883328 1096147072 11.801 96 28 56 2 1134754176 32776112 5830992
13:19:00 60676 8192000000 10075749680 10547929088 1112055120 11.952 98 7 14 0 1158941408 45641136 1245152
13:21:02 84373 8192000000 10055121536 10523586560 1131533712 12.136 100 11 20 1 1168038992 34713248 1792032
13:23:39 33023 8192000000 10053474464 10514452480 1137463136 12.192 100 7 14 1 1160476368 21449088 1564144
13:25:15 48461 8192000000 10060284560 10532868096 1111853312 11.950 98 11 22 0 1160069360 45519792 2696256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2021 13:28:48 to 05-01-2021 13:29:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2021 13:29:04 to 05-01-2021 13:29:06.

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          223218
MON$OLDEST_ACTIVE               226117
MON$OLDEST_SNAPSHOT             226117
MON$NEXT_TRANSACTION            226117
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                       2095104
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             778
MON$NEXT_STATEMENT              53


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

05.01.21 13:29:07. 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-01-05 13:29:10 No severe PSQL-related problems occured
05.01.21 13:29:11. End of report.