Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 01:39
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 15363776 kB
MemAvailable 30470700 kB
Buffers 0 kB
Cached 16369368 kB
SwapCached 3144 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.11
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 2023-02-27 01:38:39
DTS_END                         2023-02-27 01:38:39.3700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60458
TRN_ID                          244239
ATT_ID                          647
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: 9036.14 18735 1084337
customer order: creation 174.15 378 20898
customer order: refuse 14.99 354 1799
order to supplier: creation 45.57 1237 5468
order to supplier: removal 3.06 100757 367
invoice (draft): creation 45.44 3320 5453
invoice (draft): removal 50.22 677 6026
invoice accept: apply 41.84 18522 5021
invoice accept: cancel 45.47 783 5456
customer reserve: creation 3861.53 183 463384
customer reserve: removal 4463.68 55 535642
realization accept: apply 83.70 187 10044
realization accept: cancel 77.44 103 9293
payment from customer: creation 58.56 102 7027
payment from customer: removal 5.76 65 691
payment to supplier: creation 49.29 119 5915
payment to supplier: removal 3.63 66 436
service: total inventory turnovers 4.68 938 561
service: total monetary turnovers 6.36 41 763
service: aggregate perf. data 0.74 28057 89
service: refresh index statistics 0.03 218753 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2023 01:40:19 to 27-02-2023 01:40:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3252
TEST_TIME 2 3596
TEST_TIME 3 5650
TEST_TIME 4 6407
TEST_TIME 5 6191
TEST_TIME 6 6011
TEST_TIME 7 6086
TEST_TIME 8 6192
TEST_TIME 9 6356
TEST_TIME 10 6407
TEST_TIME 11 6490
TEST_TIME 12 6485
TEST_TIME 13 6811
TEST_TIME 14 6901
TEST_TIME 15 7156
TEST_TIME 16 7258
TEST_TIME 17 7249
TEST_TIME 18 8138
TEST_TIME 19 8717
TEST_TIME 20 9216
TEST_TIME 21 9239
TEST_TIME 22 9158
TEST_TIME 23 10080
TEST_TIME 24 10110
TEST_TIME 25 10372
TEST_TIME 26 10243
TEST_TIME 27 10294
TEST_TIME 28 10568
TEST_TIME 29 10430
TEST_TIME 30 10588
TEST_TIME 31 10555
TEST_TIME 32 10456
TEST_TIME 33 10294
TEST_TIME 34 10225
TEST_TIME 35 10078
TEST_TIME 36 9951
TEST_TIME 37 9907
TEST_TIME 38 9844
TEST_TIME 39 9732
TEST_TIME 40 9535
TEST_TIME 41 9518
TEST_TIME 42 9438
TEST_TIME 43 9543
TEST_TIME 44 9552
TEST_TIME 45 9542
TEST_TIME 46 9378
TEST_TIME 47 9327
TEST_TIME 48 9296
TEST_TIME 49 9186
TEST_TIME 50 9227
TEST_TIME 51 9187
TEST_TIME 52 9192
TEST_TIME 53 9175
TEST_TIME 54 9132
TEST_TIME 55 9121
TEST_TIME 56 9032
TEST_TIME 57 9106
TEST_TIME 58 9257
TEST_TIME 59 9210
TEST_TIME 60 9265
TEST_TIME 61 9261
TEST_TIME 62 9291
TEST_TIME 63 9380
TEST_TIME 64 9415
TEST_TIME 65 9361
TEST_TIME 66 9406
TEST_TIME 67 9347
TEST_TIME 68 9419
TEST_TIME 69 9307
TEST_TIME 70 9301
TEST_TIME 71 9237
TEST_TIME 72 9210
TEST_TIME 73 9225
TEST_TIME 74 9216
TEST_TIME 75 9144
TEST_TIME 76 9082
TEST_TIME 77 9109
TEST_TIME 78 9056
TEST_TIME 79 9050
TEST_TIME 80 9083
TEST_TIME 81 9043
TEST_TIME 82 9126
TEST_TIME 83 9190
TEST_TIME 84 9181
TEST_TIME 85 9179
TEST_TIME 86 9266
TEST_TIME 87 9237
TEST_TIME 88 9290
TEST_TIME 89 9278
TEST_TIME 90 9371
TEST_TIME 91 9355
TEST_TIME 92 9264
TEST_TIME 93 9181
TEST_TIME 94 9168
TEST_TIME 95 9138
TEST_TIME 96 9075
TEST_TIME 97 9074
TEST_TIME 98 9047
TEST_TIME 99 9074
TEST_TIME 100 9139
TEST_TIME 101 9150
TEST_TIME 102 9135
TEST_TIME 103 9116
TEST_TIME 104 9126
TEST_TIME 105 9206
TEST_TIME 106 9231
TEST_TIME 107 9227
TEST_TIME 108 9247
TEST_TIME 109 9297
TEST_TIME 110 9267
TEST_TIME 111 9316
TEST_TIME 112 9270
TEST_TIME 113 9214
TEST_TIME 114 9195
TEST_TIME 115 9178
TEST_TIME 116 9134
TEST_TIME 117 9101
TEST_TIME 118 9098
TEST_TIME 119 9088
TEST_TIME 120 9042

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2023 01:40:22 to 27-02-2023 01:40:26.

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 1513724 1513724 0 0.00 0 17345 6 0 0
doc_list_biud 1513724 1513724 0 0.00 0 40603 25 0 0
sp_add_invoice_to_stock 5188 5021 167 3.21 34 195234 18522 0 167
sp_cancel_adding_invoice 5456 5456 0 0.00 5 68094 784 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 25148 355 0 0
sp_cancel_customer_reserve 535642 535642 0 0.00 0 40607 55 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 3673 65 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 3927 67 0 0
sp_cancel_supplier_invoice 6044 6026 18 0.29 1 16246 678 0 18
sp_cancel_supplier_order 367 367 0 0.00 6 1094803 100757 0 0
sp_cancel_write_off 9293 9293 0 0.00 1 13403 104 0 0
sp_client_order 20898 20898 0 0.00 3 73141 378 0 0
sp_customer_reserve 463384 463384 0 0.00 0 42091 183 0 0
sp_fill_shopping_cart 31808 31808 0 0.00 1 15265 42 0 0
sp_fill_shopping_cart_clo_res 463377 463377 0 0.00 0 26491 14 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 32655 358 0 0
sp_kill_cost_storno 15876 15876 0 0.00 0 14403 51 0 0
sp_kill_qstorno_ret_qs2qd 1086970 1086970 0 0.00 0 27442 30 0 0
sp_kill_qty_storno 573637 573637 0 0.00 0 40603 65 0 0
sp_lock_selected_doc 20877 20877 0 0.00 0 8158 8 0 0
sp_make_cost_storno 27996 27996 0 0.00 0 17343 46 0 0
sp_multiply_rows_for_pdistr 27996 27996 0 0.00 0 9341 24 0 0
sp_multiply_rows_for_qdistr 20898 20898 0 0.00 0 72919 156 0 0
sp_pay_from_customer 7027 7027 0 0.00 4 8389 102 0 0
sp_pay_to_supplier 5915 5915 0 0.00 4 24016 119 0 0
sp_payment_common 12942 12942 0 0.00 1 18748 25 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 25140 314 0 0
sp_qd_handle_on_invoice_upd_sts 10466 10466 0 0.00 0 22036 322 0 0
sp_qd_handle_on_reserve_upd_sts 10044 10044 0 0.00 0 20588 71 0 0
sp_reserve_write_off 10044 10044 0 0.00 6 20718 187 0 0
sp_supplier_invoice 5453 5453 0 0.00 6 101701 3321 0 0
sp_supplier_order 5468 5468 0 0.00 5 34748 1238 0 0
srv_aggregate_perf_data 90 89 1 1.11 2067 658950 28057 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1002 167451 45383 0 0
srv_find_qd_qs_mism 1036653 1036653 0 0.00 0 12906 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 20 19205 939 0 0
srv_make_money_saldo 763 763 0 0.00 1 7080 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 153261 374116 218753 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5021 5021 0 0.00 0 7587 160 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 3288 95 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 7666 16 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 1219 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 4190 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 1874 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 5401 27 0 0
t$perf-norm:sp_cancel_supplier_order 367 367 0 0.00 0 19729 1534 0 0
t$perf-norm:sp_cancel_write_off 1039 1039 0 0.00 0 7347 11 0 0
t$perf-norm:sp_client_order 20898 20898 0 0.00 0 32629 18 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1741 5 0 0
t$perf-norm:sp_pay_from_customer 7027 7027 0 0.00 0 8365 12 0 0
t$perf-norm:sp_pay_to_supplier 5915 5915 0 0.00 0 2965 9 0 0
t$perf-norm:sp_reserve_write_off 10044 10044 0 0.00 0 6477 14 0 0
t$perf-norm:sp_supplier_invoice 5453 5453 0 0.00 0 13416 17 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 4556 14 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 192 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 127 3 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 1156 21 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 8349 48 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10354 10020 334 3.22 0 9352 23 0 334
v_all_customers 37678 37678 0 0.00 0 20 1 0 0
v_all_suppliers 30240 30240 0 0.00 0 56 1 0 0
v_all_wares 41796 41796 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3377 13 0 0
v_cancel_client_order 3598 3598 0 0.00 0 10000 8 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 3656 9 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 3299 7 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2538 5 0 0
v_cancel_supplier_invoice 828 792 36 4.34 0 1332 17 0 36
v_cancel_supplier_order 734 734 0 0.00 0 4163 10 0 0
v_cancel_write_off 2078 2078 0 0.00 0 4430 19 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 3326 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 3380 16 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 6094 8 0 0
v_max_non_paid_invoice 1705 1705 0 0.00 0 4200 4 0 0
v_max_non_paid_realizn 3866 3866 0 0.00 0 5043 6 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 5376 9 0 0
v_min_id_clo_res 995 995 0 0.00 0 474 4 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 7093 12 0 0
v_min_non_paid_invoice 5915 5915 0 0.00 0 18743 18 0 0
v_min_non_paid_realizn 7027 7027 0 0.00 0 5690 16 0 0
v_our_firm 10440 10440 0 0.00 0 18 1 0 0
v_reserve_write_off 20088 20088 0 0.00 0 9371 10 0 0
x_lock_depdocs_on_canc_invoice 11086 11086 0 0.00 0 27953 110 0 0
x_lock_depdocs_on_canc_sup_ord 367 367 0 0.00 0 8029 347 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 2 34739 1162 0 0
x_make_qstorno_1200_2000 5453 5453 0 0.00 3 96334 3154 0 0
x_make_qstorno_2100_3300 463384 463384 0 0.00 0 42091 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 01:40:27 to 27-02-2023 01:40:31.

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
23:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:44 32589 8192000000 9911131344 10222632960 984760064 10.731 100 85 168 1 1012694864 6383952 21550848
23:11:21 28627 8192000000 9951834848 10275016704 1035162928 11.218 100 88 176 0 1067092688 9185872 22743888
23:12:53 13319 8192000000 9978167248 10310189056 1063630144 11.491 100 86 173 0 1095993120 10807680 21555296
23:14:18 6190 8192000000 10031336848 10377199616 1120564304 12.032 100 90 178 0 1154074480 11545520 21964656
23:15:27 5458 8192000000 10088272384 10449211392 1176486208 12.557 100 73 146 0 1204354976 11349328 16519440
23:16:33 2718 8192000000 10130554240 10497298432 1218299088 12.946 100 89 179 1 1252215360 13955552 19960720
23:17:37 2704 8192000000 10163787712 10541035520 1245795488 13.200 100 80 158 3 1275667312 12461216 17410608
23:18:42 973 8192000000 10198494016 10578382848 1284435568 13.553 100 89 177 0 1318467424 14095392 19936464
23:19:44 15000 8192000000 10194260704 10598281216 1298489520 13.682 100 45 89 0 1318286720 9755936 10041264
23:21:01 5544 8192000000 10198120096 10610102272 1307503072 13.763 100 21 40 0 1318325744 6691312 4131360
23:22:09 4523 8192000000 10220910768 10630426624 1325328128 13.925 100 38 74 1 1341393632 7376112 8689392
23:23:14 63410 8192000000 10225360064 10643312640 1330474032 13.971 100 27 53 0 1341578256 5044224 6060000
23:25:22 4326 8192000000 10228717888 10648485888 1334167600 14.005 100 27 53 1 1346688976 6953088 5568288
23:26:29 2713 8192000000 10235066704 10650222592 1334502320 14.008 100 37 75 0 1350371312 7634656 8234336
23:27:35 8699 8192000000 10206529376 10626969600 1319211824 13.870 100 17 34 0 1328704832 5379680 4113328
23:28:44 23144 8192000000 10180513248 10592702464 1256982640 13.302 95 30 60 1 1269048656 5432240 6633776
23:30:09 5483 8192000000 10137472144 10538074112 1209779296 12.867 95 39 78 0 1224486352 5506160 9200896
23:31:17 5851 8192000000 10140006720 10541895680 1238406448 13.132 98 26 52 0 1251004192 6929264 5668480
23:32:23 10643 8192000000 10152633024 10559938560 1252687696 13.263 99 26 52 0 1264480480 5357280 6435504
23:33:35 40214 8192000000 10179051008 10586046464 1280146912 13.514 100 37 73 0 1294123920 5028464 8948544
23:35:17 13451 8192000000 10195491344 10601811968 1298640608 13.683 100 24 46 0 1309376176 5649760 5085808
23:36:35 8078 8192000000 10198930848 10614079488 1302911472 13.722 100 26 52 0 1318405312 9253424 6240416
23:37:44 11086 8192000000 10193296688 10614738944 1307680976 13.765 100 18 36 0 1321373840 9687936 4004928
23:38:57 77638 8192000000 10243303664 10662629376 1335217696 14.014 100 37 72 1 1355823904 12016640 8589568
23:41:45 4797 8192000000 10268914208 10676834304 1346951536 14.120 99 63 124 1 1375984912 15695904 13337472
23:42:54 3473 8192000000 10291940896 10704920576 1370461616 14.331 100 66 129 1 1401211520 16593648 14156256
23:44:03 12505 8192000000 10298034432 10716200960 1382149776 14.436 100 48 93 0 1408485376 16012576 10323024
23:45:22 13080 8192000000 10299910688 10722910208 1376225328 14.383 99 41 79 0 1403277840 17066752 9985760
23:46:42 4411 8192000000 10314758944 10735177728 1394568064 14.547 100 51 100 0 1426025920 19499376 11958480
23:47:50 1001 8192000000 10323081648 10730860544 1390281808 14.508 100 83 164 1 1431777456 22153584 19342064
23:48:56 10509 8192000000 10317717408 10728820736 1376196528 14.383 99 66 130 0 1416647984 25629408 14822048
23:50:09 11263 8192000000 10288332128 10700206080 1348474384 14.134 97 53 106 0 1393481056 32142032 12864640
23:51:24 3987 8192000000 10297583696 10709725184 1363872464 14.272 100 56 113 0 1405773616 28085072 13816080
23:52:49 4090 8192000000 10293907504 10695950336 1346599568 14.117 100 79 156 0 1399741440 33980000 19161872
23:53:57 1944 8192000000 10301430176 10695835648 1346707584 14.118 99 83 166 0 1404524528 37934768 19882176
23:55:04 2357 8192000000 10290788704 10688507904 1327114832 13.941 99 78 154 1 1388825120 42586736 19123552
23:56:11 2148 8192000000 10320242832 10718453760 1347955616 14.129 100 77 153 0 1419549568 51425040 20168912
23:57:16 6645 8192000000 10306433488 10721796096 1356038464 14.202 100 46 92 0 1410487040 42573136 11875440
23:58:29 4468 8192000000 10298745904 10722406400 1361460160 14.250 100 35 69 1 1405730384 35140960 9129264
00:00:01 20160 8192000000 10309274832 10740543488 1363793952 14.271 100 27 54 0 1407636096 36576192 7265952
00:01:25 14313 8192000000 10286538496 10722553856 1355991360 14.201 100 25 50 1 1400689792 37963088 6735344
00:02:54 15822 8192000000 10293705888 10727055360 1368777184 14.316 100 30 60 0 1401321680 24367232 8177264
00:04:12 52080 8192000000 10286917792 10729226240 1361480160 14.251 100 26 52 0 1394443376 25213040 7750176
00:06:28 18891 8192000000 10283784064 10730860544 1364598160 14.279 100 25 50 0 1391287984 20330544 6359280
00:07:56 34614 8192000000 10284489408 10727936000 1376313616 14.384 100 18 36 0 1397213264 16928928 3970720
00:09:32 57294 8192000000 10300220144 10731552768 1370723520 14.334 99 21 43 3 1389058912 14396336 3939056
00:11:30 72098 8192000000 10293192000 10740334592 1377757856 14.396 99 24 46 0 1392056736 9300304 4998576
00:13:43 85123 8192000000 10292431936 10746359808 1370340160 14.330 99 20 41 0 1390131056 14820752 4970144
00:16:09 94335 8192000000 10312311696 10762989568 1394748944 14.548 100 30 60 0 1431375616 29694912 6931760
00:18:54 5363 8192000000 10324286048 10768437248 1405192688 14.641 100 42 82 0 1432377440 17388112 9796640
00:20:02 40342 8192000000 10293651328 10746155008 1383325568 14.446 99 25 49 1 1406300048 17023792 5950688
00:21:45 37767 8192000000 10310542752 10762485760 1401481616 14.608 100 31 61 0 1418372272 10077840 6812816
00:23:27 90128 8192000000 10307920016 10762932224 1379357664 14.411 98 11 22 0 1393941984 11663024 2921296
00:26:18 26319 8192000000 10292424080 10762444800 1395929232 14.559 100 14 27 1 1415445184 16673760 2842192
00:27:44 66528 8192000000 10300472048 10769543168 1402435760 14.617 100 24 48 0 1424747712 17251824 5060128
00:30:27 49216 8192000000 10301448672 10776412160 1409514992 14.680 100 3 6 0 1419264720 9146032 603696
00:32:30 32071 8192000000 10293130144 10756325376 1379969104 14.416 98 15 28 0 1390771872 7533728 3269040
00:34:06 100208 8192000000 10291761968 10759225344 1327609040 13.946 94 21 42 0 1348992336 16633600 4749696
00:36:47 33328 8192000000 10294548048 10744328192 1368734000 14.316 98 25 50 1 1381884448 7788864 5361584
00:38:21 53220 8192000000 10285635184 10753245184 1343051984 14.085 96 25 50 0 1361442000 12974144 5415872
00:40:15 18446 8192000000 10270643408 10708205568 1330519392 13.972 97 46 92 0 1351745440 11302208 9923840
00:41:35 122463 8192000000 10276532880 10719657984 1296879952 13.667 95 18 34 1 1318544736 18468096 3196688
00:44:40 38917 8192000000 10259016624 10697367552 1324455264 13.917 98 28 55 1 1344253120 13757184 6040672
00:46:22 64488 8192000000 10217241088 10665832448 1306809648 13.757 98 28 56 0 1324461232 11646736 6004848
00:48:47 58682 8192000000 10243029344 10696499200 1341641216 14.072 100 18 31 0 1353412448 8325184 3446048
00:50:56 24663 8192000000 10263716240 10697113600 1330690032 13.973 98 34 64 0 1349979600 12172160 7117408
00:52:21 41815 8192000000 10242491520 10699689984 1331345824 13.979 98 10 18 0 1347231840 13634032 2251984
00:54:03 73320 8192000000 10243729728 10692272128 1315597456 13.837 97 8 14 0 1330295904 12664304 2034144
00:56:46 54704 8192000000 10255865648 10714521600 1344848608 14.101 99 22 45 0 1361203600 11388256 4966736
00:58:42 32911 8192000000 10260034560 10692222976 1298995424 13.686 95 35 70 0 1318519952 11999568 7524960
01:00:15 90730 8192000000 10266184320 10722050048 1343209424 14.086 99 17 34 0 1362537008 15105664 4221920
01:02:46 65450 8192000000 10279852208 10724139008 1344192448 14.095 98 30 62 0 1367521456 16290816 7038192
01:05:42 113703 8192000000 10279747712 10733002752 1350037168 14.148 98 14 28 0 1368175200 14122000 4016032
01:08:36 167450 8192000000 10282075184 10731474944 1338311760 14.042 96 21 43 1 1354518800 11870544 4336496
01:12:24 37284 8192000000 10303138080 10759081984 1402341520 14.616 100 29 58 0 1418757920 9610352 6806048
01:14:02 62930 8192000000 10302222688 10765860864 1393265056 14.535 99 16 32 0 1412866640 15697648 3903936
01:16:05 70097 8192000000 10309512784 10771324928 1382236352 14.437 98 17 34 0 1404899344 18097376 4565616
01:18:15 60846 8192000000 10301493472 10783023104 1369792704 14.325 98 8 16 0 1396425200 24754608 1877888
01:20:16 56640 8192000000 10307361472 10762240000 1386056224 14.471 99 25 51 0 1405679664 13528704 6094736
01:22:27 70906 8192000000 10305445968 10768470016 1372076912 14.346 98 11 22 1 1396986768 22049520 2860336
01:25:37 49742 8192000000 10295949296 10743554048 1328452992 13.953 95 30 60 0 1344830448 9740208 6637248
01:27:27 98214 8192000000 10283874816 10735882240 1325429296 13.926 96 10 20 0 1343087856 15577616 2080944
01:30:07 20348 8192000000 10277116656 10702974976 1314064784 13.823 96 48 94 0 1335433552 11257184 10111584
01:31:28 101978 8192000000 10230814480 10677088256 1292616944 13.628 96 27 54 0 1307946496 9197104 6132448
01:34:11 49024 8192000000 10224754544 10676453376 1332974896 13.994 99 9 18 0 1342055520 6773312 2307312
01:36:00 55486 8192000000 10242415584 10693279744 1325375600 13.925 98 13 26 0 1336616144 8274816 2965728
01:38:04 39654 8192000000 9524378656 9864187904 671647904 7.577 50 10 20 0 678463152 4781456 2033792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-02-2023 01:40:33 to 27-02-2023 01:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 167 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2023 01:40:49 to 27-02-2023 01:40:51.

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          244160
MON$OLDEST_ACTIVE               244598
MON$OLDEST_SNAPSHOT             244598
MON$NEXT_TRANSACTION            244598
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                       2028832
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:689: 
:690: mscs-test-161.budzdorov.ru    Mon Feb 27 01:38:45 2023
:691:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:692: 
:693: 
:694: mscs-test-161.budzdorov.ru    Mon Feb 27 01:38:46 2023
:695:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:696: 
--- end of diff output ---

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

27.02.23 01:40:52. 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
2023-02-27 01:40:55 No severe PSQL-related problems occured
27.02.23 01:40:56. End of report.