Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16441480 kB
MemAvailable 31352640 kB
Buffers 0 kB
Cached 15239468 kB
SwapCached 2552 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-26 16:38:19
DTS_END                         2022-05-26 16:38:19.8250
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/42408
TRN_ID                          242877
ATT_ID                          688
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: 9239.99 23238 1108799
customer order: creation 172.47 327 20696
customer order: refuse 14.38 352 1726
order to supplier: creation 46.29 1265 5555
order to supplier: removal 3.51 97601 421
invoice (draft): creation 46.38 3350 5566
invoice (draft): removal 52.28 818 6274
invoice accept: apply 42.23 18055 5068
invoice accept: cancel 47.29 808 5675
customer reserve: creation 3873.85 179 464862
customer reserve: removal 4643.42 57 557210
realization accept: apply 84.70 208 10164
realization accept: cancel 83.28 110 9994
payment from customer: creation 59.58 126 7149
payment from customer: removal 5.63 86 675
payment to supplier: creation 49.02 151 5882
payment to supplier: removal 3.63 146 435
service: total inventory turnovers 4.58 1258 549
service: total monetary turnovers 6.62 55 794
service: aggregate perf. data 0.83 23911 100
service: refresh index statistics 0.03 315899 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-05-2022 16:39:00 to 26-05-2022 16:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3561
TEST_TIME 2 3549
TEST_TIME 3 4776
TEST_TIME 4 4821
TEST_TIME 5 4913
TEST_TIME 6 5541
TEST_TIME 7 5455
TEST_TIME 8 6369
TEST_TIME 9 6368
TEST_TIME 10 6563
TEST_TIME 11 6582
TEST_TIME 12 6908
TEST_TIME 13 6889
TEST_TIME 14 7151
TEST_TIME 15 7513
TEST_TIME 16 7854
TEST_TIME 17 8064
TEST_TIME 18 8152
TEST_TIME 19 8431
TEST_TIME 20 8483
TEST_TIME 21 8679
TEST_TIME 22 8591
TEST_TIME 23 8747
TEST_TIME 24 8792
TEST_TIME 25 9247
TEST_TIME 26 9179
TEST_TIME 27 9231
TEST_TIME 28 9614
TEST_TIME 29 10498
TEST_TIME 30 10768
TEST_TIME 31 10766
TEST_TIME 32 10602
TEST_TIME 33 10358
TEST_TIME 34 10380
TEST_TIME 35 10386
TEST_TIME 36 10187
TEST_TIME 37 10056
TEST_TIME 38 9911
TEST_TIME 39 9872
TEST_TIME 40 9772
TEST_TIME 41 9654
TEST_TIME 42 9600
TEST_TIME 43 9690
TEST_TIME 44 9624
TEST_TIME 45 9518
TEST_TIME 46 9456
TEST_TIME 47 9350
TEST_TIME 48 9265
TEST_TIME 49 9363
TEST_TIME 50 9269
TEST_TIME 51 9206
TEST_TIME 52 9165
TEST_TIME 53 9067
TEST_TIME 54 9102
TEST_TIME 55 9030
TEST_TIME 56 9010
TEST_TIME 57 8997
TEST_TIME 58 9016
TEST_TIME 59 9017
TEST_TIME 60 9027
TEST_TIME 61 9044
TEST_TIME 62 9059
TEST_TIME 63 9063
TEST_TIME 64 9180
TEST_TIME 65 9232
TEST_TIME 66 9268
TEST_TIME 67 9199
TEST_TIME 68 9238
TEST_TIME 69 9329
TEST_TIME 70 9497
TEST_TIME 71 9463
TEST_TIME 72 9398
TEST_TIME 73 9305
TEST_TIME 74 9280
TEST_TIME 75 9292
TEST_TIME 76 9218
TEST_TIME 77 9263
TEST_TIME 78 9218
TEST_TIME 79 9203
TEST_TIME 80 9175
TEST_TIME 81 9127
TEST_TIME 82 9053
TEST_TIME 83 9035
TEST_TIME 84 9090
TEST_TIME 85 9098
TEST_TIME 86 9136
TEST_TIME 87 9158
TEST_TIME 88 9161
TEST_TIME 89 9160
TEST_TIME 90 9225
TEST_TIME 91 9272
TEST_TIME 92 9258
TEST_TIME 93 9332
TEST_TIME 94 9389
TEST_TIME 95 9427
TEST_TIME 96 9460
TEST_TIME 97 9402
TEST_TIME 98 9348
TEST_TIME 99 9302
TEST_TIME 100 9296
TEST_TIME 101 9249
TEST_TIME 102 9227
TEST_TIME 103 9190
TEST_TIME 104 9204
TEST_TIME 105 9155
TEST_TIME 106 9100
TEST_TIME 107 9087
TEST_TIME 108 9101
TEST_TIME 109 9111
TEST_TIME 110 9105
TEST_TIME 111 9142
TEST_TIME 112 9113
TEST_TIME 113 9183
TEST_TIME 114 9144
TEST_TIME 115 9183
TEST_TIME 116 9266
TEST_TIME 117 9264
TEST_TIME 118 9327
TEST_TIME 119 9303
TEST_TIME 120 9245

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-05-2022 16:39:04 to 26-05-2022 16:39:07.

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 1539914 1539914 0 0.00 0 22771 6 0 0
doc_list_biud 1539911 1539911 0 0.00 0 32356 27 0 0
sp_add_invoice_to_stock 5262 5068 194 3.68 33 182909 18056 0 194
sp_cancel_adding_invoice 5675 5675 0 0.00 5 62756 808 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 11766 352 0 0
sp_cancel_customer_reserve 557210 557210 0 0.00 1 27668 58 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 6593 86 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 12578 146 0 0
sp_cancel_supplier_invoice 6295 6274 21 0.33 1 16149 819 0 21
sp_cancel_supplier_order 421 421 0 0.00 6 904248 97602 0 0
sp_cancel_write_off 9994 9994 0 0.00 1 15755 111 0 0
sp_client_order 20696 20696 0 0.00 3 23060 328 0 0
sp_customer_reserve 464862 464862 0 0.00 0 65104 179 0 0
sp_fill_shopping_cart 31812 31812 0 0.00 1 14954 43 0 0
sp_fill_shopping_cart_clo_res 464859 464859 0 0.00 0 32158 14 0 0
sp_get_clo_for_invoice 5064 5064 0 0.00 0 32314 354 0 0
sp_kill_cost_storno 16779 16779 0 0.00 0 7317 60 0 0
sp_kill_qstorno_ret_qs2qd 1131109 1131109 0 0.00 0 25068 31 0 0
sp_kill_qty_storno 596527 596527 0 0.00 0 32354 68 0 0
sp_lock_selected_doc 21035 21035 0 0.00 0 8414 7 0 0
sp_make_cost_storno 28258 28258 0 0.00 0 16263 63 0 0
sp_multiply_rows_for_pdistr 28257 28257 0 0.00 0 8452 30 0 0
sp_multiply_rows_for_qdistr 20696 20696 0 0.00 0 21510 148 0 0
sp_pay_from_customer 7149 7149 0 0.00 4 21462 127 0 0
sp_pay_to_supplier 5882 5882 0 0.00 4 24989 152 0 0
sp_payment_common 13030 13030 0 0.00 1 12795 29 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 11759 323 0 0
sp_qd_handle_on_invoice_upd_sts 10738 10738 0 0.00 0 32354 319 0 0
sp_qd_handle_on_reserve_upd_sts 10164 10164 0 0.00 0 10452 57 0 0
sp_reserve_write_off 10164 10164 0 0.00 6 25284 208 0 0
sp_supplier_invoice 5566 5566 0 0.00 8 83318 3351 0 0
sp_supplier_order 5555 5555 0 0.00 4 31824 1266 0 0
srv_aggregate_perf_data 101 100 1 0.99 2412 233402 23912 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2326 123939 46842 0 0
srv_find_qd_qs_mism 1061152 1061152 0 0.00 0 17628 5 0 0
srv_make_invnt_saldo 549 549 0 0.00 24 100375 1259 0 0
srv_make_money_saldo 794 794 0 0.00 1 16143 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 207195 378765 315899 0 0
t$perf-abend:sp_add_invoice_to_stock 194 194 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 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 5069 5069 0 0.00 0 9300 187 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 6165 95 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 2793 15 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 4769 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2866 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 973 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 868 19 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 21422 1584 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 4998 19 0 0
t$perf-norm:sp_client_order 20696 20696 0 0.00 0 6956 17 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 8969 19 0 0
t$perf-norm:sp_pay_from_customer 7149 7149 0 0.00 0 7003 11 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 2045 8 0 0
t$perf-norm:sp_reserve_write_off 10164 10164 0 0.00 0 12093 13 0 0
t$perf-norm:sp_supplier_invoice 5566 5566 0 0.00 0 4150 16 0 0
t$perf-norm:sp_supplier_order 5555 5555 0 0.00 0 3868 13 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 316 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 454 9 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 6790 36 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 3400 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10512 10124 388 3.69 0 21456 26 0 388
v_all_customers 37690 37690 0 0.00 0 56 1 0 0
v_all_suppliers 30562 30562 0 0.00 0 19 1 0 0
v_all_wares 41392 41392 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 2074 7 0 0
v_cancel_client_order 3452 3452 0 0.00 0 4869 6 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 3415 7 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3196 8 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 12554 20 0 0
v_cancel_supplier_invoice 920 878 42 4.56 0 766 14 0 42
v_cancel_supplier_order 842 842 0 0.00 0 2240 8 0 0
v_cancel_write_off 1974 1974 0 0.00 0 1639 13 0 0
v_max_id_clo_ord 5554 5554 0 0.00 0 219 1 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 6064 16 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 4958 5 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 573 3 0 0
v_max_non_paid_realizn 3873 3873 0 0.00 0 5595 5 0 0
v_min_id_clo_ord 5554 5554 0 0.00 0 5831 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 6505 27 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 4410 8 0 0
v_min_non_paid_invoice 5881 5881 0 0.00 0 6199 19 0 0
v_min_non_paid_realizn 7149 7149 0 0.00 0 7643 22 0 0
v_our_firm 10254 10254 0 0.00 0 12 1 0 0
v_reserve_write_off 20328 20328 0 0.00 0 25229 10 0 0
x_lock_depdocs_on_canc_invoice 11510 11510 0 0.00 0 20097 114 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 11134 345 0 0
x_make_qstorno_1000_1200 5555 5555 0 0.00 2 31779 1181 0 0
x_make_qstorno_1200_2000 5566 5566 0 0.00 2 83294 3192 0 0
x_make_qstorno_2100_3300 464862 464862 0 0.00 0 65103 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-05-2022 16:39:08 to 26-05-2022 16:39:12.

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:23 5 8192000000 8798901280 8810168320 5315664 0.064 2 1 2 0 5415264 56816 42784
14:09:24 30905 8192000000 9904486848 10212614144 982013936 10.704 100 91 182 0 1011645696 5472640 24159120
14:10:58 33246 8192000000 9960519712 10285891584 1038986192 11.255 100 89 178 0 1069636464 7554112 23096160
14:12:38 17351 8192000000 9985854208 10322247680 1073416448 11.585 100 84 168 0 1103941312 9097104 21427760
14:13:59 10626 8192000000 10054200912 10407555072 1142753792 12.241 100 82 164 0 1173039456 11219760 19065904
14:15:14 8991 8192000000 10100100400 10462126080 1182294656 12.612 100 80 160 0 1213181504 12962288 17924560
14:16:24 5717 8192000000 10143255456 10513702912 1231934304 13.072 100 76 152 0 1260169616 10777968 17457344
14:17:33 5068 8192000000 10167736480 10546237440 1253225408 13.268 100 83 167 1 1287261376 15815104 18220864
14:18:39 2379 8192000000 10208734752 10589159424 1287315136 13.580 100 89 178 0 1320523808 12802336 20406336
14:19:43 1582 8192000000 10223001536 10605641728 1301495280 13.709 100 92 184 1 1336029856 13965744 20568832
14:20:46 1075 8192000000 10229461072 10616025088 1309176320 13.779 100 91 185 3 1340107536 9631808 21299408
14:21:48 5530 8192000000 10207541920 10619617280 1318933056 13.867 100 22 44 1 1330685200 7096800 4655344
14:22:54 12139 8192000000 10223989936 10634485760 1327526368 13.945 100 23 46 0 1342904624 10380864 4997392
14:24:07 60390 8192000000 10219682624 10642276352 1331033024 13.976 100 24 48 3 1341816576 5136256 5647296
14:26:08 6505 8192000000 10240289424 10657488896 1337629968 14.036 100 27 54 0 1353818480 10257648 5930864
14:27:16 8358 8192000000 10222261328 10642612224 1322141344 13.896 99 23 46 1 1332862832 4962944 5758544
14:28:28 12139 8192000000 10211323712 10623991808 1301482896 13.709 98 22 43 2 1311647008 5093216 5070896
14:29:42 28716 8192000000 10192185552 10601037824 1238301056 13.131 94 24 48 1 1249437440 5936976 5199408
14:31:15 9106 8192000000 10152302144 10550824960 1232807456 13.080 98 21 43 0 1243857536 6073648 4976432
14:32:25 10161 8192000000 10154396992 10557923328 1255973536 13.293 100 25 49 1 1267400480 5420288 6006656
14:33:37 53556 8192000000 10159715088 10567131136 1258635648 13.317 98 17 34 1 1268022928 5486240 3901040
14:35:35 5580 8192000000 10201659760 10610393088 1301841504 13.712 100 27 54 0 1314021376 5982240 6197632
14:36:41 11467 8192000000 10214894928 10627518464 1318721472 13.865 100 20 39 1 1328622320 5203728 4697120
14:37:54 14522 8192000000 10225024528 10641039360 1326189888 13.933 100 19 37 2 1335284848 4951680 4143280
14:39:10 70205 8192000000 10251340048 10665791488 1335614688 14.018 99 41 82 2 1352213792 7715488 8883616
14:41:25 3413 8192000000 10276710848 10688839680 1366926608 14.300 100 47 94 1 1386152832 8707696 10518528
14:42:31 8994 8192000000 10304942016 10715086848 1379397344 14.411 100 72 142 1 1408888784 13244720 16246720
14:43:45 3510 8192000000 10299388144 10716065792 1385351216 14.464 100 63 123 1 1414042112 14380720 14310176
14:44:54 8421 8192000000 10312820624 10733404160 1393891904 14.541 100 58 116 1 1423972864 16149456 13931504
14:46:08 5851 8192000000 10319664352 10745081856 1391729600 14.521 100 54 109 1 1431867552 27023088 13114864
14:47:20 7840 8192000000 10318931840 10743631872 1399093056 14.587 100 51 101 0 1430444288 19386704 11964528
14:48:35 7068 8192000000 10329690304 10743963648 1396631824 14.565 100 69 138 2 1434953824 22257472 16064528
14:49:57 8382 8192000000 10317926960 10746003456 1385324288 14.464 99 40 79 0 1418170752 22831936 10014528
14:51:10 6145 8192000000 10335190592 10755424256 1402160864 14.614 100 55 110 0 1440349232 25014400 13173968
14:52:18 4114 8192000000 10343346896 10765983744 1407238496 14.659 100 64 127 1 1452630016 30305104 15086416
14:53:27 3005 8192000000 10311681968 10730823680 1365250144 14.284 99 59 116 0 1415996784 36061568 14685072
14:54:40 4893 8192000000 10333295440 10745536512 1384288720 14.455 100 71 141 0 1435889808 34314144 17286944
14:55:54 2355 8192000000 10345508368 10759536640 1386770080 14.477 100 69 138 0 1448928336 45070064 17088192
14:57:00 5502 8192000000 10324688672 10736349184 1370570544 14.332 98 61 122 0 1424922784 37770000 16582240
14:58:12 2325 8192000000 10318699808 10727428096 1367022960 14.300 99 65 130 1 1422418800 39217488 16178352
14:59:18 25004 8192000000 10334240528 10767826944 1393183920 14.534 100 44 87 1 1443994736 39273856 11536960
15:00:47 6305 8192000000 10323577632 10743631872 1370096464 14.328 99 45 90 1 1416492112 33915392 12480256
15:01:59 9824 8192000000 10317704432 10747863040 1377979440 14.398 100 35 70 1 1425659872 38192480 9487952
15:03:24 13246 8192000000 10300417264 10736947200 1362884672 14.263 100 32 64 2 1415858240 43838928 9134640
15:04:47 28959 8192000000 10310998832 10746990592 1360931184 14.246 99 20 40 0 1408130048 41457984 5740880
15:06:28 16426 8192000000 10312472528 10752348160 1365805024 14.289 100 34 68 0 1422173920 46382528 9986368
15:07:51 86091 8192000000 10277069136 10708140032 1313117072 13.814 96 14 28 1 1330784784 14766512 2901200
15:10:20 62348 8192000000 10286618768 10713169920 1346726864 14.118 98 28 56 1 1361273488 7383840 7162784
15:12:28 56520 8192000000 10279497440 10733469696 1363518336 14.269 99 18 36 1 1380242080 12727904 3995840
15:14:25 107031 8192000000 10291699280 10735407104 1377980336 14.398 100 17 34 0 1393920672 12315888 3624448
15:17:23 72143 8192000000 10296883120 10747478016 1379156672 14.409 99 23 45 0 1393150752 8992240 5001840
15:19:36 81653 8192000000 10309046576 10761162752 1402252528 14.615 100 13 25 1 1421487696 16299712 2935456
15:22:10 109535 8192000000 10300246400 10751164416 1384012256 14.452 99 28 56 2 1397233488 6833296 6387936
15:25:06 35193 8192000000 10309441488 10765594624 1402353248 14.616 100 16 32 1 1414419312 8676464 3389600
15:26:43 64141 8192000000 10292213168 10755571712 1400473120 14.599 100 21 41 1 1415779440 10561056 4745264
15:28:51 64955 8192000000 10312389472 10772803584 1406650400 14.654 100 14 28 0 1418741376 9026352 3064624
15:31:47 69718 8192000000 10289221664 10755764224 1340292336 14.060 95 12 24 0 1355556032 12307344 2956352
15:33:57 59258 8192000000 10276774000 10744508416 1386014064 14.470 100 9 18 0 1397612224 9544704 2053456
15:36:08 52854 8192000000 10293614928 10760024064 1387852288 14.487 99 9 18 1 1399176624 8939248 2385088
15:38:02 42676 8192000000 10285318848 10740170752 1357583904 14.216 97 24 48 0 1371292896 8803008 4905984
15:40:16 61211 8192000000 10287929600 10740555776 1348438624 14.133 97 25 49 1 1371829888 17984768 5406496
15:42:20 93209 8192000000 10290595680 10737786880 1319947248 13.876 94 15 30 1 1336076976 13056960 3072768
15:44:54 36211 8192000000 10305751424 10750238720 1353492048 14.179 97 22 44 1 1369272272 10369920 5410304
15:46:30 20195 8192000000 10336941920 10773270528 1402200720 14.615 100 63 127 0 1431086960 14454496 14431744
15:47:53 99791 8192000000 10276284048 10735095808 1294830144 13.648 92 21 42 2 1320276848 20618576 4828128
15:50:33 55095 8192000000 10270695824 10706939904 1331705040 13.983 97 35 69 2 1348305264 8845520 7754704
15:52:35 56516 8192000000 10267464112 10706939904 1339563312 14.053 97 34 68 0 1356857824 9765216 7529296
15:54:32 8773 8192000000 10272170096 10709975040 1356645408 14.207 99 43 87 1 1377071840 10126928 10299504
15:55:45 63053 8192000000 10244806096 10694168576 1314775376 13.829 96 10 20 0 1323971296 6967408 2228512
15:57:52 41261 8192000000 10233750992 10685837312 1304377328 13.735 96 7 14 0 1314198160 8035792 1785040
15:59:35 78394 8192000000 10253238432 10703183872 1340415024 14.061 99 12 24 0 1357199104 14145232 2638848
16:01:54 53978 8192000000 10254154352 10712338432 1337321472 14.033 98 5 10 0 1350859136 12685280 852384
16:03:49 19826 8192000000 10273930656 10709561344 1342634368 14.081 98 37 73 4 1359798848 8066928 9097552
16:05:12 36018 8192000000 10275035456 10720710656 1351885520 14.164 99 16 32 0 1363346592 7797696 3663376
16:06:54 35330 8192000000 10304906432 10747858944 1368571376 14.314 99 43 86 1 1392899088 14540928 9786784
16:08:36 88905 8192000000 10295000256 10738991104 1356708640 14.208 98 9 18 0 1373767440 15344096 1714704
16:11:05 86780 8192000000 10300596640 10774601728 1397334992 14.571 100 12 24 0 1411502608 11838704 2328912
16:13:32 123939 8192000000 10303624896 10754465792 1343806656 14.092 96 15 30 2 1358288176 11259632 3221888
16:16:36 68636 8192000000 10304836848 10756534272 1383946128 14.452 99 27 54 0 1400225744 10685376 5594240
16:18:58 93249 8192000000 10283238512 10749579264 1384112816 14.453 99 18 36 1 1397410064 8902944 4394304
16:21:31 79471 8192000000 10272962976 10738700288 1337531312 14.035 96 17 34 1 1356990080 15413616 4045152
16:23:51 67872 8192000000 10287431504 10745081856 1359128640 14.230 98 31 62 1 1380445504 13681584 7635280
16:26:07 82411 8192000000 10294248336 10750496768 1373193008 14.356 99 26 50 1 1390692960 11305408 6194544
16:28:41 76499 8192000000 10287225200 10746089472 1362612336 14.261 98 21 42 0 1383092160 15352992 5126832
16:30:58 63723 8192000000 10280007216 10739183616 1347080896 14.121 97 13 26 1 1363972016 14137088 2754032
16:33:11 90161 8192000000 10274857632 10744107008 1340600160 14.063 97 12 24 0 1361540288 17765680 3174448
16:35:44 64044 8192000000 10266775344 10730819584 1324771696 13.920 97 21 42 0 1345828032 16255712 4800624
16:37:48 37272 8192000000 9111838976 9346658304 263146992 3.112 20 1 2 0 265028496 1839152 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-05-2022 16:39:14 to 26-05-2022 16:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 194 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-05-2022 16:39:30 to 26-05-2022 16:39:32.

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          243623
MON$OLDEST_ACTIVE               243624
MON$OLDEST_SNAPSHOT             243624
MON$NEXT_TRANSACTION            243624
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                       2030176
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.33596 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33596 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33596 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33596 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 ---
:62: 
:63: mscs-test-161.budzdorov.ru    Thu May 26 16:38:27 2022
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
:66: 
:67: mscs-test-161.budzdorov.ru    Thu May 26 16:38:27 2022
:68:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:69: 
--- end of diff output ---

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

26.05.22 16:39:33. 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-26 16:39:35 No severe PSQL-related problems occured
26.05.22 16:39:37. End of report.