Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 22:38
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 15860696 kB
MemAvailable 30572624 kB
Buffers 0 kB
Cached 15852012 kB
SwapCached 2028 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-28 22:38:23
DTS_END                         2022-09-28 22:38:23.2910
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/52502
TRN_ID                          240315
ATT_ID                          657
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: 9095.68 22407 1091482
customer order: creation 169.92 356 20390
customer order: refuse 14.89 321 1787
order to supplier: creation 45.31 1270 5437
order to supplier: removal 3.47 77748 416
invoice (draft): creation 45.26 3683 5431
invoice (draft): removal 51.28 714 6153
invoice accept: apply 41.38 18278 4966
invoice accept: cancel 46.01 711 5521
customer reserve: creation 3853.49 181 462419
customer reserve: removal 4541.94 45 545033
realization accept: apply 84.07 171 10088
realization accept: cancel 72.86 97 8743
payment from customer: creation 57.13 101 6855
payment from customer: removal 5.27 66 632
payment to supplier: creation 47.51 117 5701
payment to supplier: removal 3.82 83 458
service: total inventory turnovers 4.78 1012 574
service: total monetary turnovers 6.48 23 778
service: aggregate perf. data 0.80 20444 96
service: refresh index statistics 0.03 322710 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-09-2022 22:39:17 to 28-09-2022 22:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6129
TEST_TIME 2 3998
TEST_TIME 3 4289
TEST_TIME 4 4029
TEST_TIME 5 3889
TEST_TIME 6 3917
TEST_TIME 7 4442
TEST_TIME 8 4464
TEST_TIME 9 4607
TEST_TIME 10 5311
TEST_TIME 11 5669
TEST_TIME 12 6373
TEST_TIME 13 7038
TEST_TIME 14 7552
TEST_TIME 15 7449
TEST_TIME 16 7871
TEST_TIME 17 8024
TEST_TIME 18 8493
TEST_TIME 19 9750
TEST_TIME 20 9674
TEST_TIME 21 9704
TEST_TIME 22 9845
TEST_TIME 23 9732
TEST_TIME 24 9873
TEST_TIME 25 9989
TEST_TIME 26 9835
TEST_TIME 27 9986
TEST_TIME 28 9783
TEST_TIME 29 9596
TEST_TIME 30 9532
TEST_TIME 31 9562
TEST_TIME 32 9753
TEST_TIME 33 9654
TEST_TIME 34 9622
TEST_TIME 35 9717
TEST_TIME 36 9512
TEST_TIME 37 9446
TEST_TIME 38 9470
TEST_TIME 39 9333
TEST_TIME 40 9324
TEST_TIME 41 9372
TEST_TIME 42 9238
TEST_TIME 43 9221
TEST_TIME 44 9104
TEST_TIME 45 9002
TEST_TIME 46 8958
TEST_TIME 47 8886
TEST_TIME 48 8981
TEST_TIME 49 8961
TEST_TIME 50 8936
TEST_TIME 51 8960
TEST_TIME 52 9021
TEST_TIME 53 9103
TEST_TIME 54 9058
TEST_TIME 55 9115
TEST_TIME 56 9279
TEST_TIME 57 9271
TEST_TIME 58 9188
TEST_TIME 59 9352
TEST_TIME 60 9381
TEST_TIME 61 9438
TEST_TIME 62 9444
TEST_TIME 63 9302
TEST_TIME 64 9245
TEST_TIME 65 9253
TEST_TIME 66 9246
TEST_TIME 67 9152
TEST_TIME 68 9132
TEST_TIME 69 9155
TEST_TIME 70 9144
TEST_TIME 71 9141
TEST_TIME 72 9209
TEST_TIME 73 9214
TEST_TIME 74 9264
TEST_TIME 75 9329
TEST_TIME 76 9362
TEST_TIME 77 9346
TEST_TIME 78 9402
TEST_TIME 79 9386
TEST_TIME 80 9457
TEST_TIME 81 9458
TEST_TIME 82 9352
TEST_TIME 83 9387
TEST_TIME 84 9317
TEST_TIME 85 9309
TEST_TIME 86 9261
TEST_TIME 87 9240
TEST_TIME 88 9217
TEST_TIME 89 9196
TEST_TIME 90 9138
TEST_TIME 91 9117
TEST_TIME 92 9146
TEST_TIME 93 9131
TEST_TIME 94 9145
TEST_TIME 95 9141
TEST_TIME 96 9180
TEST_TIME 97 9212
TEST_TIME 98 9201
TEST_TIME 99 9239
TEST_TIME 100 9278
TEST_TIME 101 9254
TEST_TIME 102 9318
TEST_TIME 103 9317
TEST_TIME 104 9354
TEST_TIME 105 9361
TEST_TIME 106 9286
TEST_TIME 107 9257
TEST_TIME 108 9201
TEST_TIME 109 9215
TEST_TIME 110 9173
TEST_TIME 111 9134
TEST_TIME 112 9119
TEST_TIME 113 9133
TEST_TIME 114 9127
TEST_TIME 115 9148
TEST_TIME 116 9146
TEST_TIME 117 9168
TEST_TIME 118 9135
TEST_TIME 119 9134
TEST_TIME 120 9098

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2022 22:39:21 to 28-09-2022 22:39:24.

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 1519180 1519180 0 0.00 0 15862 6 0 0
doc_list_biud 1519180 1519180 0 0.00 0 26185 21 0 0
sp_add_invoice_to_stock 5059 4966 93 1.83 62 219026 18279 0 93
sp_cancel_adding_invoice 5521 5521 0 0.00 5 55213 712 0 0
sp_cancel_client_order 1787 1787 0 0.00 4 11831 322 0 0
sp_cancel_customer_reserve 545033 545033 0 0.00 0 26188 45 0 0
sp_cancel_pay_from_customer 632 632 0 0.00 3 7294 66 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 2 4443 83 0 0
sp_cancel_supplier_invoice 6159 6153 6 0.09 1 18922 714 0 6
sp_cancel_supplier_order 416 416 0 0.00 6 773472 77749 0 0
sp_cancel_write_off 8743 8743 0 0.00 1 10086 98 0 0
sp_client_order 20390 20390 0 0.00 3 27038 357 0 0
sp_customer_reserve 462419 462419 0 0.00 0 75859 182 0 0
sp_fill_shopping_cart 31249 31249 0 0.00 1 28888 45 0 0
sp_fill_shopping_cart_clo_res 462415 462415 0 0.00 0 32263 15 0 0
sp_get_clo_for_invoice 4962 4962 0 0.00 0 19385 308 0 0
sp_kill_cost_storno 15354 15354 0 0.00 0 10452 49 0 0
sp_kill_qstorno_ret_qs2qd 1105378 1105378 0 0.00 0 24495 25 0 0
sp_kill_qty_storno 582703 582703 0 0.00 0 26185 55 0 0
sp_lock_selected_doc 20771 20771 0 0.00 0 7553 6 0 0
sp_make_cost_storno 27606 27606 0 0.00 0 15859 45 0 0
sp_multiply_rows_for_pdistr 27606 27606 0 0.00 0 8639 25 0 0
sp_multiply_rows_for_qdistr 20390 20390 0 0.00 0 12844 161 0 0
sp_pay_from_customer 6855 6855 0 0.00 4 8664 102 0 0
sp_pay_to_supplier 5701 5701 0 0.00 4 8366 117 0 0
sp_payment_common 12556 12556 0 0.00 1 7689 22 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 8695 290 0 0
sp_qd_handle_on_invoice_upd_sts 10483 10483 0 0.00 0 25811 285 0 0
sp_qd_handle_on_reserve_upd_sts 10088 10088 0 0.00 0 20045 62 0 0
sp_reserve_write_off 10088 10088 0 0.00 6 20059 171 0 0
sp_supplier_invoice 5431 5431 0 0.00 6 135719 3684 0 0
sp_supplier_order 5437 5437 0 0.00 4 42945 1270 0 0
srv_aggregate_perf_data 97 96 1 1.03 2446 245655 20444 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 3448 131177 48005 0 0
srv_find_qd_qs_mism 1044124 1044124 0 0.00 0 14517 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 35 53500 1013 0 0
srv_make_money_saldo 778 778 0 0.00 0 1141 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 214420 539638 322711 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4966 4966 0 0.00 0 15718 156 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 12818 117 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 8726 16 0 0
t$perf-norm:sp_cancel_customer_reserve 719 719 0 0.00 0 1510 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 632 632 0 0.00 0 1070 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 207 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 363 363 0 0.00 0 269 8 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 31186 1360 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 2762 6 0 0
t$perf-norm:sp_client_order 20390 20390 0 0.00 0 9325 13 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 4794 9 0 0
t$perf-norm:sp_pay_from_customer 6855 6855 0 0.00 0 14896 12 0 0
t$perf-norm:sp_pay_to_supplier 5701 5701 0 0.00 0 9196 9 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 8787 11 0 0
t$perf-norm:sp_supplier_invoice 5431 5431 0 0.00 0 15327 17 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 14704 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 1489 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2178 19 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 2604 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10110 9924 186 1.83 0 5852 19 0 186
v_all_customers 36262 36262 0 0.00 0 58 0 0 0
v_all_suppliers 29792 29792 0 0.00 0 18 1 0 0
v_all_wares 40780 40780 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 315 5 0 0
v_cancel_client_order 3574 3574 0 0.00 0 3339 7 0 0
v_cancel_customer_prepayment 1264 1264 0 0.00 0 7261 10 0 0
v_cancel_customer_reserve 1438 1438 0 0.00 0 3417 10 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 1718 8 0 0
v_cancel_supplier_invoice 738 726 12 1.62 0 1818 25 0 12
v_cancel_supplier_order 832 832 0 0.00 0 863 4 0 0
v_cancel_write_off 1884 1884 0 0.00 0 1776 16 0 0
v_max_id_clo_ord 5437 5437 0 0.00 0 1538 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 2686 17 0 0
v_max_id_ord_sup 5422 5422 0 0.00 0 6304 7 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 1582 4 0 0
v_max_non_paid_realizn 4012 4012 0 0.00 0 2629 6 0 0
v_min_id_clo_ord 5437 5437 0 0.00 0 8127 11 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 5003 15 0 0
v_min_id_ord_sup 5422 5422 0 0.00 0 5591 10 0 0
v_min_non_paid_invoice 5701 5701 0 0.00 0 7676 15 0 0
v_min_non_paid_realizn 6855 6855 0 0.00 0 7683 17 0 0
v_our_firm 10204 10204 0 0.00 0 50 1 0 0
v_reserve_write_off 20176 20176 0 0.00 0 7482 8 0 0
x_lock_depdocs_on_canc_invoice 11311 11311 0 0.00 0 15697 116 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 12010 364 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 1 42939 1192 0 0
x_make_qstorno_1200_2000 5431 5431 0 0.00 3 129317 3511 0 0
x_make_qstorno_2100_3300 462419 462419 0 0.00 0 75858 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2022 22:39:26 to 28-09-2022 22:39:30.

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
20:08:27 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:28 17341 8192000000 9882458064 10191433728 969823712 10.585 100 84 168 0 997837376 5886112 22127552
20:10:50 12410 8192000000 9941236128 10260172800 1019516896 11.067 100 88 176 0 1050658768 8035232 23106640
20:12:07 17870 8192000000 9965824720 10292895744 1048759792 11.349 100 85 170 0 1080286976 9880080 21647104
20:13:34 7847 8192000000 10007882240 10349203456 1096045792 11.800 100 77 154 0 1125633008 10688752 18898464
20:14:46 6150 8192000000 10062730800 10413076480 1152930560 12.337 100 79 158 0 1182989360 12028240 18030560
20:15:55 5174 8192000000 10104340560 10474725376 1201728288 12.792 100 73 146 0 1230488368 11767424 16992656
20:17:03 2011 8192000000 10155915472 10527571968 1241995120 13.165 100 82 164 1 1273685920 13629760 18061040
20:18:07 1514 8192000000 10184792032 10560573440 1267618416 13.400 100 87 175 0 1300839712 14532736 18688560
20:19:10 1300 8192000000 10205530288 10590171136 1294136960 13.642 100 87 174 0 1327548848 13247088 20164800
20:20:12 3756 8192000000 10206673632 10606211072 1306433344 13.754 100 55 112 0 1326862160 8328496 12100320
20:21:17 8457 8192000000 10205016800 10618900480 1316833264 13.848 100 16 32 0 1326242448 6061056 3348128
20:22:25 8815 8192000000 10211720736 10628288512 1327595952 13.945 100 14 28 0 1336057792 5557056 2904784
20:23:35 15135 8192000000 10211629296 10635075584 1332364544 13.989 100 12 24 1 1340948224 5825088 2758592
20:24:51 52384 8192000000 10222739392 10649030656 1339184640 14.050 100 42 84 0 1354846736 5414448 10247648
20:26:46 5443 8192000000 10238690816 10659311616 1348410672 14.133 100 25 50 3 1360757328 7027776 5318880
20:27:53 5454 8192000000 10215462480 10628907008 1301701232 13.711 98 19 38 0 1312000640 5654896 4644512
20:28:59 8334 8192000000 10192644192 10605535232 1265653968 13.382 96 11 20 0 1276616288 8895968 2066352
20:30:10 44622 8192000000 10150536944 10550939648 1191424928 12.697 93 31 62 2 1208378864 9853024 7100912
20:31:55 8649 8192000000 10144408912 10542170112 1229945536 13.054 98 35 69 1 1244611376 7155440 7510400
20:33:06 8621 8192000000 10170183104 10568957952 1268814032 13.411 100 36 72 0 1283715072 5710800 9190240
20:34:16 30845 8192000000 10176558112 10589958144 1290886192 13.612 100 35 71 0 1305060992 6099120 8075680
20:35:58 11647 8192000000 10185078592 10601705472 1296931984 13.667 100 20 40 0 1306482656 5409216 4141456
20:37:11 9319 8192000000 10206519456 10624819200 1317422896 13.853 100 28 57 2 1329353200 5040144 6890160
20:38:21 11594 8192000000 10244027264 10658574336 1339437472 14.052 100 30 59 0 1351075536 5174720 6463344
20:39:41 5892 8192000000 10266374160 10675134464 1358054096 14.220 100 51 100 0 1377249216 8631440 10563680
20:40:57 59305 8192000000 10281023568 10709241856 1372393008 14.348 100 38 76 0 1395722096 14128400 9200688
20:42:59 44720 8192000000 10297161280 10725343232 1385106000 14.462 100 24 47 0 1406511360 15968576 5436784
20:44:49 12060 8192000000 10320253072 10746101760 1400789440 14.602 100 45 90 0 1435311024 23865232 10656352
20:46:04 12186 8192000000 10325020608 10751393792 1396478192 14.564 99 53 104 1 1431630448 23255888 11896368
20:47:23 9269 8192000000 10343072224 10768896000 1411715152 14.699 100 50 99 0 1456525136 32133824 12676160
20:48:36 3520 8192000000 10347789984 10770673664 1399836768 14.594 99 64 127 0 1450443904 35205360 15401776
20:49:44 3447 8192000000 10362123168 10778284032 1412701680 14.708 100 76 151 0 1467188832 35959232 18527920
20:50:52 3859 8192000000 10331650352 10745401344 1380722768 14.423 100 73 144 1 1436313232 37532688 18057776
20:52:00 6763 8192000000 10327137600 10743914496 1388495024 14.492 100 59 118 0 1433138496 30502944 14140528
20:53:09 5656 8192000000 10323106800 10748952576 1393751488 14.539 100 41 80 0 1430090352 25941216 10397648
20:54:17 17927 8192000000 10302362016 10731995136 1368027280 14.309 99 27 54 1 1406378496 30389792 7961424
20:55:38 6695 8192000000 10313282992 10729611264 1367311008 14.303 100 57 113 1 1410799056 28960352 14527696
20:56:46 5556 8192000000 10289575536 10714677248 1352634368 14.171 100 57 114 0 1397174672 29305712 15234592
20:57:55 15231 8192000000 10271611136 10704797696 1359907120 14.237 100 27 51 1 1385201024 19024000 6269904
20:59:27 32523 8192000000 10274888672 10710241280 1356943360 14.210 100 20 40 0 1381776448 19875856 4957232
21:01:02 19476 8192000000 10251427872 10683916288 1320970384 13.885 98 20 40 0 1345947824 19705136 5272304
21:02:23 41821 8192000000 10273110192 10714791936 1356767376 14.208 100 22 45 1 1382164128 20320096 5076656
21:04:12 35003 8192000000 10272492480 10698199040 1339697776 14.055 98 35 70 0 1364085440 16124192 8263472
21:05:52 30226 8192000000 10275552464 10716463104 1357597696 14.216 100 23 46 0 1377903600 14557344 5748560
21:07:24 60038 8192000000 10273469440 10710081536 1354405248 14.187 99 24 48 0 1377249856 17305568 5539040
21:09:43 122765 8192000000 10277229776 10719821824 1365468672 14.286 99 28 56 1 1383706128 11578736 6658720
21:12:46 83628 8192000000 10251526848 10699079680 1328399184 13.953 97 24 48 0 1344416832 10472672 5544976
21:15:14 37098 8192000000 10287095552 10727284736 1370692592 14.333 99 33 63 1 1393229456 15670656 6866208
21:16:51 35713 8192000000 10296339040 10742665216 1389919696 14.505 100 21 42 0 1407157408 12264368 4973344
21:18:31 45938 8192000000 10300215008 10748260352 1394861680 14.549 100 22 44 0 1414015424 14174448 4979296
21:20:28 23326 8192000000 10289867936 10743398400 1395915248 14.559 100 14 28 0 1407822944 8473808 3433888
21:21:57 79871 8192000000 10288339616 10744078336 1385570672 14.466 99 25 51 0 1398314144 6822736 5920736
21:24:21 50819 8192000000 10295924320 10755624960 1403721456 14.628 100 27 52 0 1421957824 12199024 6037344
21:26:12 61221 8192000000 10286654688 10759995392 1389601008 14.502 99 7 14 0 1402814672 11777792 1435872
21:28:14 31723 8192000000 10293967680 10742132736 1378743840 14.405 98 21 40 1 1393095920 9970544 4381536
21:29:46 36558 8192000000 10310451472 10753101824 1384691920 14.458 99 48 97 0 1405984912 10822144 10470848
21:31:25 43537 8192000000 10295430736 10750275584 1367869248 14.308 98 20 40 0 1386818016 14403696 4545072
21:33:09 15831 8192000000 10307493136 10745057280 1369422160 14.322 98 47 94 0 1393617200 13940880 10254160
21:34:30 75566 8192000000 10260548480 10708393984 1299823328 13.694 93 21 42 0 1316668928 11996784 4848816
21:36:52 32830 8192000000 10253209360 10692136960 1313031392 13.814 98 44 88 0 1341404752 18561872 9811488
21:38:25 59179 8192000000 10258456192 10708512768 1332504288 13.990 98 27 54 0 1348428960 9763232 6161440
21:40:35 103839 8192000000 10264584624 10703503360 1347880816 14.128 99 13 26 0 1360515264 10239536 2394912
21:43:28 46920 8192000000 10260767632 10715029504 1358293328 14.222 99 30 60 1 1377146944 12103744 6749872
21:45:24 29104 8192000000 10260162608 10717147136 1361200000 14.248 100 8 15 0 1374384592 11984352 1200240
21:46:53 72806 8192000000 10267016944 10703618048 1327607680 13.946 97 21 39 1 1343297888 11304032 4386176
21:49:06 69512 8192000000 10283108368 10726195200 1355074192 14.193 99 32 64 0 1374870848 12649216 7147440
21:51:16 94106 8192000000 10287434832 10741030912 1369149264 14.319 99 13 27 0 1388576944 16231792 3195888
21:53:56 66838 8192000000 10275165152 10726711296 1332615088 13.991 97 11 22 0 1348596672 13733408 2248176
21:56:03 39434 8192000000 10287943856 10724978688 1343907072 14.093 98 26 52 0 1359242864 9673200 5662592
21:57:47 101346 8192000000 10308683552 10758549504 1347729408 14.127 98 10 20 0 1362670960 12656736 2284816
22:00:30 113935 8192000000 10269958128 10713632768 1350387840 14.151 98 22 42 2 1376780272 20965216 5427216
22:03:28 46936 8192000000 10278642496 10727337984 1375678816 14.378 100 14 28 0 1386175376 7311216 3185344
22:05:16 45658 8192000000 10263448016 10719383552 1368953504 14.318 100 9 17 0 1379445520 8507280 1984736
22:07:02 49904 8192000000 10276940208 10729676800 1376857184 14.388 100 17 34 0 1391781856 10737136 4187536
22:08:52 40504 8192000000 10284299920 10734436352 1370906080 14.335 99 18 36 0 1384218896 9311520 4001296
22:10:53 83477 8192000000 10289225408 10743853056 1374917072 14.371 99 12 25 0 1385276336 7616336 2742928
22:14:00 47031 8192000000 10299560688 10748862464 1375174768 14.373 98 12 25 0 1388307392 10501936 2630688
22:15:51 60596 8192000000 10323153056 10765410304 1395272400 14.553 100 29 59 0 1412938832 10848240 6818192
22:17:55 88880 8192000000 10322316256 10758463488 1372511760 14.350 98 33 66 0 1399757744 20094352 7151632
22:20:24 84804 8192000000 10285758944 10732204032 1292185184 13.624 93 20 40 0 1313543536 17143280 4215072
22:23:03 14033 8192000000 10311854384 10736947200 1380147376 14.418 100 56 112 1 1400785072 7710048 12927648
22:24:21 116297 8192000000 10282487456 10734989312 1380494672 14.421 100 26 52 0 1396700720 10193200 6012848
22:27:22 52489 8192000000 10261114528 10709716992 1340395792 14.061 98 21 42 0 1355142832 9942784 4804256
22:29:16 73430 8192000000 10271383440 10718117888 1365918944 14.290 100 14 27 0 1378702128 9605200 3177984
22:31:34 82674 8192000000 10248466880 10687975424 1301807360 13.712 95 15 30 0 1319456832 14258704 3390768
22:34:00 131177 8192000000 10270144816 10714628096 1334204912 14.005 97 14 28 0 1347796320 10335376 3256032
22:37:17 38099 8192000000 10264837024 10693226496 1310480896 13.790 96 26 52 0 1329298560 12800928 6016736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-09-2022 22:39:32 to 28-09-2022 22:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2022 22:39:48 to 28-09-2022 22:39:50.

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          237363
MON$OLDEST_ACTIVE               240921
MON$OLDEST_SNAPSHOT             240921
MON$NEXT_TRANSACTION            240921
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                       1995136
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 ---
:192: 
:193: mscs-test-161.budzdorov.ru    Wed Sep 28 22:38:24 2022
:194:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:195: 
:196: 
:197: mscs-test-161.budzdorov.ru    Wed Sep 28 22:38:24 2022
:198:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:199: 
:200: 
:201: mscs-test-161.budzdorov.ru    Wed Sep 28 22:38:26 2022
:202:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:203: 
--- end of diff output ---

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

28.09.22 22:39:51. 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-28 22:39:53 No severe PSQL-related problems occured
28.09.22 22:39:55. End of report.