Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2022 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 15860532 kB
MemAvailable 30557476 kB
Buffers 0 kB
Cached 15845836 kB
SwapCached 2056 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 2022-09-30 01:38:28
DTS_END                         2022-09-30 01:38:28.3040
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/41732
TRN_ID                          240563
ATT_ID                          698
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: 8956.05 24270 1074726
customer order: creation 170.88 359 20506
customer order: refuse 14.58 361 1750
order to supplier: creation 45.37 1229 5444
order to supplier: removal 3.21 92337 385
invoice (draft): creation 44.85 3223 5382
invoice (draft): removal 48.58 738 5830
invoice accept: apply 41.95 17929 5034
invoice accept: cancel 43.86 720 5263
customer reserve: creation 3914.26 176 469711
customer reserve: removal 4336.58 53 520389
realization accept: apply 83.99 193 10079
realization accept: cancel 77.85 113 9342
payment from customer: creation 59.20 118 7104
payment from customer: removal 5.87 75 704
payment to supplier: creation 49.60 154 5952
payment to supplier: removal 3.61 113 433
service: total inventory turnovers 4.54 1173 545
service: total monetary turnovers 6.45 40 774
service: aggregate perf. data 0.79 17019 95
service: refresh index statistics 0.03 349267 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-09-2022 01:39:36 to 30-09-2022 01:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1684
TEST_TIME 2 3172
TEST_TIME 3 2691
TEST_TIME 4 3499
TEST_TIME 5 3608
TEST_TIME 6 4132
TEST_TIME 7 4230
TEST_TIME 8 4247
TEST_TIME 9 4922
TEST_TIME 10 5063
TEST_TIME 11 5512
TEST_TIME 12 6341
TEST_TIME 13 6528
TEST_TIME 14 6728
TEST_TIME 15 7329
TEST_TIME 16 7777
TEST_TIME 17 8337
TEST_TIME 18 8267
TEST_TIME 19 8209
TEST_TIME 20 8938
TEST_TIME 21 9363
TEST_TIME 22 9407
TEST_TIME 23 9443
TEST_TIME 24 9956
TEST_TIME 25 10402
TEST_TIME 26 10193
TEST_TIME 27 10236
TEST_TIME 28 10304
TEST_TIME 29 10251
TEST_TIME 30 10061
TEST_TIME 31 9904
TEST_TIME 32 9763
TEST_TIME 33 9915
TEST_TIME 34 9750
TEST_TIME 35 9671
TEST_TIME 36 9614
TEST_TIME 37 9514
TEST_TIME 38 9330
TEST_TIME 39 9270
TEST_TIME 40 9166
TEST_TIME 41 9236
TEST_TIME 42 9149
TEST_TIME 43 9147
TEST_TIME 44 9038
TEST_TIME 45 8985
TEST_TIME 46 8941
TEST_TIME 47 8982
TEST_TIME 48 8882
TEST_TIME 49 8879
TEST_TIME 50 8904
TEST_TIME 51 8945
TEST_TIME 52 8879
TEST_TIME 53 8840
TEST_TIME 54 8977
TEST_TIME 55 9103
TEST_TIME 56 9050
TEST_TIME 57 9130
TEST_TIME 58 9080
TEST_TIME 59 9253
TEST_TIME 60 9232
TEST_TIME 61 9217
TEST_TIME 62 9204
TEST_TIME 63 9123
TEST_TIME 64 9133
TEST_TIME 65 9059
TEST_TIME 66 9027
TEST_TIME 67 9067
TEST_TIME 68 8978
TEST_TIME 69 8914
TEST_TIME 70 8966
TEST_TIME 71 8982
TEST_TIME 72 9031
TEST_TIME 73 9010
TEST_TIME 74 8978
TEST_TIME 75 9020
TEST_TIME 76 9121
TEST_TIME 77 9131
TEST_TIME 78 9092
TEST_TIME 79 9172
TEST_TIME 80 9132
TEST_TIME 81 9172
TEST_TIME 82 9298
TEST_TIME 83 9280
TEST_TIME 84 9183
TEST_TIME 85 9171
TEST_TIME 86 9149
TEST_TIME 87 9123
TEST_TIME 88 9061
TEST_TIME 89 8999
TEST_TIME 90 9015
TEST_TIME 91 9036
TEST_TIME 92 9026
TEST_TIME 93 8995
TEST_TIME 94 9004
TEST_TIME 95 8994
TEST_TIME 96 9063
TEST_TIME 97 9048
TEST_TIME 98 9082
TEST_TIME 99 9074
TEST_TIME 100 9068
TEST_TIME 101 9063
TEST_TIME 102 9122
TEST_TIME 103 9115
TEST_TIME 104 9099
TEST_TIME 105 9171
TEST_TIME 106 9168
TEST_TIME 107 9233
TEST_TIME 108 9169
TEST_TIME 109 9121
TEST_TIME 110 9100
TEST_TIME 111 9058
TEST_TIME 112 9034
TEST_TIME 113 8999
TEST_TIME 114 9016
TEST_TIME 115 8979
TEST_TIME 116 8982
TEST_TIME 117 8958
TEST_TIME 118 8965
TEST_TIME 119 8965
TEST_TIME 120 8956

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2022 01:39:40 to 30-09-2022 01:39:43.

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 1508334 1508334 0 0.00 0 25507 6 0 0
doc_list_biud 1508333 1508333 0 0.00 0 41230 24 0 0
sp_add_invoice_to_stock 5144 5034 110 2.13 19 171857 17930 0 110
sp_cancel_adding_invoice 5263 5263 0 0.00 3 52057 721 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 12596 362 0 0
sp_cancel_customer_reserve 520389 520389 0 0.00 0 28682 53 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 4137 75 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5286 113 0 0
sp_cancel_supplier_invoice 5837 5830 7 0.11 2 14837 738 0 7
sp_cancel_supplier_order 385 385 0 0.00 7 1007926 92337 0 0
sp_cancel_write_off 9342 9342 0 0.00 1 12239 113 0 0
sp_client_order 20506 20506 0 0.00 3 18954 359 0 0
sp_customer_reserve 469711 469711 0 0.00 0 61016 177 0 0
sp_fill_shopping_cart 31314 31314 0 0.00 1 21621 41 0 0
sp_fill_shopping_cart_clo_res 469711 469711 0 0.00 0 21270 15 0 0
sp_get_clo_for_invoice 5034 5034 0 0.00 0 28195 333 0 0
sp_kill_cost_storno 15742 15742 0 0.00 0 25503 55 0 0
sp_kill_qstorno_ret_qs2qd 1056335 1056335 0 0.00 0 16966 29 0 0
sp_kill_qty_storno 558072 558072 0 0.00 0 41229 63 0 0
sp_lock_selected_doc 20860 20860 0 0.00 0 8545 6 0 0
sp_make_cost_storno 28169 28169 0 0.00 0 14466 54 0 0
sp_multiply_rows_for_pdistr 28169 28169 0 0.00 0 16100 28 0 0
sp_multiply_rows_for_qdistr 20506 20506 0 0.00 0 14697 153 0 0
sp_pay_from_customer 7104 7104 0 0.00 4 15558 118 0 0
sp_pay_to_supplier 5952 5952 0 0.00 5 16129 155 0 0
sp_payment_common 13056 13056 0 0.00 1 14323 39 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 12583 330 0 0
sp_qd_handle_on_invoice_upd_sts 10297 10297 0 0.00 0 41228 309 0 0
sp_qd_handle_on_reserve_upd_sts 10079 10079 0 0.00 0 20172 63 0 0
sp_reserve_write_off 10079 10079 0 0.00 6 20185 194 0 0
sp_supplier_invoice 5382 5382 0 0.00 5 83487 3224 0 0
sp_supplier_order 5444 5444 0 0.00 5 45149 1229 0 0
srv_aggregate_perf_data 96 95 1 1.04 2055 283718 17019 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 803 140750 40838 0 0
srv_find_qd_qs_mism 1026113 1026113 0 0.00 0 15939 6 0 0
srv_make_invnt_saldo 545 545 0 0.00 47 25607 1174 0 0
srv_make_money_saldo 774 774 0 0.00 1 8793 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 232808 479072 349268 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5034 5034 0 0.00 0 10961 159 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 9685 137 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 4127 14 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 2885 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 2577 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 4549 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 2221 16 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 29328 1616 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2388 10 0 0
t$perf-norm:sp_client_order 20506 20506 0 0.00 0 10149 19 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 11854 17 0 0
t$perf-norm:sp_pay_from_customer 7104 7104 0 0.00 0 10025 14 0 0
t$perf-norm:sp_pay_to_supplier 5952 5952 0 0.00 0 6508 12 0 0
t$perf-norm:sp_reserve_write_off 10079 10079 0 0.00 0 6871 14 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 4303 14 0 0
t$perf-norm:sp_supplier_order 5444 5444 0 0.00 0 3778 17 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 140 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1745 39 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 3575 23 0 0
t$perf-norm:srv_make_money_saldo 774 774 0 0.00 0 5491 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10288 10068 220 2.13 0 17095 20 0 220
v_all_customers 37496 37496 0 0.00 0 89 1 0 0
v_all_suppliers 30362 30362 0 0.00 0 60 1 0 0
v_all_wares 41012 41012 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 2279 11 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4052 6 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3731 9 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 3570 9 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 4052 12 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 2582 21 0 14
v_cancel_supplier_order 770 770 0 0.00 0 712 5 0 0
v_cancel_write_off 2006 2006 0 0.00 0 5633 21 0 0
v_max_id_clo_ord 5435 5435 0 0.00 0 224 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 5135 31 0 0
v_max_id_ord_sup 5373 5373 0 0.00 0 6591 4 0 0
v_max_non_paid_invoice 1578 1578 0 0.00 0 5922 7 0 0
v_max_non_paid_realizn 3649 3649 0 0.00 0 5290 5 0 0
v_min_id_clo_ord 5435 5435 0 0.00 0 2815 8 0 0
v_min_id_clo_res 992 992 0 0.00 0 3580 13 0 0
v_min_id_ord_sup 5373 5373 0 0.00 0 5279 7 0 0
v_min_non_paid_invoice 5952 5952 0 0.00 0 10646 28 0 0
v_min_non_paid_realizn 7104 7104 0 0.00 0 14317 32 0 0
v_our_firm 10426 10426 0 0.00 0 89 1 0 0
v_reserve_write_off 20158 20158 0 0.00 0 13444 11 0 0
x_lock_depdocs_on_canc_invoice 10694 10694 0 0.00 0 12946 126 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 11700 416 0 0
x_make_qstorno_1000_1200 5444 5444 0 0.00 1 45030 1157 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 3 82714 3066 0 0
x_make_qstorno_2100_3300 469711 469711 0 0.00 0 61009 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-09-2022 01:39:44 to 30-09-2022 01:39:49.

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:32 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:32 24789 8192000000 9891416032 10196250624 969995920 10.587 100 83 166 0 997387920 5779744 21612256
23:11:02 21393 8192000000 9933374672 10249400320 1012510096 11.000 100 91 182 0 1044950480 8397216 24043168
23:12:28 9833 8192000000 9960389088 10287280128 1046444864 11.327 100 81 162 0 1076837808 9648528 20744416
23:13:41 11397 8192000000 10021862032 10360557568 1103003120 11.866 100 81 157 1 1132909584 11321536 18584928
23:14:55 5220 8192000000 10058788576 10411327488 1145708512 12.269 100 84 166 0 1178420144 13170144 19541488
23:16:06 4711 8192000000 10111245936 10474655744 1198747504 12.765 100 83 160 0 1228668400 12408112 17512784
23:17:12 2310 8192000000 10149961264 10521919488 1236475904 13.114 100 84 166 0 1268545536 14238960 17830672
23:18:17 2948 8192000000 10169667296 10547372032 1256940400 13.302 100 85 170 0 1289094112 13572336 18581376
23:19:22 1678 8192000000 10208074656 10587226112 1290538832 13.609 100 90 180 1 1322883488 11437136 20907520
23:20:26 23372 8192000000 10211305088 10604539904 1306836336 13.757 100 57 112 0 1326507776 7506096 12165344
23:21:54 891 8192000000 10223536400 10613112832 1311433296 13.799 100 84 165 1 1342637616 12975216 18229104
23:22:56 2574 8192000000 10214748992 10621657088 1321562688 13.891 100 40 80 2 1338824240 8944672 8316880
23:24:03 9564 8192000000 10208459440 10625835008 1325592768 13.927 100 19 38 0 1340178080 10706528 3878784
23:25:13 7713 8192000000 10212593024 10634158080 1330535456 13.972 100 20 37 2 1340285104 5830080 3919568
23:26:22 31158 8192000000 10222387344 10646609920 1334230480 14.005 100 30 60 0 1346281408 4880864 7170064
23:27:56 3741 8192000000 10215495056 10634571776 1315803088 13.839 99 23 42 0 1326098672 5648256 4647328
23:29:01 6762 8192000000 10217172592 10634174464 1322026864 13.895 100 29 58 0 1334404608 6216384 6161360
23:30:10 12758 8192000000 10164871232 10568773632 1214591296 12.912 94 38 76 1 1228025216 4534320 8899600
23:31:31 17059 8192000000 10136545872 10534785024 1150954512 12.318 90 22 44 2 1160198896 4932464 4311920
23:32:55 5800 8192000000 10124054672 10517008384 1220235776 12.964 97 18 34 1 1229294032 5477376 3580880
23:34:03 14720 8192000000 10147919024 10554892288 1241211072 13.157 98 17 33 0 1250247600 4981456 4055072
23:35:22 42991 8192000000 10199869904 10595115008 1293122480 13.633 100 63 127 1 1313491824 4581792 15787552
23:37:05 7493 8192000000 10187857040 10599616512 1300123968 13.696 100 21 42 1 1310520208 5071376 5324864
23:38:14 5272 8192000000 10212689216 10627579904 1322352240 13.898 100 24 48 1 1331724896 4440432 4932224
23:39:27 10177 8192000000 10245681472 10654986240 1342906768 14.084 100 49 97 0 1363229168 9294320 11028080
23:40:45 43538 8192000000 10274042064 10690768896 1364210000 14.275 100 55 107 0 1392990752 16643824 12136928
23:43:16 63352 8192000000 10297258736 10720739328 1378561184 14.404 100 33 64 1 1408856976 22337120 7958672
23:45:27 9321 8192000000 10330115312 10746748928 1402187600 14.614 100 60 116 0 1439669136 23823456 13658080
23:46:43 21361 8192000000 10323631392 10748571648 1398367216 14.580 100 41 82 2 1434467072 26129136 9970720
23:48:08 2294 8192000000 10337467216 10757005312 1405232992 14.642 100 70 140 0 1447808512 25918512 16657008
23:49:18 2125 8192000000 10349109136 10765565952 1404771296 14.637 99 69 136 2 1455271376 33978656 16521424
23:50:24 13942 8192000000 10335976704 10753691648 1396927632 14.568 100 58 114 0 1443951456 32698080 14325744
23:51:45 1983 8192000000 10357842656 10775822336 1411356656 14.696 100 72 142 2 1460189568 31569056 17263856
23:52:51 799 8192000000 10362610832 10780606464 1415865744 14.736 100 77 147 1 1468964416 34246880 18851792
23:53:59 3595 8192000000 10356016160 10774048768 1394439456 14.545 99 82 162 0 1450836048 36412208 19984384
23:55:07 1414 8192000000 10351479680 10759749632 1398496560 14.582 100 90 173 1 1454010352 34876432 20637360
23:56:12 14145 8192000000 10338445376 10763341824 1384638368 14.458 98 39 76 0 1420950096 26374064 9937664
23:57:31 17340 8192000000 10309917232 10735132672 1346864768 14.119 97 29 58 0 1389054848 34056256 8133824
23:58:50 24362 8192000000 10282552608 10721562624 1362050496 14.256 100 21 42 0 1402960928 34900576 6009856
00:00:33 6832 8192000000 10295330064 10715967488 1354694000 14.190 100 38 74 1 1390878816 26474336 9710480
00:01:43 3866 8192000000 10295105280 10716299264 1351662176 14.162 100 48 95 1 1397431360 33335888 12433296
00:02:48 24063 8192000000 10289845536 10731601920 1359830960 14.236 100 30 60 1 1396538784 28686160 8021664
00:04:12 62551 8192000000 10277122416 10714755072 1356857136 14.209 99 26 49 0 1381462736 18613520 5992080
00:06:22 23354 8192000000 10258097072 10708062208 1359491792 14.233 100 20 40 0 1377871920 13691408 4688720
00:07:59 102620 8192000000 10253287968 10690990080 1323862640 13.912 97 24 48 0 1339876080 10636176 5377264
00:10:42 99651 8192000000 10273566080 10718011392 1346326400 14.114 99 6 12 0 1364837696 17426256 1085040
00:13:27 59814 8192000000 10248082608 10696581120 1329320816 13.961 97 16 32 0 1344926496 11923168 3682512
00:15:27 33357 8192000000 10243079472 10685902848 1344676320 14.100 99 36 69 0 1358827280 6442304 7708656
00:17:04 3424 8192000000 10270530352 10706329600 1359134976 14.230 100 37 72 0 1377352608 9660096 8557536
00:18:11 34838 8192000000 10284134352 10732118016 1374669840 14.369 100 24 46 2 1396825040 15948496 6206704
00:19:46 41697 8192000000 10275969712 10739744768 1377729872 14.396 100 7 14 0 1398164032 18706032 1728128
00:21:29 65335 8192000000 10281604896 10726756352 1364184208 14.275 99 19 39 0 1376398944 7839632 4375104
00:23:36 95542 8192000000 10279451360 10731380736 1378483280 14.403 100 26 52 1 1399280304 14713728 6083296
00:26:12 60282 8192000000 10284059680 10737328128 1387775312 14.486 100 16 32 0 1404610464 13139952 3695200
00:30:07 33737 8192000000 10289121808 10744950784 1378586496 14.404 99 17 34 0 1391605088 9278416 3740176
00:31:45 55953 8192000000 10305163792 10768687104 1388790720 14.495 99 15 30 0 1407466560 15325440 3350400
00:33:41 60235 8192000000 10298490448 10749829120 1342420000 14.079 96 12 24 0 1358968656 14019776 2528880
00:35:50 100385 8192000000 10315000288 10782629888 1411079232 14.694 100 25 50 0 1432236768 15625344 5532192
00:39:03 36328 8192000000 10297291200 10746191872 1377034032 14.390 98 26 48 2 1390798912 8520432 5244448
00:40:40 10551 8192000000 10300367664 10734071808 1365732544 14.289 99 55 110 0 1389903600 12301680 11869376
00:41:53 54735 8192000000 10264376848 10716393472 1348465072 14.134 98 39 78 0 1369746560 12372880 8908608
00:43:51 11332 8192000000 10272649312 10719158272 1349850480 14.146 98 31 62 0 1369645056 12963408 6831168
00:45:05 69514 8192000000 10284168400 10752331776 1384968624 14.461 100 11 22 0 1402212608 14670368 2573616
00:47:15 71274 8192000000 10282474384 10734460928 1340955104 14.066 96 12 24 0 1362078048 18270288 2852656
00:49:54 67552 8192000000 10270028560 10731565056 1363914688 14.272 99 12 24 0 1381442304 14893136 2634480
00:52:07 26813 8192000000 10267557264 10708426752 1345044176 14.103 99 44 84 1 1366764128 12474800 9245152
00:53:38 50571 8192000000 10242366528 10692366336 1338118752 14.040 99 20 40 0 1364130768 20934416 5077600
00:55:28 53105 8192000000 10262861584 10695065600 1321160720 13.887 98 14 28 0 1333665440 9343216 3161504
00:57:22 45355 8192000000 10250447104 10696335360 1337633760 14.036 99 22 44 0 1358404960 16086704 4684496
00:59:11 29331 8192000000 10271006544 10712047616 1334259056 14.006 98 25 50 0 1357402592 17771664 5371872
01:00:45 140750 8192000000 10261226096 10699976704 1307010912 13.759 96 15 30 0 1321534096 11189760 3333424
01:04:18 29422 8192000000 10268822640 10698481664 1341748336 14.073 99 47 94 2 1361976160 9720112 10507712
01:05:49 45123 8192000000 10253308544 10703491072 1356979008 14.210 100 12 24 0 1371093872 11367616 2747248
01:07:35 52398 8192000000 10264623168 10719760384 1347979824 14.129 99 14 28 1 1363166576 11584400 3602352
01:09:36 37386 8192000000 10255540736 10704326656 1351448320 14.161 99 12 25 0 1368392928 14679200 2265408
01:11:36 3732 8192000000 10256207488 10692599808 1352202784 14.167 99 41 81 1 1367029056 5413872 9412400
01:12:41 55687 8192000000 10286811392 10738696192 1383237040 14.445 100 13 26 0 1401337296 15428304 2671952
01:15:24 54699 8192000000 10289128880 10748604416 1364862448 14.281 98 18 36 1 1377095808 8072736 4160624
01:17:19 20914 8192000000 10304613264 10749104128 1390158912 14.507 100 36 72 0 1406143600 8405232 7579456
01:18:43 38106 8192000000 10299095632 10745446400 1365124672 14.283 98 34 68 0 1386383360 13721168 7537520
01:20:30 48700 8192000000 10310543264 10752126976 1381414384 14.429 99 26 52 0 1395132592 8336464 5381744
01:22:23 42660 8192000000 10299998352 10744975360 1365193504 14.284 98 36 71 0 1384051152 10923920 7933728
01:24:05 76264 8192000000 10276306416 10726277120 1299410688 13.690 94 16 32 0 1317359264 14142624 3805952
01:26:45 92291 8192000000 10269396640 10722414592 1355243312 14.195 99 18 36 1 1371335680 12095024 3997344
01:29:22 24436 8192000000 10283050208 10719612928 1340260400 14.060 97 32 64 0 1355248272 7636048 7351824
01:30:48 46222 8192000000 10261984464 10715852800 1366150256 14.293 100 9 18 0 1377623936 9256912 2216768
01:32:37 50944 8192000000 10248388368 10702946304 1345527792 14.107 99 23 42 0 1358756512 8038992 5189728
01:34:30 48726 8192000000 10247900256 10706845696 1350355184 14.151 100 5 10 1 1359992592 8383120 1254288
01:36:28 22321 8192000000 10260431440 10701852672 1310056368 13.787 96 31 59 0 1324231328 7692464 6482496
01:37:50 40659 8192000000 9838061856 10217177088 923921456 10.135 69 19 35 0 932877264 5432448 3523360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-09-2022 01:39:51 to 30-09-2022 01:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 110 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 30-09-2022 01:40:07 to 30-09-2022 01:40:09.

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          240799
MON$OLDEST_ACTIVE               240800
MON$OLDEST_SNAPSHOT             240800
MON$NEXT_TRANSACTION            240800
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                       1994944
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:269: 
:270: mscs-test-161.budzdorov.ru    Fri Sep 30 01:38:33 2022
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Fri Sep 30 01:38:34 2022
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:276: 
:277: 
:278: mscs-test-161.budzdorov.ru    Fri Sep 30 01:38:34 2022
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:280: 
--- end of diff output ---

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

30.09.22 01:40:11. 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
2022-09-30 01:40:13 No severe PSQL-related problems occured
30.09.22 01:40:14. End of report.