Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 16:43
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15896444 kB
MemAvailable 30788784 kB
Buffers 0 kB
Cached 15762756 kB
SwapCached 416 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-17 16:42:45
DTS_END                         2021-09-17 16:42:45.1670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42396
TRN_ID                          231804
ATT_ID                          695
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: 8909.25 21973 1069110
customer order: creation 169.40 400 20328
customer order: refuse 14.65 400 1758
order to supplier: creation 44.21 1507 5305
order to supplier: removal 3.40 122425 408
invoice (draft): creation 45.29 3883 5435
invoice (draft): removal 51.07 1027 6128
invoice accept: apply 40.29 20594 4835
invoice accept: cancel 46.04 1025 5525
customer reserve: creation 3717.60 202 446112
customer reserve: removal 4477.91 71 537349
realization accept: apply 81.09 239 9731
realization accept: cancel 90.52 139 10862
payment from customer: creation 58.12 136 6974
payment from customer: removal 5.33 126 640
payment to supplier: creation 48.45 150 5814
payment to supplier: removal 3.43 124 411
service: total inventory turnovers 4.86 1262 583
service: total monetary turnovers 6.71 50 805
service: aggregate perf. data 0.86 16738 103
service: refresh index statistics 0.03 268970 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 16:43:54 to 17-09-2021 16:43:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2302
TEST_TIME 2 3112
TEST_TIME 3 5550
TEST_TIME 4 5638
TEST_TIME 5 5042
TEST_TIME 6 4993
TEST_TIME 7 4797
TEST_TIME 8 4894
TEST_TIME 9 5147
TEST_TIME 10 5190
TEST_TIME 11 5353
TEST_TIME 12 5578
TEST_TIME 13 5617
TEST_TIME 14 5998
TEST_TIME 15 6031
TEST_TIME 16 6116
TEST_TIME 17 6138
TEST_TIME 18 6208
TEST_TIME 19 6836
TEST_TIME 20 7121
TEST_TIME 21 7416
TEST_TIME 22 7558
TEST_TIME 23 7427
TEST_TIME 24 7631
TEST_TIME 25 7669
TEST_TIME 26 8148
TEST_TIME 27 7995
TEST_TIME 28 8215
TEST_TIME 29 8087
TEST_TIME 30 8197
TEST_TIME 31 8432
TEST_TIME 32 8296
TEST_TIME 33 8208
TEST_TIME 34 9537
TEST_TIME 35 10424
TEST_TIME 36 10242
TEST_TIME 37 10016
TEST_TIME 38 9948
TEST_TIME 39 9800
TEST_TIME 40 9773
TEST_TIME 41 9683
TEST_TIME 42 9635
TEST_TIME 43 9626
TEST_TIME 44 9529
TEST_TIME 45 9422
TEST_TIME 46 9409
TEST_TIME 47 9285
TEST_TIME 48 9275
TEST_TIME 49 9329
TEST_TIME 50 9255
TEST_TIME 51 9210
TEST_TIME 52 9142
TEST_TIME 53 9010
TEST_TIME 54 8938
TEST_TIME 55 8986
TEST_TIME 56 8958
TEST_TIME 57 9008
TEST_TIME 58 8963
TEST_TIME 59 8899
TEST_TIME 60 8876
TEST_TIME 61 8816
TEST_TIME 62 8827
TEST_TIME 63 8927
TEST_TIME 64 8891
TEST_TIME 65 8948
TEST_TIME 66 8937
TEST_TIME 67 8968
TEST_TIME 68 8991
TEST_TIME 69 9037
TEST_TIME 70 9058
TEST_TIME 71 9063
TEST_TIME 72 9114
TEST_TIME 73 9079
TEST_TIME 74 9164
TEST_TIME 75 9126
TEST_TIME 76 9013
TEST_TIME 77 8937
TEST_TIME 78 8885
TEST_TIME 79 8937
TEST_TIME 80 8920
TEST_TIME 81 8860
TEST_TIME 82 8849
TEST_TIME 83 8809
TEST_TIME 84 8781
TEST_TIME 85 8830
TEST_TIME 86 8817
TEST_TIME 87 8823
TEST_TIME 88 8802
TEST_TIME 89 8805
TEST_TIME 90 8882
TEST_TIME 91 8909
TEST_TIME 92 8943
TEST_TIME 93 8930
TEST_TIME 94 8962
TEST_TIME 95 9004
TEST_TIME 96 8970
TEST_TIME 97 9036
TEST_TIME 98 8988
TEST_TIME 99 8910
TEST_TIME 100 8925
TEST_TIME 101 8907
TEST_TIME 102 8885
TEST_TIME 103 8862
TEST_TIME 104 8831
TEST_TIME 105 8801
TEST_TIME 106 8825
TEST_TIME 107 8830
TEST_TIME 108 8793
TEST_TIME 109 8779
TEST_TIME 110 8815
TEST_TIME 111 8878
TEST_TIME 112 8872
TEST_TIME 113 8916
TEST_TIME 114 8925
TEST_TIME 115 8944
TEST_TIME 116 8977
TEST_TIME 117 8969
TEST_TIME 118 8974
TEST_TIME 119 8984
TEST_TIME 120 8914

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 16:43:58 to 17-09-2021 16:44:01.

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 1481674 1481674 0 0.00 0 33633 7 0 0
doc_list_biud 1481667 1481667 0 0.00 0 96148 33 0 0
sp_add_invoice_to_stock 4967 4835 132 2.65 36 179836 20595 0 132
sp_cancel_adding_invoice 5525 5525 0 0.00 4 84666 1026 0 0
sp_cancel_client_order 1758 1758 0 0.00 5 11955 401 0 0
sp_cancel_customer_reserve 537349 537349 0 0.00 1 37969 72 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 3 12020 126 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 7549 124 0 0
sp_cancel_supplier_invoice 6139 6128 11 0.17 1 96157 1027 0 11
sp_cancel_supplier_order 408 408 0 0.00 7 1144811 122425 0 0
sp_cancel_write_off 10862 10862 0 0.00 1 11924 139 0 0
sp_client_order 20328 20328 0 0.00 3 38918 400 0 0
sp_customer_reserve 446112 446112 0 0.00 0 114551 202 0 0
sp_fill_shopping_cart 31054 31054 0 0.00 1 39050 46 0 0
sp_fill_shopping_cart_clo_res 446097 446097 0 0.00 0 11769 16 0 0
sp_get_clo_for_invoice 4820 4820 0 0.00 0 21034 356 0 0
sp_kill_cost_storno 17438 17438 0 0.00 0 5875 74 0 0
sp_kill_qstorno_ret_qs2qd 1092096 1092096 0 0.00 0 96145 39 0 0
sp_kill_qty_storno 576581 576581 0 0.00 0 96147 85 0 0
sp_lock_selected_doc 20079 20079 0 0.00 0 7236 5 0 0
sp_make_cost_storno 27339 27339 0 0.00 0 33430 68 0 0
sp_multiply_rows_for_pdistr 27339 27339 0 0.00 0 10296 33 0 0
sp_multiply_rows_for_qdistr 20328 20328 0 0.00 0 27139 174 0 0
sp_pay_from_customer 6974 6974 0 0.00 4 14426 137 0 0
sp_pay_to_supplier 5814 5814 0 0.00 5 17897 150 0 0
sp_payment_common 12788 12788 0 0.00 1 13324 35 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 11888 372 0 0
sp_qd_handle_on_invoice_upd_sts 10345 10345 0 0.00 0 17659 410 0 0
sp_qd_handle_on_reserve_upd_sts 9731 9731 0 0.00 0 17874 87 0 0
sp_reserve_write_off 9731 9731 0 0.00 6 18012 240 0 0
sp_supplier_invoice 5435 5435 0 0.00 7 112908 3884 0 0
sp_supplier_order 5305 5305 0 0.00 5 36153 1507 0 0
srv_aggregate_perf_data 103 103 0 0.00 2903 367676 16738 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2015 152740 42944 0 0
srv_find_qd_qs_mism 1022216 1022216 0 0.00 0 13085 6 0 0
srv_make_invnt_saldo 583 583 0 0.00 37 112275 1263 0 0
srv_make_money_saldo 805 805 0 0.00 1 6700 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 99667 468575 268971 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 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 4835 4835 0 0.00 0 6939 179 0 0
t$perf-norm:sp_cancel_adding_invoice 368 368 0 0.00 1 1954 117 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 15074 23 0 0
t$perf-norm:sp_cancel_customer_reserve 595 595 0 0.00 0 2750 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 5264 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 2100 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 685 16 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 33856 2261 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 8331 17 0 0
t$perf-norm:sp_client_order 20328 20328 0 0.00 0 11280 22 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 4950 12 0 0
t$perf-norm:sp_pay_from_customer 6974 6974 0 0.00 0 4521 10 0 0
t$perf-norm:sp_pay_to_supplier 5814 5814 0 0.00 0 4989 12 0 0
t$perf-norm:sp_reserve_write_off 9731 9731 0 0.00 0 4198 11 0 0
t$perf-norm:sp_supplier_invoice 5435 5435 0 0.00 0 13333 24 0 0
t$perf-norm:sp_supplier_order 5305 5305 0 0.00 0 4232 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1348 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 260 7 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 3106 29 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 2276 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 7 0 0
v_add_invoice_to_stock 9902 9638 264 2.66 0 10329 23 0 264
v_all_customers 37270 37270 0 0.00 0 150 1 0 0
v_all_suppliers 29804 29804 0 0.00 0 151 1 0 0
v_all_wares 40656 40656 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 736 736 0 0.00 0 2741 15 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4520 5 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 12010 22 0 0
v_cancel_customer_reserve 1190 1190 0 0.00 0 323 3 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 4014 17 0 0
v_cancel_supplier_invoice 758 736 22 2.90 0 2222 24 0 22
v_cancel_supplier_order 816 816 0 0.00 0 3347 11 0 0
v_cancel_write_off 1962 1962 0 0.00 0 1472 13 0 0
v_max_id_clo_ord 5296 5296 0 0.00 0 360 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 8394 24 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 3643 4 0 0
v_max_non_paid_invoice 1637 1637 0 0.00 0 1562 5 0 0
v_max_non_paid_realizn 3544 3544 0 0.00 0 3911 6 0 0
v_min_id_clo_ord 5296 5296 0 0.00 0 5349 6 0 0
v_min_id_clo_res 981 981 0 0.00 0 3742 6 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 7282 11 0 0
v_min_non_paid_invoice 5814 5814 0 0.00 0 6102 17 0 0
v_min_non_paid_realizn 6974 6974 0 0.00 0 11742 23 0 0
v_our_firm 10246 10246 0 0.00 0 152 1 0 0
v_reserve_write_off 19462 19462 0 0.00 0 4193 8 0 0
x_lock_depdocs_on_canc_invoice 11285 11285 0 0.00 0 15179 154 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 7066 480 0 0
x_make_qstorno_1000_1200 5305 5305 0 0.00 2 36144 1414 0 0
x_make_qstorno_1200_2000 5435 5435 0 0.00 3 92733 3714 0 0
x_make_qstorno_2100_3300 446112 446112 0 0.00 0 114550 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 16:44:02 to 17-09-2021 16:44:06.

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
14:12:47 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:13:48 23979 8192000000 10373798992 10693156864 1423185120 14.801 100 86 172 0 1456905792 5937408 27783264
14:15:19 14286 8192000000 10451654720 10780667904 1503309440 15.505 100 89 178 0 1539578896 8003568 28265888
14:16:45 20098 8192000000 10489819024 10835750912 1551607728 15.924 100 85 170 0 1588371664 10299856 26464080
14:18:08 12564 8192000000 10557383168 10913234944 1613645568 16.456 100 80 160 0 1649643104 11206656 24790880
14:19:24 13695 8192000000 10640423072 11010633728 1696175968 17.153 100 74 148 1 1729551936 11723568 21652400
14:20:43 7128 8192000000 10701833824 11077427200 1759350224 17.679 100 84 167 1 1796007792 13023248 23634320
14:21:52 3955 8192000000 10748421072 11128483840 1807334192 18.074 100 89 176 0 1845488784 13488496 24666096
14:22:59 11284 8192000000 10775682944 11166277632 1836614240 18.313 100 85 170 2 1873501440 12840992 24046208
14:24:13 2361 8192000000 10823642672 11214090240 1877213568 18.643 100 92 184 2 1916807600 13826736 25767296
14:25:17 12051 8192000000 10830739792 11228774400 1893467920 18.774 100 73 144 3 1927188144 13301680 20418544
14:26:31 14278 8192000000 10831857856 11238772736 1899289664 18.821 100 63 124 1 1923570016 5258960 19021392
14:27:46 6315 8192000000 10808561488 11241672704 1902377072 18.845 100 35 70 0 1919572048 7136144 10058832
14:28:55 7537 8192000000 10813016448 11247136768 1906750256 18.881 100 29 58 1 1921816688 6550096 8516336
14:30:07 6000 8192000000 10824782800 11256803328 1911998256 18.923 100 20 41 1 1924060752 6428784 5633712
14:31:14 12131 8192000000 10831347968 11257712640 1914193536 18.940 100 21 43 2 1926505328 5101232 7210560
14:32:29 5095 8192000000 10874661680 11276214272 1925057872 19.027 100 70 140 2 1952015280 6091632 20865776
14:33:34 6786 8192000000 10814891888 11247902720 1883072432 18.690 99 26 52 0 1901270480 10627712 7570336
14:34:41 5065 8192000000 10775632304 11203465216 1843734224 18.371 99 31 62 1 1859466992 6994192 8738576
14:35:49 44448 8192000000 10708403824 11135848448 1760599552 17.689 95 46 91 1 1778912192 4927760 13384880
14:37:34 5254 8192000000 10694198320 11103178752 1765030976 17.726 98 27 53 2 1778203392 5565376 7607040
14:38:40 7533 8192000000 10718676688 11140190208 1808071296 18.080 100 25 50 0 1821463648 6115728 7276624
14:39:50 12626 8192000000 10778156560 11190611968 1846300032 18.392 100 18 35 2 1857229216 5690672 5238512
14:41:03 44659 8192000000 10785394032 11202879488 1858832400 18.494 100 40 78 1 1875400352 5815360 10752592
14:42:55 26109 8192000000 10835562480 11252654080 1903752128 18.856 100 44 86 0 1922932912 7147872 12032912
14:44:33 33602 8192000000 10865557168 11291918336 1930968192 19.075 100 50 98 1 1959573072 14566848 14038032
14:46:14 69672 8192000000 10882228992 11321905152 1945807600 19.193 100 28 53 1 1970825008 17418400 7599008
14:48:27 46579 8192000000 10904531056 11334148096 1962831472 19.329 100 35 70 1 1990967696 17192784 10943440
14:50:38 9805 8192000000 10924606240 11356368896 1973912096 19.416 100 46 92 0 2011793312 24330736 13550480
14:51:51 8676 8192000000 10953519248 11375484928 1988367440 19.531 100 60 120 0 2032456224 25746448 18342336
14:53:06 13350 8192000000 10941629664 11379785728 1990354272 19.547 100 50 100 1 2033342336 27250064 15738000
14:54:32 3282 8192000000 10981333520 11407761408 2014508144 19.737 100 76 154 2 2068392416 31528496 22355776
14:55:39 7309 8192000000 10992275984 11427676160 2022593568 19.801 100 56 110 0 2073474944 33919712 16961664
14:56:54 3106 8192000000 11009693440 11433398272 2031896848 19.873 100 82 162 0 2089858176 33944080 24017248
14:58:02 11504 8192000000 10982120896 11424747520 2014616496 19.738 100 56 112 0 2074064816 41786032 17662288
14:59:20 8407 8192000000 10980243840 11404324864 1983712512 19.494 99 77 153 2 2051260208 44060592 23487104
15:00:35 2909 8192000000 10965626720 11393040384 1976514704 19.437 100 70 139 2 2052242320 52597152 23130464
15:01:42 3080 8192000000 10996957344 11409874944 2001784784 19.637 100 82 164 0 2071851808 44908592 25158432
15:02:50 4143 8192000000 10966465616 11386114048 1969685296 19.383 99 80 158 1 2041479280 45992352 25801632
15:03:57 2387 8192000000 10959153744 11379798016 1960778656 19.312 99 72 142 1 2024465472 42890832 20795984
15:05:06 3105 8192000000 10960322720 11381673984 1976444544 19.437 100 63 121 0 2035995200 41497840 18052816
15:06:17 2014 8192000000 10968853920 11388723200 1971260032 19.395 100 70 138 0 2042438272 49580672 21597568
15:07:26 3859 8192000000 10957537312 11384918016 1965498224 19.350 100 53 106 0 2036395776 53841952 17055600
15:08:35 10965 8192000000 10942175376 11379277824 1944678224 19.184 99 49 98 0 2013382160 52784752 15919184
15:10:04 3868 8192000000 10967027072 11394605056 1966397408 19.357 99 63 124 1 2037629328 50901728 20330192
15:11:16 12735 8192000000 10972510576 11401781248 1982411104 19.484 100 40 80 2 2048535200 52696976 13427120
15:12:34 4632 8192000000 10985833008 11416928256 2000471376 19.626 100 52 104 2 2068628656 51183248 16974032
15:13:43 9834 8192000000 10962283616 11409862656 1990048832 19.544 100 33 64 0 2048738464 47818240 10871392
15:14:55 15222 8192000000 10936605456 11378429952 1909576640 18.903 98 32 64 1 1990160128 67776288 12807200
15:16:27 6810 8192000000 10964631968 11405754368 1941503968 19.159 99 53 104 1 2038065632 79040368 17521296
15:17:41 50321 8192000000 10888453296 11355631616 1941695296 19.160 99 8 16 1 1964332160 20657648 1979216
15:20:00 44279 8192000000 10901027376 11363971072 1974116800 19.418 100 50 97 1 1999683552 10718272 14848480
15:22:49 152740 8192000000 10905914000 11382956032 1984971696 19.504 100 26 52 2 2003961408 12018672 6971040
15:26:23 38727 8192000000 10900733056 11373477888 1966602896 19.358 99 18 35 1 1981846736 10641056 4602784
15:28:25 90643 8192000000 10916144480 11368919040 1939914832 19.146 98 16 32 0 1960009984 15269088 4826064
15:30:56 115307 8192000000 10898944896 11387023360 1975798112 19.431 100 13 24 4 1994065664 14555184 3712368
15:34:06 66716 8192000000 10924812336 11379822592 1972783808 19.408 99 24 48 0 1990347904 10331504 7232592
15:36:15 10233 8192000000 10921516448 11364343808 1970972720 19.393 99 64 126 0 2002144816 12314272 18857824
15:37:26 56552 8192000000 10915587648 11399077888 1997844368 19.606 100 20 40 0 2017181952 13480512 5857072
15:39:26 72161 8192000000 10887151568 11366137856 1954420864 19.262 98 9 18 0 1966622752 9464672 2737216
15:42:48 91236 8192000000 10933054992 11399159808 1984936368 19.504 99 23 44 1 2002211232 11554608 5720256
15:45:26 81307 8192000000 10887313440 11356327936 1896405264 18.797 96 17 33 1 1914011440 12932000 4674176
15:47:52 41149 8192000000 10886786784 11344687104 1875805008 18.631 95 39 78 0 1895621200 8323568 11492624
15:49:35 47600 8192000000 10879837568 11345039360 1916687504 18.960 98 18 36 0 1937412400 15568960 5155936
15:51:25 62887 8192000000 10869724784 11343790080 1939340624 19.141 99 22 44 0 1954882304 9611456 5930224
15:53:30 86822 8192000000 10861928848 11314593792 1883980848 18.697 97 27 54 1 1902505552 11095584 7429120
15:56:01 126616 8192000000 10862924480 11337871360 1881742544 18.679 97 6 12 0 1898849152 15515088 1591520
15:59:44 61785 8192000000 10883838576 11337154560 1902992352 18.850 97 32 64 0 1922169312 9837328 9339632
16:01:47 47992 8192000000 10806126032 11282231296 1833886464 18.291 95 11 22 0 1845396176 8580176 2929536
16:03:36 65715 8192000000 10827383872 11295989760 1895737376 18.792 99 11 23 0 1907320544 8304720 3278448
16:05:47 38950 8192000000 10839902832 11315036160 1904238416 18.860 99 15 29 0 1918960000 10701056 4020528
16:07:27 57967 8192000000 10858770992 11333111808 1882406240 18.685 97 14 28 0 1897127984 11175072 3546672
16:09:29 37753 8192000000 10853203856 11310792704 1892859376 18.769 97 16 32 0 1906683824 9183776 4640672
16:11:32 50872 8192000000 10857035568 11326685184 1902699408 18.848 99 26 52 1 1923720432 13614800 7406224
16:13:33 62156 8192000000 10875582080 11346735104 1943680288 19.176 100 18 36 1 1961902784 13151920 5070576
16:15:45 59856 8192000000 10909272672 11381379072 1970272784 19.388 100 18 36 0 1987241536 11829248 5139504
16:17:46 42388 8192000000 10906015232 11367239680 1939560384 19.143 98 33 66 0 1961347296 12805312 8981600
16:19:31 82617 8192000000 10919924400 11368955904 1938944480 19.138 98 17 34 0 1952501424 9133424 4423520
16:21:53 87780 8192000000 10902234912 11375542272 1949031872 19.219 98 20 40 0 1964391504 9989712 5369920
16:24:36 54195 8192000000 10880127824 11364536320 1933203904 19.092 97 22 45 1 1950463744 10949120 6310720
16:26:31 67919 8192000000 10917527728 11390541824 1972038768 19.402 99 11 22 1 1989574688 14665248 2870672
16:28:40 47886 8192000000 10909869808 11409874944 1991982272 19.559 100 4 8 0 2004375408 11338224 1054912
16:30:28 36071 8192000000 10890670480 11359166464 1886877216 18.721 95 30 60 1 1906947440 11168656 8901568
16:32:06 38342 8192000000 10884222096 11353903104 1932915968 19.090 99 26 52 0 1954858064 14339808 7602288
16:33:45 59924 8192000000 10893852336 11370258432 1936213664 19.117 98 20 40 1 1954369680 12134704 6021312
16:35:45 69476 8192000000 10898886800 11384709120 1941504976 19.159 98 14 28 0 1962575760 17384112 3686672
16:37:55 67838 8192000000 10888249584 11366187008 1928933600 19.058 98 15 30 0 1949367760 16007216 4426944
16:40:03 18895 8192000000 10886745632 11335032832 1916660384 18.960 98 65 130 0 1946462544 11325104 18477056
16:41:27 72613 8192000000 10867493664 11341914112 1943417376 19.174 100 15 31 0 1958275216 10889456 3968384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 16:44:08 to 17-09-2021 16:44:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 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 17-09-2021 16:44:24 to 17-09-2021 16:44:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228346
MON$OLDEST_ACTIVE               231994
MON$OLDEST_SNAPSHOT             231994
MON$NEXT_TRANSACTION            231994
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-03 16:41:55.3470
MON$PAGES                       2053088
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Sep 17 16:42:51 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Sep 17 16:42:52 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Sep 17 16:42:52 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
--- end of diff output ---

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

17.09.21 16:44:27. There are 3 logs in /var/tmp/logs-oltp30 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-09-17 16:44:29 No severe PSQL-related problems occured
17.09.21 16:44:30. End of report.