Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 16: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 15812616 kB
MemAvailable 30567100 kB
Buffers 0 kB
Cached 15893236 kB
SwapCached 2036 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 16:38:30
DTS_END                         2022-09-28 16:38:30.3510
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/48498
TRN_ID                          235759
ATT_ID                          656
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: 9011.45 19069 1081374
customer order: creation 169.62 349 20354
customer order: refuse 14.60 353 1752
order to supplier: creation 45.27 1247 5432
order to supplier: removal 3.38 101419 406
invoice (draft): creation 44.97 3286 5396
invoice (draft): removal 49.28 822 5913
invoice accept: apply 41.91 17550 5029
invoice accept: cancel 45.16 759 5419
customer reserve: creation 3901.18 173 468141
customer reserve: removal 4407.33 63 528879
realization accept: apply 82.87 174 9944
realization accept: cancel 80.23 108 9628
payment from customer: creation 59.03 109 7084
payment from customer: removal 5.38 69 645
payment to supplier: creation 45.95 143 5514
payment to supplier: removal 3.59 114 431
service: total inventory turnovers 4.47 1063 537
service: total monetary turnovers 6.41 26 769
service: aggregate perf. data 0.81 21223 97
service: refresh index statistics 0.03 232324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2022 16:39:55 to 28-09-2022 16:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4735
TEST_TIME 2 4699
TEST_TIME 3 4986
TEST_TIME 4 4643
TEST_TIME 5 4436
TEST_TIME 6 4207
TEST_TIME 7 5360
TEST_TIME 8 5153
TEST_TIME 9 5112
TEST_TIME 10 5284
TEST_TIME 11 5198
TEST_TIME 12 5162
TEST_TIME 13 5209
TEST_TIME 14 5905
TEST_TIME 15 5954
TEST_TIME 16 6303
TEST_TIME 17 7071
TEST_TIME 18 7461
TEST_TIME 19 7366
TEST_TIME 20 7818
TEST_TIME 21 7918
TEST_TIME 22 8269
TEST_TIME 23 8380
TEST_TIME 24 8961
TEST_TIME 25 9106
TEST_TIME 26 9514
TEST_TIME 27 9500
TEST_TIME 28 10035
TEST_TIME 29 10629
TEST_TIME 30 10548
TEST_TIME 31 10381
TEST_TIME 32 10155
TEST_TIME 33 9872
TEST_TIME 34 9731
TEST_TIME 35 9706
TEST_TIME 36 9797
TEST_TIME 37 9749
TEST_TIME 38 9595
TEST_TIME 39 9473
TEST_TIME 40 9473
TEST_TIME 41 9378
TEST_TIME 42 9338
TEST_TIME 43 9200
TEST_TIME 44 9084
TEST_TIME 45 8984
TEST_TIME 46 8935
TEST_TIME 47 8992
TEST_TIME 48 8943
TEST_TIME 49 8963
TEST_TIME 50 8976
TEST_TIME 51 8963
TEST_TIME 52 8950
TEST_TIME 53 9024
TEST_TIME 54 9110
TEST_TIME 55 9218
TEST_TIME 56 9156
TEST_TIME 57 9188
TEST_TIME 58 9258
TEST_TIME 59 9275
TEST_TIME 60 9384
TEST_TIME 61 9400
TEST_TIME 62 9374
TEST_TIME 63 9419
TEST_TIME 64 9298
TEST_TIME 65 9200
TEST_TIME 66 9272
TEST_TIME 67 9184
TEST_TIME 68 9114
TEST_TIME 69 9109
TEST_TIME 70 9108
TEST_TIME 71 9163
TEST_TIME 72 9154
TEST_TIME 73 9092
TEST_TIME 74 9099
TEST_TIME 75 9041
TEST_TIME 76 9117
TEST_TIME 77 9204
TEST_TIME 78 9183
TEST_TIME 79 9219
TEST_TIME 80 9200
TEST_TIME 81 9247
TEST_TIME 82 9309
TEST_TIME 83 9297
TEST_TIME 84 9279
TEST_TIME 85 9324
TEST_TIME 86 9310
TEST_TIME 87 9290
TEST_TIME 88 9200
TEST_TIME 89 9210
TEST_TIME 90 9210
TEST_TIME 91 9197
TEST_TIME 92 9194
TEST_TIME 93 9139
TEST_TIME 94 9077
TEST_TIME 95 9084
TEST_TIME 96 9065
TEST_TIME 97 9063
TEST_TIME 98 9059
TEST_TIME 99 9064
TEST_TIME 100 9085
TEST_TIME 101 9102
TEST_TIME 102 9102
TEST_TIME 103 9189
TEST_TIME 104 9155
TEST_TIME 105 9138
TEST_TIME 106 9151
TEST_TIME 107 9239
TEST_TIME 108 9227
TEST_TIME 109 9202
TEST_TIME 110 9133
TEST_TIME 111 9129
TEST_TIME 112 9104
TEST_TIME 113 9078
TEST_TIME 114 9061
TEST_TIME 115 9040
TEST_TIME 116 9013
TEST_TIME 117 8999
TEST_TIME 118 9062
TEST_TIME 119 9054
TEST_TIME 120 9013

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-09-2022 16:39:59 to 28-09-2022 16:40:02.

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 1513790 1513790 0 0.00 0 32648 6 0 0
doc_list_biud 1513790 1513790 0 0.00 0 83165 28 0 0
sp_add_invoice_to_stock 5150 5029 121 2.34 21 161846 17550 0 121
sp_cancel_adding_invoice 5419 5419 0 0.00 5 61567 760 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 9820 354 0 0
sp_cancel_customer_reserve 528879 528879 0 0.00 0 83169 63 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 2777 70 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 12933 114 0 0
sp_cancel_supplier_invoice 5932 5913 19 0.32 1 55766 822 0 19
sp_cancel_supplier_order 406 406 0 0.00 7 1059100 101419 0 0
sp_cancel_write_off 9628 9628 0 0.00 1 8125 109 0 0
sp_client_order 20354 20354 0 0.00 3 38355 350 0 0
sp_customer_reserve 468141 468141 0 0.00 0 105083 174 0 0
sp_fill_shopping_cart 31177 31177 0 0.00 1 10791 42 0 0
sp_fill_shopping_cart_clo_res 468139 468139 0 0.00 0 14737 16 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 21020 325 0 0
sp_kill_cost_storno 16123 16123 0 0.00 0 6184 51 0 0
sp_kill_qstorno_ret_qs2qd 1073705 1073705 0 0.00 0 83161 34 0 0
sp_kill_qty_storno 566968 566968 0 0.00 0 83164 73 0 0
sp_lock_selected_doc 20751 20751 0 0.00 0 9042 8 0 0
sp_make_cost_storno 27569 27569 0 0.00 0 25486 55 0 0
sp_multiply_rows_for_pdistr 27569 27569 0 0.00 0 8025 27 0 0
sp_multiply_rows_for_qdistr 20354 20354 0 0.00 0 38336 160 0 0
sp_pay_from_customer 7084 7084 0 0.00 4 17236 110 0 0
sp_pay_to_supplier 5514 5514 0 0.00 4 25533 143 0 0
sp_payment_common 12598 12598 0 0.00 1 9820 32 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 9810 322 0 0
sp_qd_handle_on_invoice_upd_sts 10446 10446 0 0.00 0 26937 297 0 0
sp_qd_handle_on_reserve_upd_sts 9944 9944 0 0.00 0 11394 61 0 0
sp_reserve_write_off 9944 9944 0 0.00 6 13105 174 0 0
sp_supplier_invoice 5396 5396 0 0.00 7 74657 3286 0 0
sp_supplier_order 5432 5432 0 0.00 4 24923 1248 0 0
srv_aggregate_perf_data 98 97 1 1.02 3173 381393 21223 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2214 160370 48066 0 0
srv_find_qd_qs_mism 1033545 1033545 0 0.00 0 32647 6 0 0
srv_make_invnt_saldo 537 537 0 0.00 38 43670 1063 0 0
srv_make_money_saldo 769 769 0 0.00 1 2098 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 103397 363125 232325 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 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 5029 5029 0 0.00 0 15766 183 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 6368 139 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 5823 11 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 3387 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 2306 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 101 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 5608 41 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 27546 1503 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 6075 20 0 0
t$perf-norm:sp_client_order 20354 20354 0 0.00 0 15489 19 0 0
t$perf-norm:sp_customer_reserve 917 917 0 0.00 0 6691 13 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 16237 17 0 0
t$perf-norm:sp_pay_to_supplier 5514 5514 0 0.00 0 9963 12 0 0
t$perf-norm:sp_reserve_write_off 9944 9944 0 0.00 0 7969 13 0 0
t$perf-norm:sp_supplier_invoice 5396 5396 0 0.00 0 14583 20 0 0
t$perf-norm:sp_supplier_order 5432 5432 0 0.00 0 7172 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 128 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 118 2 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 750 17 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 872 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10294 10052 242 2.35 0 7714 19 0 242
v_all_customers 37252 37252 0 0.00 0 108 1 0 0
v_all_suppliers 29410 29410 0 0.00 0 32 1 0 0
v_all_wares 40708 40708 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 1732 8 0 0
v_cancel_client_order 3504 3504 0 0.00 0 2595 6 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 2486 8 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2161 5 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 9189 20 0 0
v_cancel_supplier_invoice 776 738 38 4.89 0 2185 22 0 38
v_cancel_supplier_order 812 812 0 0.00 0 535 3 0 0
v_cancel_write_off 2098 2098 0 0.00 0 2113 14 0 0
v_max_id_clo_ord 5432 5432 0 0.00 0 371 1 0 0
v_max_id_clo_res 917 917 0 0.00 0 1562 12 0 0
v_max_id_ord_sup 5391 5391 0 0.00 0 1506 2 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 2750 7 0 0
v_max_non_paid_realizn 3759 3759 0 0.00 0 2052 4 0 0
v_min_id_clo_ord 5432 5432 0 0.00 0 10383 19 0 0
v_min_id_clo_res 917 917 0 0.00 0 7722 16 0 0
v_min_id_ord_sup 5391 5391 0 0.00 0 4051 7 0 0
v_min_non_paid_invoice 5514 5514 0 0.00 0 7460 27 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 7951 21 0 0
v_our_firm 10106 10106 0 0.00 0 16 1 0 0
v_reserve_write_off 19888 19888 0 0.00 0 2999 5 0 0
x_lock_depdocs_on_canc_invoice 10963 10963 0 0.00 0 10328 105 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 4484 286 0 0
x_make_qstorno_1000_1200 5432 5432 0 0.00 1 24837 1158 0 0
x_make_qstorno_1200_2000 5396 5396 0 0.00 2 74642 3139 0 0
x_make_qstorno_2100_3300 468141 468141 0 0.00 0 105082 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2022 16:40:03 to 28-09-2022 16:40:07.

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
14:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:35 7446 8192000000 9891811488 10199482368 982985824 10.713 100 85 170 0 1011214800 5790656 22438320
14:10:47 22522 8192000000 9944092064 10266243072 1030350544 11.172 100 87 174 0 1060849328 8158672 22340112
14:12:12 14295 8192000000 9991212352 10320150528 1072381616 11.575 100 94 188 1 1106556816 9679248 24495952
14:13:32 10831 8192000000 10012856336 10356080640 1099862768 11.836 100 71 142 0 1127473312 10249568 17360976
14:14:48 11567 8192000000 10071642592 10421936128 1148782416 12.298 100 80 160 1 1179007424 11127344 19097664
14:16:06 3505 8192000000 10105254160 10469494784 1190306592 12.686 100 78 155 1 1220768416 13262656 17199168
14:17:13 2928 8192000000 10148769936 10520764416 1238819280 13.135 100 82 159 1 1268956816 12653088 17484448
14:18:17 2262 8192000000 10182508336 10559877120 1267394752 13.398 100 91 180 0 1300710432 14496704 18818976
14:19:21 793 8192000000 10211667744 10594217984 1296629296 13.665 100 89 172 2 1328910672 13464432 18816944
14:20:23 17834 8192000000 10217907136 10608033792 1311212816 13.797 100 78 154 0 1338880640 11036240 16631584
14:21:41 18267 8192000000 10195519328 10611814400 1314905456 13.831 100 26 49 0 1325960256 5118640 5936160
14:23:06 5488 8192000000 10219846896 10632601600 1325874624 13.930 100 23 46 0 1341522224 10286656 5360944
14:24:14 10167 8192000000 10210938512 10631450624 1330984768 13.976 100 26 52 1 1343059968 6656448 5418752
14:25:26 8432 8192000000 10232501072 10648485888 1338404928 14.043 100 16 32 0 1351285840 9606416 3274496
14:26:35 46930 8192000000 10215741616 10639667200 1316340800 13.844 98 31 58 0 1332328208 9154976 6832432
14:28:25 5121 8192000000 10229135952 10647601152 1335285744 14.015 100 28 57 0 1347608752 6110016 6212992
14:29:33 6169 8192000000 10219387376 10631815168 1321564368 13.891 100 25 51 0 1333492768 6563744 5364656
14:30:39 6746 8192000000 10184547792 10590482432 1278634016 13.501 99 18 34 0 1287948656 5429696 3884944
14:31:46 36994 8192000000 10127887936 10527711232 1153106080 12.339 91 18 36 0 1161823952 5061536 3656336
14:33:25 6754 8192000000 10125716352 10526121984 1231026544 13.064 97 17 33 0 1240145040 5405296 3713200
14:34:33 9413 8192000000 10178840880 10586177536 1281553296 13.527 100 15 30 0 1294218240 9182512 3482432
14:35:43 65427 8192000000 10196955312 10609442816 1304962688 13.740 100 33 64 0 1317829104 5597568 7268848
14:37:51 5059 8192000000 10201597456 10617495552 1311434336 13.799 100 23 47 0 1322699168 5775888 5488944
14:38:59 11140 8192000000 10237756352 10651426816 1339285520 14.051 100 40 80 0 1356036080 7758336 8992224
14:40:17 14894 8192000000 10264333440 10674651136 1353846688 14.182 100 46 94 0 1373757552 9453472 10457392
14:41:38 28569 8192000000 10270793808 10689925120 1368597552 14.314 100 50 97 1 1388530032 8809824 11122656
14:43:30 55248 8192000000 10284502912 10713456640 1379278448 14.410 100 34 67 0 1399506016 12526464 7701104
14:45:43 10927 8192000000 10301467184 10727923712 1388364672 14.491 100 34 66 0 1413459792 16538128 8556992
14:46:59 13133 8192000000 10315226560 10741334016 1393041936 14.533 100 52 101 0 1428311232 23544128 11725168
14:48:27 2878 8192000000 10334195696 10752200704 1401902000 14.612 100 67 131 0 1441541648 23932272 15707376
14:49:33 10103 8192000000 10347405648 10774642688 1417040368 14.746 100 40 78 1 1454108304 27475840 9592096
14:50:56 8599 8192000000 10375220656 10797621248 1432135056 14.880 100 60 120 0 1482219072 34461296 15622720
14:52:10 2212 8192000000 10374750800 10794053632 1426968224 14.834 100 75 148 2 1488671696 43344864 18358608
14:53:27 6598 8192000000 10365278048 10786185216 1423849392 14.807 100 58 116 1 1474427216 35483056 15094768
14:54:39 4579 8192000000 10364678400 10783322112 1412128384 14.703 100 68 137 0 1470956640 41766528 17061728
14:55:53 4702 8192000000 10344223408 10763313152 1387496272 14.484 98 53 105 1 1446225424 45490720 13238432
14:57:03 10789 8192000000 10335360160 10760019968 1390442688 14.510 100 39 78 0 1439986064 39085520 10457856
14:58:18 7187 8192000000 10338000240 10755760128 1381453824 14.430 99 47 94 1 1436671488 42394288 12823376
14:59:33 4025 8192000000 10333483392 10748317696 1375065504 14.372 100 54 106 0 1436496064 47483312 13947248
15:00:51 6004 8192000000 10334430080 10762137600 1374702496 14.369 99 29 58 0 1431708352 48483936 8521920
15:02:07 6117 8192000000 10328440272 10744258560 1351758480 14.163 99 61 120 3 1422016944 54932448 15326016
15:03:18 30072 8192000000 10311668784 10740531200 1340558784 14.062 97 29 58 0 1385245344 36458848 8227712
15:04:51 17966 8192000000 10300519776 10736599040 1363300640 14.267 100 20 40 1 1406966880 37929376 5736864
15:06:28 50649 8192000000 10265738960 10716250112 1307121232 13.760 97 13 25 1 1339850400 29480240 3248928
15:08:20 19093 8192000000 10258705728 10696486912 1349226080 14.141 100 22 44 1 1365563504 11610944 4726480
15:09:44 133625 8192000000 10275727760 10706739200 1342355728 14.079 98 30 55 0 1368861440 20067136 6438576
15:12:59 67083 8192000000 10296877056 10744807424 1358383664 14.223 99 15 28 0 1384386624 23181024 2821936
15:15:11 89902 8192000000 10259855408 10709094400 1343574016 14.090 98 21 41 0 1356114816 7771760 4769040
15:17:50 45738 8192000000 10275747744 10733854720 1377398224 14.393 100 20 40 1 1398191472 16116016 4677232
15:19:41 61451 8192000000 10281570240 10741518336 1390258352 14.508 100 16 32 1 1404697040 10638416 3800272
15:22:00 67367 8192000000 10281008624 10734280704 1377987744 14.399 99 30 60 0 1393978752 9394976 6596032
15:24:09 11029 8192000000 10288013280 10741768192 1386867264 14.478 100 17 34 0 1402044768 11607904 3569600
15:25:22 32798 8192000000 10300764448 10765271040 1399238848 14.588 100 24 48 0 1418812352 14260288 5313216
15:26:58 95831 8192000000 10290400624 10757758976 1397141104 14.570 100 11 22 0 1412195712 12545744 2508864
15:29:34 50536 8192000000 10328392464 10779693056 1412408400 14.705 100 30 58 2 1428815696 9621024 6786272
15:31:36 80770 8192000000 10300043056 10764189696 1353460416 14.179 96 14 28 0 1367526448 10972448 3093584
15:33:57 37845 8192000000 10294689936 10744147968 1341583120 14.072 96 18 37 0 1354921440 8769264 4569056
15:35:55 75906 8192000000 10297126688 10753048576 1367648688 14.306 98 27 54 0 1389458976 15319136 6491152
15:38:32 71094 8192000000 10305233728 10766028800 1347770432 14.127 96 14 28 1 1362082864 11346688 2965744
15:40:48 53999 8192000000 10288017600 10736181248 1319779968 13.875 94 34 68 0 1342591200 15399440 7411792
15:43:25 45584 8192000000 10284520272 10731327488 1348504848 14.134 98 40 78 1 1376374944 19063024 8807072
15:45:11 58995 8192000000 10279292048 10747908096 1381666112 14.431 100 7 13 0 1396533968 13417136 1450720
15:47:11 56266 8192000000 10273392352 10733113344 1336576576 14.027 97 15 30 0 1358896000 18150176 4169248
15:49:07 65588 8192000000 10260760912 10717872128 1326018448 13.931 97 24 50 0 1343938992 12578416 5342128
15:51:16 45742 8192000000 10268286240 10730369024 1362237808 14.257 100 7 14 0 1371592000 8081024 1273168
15:53:01 64126 8192000000 10278674016 10727645184 1346680272 14.118 98 14 28 1 1365478624 15446384 3351968
15:55:08 62642 8192000000 10265003712 10723635200 1335768816 14.019 98 12 22 0 1353341440 15321296 2251328
15:57:11 65634 8192000000 10274432880 10723360768 1322435120 13.899 97 30 60 0 1342610816 13819200 6356496
15:59:17 160369 8192000000 10252448016 10702200832 1266592368 13.390 93 19 38 0 1292247968 21511584 4144016
16:03:02 82442 8192000000 10265031936 10714501120 1323637904 13.910 97 11 22 0 1341768176 15798352 2331920
16:05:27 101602 8192000000 10288465392 10721951744 1356573888 14.207 99 44 88 1 1376257664 9040512 10643264
16:08:16 71667 8192000000 10293220624 10751598592 1375988192 14.381 99 15 30 0 1393056560 14152304 2916064
16:10:29 44258 8192000000 10285509488 10749669376 1385134624 14.462 99 16 32 0 1395410944 6974560 3301760
16:12:13 78266 8192000000 10282600544 10757656576 1393477920 14.537 100 10 20 0 1406040384 10237136 2325328
16:14:38 36194 8192000000 10291409872 10748354560 1372672544 14.351 98 16 32 0 1382053808 5644608 3736656
16:16:19 31300 8192000000 10293314368 10752090112 1380798688 14.424 99 34 65 1 1401083200 12875776 7408736
16:17:50 60429 8192000000 10290663312 10754256896 1363047824 14.265 97 24 46 0 1378557680 10299632 5210224
16:19:56 60273 8192000000 10306811792 10767450112 1391914976 14.523 99 29 58 0 1411396720 12771760 6709984
16:22:11 54687 8192000000 10305713088 10757464064 1364546800 14.278 98 23 47 3 1382764768 13284000 4933968
16:24:09 50520 8192000000 10309581328 10766974976 1396061472 14.560 100 35 70 0 1421372160 16853664 8457024
16:26:03 77911 8192000000 10279376976 10730532864 1320099328 13.878 95 20 40 0 1334204880 9724144 4381408
16:28:29 130469 8192000000 10275186736 10741235712 1351661600 14.162 98 26 52 0 1368618768 11094176 5862992
16:31:40 54581 8192000000 10278822816 10743840768 1369685280 14.324 99 7 14 0 1381415872 9858384 1872208
16:33:51 72172 8192000000 10258986784 10717388800 1305275728 13.743 95 7 14 0 1314562064 7321056 1965280
16:36:08 54297 8192000000 10265719424 10736369664 1339877392 14.056 98 7 14 0 1362035920 20446352 1712176
16:38:02 41567 8192000000 9354970432 9656438784 509142992 5.851 38 10 20 0 518064672 6591904 2329776

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 16:40:09 to 28-09-2022 16:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 121 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2022 16:40:25 to 28-09-2022 16:40:27.

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          237434
MON$OLDEST_ACTIVE               237685
MON$OLDEST_SNAPSHOT             237685
MON$NEXT_TRANSACTION            237685
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                       2003392
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 ---
:162: 
:163: mscs-test-161.budzdorov.ru    Wed Sep 28 16:38:46 2022
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:165: 
:166: 
:167: mscs-test-161.budzdorov.ru    Wed Sep 28 16:38:50 2022
:168:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:169: 
--- end of diff output ---

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

28.09.22 16:40:28. 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 16:40:31 No severe PSQL-related problems occured
28.09.22 16:40:32. End of report.