Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.05.2022 22: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 16647504 kB
MemAvailable 31522128 kB
Buffers 0 kB
Cached 15097012 kB
SwapCached 2572 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.10
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-05-07 22:38:19
DTS_END                         2022-05-07 22:38:19.2430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38120
TRN_ID                          231050
ATT_ID                          633
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: 9076.00 21632 1089120
customer order: creation 160.77 401 19292
customer order: refuse 13.88 408 1666
order to supplier: creation 42.74 1414 5129
order to supplier: removal 3.41 119707 409
invoice (draft): creation 42.93 3668 5151
invoice (draft): removal 54.67 849 6560
invoice accept: apply 38.54 19771 4625
invoice accept: cancel 49.16 751 5899
customer reserve: creation 3558.86 195 427063
customer reserve: removal 4818.73 66 578247
realization accept: apply 79.20 230 9504
realization accept: cancel 90.89 111 10907
payment from customer: creation 55.75 152 6690
payment from customer: removal 5.42 88 650
payment to supplier: creation 45.98 159 5518
payment to supplier: removal 3.23 144 387
service: total inventory turnovers 4.69 1189 563
service: total monetary turnovers 6.22 24 746
service: aggregate perf. data 0.92 16708 110
service: refresh index statistics 0.03 266608 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-05-2022 22:40:09 to 07-05-2022 22:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4772
TEST_TIME 2 4284
TEST_TIME 3 3454
TEST_TIME 4 3267
TEST_TIME 5 3455
TEST_TIME 6 3693
TEST_TIME 7 4253
TEST_TIME 8 4235
TEST_TIME 9 4329
TEST_TIME 10 5219
TEST_TIME 11 5506
TEST_TIME 12 5980
TEST_TIME 13 6506
TEST_TIME 14 7016
TEST_TIME 15 7117
TEST_TIME 16 7188
TEST_TIME 17 7230
TEST_TIME 18 7230
TEST_TIME 19 7291
TEST_TIME 20 8075
TEST_TIME 21 8048
TEST_TIME 22 8114
TEST_TIME 23 8176
TEST_TIME 24 8474
TEST_TIME 25 8512
TEST_TIME 26 9146
TEST_TIME 27 9393
TEST_TIME 28 10234
TEST_TIME 29 11425
TEST_TIME 30 11339
TEST_TIME 31 11025
TEST_TIME 32 10941
TEST_TIME 33 10740
TEST_TIME 34 10564
TEST_TIME 35 10441
TEST_TIME 36 10328
TEST_TIME 37 10248
TEST_TIME 38 10220
TEST_TIME 39 10003
TEST_TIME 40 9886
TEST_TIME 41 9719
TEST_TIME 42 9822
TEST_TIME 43 9768
TEST_TIME 44 9716
TEST_TIME 45 9585
TEST_TIME 46 9544
TEST_TIME 47 9502
TEST_TIME 48 9387
TEST_TIME 49 9439
TEST_TIME 50 9419
TEST_TIME 51 9387
TEST_TIME 52 9362
TEST_TIME 53 9315
TEST_TIME 54 9337
TEST_TIME 55 9350
TEST_TIME 56 9319
TEST_TIME 57 9335
TEST_TIME 58 9410
TEST_TIME 59 9375
TEST_TIME 60 9398
TEST_TIME 61 9523
TEST_TIME 62 9541
TEST_TIME 63 9669
TEST_TIME 64 9607
TEST_TIME 65 9662
TEST_TIME 66 9694
TEST_TIME 67 9657
TEST_TIME 68 9675
TEST_TIME 69 9592
TEST_TIME 70 9498
TEST_TIME 71 9428
TEST_TIME 72 9364
TEST_TIME 73 9404
TEST_TIME 74 9352
TEST_TIME 75 9323
TEST_TIME 76 9313
TEST_TIME 77 9238
TEST_TIME 78 9159
TEST_TIME 79 9137
TEST_TIME 80 9173
TEST_TIME 81 9175
TEST_TIME 82 9144
TEST_TIME 83 9167
TEST_TIME 84 9151
TEST_TIME 85 9209
TEST_TIME 86 9223
TEST_TIME 87 9209
TEST_TIME 88 9233
TEST_TIME 89 9215
TEST_TIME 90 9314
TEST_TIME 91 9288
TEST_TIME 92 9279
TEST_TIME 93 9190
TEST_TIME 94 9127
TEST_TIME 95 9195
TEST_TIME 96 9238
TEST_TIME 97 9200
TEST_TIME 98 9129
TEST_TIME 99 9107
TEST_TIME 100 9107
TEST_TIME 101 9120
TEST_TIME 102 9093
TEST_TIME 103 9127
TEST_TIME 104 9146
TEST_TIME 105 9122
TEST_TIME 106 9118
TEST_TIME 107 9181
TEST_TIME 108 9278
TEST_TIME 109 9229
TEST_TIME 110 9317
TEST_TIME 111 9281
TEST_TIME 112 9296
TEST_TIME 113 9251
TEST_TIME 114 9212
TEST_TIME 115 9219
TEST_TIME 116 9207
TEST_TIME 117 9177
TEST_TIME 118 9134
TEST_TIME 119 9123
TEST_TIME 120 9078

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-05-2022 22:40:13 to 07-05-2022 22:40:16.

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 1485685 1485685 0 0.00 0 41124 7 0 0
doc_list_biud 1485685 1485685 0 0.00 0 32270 32 0 0
sp_add_invoice_to_stock 4720 4625 95 2.01 24 191162 19771 0 95
sp_cancel_adding_invoice 5899 5899 0 0.00 4 51935 752 0 0
sp_cancel_client_order 1666 1666 0 0.00 4 24636 408 0 0
sp_cancel_customer_reserve 578247 578247 0 0.00 1 30351 67 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 2 9067 88 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 8237 145 0 0
sp_cancel_supplier_invoice 6576 6560 16 0.24 1 14378 850 0 16
sp_cancel_supplier_order 409 409 0 0.00 7 1193205 119707 0 0
sp_cancel_write_off 10907 10907 0 0.00 1 17946 112 0 0
sp_client_order 19292 19292 0 0.00 3 26548 402 0 0
sp_customer_reserve 427063 427063 0 0.00 0 42720 195 0 0
sp_fill_shopping_cart 29569 29569 0 0.00 1 19403 40 0 0
sp_fill_shopping_cart_clo_res 427059 427059 0 0.00 0 30139 16 0 0
sp_get_clo_for_invoice 4621 4621 0 0.00 0 36642 370 0 0
sp_kill_cost_storno 17843 17843 0 0.00 0 24493 63 0 0
sp_kill_qstorno_ret_qs2qd 1174370 1174370 0 0.00 0 32268 36 0 0
sp_kill_qty_storno 617813 617813 0 0.00 0 32269 77 0 0
sp_lock_selected_doc 19727 19727 0 0.00 0 9435 6 0 0
sp_make_cost_storno 26333 26333 0 0.00 0 41065 75 0 0
sp_multiply_rows_for_pdistr 26333 26333 0 0.00 0 15366 37 0 0
sp_multiply_rows_for_qdistr 19292 19292 0 0.00 0 26495 183 0 0
sp_pay_from_customer 6690 6690 0 0.00 4 15769 152 0 0
sp_pay_to_supplier 5518 5518 0 0.00 4 15442 160 0 0
sp_payment_common 12208 12208 0 0.00 1 15007 52 0 0
sp_qd_handle_on_cancel_clo 1666 1666 0 0.00 0 24617 374 0 0
sp_qd_handle_on_invoice_upd_sts 10520 10520 0 0.00 0 30049 363 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 21733 75 0 0
sp_reserve_write_off 9504 9504 0 0.00 6 27515 231 0 0
sp_supplier_invoice 5151 5151 0 0.00 5 93881 3669 0 0
sp_supplier_order 5129 5129 0 0.00 5 32074 1415 0 0
srv_aggregate_perf_data 111 110 1 0.90 2543 140862 16708 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1643 148209 47128 0 0
srv_find_qd_qs_mism 1045129 1045129 0 0.00 0 12525 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 32 119824 1190 0 0
srv_make_money_saldo 746 746 0 0.00 1 374 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 162932 412666 266608 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 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 4625 4625 0 0.00 0 17809 233 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 0 9097 106 0 0
t$perf-norm:sp_cancel_client_order 1666 1666 0 0.00 0 10143 27 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2235 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 11553 42 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 4057 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 3013 24 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 34541 1911 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 11480 20 0 0
t$perf-norm:sp_client_order 19292 19292 0 0.00 0 12441 21 0 0
t$perf-norm:sp_customer_reserve 898 898 0 0.00 0 5924 14 0 0
t$perf-norm:sp_pay_from_customer 6690 6690 0 0.00 0 8103 16 0 0
t$perf-norm:sp_pay_to_supplier 5518 5518 0 0.00 0 6603 13 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 9564 17 0 0
t$perf-norm:sp_supplier_invoice 5151 5151 0 0.00 0 8871 24 0 0
t$perf-norm:sp_supplier_order 5129 5129 0 0.00 0 7073 22 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 644 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 642 19 0 0
t$perf-norm:srv_make_money_saldo 746 746 0 0.00 0 962 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9428 9238 190 2.01 0 9748 21 0 190
v_all_customers 35358 35358 0 0.00 0 23 1 0 0
v_all_suppliers 28534 28534 0 0.00 0 70 1 0 0
v_all_wares 38584 38584 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 302 6 0 0
v_cancel_client_order 3332 3332 0 0.00 0 3085 7 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 2882 10 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2587 8 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 8107 14 0 0
v_cancel_supplier_invoice 840 808 32 3.80 0 3854 21 0 32
v_cancel_supplier_order 818 818 0 0.00 0 1280 4 0 0
v_cancel_write_off 1992 1992 0 0.00 0 5071 16 0 0
v_max_id_clo_ord 5129 5129 0 0.00 0 529 1 0 0
v_max_id_clo_res 898 898 0 0.00 0 2251 13 0 0
v_max_id_ord_sup 5148 5148 0 0.00 0 5595 4 0 0
v_max_non_paid_invoice 1528 1528 0 0.00 0 6593 10 0 0
v_max_non_paid_realizn 3521 3521 0 0.00 0 7935 12 0 0
v_min_id_clo_ord 5129 5129 0 0.00 0 5588 12 0 0
v_min_id_clo_res 898 898 0 0.00 0 1092 4 0 0
v_min_id_ord_sup 5148 5148 0 0.00 0 6919 5 0 0
v_min_non_paid_invoice 5518 5518 0 0.00 0 15002 40 0 0
v_min_non_paid_realizn 6690 6690 0 0.00 0 13949 41 0 0
v_our_firm 9564 9564 0 0.00 0 11 1 0 0
v_reserve_write_off 19008 19008 0 0.00 0 6911 6 0 0
x_lock_depdocs_on_canc_invoice 12055 12055 0 0.00 0 25364 130 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 9281 404 0 0
x_make_qstorno_1000_1200 5129 5129 0 0.00 2 32060 1324 0 0
x_make_qstorno_1200_2000 5151 5151 0 0.00 2 93865 3533 0 0
x_make_qstorno_2100_3300 427063 427063 0 0.00 0 42718 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-05-2022 22:40:17 to 07-05-2022 22:40:21.

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:23 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 27494 8192000000 9913542640 10226147328 995951136 10.839 100 81 162 0 1022846240 6048704 20846400
20:10:56 24114 8192000000 9957912912 10283958272 1043469280 11.298 100 84 168 0 1072953264 8046560 21437424
20:12:26 22032 8192000000 10006816128 10342936576 1084514240 11.690 100 85 170 0 1114360448 9476064 20370144
20:13:53 11181 8192000000 10046172752 10395959296 1127143856 12.094 100 73 146 0 1155162320 10750944 17267520
20:15:09 8945 8192000000 10085527600 10447523840 1173451792 12.529 100 73 146 1 1201968496 12136896 16379808
20:16:21 5291 8192000000 10139277872 10505707520 1217774848 12.941 100 82 164 0 1250074464 14322320 17977296
20:17:28 2785 8192000000 10173954416 10550255616 1259822032 13.328 100 90 178 1 1292474736 13110064 19542640
20:18:32 1899 8192000000 10203318112 10585812992 1285889808 13.567 100 87 174 0 1318929616 13553664 19486144
20:19:35 1390 8192000000 10206501664 10600361984 1299975024 13.695 100 73 147 0 1329882880 13793216 16114640
20:20:38 11109 8192000000 10208039760 10609684480 1305456672 13.745 100 24 48 2 1317017232 6009120 5551440
20:21:49 7865 8192000000 10205340672 10625814528 1323026096 13.904 100 19 38 0 1333080144 6048544 4005504
20:22:58 9943 8192000000 10215096752 10634579968 1328673200 13.955 100 9 18 0 1336067648 5255152 2139296
20:24:09 61142 8192000000 10238254848 10653548544 1339384656 14.052 100 40 81 1 1354313968 5659824 9269488
20:26:11 7687 8192000000 10233390368 10655756288 1344549904 14.098 100 26 49 2 1356163632 5777408 5836320
20:27:22 8542 8192000000 10229340864 10646814720 1326611808 13.937 99 22 44 1 1337309968 5574736 5123424
20:28:31 11344 8192000000 10183137392 10598535168 1257733104 13.309 96 17 33 0 1266919024 5544624 3641296
20:29:43 26423 8192000000 10155623648 10568458240 1209861136 12.868 94 14 28 0 1218134224 5115232 3157856
20:31:11 5480 8192000000 10113356944 10508705792 1195666720 12.736 96 34 68 0 1209502912 5608912 8227280
20:32:17 6234 8192000000 10152868960 10554540032 1248068400 13.220 99 20 41 1 1257879856 5449696 4361760
20:33:23 17462 8192000000 10172958080 10573991936 1269376880 13.416 100 32 64 0 1282577920 5402048 7798992
20:34:45 13944 8192000000 10184213952 10597564416 1290515392 13.609 100 26 52 1 1302885760 6680768 5689600
20:35:59 7064 8192000000 10191850976 10604756992 1297074288 13.669 100 30 60 0 1310900672 5882352 7944032
20:37:07 13461 8192000000 10194042608 10612305920 1305252656 13.743 100 33 65 2 1318826944 5813984 7760304
20:38:31 22085 8192000000 10232108384 10637942784 1325547728 13.927 100 41 77 4 1339596848 5023296 9025824
20:39:58 23025 8192000000 10254227856 10673696768 1347134144 14.122 100 32 64 1 1364900400 9708768 8057488
20:41:27 78887 8192000000 10274371424 10698747904 1366311344 14.294 100 41 82 1 1392255904 16650352 9294208
20:43:53 10991 8192000000 10291420000 10720534528 1378242032 14.401 100 39 78 2 1408061440 20231664 9587744
20:45:07 11330 8192000000 10316654160 10737872896 1392743504 14.530 100 57 114 2 1429267328 23694288 12829536
20:46:21 3447 8192000000 10330617952 10748874752 1397881648 14.576 100 72 143 4 1440474528 26148512 16444368
20:47:30 17443 8192000000 10330670192 10758995968 1390061824 14.506 99 43 86 4 1433621600 32600848 10958928
20:48:51 2557 8192000000 10341448448 10760806400 1391030080 14.515 99 74 147 1 1442794208 33887264 17876864
20:49:57 2588 8192000000 10369902176 10788589568 1411327056 14.696 100 75 149 1 1474033584 44565024 18141504
20:51:05 7327 8192000000 10349267616 10771513344 1394556112 14.546 100 70 138 1 1461327808 49417248 17354448
20:52:17 7187 8192000000 10355345184 10773463040 1390940112 14.514 99 69 136 1 1453312800 45926384 16446304
20:53:29 3943 8192000000 10357313664 10769350656 1389920304 14.505 99 82 156 3 1455998480 46341488 19736688
20:54:37 2350 8192000000 10382489376 10792423424 1413115120 14.712 100 79 158 0 1483086784 50672096 19299568
20:55:44 3828 8192000000 10365351440 10777853952 1399096752 14.587 100 65 130 2 1471821424 55818480 16906192
20:56:59 6642 8192000000 10382264864 10793652224 1404894000 14.639 100 68 134 0 1484168576 61667952 17606624
20:58:10 1643 8192000000 10397597424 10802528256 1402723744 14.619 100 82 164 2 1496541664 71584448 22233472
20:59:15 10565 8192000000 10352874992 10774188032 1390657376 14.512 99 46 90 2 1459455920 56286432 12512112
21:00:31 13263 8192000000 10368881184 10794598400 1398080256 14.578 100 42 84 0 1471918528 62179808 11658464
21:01:57 2802 8192000000 10396452144 10805370880 1405612832 14.645 100 89 176 2 1495000256 65526256 23861168
21:03:04 27590 8192000000 10361636224 10799706112 1378869664 14.406 99 33 66 1 1461785536 73092944 9822928
21:04:34 13340 8192000000 10343299392 10799976448 1378190256 14.400 100 32 64 1 1464703248 77038112 9474880
21:05:56 29169 8192000000 10331844288 10784342016 1353335744 14.177 100 22 44 0 1436475888 75733520 7406624
21:07:39 16205 8192000000 10282746880 10719457280 1353641088 14.180 99 52 104 1 1374209232 8990448 11577696
21:09:01 45987 8192000000 10274039648 10711007232 1353671472 14.180 99 37 74 0 1367991536 5890864 8429200
21:10:49 66455 8192000000 10270607136 10727378944 1360568832 14.242 99 17 35 1 1371525040 7584160 3372048
21:12:56 37371 8192000000 10257167760 10702970880 1324444912 13.917 97 13 27 0 1338688000 11532512 2710576
21:14:53 54084 8192000000 10263071552 10712092672 1346381424 14.115 99 13 26 1 1362616240 12528688 3706128
21:16:48 120667 8192000000 10285958288 10730876928 1355328480 14.195 99 22 44 0 1372457888 12086336 5043072
21:20:43 115831 8192000000 10274624512 10722000896 1322211248 13.897 96 24 48 1 1339918528 11779232 5928048
21:23:42 42014 8192000000 10274071920 10728120320 1374183120 14.365 100 19 38 0 1390529168 11569488 4776560
21:25:31 49633 8192000000 10301255424 10741673984 1366395456 14.295 99 36 72 1 1382820896 7692928 8732512
21:27:49 23725 8192000000 10287682192 10760003584 1385242224 14.463 100 9 18 0 1407110848 19581872 2286752
21:29:13 95639 8192000000 10292353072 10769276928 1360531648 14.242 98 11 22 0 1386760064 24087568 2140848
21:31:54 63603 8192000000 10274093664 10734272512 1358916752 14.228 98 11 22 3 1374588304 13158288 2513264
21:34:08 57202 8192000000 10278437120 10732429312 1312060272 13.805 95 15 30 0 1323567600 8106384 3400944
21:36:06 87377 8192000000 10278112176 10737647616 1330391664 13.971 96 17 34 0 1356628784 22085408 4151712
21:38:37 82143 8192000000 10287218448 10751606784 1359284336 14.231 98 20 40 2 1377013664 13281280 4448048
21:42:10 83076 8192000000 10289992240 10751410176 1372208960 14.347 99 21 42 0 1387185840 10390224 4586656
21:44:34 82758 8192000000 10312546864 10764849152 1395940480 14.559 100 32 65 2 1416187040 13086816 7159744
21:46:58 92649 8192000000 10305935456 10755538944 1389273376 14.499 99 28 56 0 1405412656 9301904 6837376
21:49:53 29978 8192000000 10281431920 10735050752 1358493568 14.224 98 30 58 3 1375914320 10964896 6455856
21:51:23 57648 8192000000 10273604800 10735980544 1362368912 14.259 99 11 22 1 1375031232 10392720 2269600
21:53:29 64304 8192000000 10254717056 10720444416 1334736480 14.010 97 6 12 0 1345113696 8566656 1810560
21:55:33 58470 8192000000 10262078688 10719252480 1342595520 14.081 98 13 27 0 1353065360 7414512 3055328
21:57:32 64986 8192000000 10245908512 10710302720 1321099808 13.887 97 11 22 0 1334831376 10650800 3080768
21:59:50 35659 8192000000 10268087136 10708860928 1341155760 14.068 98 31 62 2 1356517440 8556464 6805216
22:01:40 27217 8192000000 10273036144 10725101568 1370382416 14.330 100 25 50 0 1386638272 10973280 5282576
22:03:14 56468 8192000000 10264675968 10704871424 1326814928 13.938 97 23 46 0 1348808880 16811200 5182752
22:05:12 81981 8192000000 10261723024 10700230656 1280743888 13.520 94 14 28 0 1293831680 10171904 2915888
22:07:34 27557 8192000000 10270164512 10709454848 1344889760 14.101 99 34 68 0 1364116560 11430512 7796288
22:09:33 92775 8192000000 10284971600 10727919616 1352400896 14.169 99 22 44 1 1371841872 14869760 4571216
22:12:10 75913 8192000000 10246100528 10702966784 1322423264 13.899 97 25 50 1 1345469392 16956128 6090000
22:14:39 36743 8192000000 10266941440 10726490112 1371867232 14.344 100 14 27 2 1385047968 10296544 2884192
22:16:17 84183 8192000000 10281803840 10736267264 1380338992 14.420 100 16 32 0 1394380208 9835024 4206192
22:18:41 65557 8192000000 10290533936 10763776000 1386315248 14.473 100 18 36 0 1405614080 14504384 4794448
22:20:58 63687 8192000000 10302504160 10748628992 1350934624 14.156 97 13 26 0 1364943696 11139120 2869952
22:23:18 44669 8192000000 10305721680 10763960320 1396551872 14.564 100 22 44 1 1416945472 15123056 5270544
22:25:02 148209 8192000000 10299787728 10759421952 1369181328 14.320 99 6 12 0 1391155664 20736480 1237856
22:28:31 54908 8192000000 10285848384 10742013952 1356158240 14.203 98 14 28 0 1370521872 10979440 3384192
22:30:26 41668 8192000000 10309688896 10757902336 1392154592 14.525 100 43 86 1 1416134832 14597296 9382944
22:32:11 126922 8192000000 10286322048 10733658112 1353654128 14.180 97 21 42 0 1371909472 13570208 4685136
22:35:18 45680 8192000000 10287044256 10749796352 1389858576 14.505 100 11 22 0 1398165088 5873920 2432592
22:37:24 65376 8192000000 9288027120 9590390784 430827744 4.996 31 7 13 0 436054976 3999232 1228000

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-05-2022 22:40:23 to 07-05-2022 22:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 95 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-05-2022 22:40:39 to 07-05-2022 22:40:41.

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          231461
MON$OLDEST_ACTIVE               231462
MON$OLDEST_SNAPSHOT             231462
MON$NEXT_TRANSACTION            231462
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                       2025888
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
--- end of diff output ---

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

07.05.22 22:40:42. 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-05-07 22:40:44 No severe PSQL-related problems occured
07.05.22 22:40:46. End of report.