Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16439364 kB
MemAvailable 31433736 kB
Buffers 0 kB
Cached 15202736 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 3.0.9
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-12-11 16:43:17
DTS_END                         2021-12-11 16:43:17.5190
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58090
TRN_ID                          241544
ATT_ID                          700
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: 9736.08 18799 1168330
customer order: creation 173.46 343 20815
customer order: refuse 14.91 317 1789
order to supplier: creation 45.83 1269 5499
order to supplier: removal 3.48 102071 417
invoice (draft): creation 46.82 3448 5618
invoice (draft): removal 56.52 731 6782
invoice accept: apply 42.68 15731 5122
invoice accept: cancel 51.13 777 6136
customer reserve: creation 3995.35 153 479442
customer reserve: removal 4997.04 55 599645
realization accept: apply 85.65 178 10278
realization accept: cancel 95.83 102 11500
payment from customer: creation 58.23 113 6988
payment from customer: removal 5.90 70 708
payment to supplier: creation 48.23 124 5787
payment to supplier: removal 3.28 63 393
service: total inventory turnovers 4.59 1177 551
service: total monetary turnovers 6.41 25 769
service: aggregate perf. data 0.73 28275 87
service: refresh index statistics 0.03 220961 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-12-2021 16:44:21 to 11-12-2021 16:44:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3890
TEST_TIME 2 4360
TEST_TIME 3 5041
TEST_TIME 4 5366
TEST_TIME 5 5431
TEST_TIME 6 5281
TEST_TIME 7 5058
TEST_TIME 8 5077
TEST_TIME 9 5065
TEST_TIME 10 5121
TEST_TIME 11 5434
TEST_TIME 12 5623
TEST_TIME 13 5633
TEST_TIME 14 6222
TEST_TIME 15 6558
TEST_TIME 16 6531
TEST_TIME 17 7118
TEST_TIME 18 8533
TEST_TIME 19 8578
TEST_TIME 20 8935
TEST_TIME 21 9131
TEST_TIME 22 9218
TEST_TIME 23 9338
TEST_TIME 24 9773
TEST_TIME 25 10217
TEST_TIME 26 10171
TEST_TIME 27 10268
TEST_TIME 28 10105
TEST_TIME 29 10506
TEST_TIME 30 10397
TEST_TIME 31 10330
TEST_TIME 32 10211
TEST_TIME 33 10151
TEST_TIME 34 9977
TEST_TIME 35 10167
TEST_TIME 36 10116
TEST_TIME 37 10032
TEST_TIME 38 10086
TEST_TIME 39 10270
TEST_TIME 40 10142
TEST_TIME 41 10099
TEST_TIME 42 10260
TEST_TIME 43 10278
TEST_TIME 44 10260
TEST_TIME 45 10172
TEST_TIME 46 9997
TEST_TIME 47 9938
TEST_TIME 48 9904
TEST_TIME 49 9929
TEST_TIME 50 9800
TEST_TIME 51 9839
TEST_TIME 52 9814
TEST_TIME 53 9773
TEST_TIME 54 9930
TEST_TIME 55 9920
TEST_TIME 56 9810
TEST_TIME 57 9830
TEST_TIME 58 9715
TEST_TIME 59 9712
TEST_TIME 60 9805
TEST_TIME 61 9856
TEST_TIME 62 9896
TEST_TIME 63 9948
TEST_TIME 64 9931
TEST_TIME 65 9876
TEST_TIME 66 9998
TEST_TIME 67 10005
TEST_TIME 68 10069
TEST_TIME 69 10148
TEST_TIME 70 10094
TEST_TIME 71 10093
TEST_TIME 72 10179
TEST_TIME 73 10236
TEST_TIME 74 10194
TEST_TIME 75 10071
TEST_TIME 76 10037
TEST_TIME 77 9995
TEST_TIME 78 9928
TEST_TIME 79 9983
TEST_TIME 80 9932
TEST_TIME 81 9901
TEST_TIME 82 9872
TEST_TIME 83 9802
TEST_TIME 84 9818
TEST_TIME 85 9769
TEST_TIME 86 9709
TEST_TIME 87 9717
TEST_TIME 88 9712
TEST_TIME 89 9727
TEST_TIME 90 9706
TEST_TIME 91 9740
TEST_TIME 92 9712
TEST_TIME 93 9824
TEST_TIME 94 9817
TEST_TIME 95 9813
TEST_TIME 96 9815
TEST_TIME 97 9879
TEST_TIME 98 9899
TEST_TIME 99 9885
TEST_TIME 100 9854
TEST_TIME 101 9912
TEST_TIME 102 9969
TEST_TIME 103 9949
TEST_TIME 104 9876
TEST_TIME 105 9837
TEST_TIME 106 9861
TEST_TIME 107 9832
TEST_TIME 108 9835
TEST_TIME 109 9800
TEST_TIME 110 9765
TEST_TIME 111 9772
TEST_TIME 112 9739
TEST_TIME 113 9717
TEST_TIME 114 9751
TEST_TIME 115 9798
TEST_TIME 116 9783
TEST_TIME 117 9799
TEST_TIME 118 9797
TEST_TIME 119 9810
TEST_TIME 120 9742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-12-2021 16:44:25 to 11-12-2021 16:44:29.

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 1610525 1610525 0 0.00 0 17492 5 0 0
doc_list_biud 1610520 1610520 0 0.00 0 32349 26 0 0
sp_add_invoice_to_stock 5211 5122 89 1.70 32 151958 15731 0 89
sp_cancel_adding_invoice 6136 6136 0 0.00 5 40826 777 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 18871 318 0 0
sp_cancel_customer_reserve 599645 599645 0 0.00 1 23666 56 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5369 70 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 3295 63 0 0
sp_cancel_supplier_invoice 6789 6782 7 0.10 1 17050 731 0 7
sp_cancel_supplier_order 417 417 0 0.00 7 1021301 102071 0 0
sp_cancel_write_off 11500 11500 0 0.00 1 18709 103 0 0
sp_client_order 20815 20815 0 0.00 3 22530 344 0 0
sp_customer_reserve 479442 479442 0 0.00 0 137792 153 0 0
sp_fill_shopping_cart 31928 31928 0 0.00 1 18939 35 0 0
sp_fill_shopping_cart_clo_res 479427 479427 0 0.00 0 9742 12 0 0
sp_get_clo_for_invoice 5104 5104 0 0.00 0 19628 266 0 0
sp_kill_cost_storno 18737 18737 0 0.00 0 17482 56 0 0
sp_kill_qstorno_ret_qs2qd 1217989 1217989 0 0.00 0 29536 30 0 0
sp_kill_qty_storno 641651 641651 0 0.00 0 32345 66 0 0
sp_lock_selected_doc 21276 21276 0 0.00 0 8279 6 0 0
sp_make_cost_storno 28157 28157 0 0.00 0 17081 53 0 0
sp_multiply_rows_for_pdistr 28157 28157 0 0.00 0 13942 24 0 0
sp_multiply_rows_for_qdistr 20815 20815 0 0.00 0 22523 143 0 0
sp_pay_from_customer 6988 6988 0 0.00 4 41292 114 0 0
sp_pay_to_supplier 5787 5787 0 0.00 5 17089 124 0 0
sp_payment_common 12775 12775 0 0.00 1 41281 29 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 18864 286 0 0
sp_qd_handle_on_invoice_upd_sts 11240 11240 0 0.00 0 32339 341 0 0
sp_qd_handle_on_reserve_upd_sts 10278 10278 0 0.00 0 17826 64 0 0
sp_reserve_write_off 10278 10278 0 0.00 6 17970 179 0 0
sp_supplier_invoice 5618 5618 0 0.00 5 79586 3449 0 0
sp_supplier_order 5499 5499 0 0.00 5 49115 1269 0 0
srv_aggregate_perf_data 87 87 0 0.00 2326 645979 28276 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 985 142845 46142 0 0
srv_find_qd_qs_mism 1119154 1119154 0 0.00 0 12110 4 0 0
srv_make_invnt_saldo 551 551 0 0.00 72 49504 1178 0 0
srv_make_money_saldo 769 769 0 0.00 1 3794 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 159547 267250 220961 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5122 5122 0 0.00 0 11676 157 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 7873 119 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 4431 14 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 5416 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 337 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 1611 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 451 451 0 0.00 0 936 13 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 19573 1695 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 3274 10 0 0
t$perf-norm:sp_client_order 20815 20815 0 0.00 0 16510 22 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 2106 12 0 0
t$perf-norm:sp_pay_from_customer 6988 6988 0 0.00 0 13304 14 0 0
t$perf-norm:sp_pay_to_supplier 5787 5787 0 0.00 0 3587 11 0 0
t$perf-norm:sp_reserve_write_off 10278 10278 0 0.00 0 15984 13 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 9761 21 0 0
t$perf-norm:sp_supplier_order 5499 5499 0 0.00 0 6444 16 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 258 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 2228 19 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 895 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10386 10208 178 1.71 0 7865 20 0 178
v_all_customers 37284 37284 0 0.00 0 42 0 0 0
v_all_suppliers 30694 30694 0 0.00 0 56 1 0 0
v_all_wares 41630 41630 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1913 11 0 0
v_cancel_client_order 3578 3578 0 0.00 0 4191 8 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 1001 6 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1419 6 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 3284 10 0 0
v_cancel_supplier_invoice 916 902 14 1.52 0 2029 17 0 14
v_cancel_supplier_order 834 834 0 0.00 0 558 3 0 0
v_cancel_write_off 2038 2038 0 0.00 0 2538 15 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 358 1 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1804 13 0 0
v_max_id_ord_sup 5612 5612 0 0.00 0 2347 2 0 0
v_max_non_paid_invoice 1549 1549 0 0.00 0 326 2 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 4438 8 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 4393 7 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 1453 5 0 0
v_min_id_ord_sup 5612 5612 0 0.00 0 6323 7 0 0
v_min_non_paid_invoice 5787 5787 0 0.00 0 6386 19 0 0
v_min_non_paid_realizn 6988 6988 0 0.00 0 41276 24 0 0
v_our_firm 10552 10552 0 0.00 0 40 1 0 0
v_reserve_write_off 20556 20556 0 0.00 0 5179 7 0 0
x_lock_depdocs_on_canc_invoice 12467 12467 0 0.00 0 15219 100 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 7895 313 0 0
x_make_qstorno_1000_1200 5499 5499 0 0.00 1 49103 1199 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 2 79572 3324 0 0
x_make_qstorno_2100_3300 479442 479442 0 0.00 0 137791 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2021 16:44:30 to 11-12-2021 16:44:34.

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:13:21 15 8192000000 8803529824 8816525312 7669360 0.093 3 1 2 0 7770320 58176 42784
14:14:22 19339 8192000000 9915066544 10232541184 1001492720 10.893 100 86 172 0 1029734672 5897568 22344384
14:15:53 21524 8192000000 9965082368 10294202368 1049802704 11.359 100 88 176 0 1080876384 8580112 22493568
14:17:22 22941 8192000000 10012242992 10352758784 1092794848 11.769 100 88 177 0 1124983904 10281376 21907680
14:18:48 15451 8192000000 10065627280 10420428800 1143547056 12.249 100 85 170 0 1175690304 11963456 20179792
14:20:13 10215 8192000000 10088700400 10450452480 1175654352 12.550 100 78 155 0 1206210832 12836592 17719888
14:21:27 5775 8192000000 10133814608 10509156352 1217062320 12.935 100 74 147 1 1249144592 16296880 15785392
14:22:36 8500 8192000000 10159069168 10540904448 1252147024 13.258 100 69 138 4 1279444784 11985632 15312128
14:23:47 2249 8192000000 10217044720 10599710720 1295385056 13.653 100 88 176 0 1328419488 14186816 18847616
14:24:51 25718 8192000000 10210799808 10613583872 1311205984 13.797 100 70 137 0 1342903280 16544512 15152784
14:26:18 4666 8192000000 10215869504 10625777664 1324923344 13.921 100 46 92 2 1342211744 6897488 10390912
14:27:25 14573 8192000000 10209281424 10628775936 1327194880 13.942 100 8 16 0 1334429184 5058128 2176176
14:28:41 11558 8192000000 10225757824 10637221888 1333968304 14.003 100 17 34 3 1348073712 10764576 3340832
14:29:53 29933 8192000000 10231905504 10656657408 1340422304 14.061 100 23 46 0 1352075024 6667040 4985680
14:31:27 4914 8192000000 10237907488 10661924864 1344323696 14.096 100 30 60 0 1358104432 6957648 6823088
14:32:36 11480 8192000000 10237695824 10663510016 1345251616 14.105 100 12 24 1 1353186208 5349824 2584768
14:33:53 66345 8192000000 10197546096 10609553408 1257141984 13.304 95 29 59 1 1269105232 5412400 6550848
14:36:03 4794 8192000000 10135012512 10530373632 1211807840 12.886 96 37 73 2 1226894272 5528800 9557632
14:37:09 9269 8192000000 10139445824 10536120320 1233607712 13.087 99 17 32 1 1243154896 5510496 4036688
14:38:21 19477 8192000000 10134681488 10543882240 1242650896 13.171 99 24 48 1 1253203616 4773568 5779152
14:40:23 5920 8192000000 10176636896 10582847488 1280377856 13.516 100 29 58 3 1293680448 6714784 6587808
14:41:31 6511 8192000000 10188943744 10607685632 1301322080 13.707 100 17 33 0 1311390480 5473856 4594544
14:42:38 8232 8192000000 10202709552 10620657664 1313887696 13.821 100 15 28 2 1322864480 5526144 3450640
14:43:50 65959 8192000000 10238061664 10655178752 1338731280 14.046 100 44 87 2 1354637408 5497472 10408656
14:46:04 4343 8192000000 10290172144 10704375808 1369081600 14.319 100 64 128 1 1401255520 17833040 14340880
14:47:11 13452 8192000000 10300695840 10722734080 1383518640 14.448 100 55 107 2 1415084304 18181264 13384400
14:48:28 16669 8192000000 10309029824 10733412352 1394088176 14.542 100 44 88 1 1424918192 20244320 10585696
14:49:49 6929 8192000000 10325151792 10748465152 1403291584 14.624 100 58 116 1 1438690688 21335088 14064016
14:51:04 17426 8192000000 10337476592 10765611008 1415719840 14.735 100 44 88 2 1452537536 25959104 10858592
14:52:27 5011 8192000000 10350829232 10775146496 1416691888 14.743 100 49 97 1 1457570928 28604112 12274928
14:53:39 7973 8192000000 10371522096 10795327488 1433985872 14.897 100 56 110 3 1481502960 32325024 15192064
14:54:52 5148 8192000000 10380000640 10798088192 1432505744 14.883 100 74 148 1 1487005840 35970144 18529952
14:56:01 984 8192000000 10359969152 10765320192 1399809888 14.593 99 91 180 2 1461780096 39861184 22109024
14:57:07 2340 8192000000 10357884048 10763198464 1396682464 14.565 100 79 156 1 1465385824 49096960 19606400
14:58:18 5265 8192000000 10362957392 10778554368 1397946928 14.577 99 75 148 0 1464223024 47394976 18881120
14:59:31 4403 8192000000 10346779744 10757107712 1382890672 14.442 99 78 154 0 1450868784 48701344 19276768
15:00:38 1737 8192000000 10370252064 10783588352 1396440816 14.563 100 88 178 1 1473130272 53182032 23507424
15:01:46 10351 8192000000 10332747424 10770440192 1390580000 14.511 100 35 70 1 1444130192 43449008 10101184
15:03:05 13962 8192000000 10328014160 10758389760 1383560016 14.448 100 38 76 2 1437111008 43332912 10218080
15:04:42 16206 8192000000 10304895552 10737508352 1336244720 14.024 97 29 58 1 1382468224 38264576 7958928
15:06:29 12713 8192000000 10292269088 10714787840 1331793792 13.983 99 27 54 0 1379592368 40166032 7632544
15:07:46 36225 8192000000 10292091024 10738835456 1373431520 14.358 100 27 54 2 1415972736 35484752 7056464
15:09:30 27732 8192000000 10290316784 10723987456 1362937536 14.264 100 29 58 0 1392316608 22209264 7169808
15:11:19 16831 8192000000 10299755632 10730696704 1364332640 14.276 100 51 102 1 1399405632 22580864 12492128
15:12:45 49754 8192000000 10263260432 10714705920 1322508352 13.899 97 4 8 0 1335449424 12407808 533264
15:14:35 57472 8192000000 10264452016 10705670144 1340956032 14.066 98 10 20 0 1350237712 7534256 1747424
15:16:39 53136 8192000000 10274363328 10710908928 1332419920 13.989 97 29 58 1 1348261632 9185264 6656448
15:20:19 53358 8192000000 10306308192 10744795136 1383223856 14.445 100 15 30 1 1396945792 10218096 3503840
15:22:13 94639 8192000000 10309741072 10761289728 1374124016 14.364 99 8 15 2 1391392000 15354528 1913456
15:24:55 131217 8192000000 10293771600 10737061888 1350359040 14.151 97 5 10 0 1368170880 17178256 633584
15:28:14 91838 8192000000 10309993136 10751827968 1381974608 14.434 99 28 56 2 1395875504 6426176 7474720
15:30:52 34856 8192000000 10318232128 10762682368 1386133216 14.471 99 37 70 1 1400156944 5595184 8428544
15:32:27 142844 8192000000 10310941632 10762285056 1354870976 14.191 97 30 60 2 1374350240 12689536 6789728
15:35:53 31029 8192000000 10350716192 10784649216 1400177952 14.597 99 40 80 1 1429327136 19450128 9699056
15:37:30 63893 8192000000 10284682368 10758234112 1356859440 14.209 97 15 31 0 1390284832 29385616 4039776
15:39:36 46237 8192000000 10305342736 10761383936 1377211392 14.392 99 12 25 0 1405787680 25602976 2973312
15:43:22 67804 8192000000 10267644400 10737913856 1316595632 13.846 96 9 18 0 1334868912 16425520 1847760
15:45:35 55822 8192000000 10282604032 10744545280 1379773296 14.415 100 14 28 0 1394564144 11658720 3132128
15:47:37 36904 8192000000 10308987712 10755010560 1337760880 14.037 96 26 52 1 1357740912 13777872 6202160
15:49:19 81134 8192000000 10279497296 10723749888 1310462816 13.790 95 15 30 1 1330111760 16147744 3501200
15:51:40 60829 8192000000 10278933920 10725113856 1323865920 13.912 97 21 41 0 1346170864 17676160 4628784
15:53:42 32708 8192000000 10292425712 10733101056 1357840464 14.218 98 34 70 0 1376094128 11005664 7248000
15:55:15 67301 8192000000 10275691744 10721767424 1323015824 13.904 97 12 24 1 1340442640 14158816 3268000
15:58:03 122608 8192000000 10249684944 10696847360 1307155872 13.760 96 20 39 1 1321203232 9231232 4816128
16:01:09 19024 8192000000 10268649440 10712285184 1358535248 14.224 100 37 74 1 1376232768 9617104 8080416
16:02:29 45764 8192000000 10259489104 10719539200 1350312352 14.150 99 22 44 0 1365296192 10557904 4425936
16:04:19 62556 8192000000 10272144528 10725511168 1359065248 14.229 99 15 28 1 1369456976 7469424 2922304
16:06:29 30214 8192000000 10264140960 10723364864 1343713728 14.091 98 5 10 0 1354522176 9540256 1268192
16:08:00 46273 8192000000 10260716416 10714771456 1361415264 14.250 100 21 42 1 1373603136 7490448 4697424
16:09:47 59260 8192000000 10266210272 10723528704 1343243440 14.087 98 26 52 0 1360814512 11835616 5735456
16:12:39 42109 8192000000 10283850800 10739490816 1364875488 14.281 99 10 20 0 1376004144 9248016 1880640
16:14:27 54949 8192000000 10287493472 10741260288 1334643920 14.009 96 16 32 0 1346343856 8638064 3061872
16:16:23 91214 8192000000 10302699984 10767589376 1379654416 14.413 99 9 18 0 1391253984 9586816 2012752
16:18:55 39223 8192000000 10315316560 10773069824 1391280976 14.517 99 20 40 2 1414922976 18902656 4739344
16:20:44 42076 8192000000 10303537776 10766352384 1375934464 14.380 99 29 57 1 1396186400 13679072 6572864
16:22:29 70169 8192000000 10305603040 10764333056 1379735680 14.414 99 12 24 1 1398134592 15527392 2871520
16:24:47 77960 8192000000 10293565056 10748268544 1357769632 14.217 98 6 12 0 1371967712 12744592 1453488
16:27:17 84864 8192000000 10291647680 10747772928 1360114880 14.238 98 14 28 0 1369156816 6251904 2790032
16:29:45 45918 8192000000 10306714912 10771480576 1391131632 14.516 100 26 52 0 1414386880 17401776 5853472
16:31:33 40998 8192000000 10283960448 10745180160 1374677872 14.369 99 16 32 0 1388542624 10579472 3285280
16:33:16 49710 8192000000 10295363216 10752729088 1372656752 14.351 99 31 58 0 1397717600 18271184 6789664
16:35:06 101460 8192000000 10308013888 10761781248 1370938304 14.335 99 25 50 1 1404721952 27392176 6391472
16:38:06 69351 8192000000 10275091200 10729435136 1348156112 14.131 98 13 26 0 1365042960 13823936 3062912
16:40:20 50454 8192000000 10264957552 10721488896 1323171712 13.905 97 26 52 0 1338929056 10098528 5658816
16:42:10 70193 8192000000 9689622384 10062327808 781757504 8.711 57 11 22 0 794179328 10347248 2074576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2021 16:44:35 to 11-12-2021 16:44:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-12-2021 16:44:51 to 11-12-2021 16:44:53.

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          240080
MON$OLDEST_ACTIVE               241772
MON$OLDEST_SNAPSHOT             241772
MON$NEXT_TRANSACTION            241772
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                       2052640
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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    Sat Dec 11 16:43:33 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
--- end of diff output ---

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

11.12.21 16:44:54. 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-12-11 16:44:56 No severe PSQL-related problems occured
11.12.21 16:44:58. End of report.