Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.2022 22:40
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 16496392 kB
MemAvailable 31337084 kB
Buffers 0 kB
Cached 15254392 kB
SwapCached 2440 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-06-09 22:38:18
DTS_END                         2022-06-09 22:38:18.8650
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(676:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35480
TRN_ID                          237295
ATT_ID                          611
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: 9006.79 22140 1080815
customer order: creation 167.88 332 20146
customer order: refuse 14.33 380 1719
order to supplier: creation 44.58 1298 5350
order to supplier: removal 3.30 89072 396
invoice (draft): creation 45.24 3389 5429
invoice (draft): removal 50.31 716 6037
invoice accept: apply 41.70 17791 5004
invoice accept: cancel 45.52 745 5462
customer reserve: creation 3793.79 178 455255
customer reserve: removal 4515.91 51 541909
realization accept: apply 81.53 193 9783
realization accept: cancel 76.34 106 9161
payment from customer: creation 57.39 143 6887
payment from customer: removal 5.69 110 683
payment to supplier: creation 48.36 154 5803
payment to supplier: removal 3.16 70 379
service: total inventory turnovers 4.67 1020 560
service: total monetary turnovers 6.20 36 744
service: aggregate perf. data 0.87 21471 104
service: refresh index statistics 0.03 305549 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2022 22:40:50 to 09-06-2022 22:40:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4232
TEST_TIME 2 3949
TEST_TIME 3 4566
TEST_TIME 4 4285
TEST_TIME 5 4277
TEST_TIME 6 4795
TEST_TIME 7 4797
TEST_TIME 8 5095
TEST_TIME 9 5489
TEST_TIME 10 5983
TEST_TIME 11 6115
TEST_TIME 12 6207
TEST_TIME 13 6551
TEST_TIME 14 7048
TEST_TIME 15 7359
TEST_TIME 16 7367
TEST_TIME 17 7803
TEST_TIME 18 8349
TEST_TIME 19 8439
TEST_TIME 20 8572
TEST_TIME 21 9515
TEST_TIME 22 9396
TEST_TIME 23 9533
TEST_TIME 24 10241
TEST_TIME 25 10352
TEST_TIME 26 10212
TEST_TIME 27 10250
TEST_TIME 28 10158
TEST_TIME 29 10280
TEST_TIME 30 10103
TEST_TIME 31 10050
TEST_TIME 32 9889
TEST_TIME 33 9819
TEST_TIME 34 9597
TEST_TIME 35 9596
TEST_TIME 36 9514
TEST_TIME 37 9584
TEST_TIME 38 9444
TEST_TIME 39 9330
TEST_TIME 40 9254
TEST_TIME 41 9216
TEST_TIME 42 9268
TEST_TIME 43 9325
TEST_TIME 44 9356
TEST_TIME 45 9248
TEST_TIME 46 9215
TEST_TIME 47 9238
TEST_TIME 48 9176
TEST_TIME 49 9126
TEST_TIME 50 9202
TEST_TIME 51 9147
TEST_TIME 52 9053
TEST_TIME 53 9062
TEST_TIME 54 9102
TEST_TIME 55 9296
TEST_TIME 56 9321
TEST_TIME 57 9348
TEST_TIME 58 9309
TEST_TIME 59 9455
TEST_TIME 60 9478
TEST_TIME 61 9483
TEST_TIME 62 9464
TEST_TIME 63 9325
TEST_TIME 64 9316
TEST_TIME 65 9274
TEST_TIME 66 9180
TEST_TIME 67 9263
TEST_TIME 68 9289
TEST_TIME 69 9288
TEST_TIME 70 9272
TEST_TIME 71 9194
TEST_TIME 72 9178
TEST_TIME 73 9122
TEST_TIME 74 9067
TEST_TIME 75 9051
TEST_TIME 76 8988
TEST_TIME 77 8952
TEST_TIME 78 8957
TEST_TIME 79 8948
TEST_TIME 80 8941
TEST_TIME 81 8924
TEST_TIME 82 8882
TEST_TIME 83 9082
TEST_TIME 84 9065
TEST_TIME 85 9129
TEST_TIME 86 9133
TEST_TIME 87 9109
TEST_TIME 88 9184
TEST_TIME 89 9182
TEST_TIME 90 9195
TEST_TIME 91 9120
TEST_TIME 92 9120
TEST_TIME 93 9108
TEST_TIME 94 9096
TEST_TIME 95 9048
TEST_TIME 96 9038
TEST_TIME 97 9056
TEST_TIME 98 9069
TEST_TIME 99 9093
TEST_TIME 100 9079
TEST_TIME 101 9053
TEST_TIME 102 9100
TEST_TIME 103 9181
TEST_TIME 104 9187
TEST_TIME 105 9222
TEST_TIME 106 9211
TEST_TIME 107 9223
TEST_TIME 108 9289
TEST_TIME 109 9271
TEST_TIME 110 9252
TEST_TIME 111 9192
TEST_TIME 112 9147
TEST_TIME 113 9123
TEST_TIME 114 9117
TEST_TIME 115 9091
TEST_TIME 116 9104
TEST_TIME 117 9082
TEST_TIME 118 9083
TEST_TIME 119 9039
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-06-2022 22:40:53 to 09-06-2022 22:40:57.

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 1501979 1501979 0 0.00 0 33718 6 0 0
doc_list_biud 1501978 1501978 0 0.00 0 27765 24 0 0
sp_add_invoice_to_stock 5087 5004 83 1.63 20 204293 17791 0 83
sp_cancel_adding_invoice 5462 5462 0 0.00 4 52867 745 0 0
sp_cancel_client_order 1719 1719 0 0.00 4 17861 380 0 0
sp_cancel_customer_reserve 541909 541909 0 0.00 1 27767 51 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 19531 111 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 5453 70 0 0
sp_cancel_supplier_invoice 6043 6037 6 0.09 2 17697 717 0 6
sp_cancel_supplier_order 396 396 0 0.00 5 1025709 89072 0 0
sp_cancel_write_off 9161 9161 0 0.00 1 15679 106 0 0
sp_client_order 20146 20146 0 0.00 3 25268 332 0 0
sp_customer_reserve 455255 455255 0 0.00 0 37683 178 0 0
sp_fill_shopping_cart 30908 30908 0 0.00 1 11060 40 0 0
sp_fill_shopping_cart_clo_res 455248 455248 0 0.00 0 11673 15 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 19675 334 0 0
sp_kill_cost_storno 15685 15685 0 0.00 0 15632 50 0 0
sp_kill_qstorno_ret_qs2qd 1099412 1099412 0 0.00 0 27753 28 0 0
sp_kill_qty_storno 579463 579463 0 0.00 0 27764 61 0 0
sp_lock_selected_doc 20403 20403 0 0.00 0 5219 4 0 0
sp_make_cost_storno 27469 27469 0 0.00 0 33713 61 0 0
sp_multiply_rows_for_pdistr 27469 27469 0 0.00 0 25486 34 0 0
sp_multiply_rows_for_qdistr 20146 20146 0 0.00 0 11646 138 0 0
sp_pay_from_customer 6887 6887 0 0.00 4 41242 144 0 0
sp_pay_to_supplier 5803 5803 0 0.00 5 41163 155 0 0
sp_payment_common 12690 12690 0 0.00 1 23314 35 0 0
sp_qd_handle_on_cancel_clo 1719 1719 0 0.00 0 17852 346 0 0
sp_qd_handle_on_invoice_upd_sts 10458 10458 0 0.00 0 14118 313 0 0
sp_qd_handle_on_reserve_upd_sts 9783 9783 0 0.00 0 9045 63 0 0
sp_reserve_write_off 9783 9783 0 0.00 6 33946 193 0 0
sp_supplier_invoice 5429 5429 0 0.00 5 83298 3390 0 0
sp_supplier_order 5350 5350 0 0.00 5 38168 1299 0 0
srv_aggregate_perf_data 105 104 1 0.95 2449 250076 21471 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1010 120441 48657 0 0
srv_find_qd_qs_mism 1033853 1033853 0 0.00 0 16735 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 23 30001 1021 0 0
srv_make_money_saldo 744 744 0 0.00 1 10040 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 182196 437321 305550 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 5004 5004 0 0.00 0 15190 157 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 4832 69 0 0
t$perf-norm:sp_cancel_client_order 1719 1719 0 0.00 0 5751 17 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 2539 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1175 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 3321 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 2691 15 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 18773 1470 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 1166 5 0 0
t$perf-norm:sp_client_order 20146 20146 0 0.00 0 9180 19 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 960 5 0 0
t$perf-norm:sp_pay_from_customer 6887 6887 0 0.00 0 5761 12 0 0
t$perf-norm:sp_pay_to_supplier 5803 5803 0 0.00 0 3998 11 0 0
t$perf-norm:sp_reserve_write_off 9783 9783 0 0.00 0 5747 13 0 0
t$perf-norm:sp_supplier_invoice 5429 5429 0 0.00 0 8221 18 0 0
t$perf-norm:sp_supplier_order 5350 5350 0 0.00 0 8309 20 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 179 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 1360 25 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 10957 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10158 9992 166 1.63 0 19463 23 0 166
v_all_customers 36058 36058 0 0.00 0 93 0 0 0
v_all_suppliers 29926 29926 0 0.00 0 29 1 0 0
v_all_wares 40292 40292 0 0.00 0 154 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 787 6 0 0
v_cancel_client_order 3438 3438 0 0.00 0 5499 8 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 19471 22 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 3720 16 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 3224 7 0 0
v_cancel_supplier_invoice 774 762 12 1.55 0 4975 29 0 12
v_cancel_supplier_order 792 792 0 0.00 0 3167 9 0 0
v_cancel_write_off 1974 1974 0 0.00 0 4916 15 0 0
v_max_id_clo_ord 5344 5344 0 0.00 0 178 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 1508 7 0 0
v_max_id_ord_sup 5417 5417 0 0.00 0 4820 5 0 0
v_max_non_paid_invoice 1600 1600 0 0.00 0 2987 5 0 0
v_max_non_paid_realizn 4004 4004 0 0.00 0 4453 5 0 0
v_min_id_clo_ord 5344 5344 0 0.00 0 3103 7 0 0
v_min_id_clo_res 947 947 0 0.00 0 4641 13 0 0
v_min_id_ord_sup 5417 5417 0 0.00 0 6464 8 0 0
v_min_non_paid_invoice 5803 5803 0 0.00 0 16325 27 0 0
v_min_non_paid_realizn 6887 6887 0 0.00 0 23307 25 0 0
v_our_firm 10000 10000 0 0.00 0 13 1 0 0
v_reserve_write_off 19565 19565 0 0.00 0 19470 10 0 0
x_lock_depdocs_on_canc_invoice 11118 11118 0 0.00 0 21770 120 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 5909 346 0 0
x_make_qstorno_1000_1200 5350 5350 0 0.00 1 38145 1216 0 0
x_make_qstorno_1200_2000 5429 5429 0 0.00 2 83253 3250 0 0
x_make_qstorno_2100_3300 455255 455255 0 0.00 0 36377 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-06-2022 22:40:58 to 09-06-2022 22:41:02.

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:22 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:23 40701 8192000000 9906063040 10217185280 984329376 10.726 100 89 178 0 1014350560 6428624 23592560
20:11:16 14679 8192000000 9961673328 10284933120 1040370368 11.268 100 88 176 0 1072127136 8881328 22875440
20:12:43 21382 8192000000 9999801408 10336612352 1078555728 11.634 100 82 164 1 1108570112 9619680 20394704
20:14:09 10222 8192000000 10052789984 10405572608 1142038864 12.235 100 76 150 2 1170833552 11497536 17297152
20:15:24 5823 8192000000 10098058432 10460717056 1187351824 12.659 100 76 151 1 1216924656 12514288 17058544
20:16:33 4785 8192000000 10146928112 10519498752 1238006240 13.128 100 81 161 1 1268483920 12726080 17751600
20:17:42 2724 8192000000 10178186720 10560258048 1267464400 13.398 100 79 159 0 1298102672 12731168 17907104
20:18:47 1043 8192000000 10206585536 10585714688 1283066768 13.541 100 91 181 1 1317099712 13667664 20365280
20:19:50 1656 8192000000 10237373840 10621587456 1310369216 13.789 100 97 194 3 1345465104 13465920 21629968
20:20:52 5616 8192000000 10227394880 10622996480 1321452416 13.890 100 68 137 2 1344816288 7112400 16251472
20:21:58 6638 8192000000 10213000576 10625503232 1324079616 13.914 100 31 63 0 1337770464 6776176 6914672
20:23:08 9805 8192000000 10214370320 10632884224 1329553392 13.963 100 21 42 4 1340226448 5546000 5127056
20:24:18 8764 8192000000 10227552992 10646609920 1340262960 14.060 100 19 38 0 1350551728 6008272 4280496
20:25:28 9133 8192000000 10229219536 10652217344 1343846176 14.092 100 22 44 0 1355001648 6343344 4812128
20:26:44 17154 8192000000 10250845104 10654814208 1340971632 14.066 100 69 137 1 1364322384 5147584 18203168
20:28:02 5886 8192000000 10213666768 10629226496 1316617664 13.846 99 22 44 0 1327886928 6339504 4929760
20:29:11 8615 8192000000 10190111712 10602258432 1271178304 13.432 97 26 50 3 1282458912 5426640 5853968
20:30:23 30978 8192000000 10157563552 10563031040 1237764720 13.126 97 34 68 1 1251421792 5325776 8331296
20:32:00 7349 8192000000 10139668288 10535579648 1209682816 12.866 97 28 56 0 1221932880 5555584 6694480
20:33:09 11511 8192000000 10134070304 10542211072 1246668944 13.208 99 35 70 2 1261315952 5280768 9366240
20:34:21 48057 8192000000 10167953488 10579632128 1278620480 13.500 100 19 37 0 1288530512 5727888 4182144
20:36:16 7223 8192000000 10181424112 10590830592 1286780112 13.575 100 21 42 0 1297807984 6093664 4934208
20:37:25 13102 8192000000 10194994640 10606198784 1296421664 13.663 100 22 42 1 1307629232 5704016 5503552
20:38:40 9918 8192000000 10237974480 10644922368 1328907904 13.957 100 47 94 0 1346643392 6969408 10766080
20:39:55 54509 8192000000 10264307184 10670948352 1345377360 14.106 100 53 106 2 1370869168 13562224 11929584
20:41:59 29578 8192000000 10266925520 10686394368 1350559600 14.153 100 38 76 0 1374237072 14800256 8877216
20:43:31 16423 8192000000 10277428832 10699771904 1365975184 14.291 100 40 80 0 1395267136 19433920 9858032
20:44:52 20014 8192000000 10280799440 10705772544 1368942096 14.318 100 40 80 0 1400002496 21086128 9974272
20:46:14 17101 8192000000 10299839520 10727276544 1378387888 14.402 100 39 78 0 1412392304 24408064 9596352
20:47:36 1806 8192000000 10335202176 10750783488 1398950416 14.586 100 68 136 0 1443044240 28155984 15937840
20:48:42 2262 8192000000 10345765744 10754105344 1402124720 14.614 100 84 166 1 1449215680 27744400 19346560
20:49:51 16063 8192000000 10335589120 10755284992 1397032576 14.569 100 62 122 0 1444035408 32067232 14935600
20:51:11 8625 8192000000 10346263728 10763534336 1395930288 14.559 99 66 132 0 1449440176 37175504 16334384
20:52:27 4111 8192000000 10330052032 10740469760 1380426672 14.420 99 71 141 4 1427127344 29928976 16771696
20:53:36 1009 8192000000 10336289264 10747158528 1390191984 14.508 99 72 144 2 1444213648 36033552 17988112
20:54:40 6869 8192000000 10315783312 10729484288 1358316928 14.222 99 49 97 0 1409540720 39063888 12159904
20:55:51 12078 8192000000 10306272704 10730934272 1355269840 14.195 98 46 92 2 1406451376 38902064 12279472
20:57:12 6830 8192000000 10302743232 10718699520 1325499696 13.926 96 48 96 0 1368517408 30433360 12584352
20:58:23 10256 8192000000 10299066752 10726912000 1365944464 14.291 100 49 97 1 1412088576 33578672 12565440
20:59:41 11792 8192000000 10295521344 10729443328 1354623232 14.189 99 22 44 1 1399517664 38650864 6243568
21:00:59 26034 8192000000 10284878576 10716041216 1348327264 14.132 99 24 48 0 1386630016 31224448 7078304
21:02:29 18753 8192000000 10290002512 10722402304 1360810256 14.245 99 21 40 1 1388565008 22529408 5225344
21:03:57 52367 8192000000 10266635920 10706616320 1332824016 13.993 97 35 68 1 1358270448 17183968 8262464
21:06:05 23409 8192000000 10267970320 10710769664 1351021280 14.157 99 31 63 0 1371839152 13629664 7188208
21:07:31 28948 8192000000 10259794592 10707300352 1350197744 14.149 99 11 22 1 1367029360 14407776 2423840
21:09:10 64719 8192000000 10275227184 10723368960 1361008656 14.246 99 28 56 0 1375108272 7529008 6570608
21:11:16 59940 8192000000 10266859248 10721587200 1363347920 14.267 99 22 44 1 1380279136 12103344 4827872
21:13:46 84404 8192000000 10291708448 10733162496 1359263728 14.231 98 22 44 1 1374453600 10062192 5127680
21:16:11 64825 8192000000 10281515808 10733502464 1365714720 14.289 99 15 30 0 1382640032 13575056 3350256
21:18:17 31274 8192000000 10317177952 10764558336 1403809664 14.629 100 24 48 2 1426928944 17613088 5506192
21:20:01 43705 8192000000 10308549776 10766848000 1404670992 14.637 100 22 44 0 1430544544 20319472 5554080
21:21:49 49058 8192000000 10293637120 10755010560 1392757936 14.530 99 24 49 0 1414197616 15576096 5863584
21:23:39 64523 8192000000 10304253360 10772946944 1410324304 14.687 100 17 32 2 1426336208 12380448 3631456
21:25:46 54212 8192000000 10297148112 10762502144 1383468576 14.448 99 14 28 2 1400552544 13637376 3446592
21:27:40 100419 8192000000 10303569664 10773368832 1379913536 14.416 98 20 41 1 1399087776 14745792 4428448
21:30:28 36791 8192000000 10339719072 10794864640 1425806272 14.824 100 40 80 1 1452129568 17300832 9022464
21:32:17 84130 8192000000 10338636304 10803916800 1396675536 14.565 98 12 24 1 1417444528 18276400 2492592
21:35:32 57412 8192000000 10314070208 10751479808 1341258080 14.069 96 38 76 2 1360623952 11262576 8103296
21:39:18 66700 8192000000 10264337616 10713534464 1273931808 13.458 92 21 42 1 1288843728 10288816 4623104
21:41:25 74749 8192000000 10280588928 10730676224 1364133840 14.274 99 31 60 1 1384765248 13373408 7258000
21:43:47 35713 8192000000 10308213216 10757173248 1389522368 14.502 100 36 72 0 1416272304 18730848 8019088
21:45:24 87168 8192000000 10269163600 10744750080 1339554176 14.053 97 15 30 0 1360678800 17776000 3348624
21:47:55 70594 8192000000 10266193376 10728189952 1356306816 14.204 99 16 32 1 1370325520 10658128 3360576
21:50:06 86481 8192000000 10239722560 10691244032 1321214160 13.888 97 9 16 1 1331165984 7637280 2314544
21:52:33 49674 8192000000 10244738720 10699923456 1330026752 13.967 98 11 22 1 1338134560 6095568 2012240
21:54:27 74927 8192000000 10250086464 10713825280 1362106048 14.256 100 15 29 3 1375877856 10301728 3470080
21:56:42 43959 8192000000 10240476752 10687107072 1322625088 13.900 98 22 44 0 1336195664 7696384 5874192
21:58:29 20138 8192000000 10276054144 10719281152 1365604400 14.288 100 41 82 2 1385505408 10905776 8995232
21:59:51 28814 8192000000 10300613072 10743885824 1378406320 14.402 100 41 81 1 1406915168 19472688 9036160
22:01:32 73012 8192000000 10282263200 10734661632 1350642208 14.153 98 17 34 0 1370017008 15294016 4080784
22:03:47 103759 8192000000 10272630192 10718887936 1319166224 13.869 96 10 20 0 1332499216 11550928 1782064
22:07:22 51159 8192000000 10302306864 10734616576 1374356608 14.366 100 34 67 1 1395901024 14125184 7419232
22:09:15 120440 8192000000 10296090080 10750767104 1370339184 14.330 99 27 54 0 1388536320 12210384 5986752
22:12:21 81011 8192000000 10281755648 10751586304 1376925136 14.389 99 5 10 0 1390591168 12776000 890032
22:14:42 11348 8192000000 10300518720 10748379136 1389151360 14.498 100 24 48 0 1401154464 6815696 5187408
22:15:55 50037 8192000000 10288887840 10747080704 1362912736 14.263 98 20 40 1 1381325408 13630256 4782416
22:17:45 85274 8192000000 10299817200 10747740160 1359792832 14.235 98 20 40 0 1375295872 11010800 4492240
22:20:15 103380 8192000000 10297167424 10776563712 1376281536 14.383 99 17 34 0 1398462960 17926576 4254848
22:23:08 106972 8192000000 10305943872 10768961536 1372105888 14.346 98 10 19 1 1391165184 16211792 2847504
22:25:55 92085 8192000000 10313415008 10749554688 1315900160 13.840 94 19 38 1 1337232160 16392128 4939872
22:28:28 113536 8192000000 10236225824 10673328128 1297910576 13.676 94 12 24 0 1310323424 9510512 2902336
22:31:23 38821 8192000000 10275637376 10728361984 1364606752 14.279 99 17 32 1 1380980352 12526832 3846768
22:33:05 58136 8192000000 10285330448 10751307776 1367785840 14.307 99 9 18 1 1389744512 19888512 2070160
22:35:03 62971 8192000000 10249524416 10703486976 1332234512 13.987 97 12 24 0 1344692336 9585504 2872320
22:37:12 51627 8192000000 10246224416 10690785280 1318357040 13.862 98 18 36 0 1331794304 9231136 4206128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-06-2022 22:41:04 to 09-06-2022 22:41:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 83 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 09-06-2022 22:41:19 to 09-06-2022 22:41:21.

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          238006
MON$OLDEST_ACTIVE               238007
MON$OLDEST_SNAPSHOT             238007
MON$NEXT_TRANSACTION            238007
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                       2017920
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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'

09.06.22 22:41:22. 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-06-09 22:41:24 No severe PSQL-related problems occured
09.06.22 22:41:26. End of report.