Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2021 19: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 16776416 kB
MemAvailable 31388876 kB
Buffers 0 kB
Cached 14873400 kB
SwapCached 0 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.9
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 2021-12-18 19:37:57
DTS_END                         2021-12-18 19:37:57.3390
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/56534
TRN_ID                          240674
ATT_ID                          655
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: 9060.87 19000 1087304
customer order: creation 178.02 332 21362
customer order: refuse 15.10 326 1812
order to supplier: creation 47.03 1331 5644
order to supplier: removal 3.34 90803 401
invoice (draft): creation 46.79 3416 5615
invoice (draft): removal 49.81 848 5977
invoice accept: apply 43.31 16790 5197
invoice accept: cancel 44.55 814 5346
customer reserve: creation 3989.52 165 478742
customer reserve: removal 4350.30 53 522036
realization accept: apply 86.55 198 10386
realization accept: cancel 76.27 116 9152
payment from customer: creation 60.52 118 7262
payment from customer: removal 5.50 82 660
payment to supplier: creation 48.43 129 5812
payment to supplier: removal 3.43 92 411
service: total inventory turnovers 4.68 1326 562
service: total monetary turnovers 6.81 55 817
service: aggregate perf. data 0.88 16187 106
service: refresh index statistics 0.03 246815 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2021 19:38:57 to 18-12-2021 19:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2138
TEST_TIME 2 4273
TEST_TIME 3 3850
TEST_TIME 4 4253
TEST_TIME 5 3999
TEST_TIME 6 4654
TEST_TIME 7 4741
TEST_TIME 8 5687
TEST_TIME 9 5881
TEST_TIME 10 6407
TEST_TIME 11 6687
TEST_TIME 12 6966
TEST_TIME 13 7448
TEST_TIME 14 8009
TEST_TIME 15 8428
TEST_TIME 16 8473
TEST_TIME 17 8817
TEST_TIME 18 8860
TEST_TIME 19 9129
TEST_TIME 20 9595
TEST_TIME 21 9654
TEST_TIME 22 9566
TEST_TIME 23 9661
TEST_TIME 24 9688
TEST_TIME 25 9576
TEST_TIME 26 9958
TEST_TIME 27 9795
TEST_TIME 28 10674
TEST_TIME 29 10560
TEST_TIME 30 10334
TEST_TIME 31 10373
TEST_TIME 32 10437
TEST_TIME 33 10201
TEST_TIME 34 10174
TEST_TIME 35 10194
TEST_TIME 36 9997
TEST_TIME 37 9876
TEST_TIME 38 9815
TEST_TIME 39 9660
TEST_TIME 40 9506
TEST_TIME 41 9680
TEST_TIME 42 9637
TEST_TIME 43 9523
TEST_TIME 44 9351
TEST_TIME 45 9332
TEST_TIME 46 9356
TEST_TIME 47 9283
TEST_TIME 48 9212
TEST_TIME 49 9126
TEST_TIME 50 9077
TEST_TIME 51 9042
TEST_TIME 52 9123
TEST_TIME 53 9176
TEST_TIME 54 9024
TEST_TIME 55 8990
TEST_TIME 56 8946
TEST_TIME 57 8948
TEST_TIME 58 8918
TEST_TIME 59 8976
TEST_TIME 60 8903
TEST_TIME 61 8898
TEST_TIME 62 8881
TEST_TIME 63 9097
TEST_TIME 64 9074
TEST_TIME 65 9049
TEST_TIME 66 9070
TEST_TIME 67 9182
TEST_TIME 68 9207
TEST_TIME 69 9245
TEST_TIME 70 9194
TEST_TIME 71 9239
TEST_TIME 72 9231
TEST_TIME 73 9275
TEST_TIME 74 9243
TEST_TIME 75 9124
TEST_TIME 76 9129
TEST_TIME 77 9136
TEST_TIME 78 9073
TEST_TIME 79 9090
TEST_TIME 80 9092
TEST_TIME 81 9065
TEST_TIME 82 9165
TEST_TIME 83 9113
TEST_TIME 84 9070
TEST_TIME 85 9009
TEST_TIME 86 8976
TEST_TIME 87 8924
TEST_TIME 88 8923
TEST_TIME 89 8898
TEST_TIME 90 8936
TEST_TIME 91 8981
TEST_TIME 92 8983
TEST_TIME 93 9024
TEST_TIME 94 9009
TEST_TIME 95 9059
TEST_TIME 96 9091
TEST_TIME 97 9120
TEST_TIME 98 9177
TEST_TIME 99 9138
TEST_TIME 100 9162
TEST_TIME 101 9161
TEST_TIME 102 9223
TEST_TIME 103 9233
TEST_TIME 104 9161
TEST_TIME 105 9128
TEST_TIME 106 9131
TEST_TIME 107 9112
TEST_TIME 108 9097
TEST_TIME 109 9057
TEST_TIME 110 9046
TEST_TIME 111 9001
TEST_TIME 112 8963
TEST_TIME 113 8993
TEST_TIME 114 9031
TEST_TIME 115 9041
TEST_TIME 116 9025
TEST_TIME 117 9068
TEST_TIME 118 9057
TEST_TIME 119 9073
TEST_TIME 120 9068

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2021 19:39:01 to 18-12-2021 19:39:04.

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 1530254 1530254 0 0.00 0 23042 6 0 0
doc_list_biud 1530251 1530251 0 0.00 0 59231 25 0 0
sp_add_invoice_to_stock 5268 5197 71 1.34 46 143374 16791 0 71
sp_cancel_adding_invoice 5346 5346 0 0.00 5 62284 814 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 11352 326 0 0
sp_cancel_customer_reserve 522036 522036 0 0.00 1 21136 54 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 7190 82 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 4909 92 0 0
sp_cancel_supplier_invoice 5980 5977 3 0.05 1 59244 849 0 3
sp_cancel_supplier_order 401 401 0 0.00 4 772798 90804 0 0
sp_cancel_write_off 9152 9152 0 0.00 1 21627 117 0 0
sp_client_order 21362 21362 0 0.00 3 16545 333 0 0
sp_customer_reserve 478742 478742 0 0.00 0 35308 166 0 0
sp_fill_shopping_cart 32609 32609 0 0.00 1 21295 43 0 0
sp_fill_shopping_cart_clo_res 478732 478732 0 0.00 0 13702 13 0 0
sp_get_clo_for_invoice 5183 5183 0 0.00 0 27765 320 0 0
sp_kill_cost_storno 15569 15569 0 0.00 0 5494 53 0 0
sp_kill_qstorno_ret_qs2qd 1059602 1059602 0 0.00 0 59226 30 0 0
sp_kill_qty_storno 560290 560290 0 0.00 0 59229 66 0 0
sp_lock_selected_doc 21386 21386 0 0.00 0 9321 7 0 0
sp_make_cost_storno 28641 28641 0 0.00 0 13941 55 0 0
sp_multiply_rows_for_pdistr 28640 28640 0 0.00 0 13245 28 0 0
sp_multiply_rows_for_qdistr 21362 21362 0 0.00 0 16510 143 0 0
sp_pay_from_customer 7262 7262 0 0.00 4 15344 119 0 0
sp_pay_to_supplier 5812 5812 0 0.00 4 16976 129 0 0
sp_payment_common 13074 13074 0 0.00 1 14474 37 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 11345 291 0 0
sp_qd_handle_on_invoice_upd_sts 10526 10526 0 0.00 0 24512 357 0 0
sp_qd_handle_on_reserve_upd_sts 10386 10386 0 0.00 0 21084 78 0 0
sp_reserve_write_off 10386 10386 0 0.00 6 21130 198 0 0
sp_supplier_invoice 5615 5615 0 0.00 6 85483 3417 0 0
sp_supplier_order 5644 5644 0 0.00 5 35513 1331 0 0
srv_aggregate_perf_data 107 106 1 0.93 2796 112007 16188 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1399 129364 48453 0 0
srv_find_qd_qs_mism 1038207 1038207 0 0.00 0 23041 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 45 96730 1326 0 0
srv_make_money_saldo 817 817 0 0.00 1 15647 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 112227 467087 246816 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 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 5197 5197 0 0.00 0 9290 158 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3967 86 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 6376 20 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 3448 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 351 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 1502 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 3567 25 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 24228 1513 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 5405 12 0 0
t$perf-norm:sp_client_order 21362 21362 0 0.00 0 9349 20 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 5819 11 0 0
t$perf-norm:sp_pay_from_customer 7262 7262 0 0.00 0 7641 11 0 0
t$perf-norm:sp_pay_to_supplier 5812 5812 0 0.00 0 5988 12 0 0
t$perf-norm:sp_reserve_write_off 10386 10386 0 0.00 0 19727 12 0 0
t$perf-norm:sp_supplier_invoice 5615 5615 0 0.00 0 5609 21 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 4857 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1886 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 126 2 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 2985 23 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 2271 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10502 10360 142 1.35 0 7150 20 0 142
v_all_customers 38634 38634 0 0.00 0 16 1 0 0
v_all_suppliers 30464 30464 0 0.00 0 18 1 0 0
v_all_wares 42724 42724 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2020 8 0 0
v_cancel_client_order 3624 3624 0 0.00 0 5564 7 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 4161 8 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 3614 9 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 2363 5 0 0
v_cancel_supplier_invoice 762 756 6 0.78 0 3498 24 0 6
v_cancel_supplier_order 802 802 0 0.00 0 462 3 0 0
v_cancel_write_off 2054 2054 0 0.00 0 4474 18 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 1723 2 0 0
v_max_id_clo_res 980 980 0 0.00 0 7420 18 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 4887 4 0 0
v_max_non_paid_invoice 1827 1827 0 0.00 0 2328 4 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 11097 11 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 2250 7 0 0
v_min_id_clo_res 980 980 0 0.00 0 4103 11 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 7702 7 0 0
v_min_non_paid_invoice 5812 5812 0 0.00 0 11005 24 0 0
v_min_non_paid_realizn 7262 7262 0 0.00 0 14121 31 0 0
v_our_firm 10734 10734 0 0.00 0 14 1 0 0
v_reserve_write_off 20770 20770 0 0.00 0 6064 6 0 0
x_lock_depdocs_on_canc_invoice 10945 10945 0 0.00 0 16491 107 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 9744 360 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 1 35229 1245 0 0
x_make_qstorno_1200_2000 5615 5615 0 0.00 2 85468 3256 0 0
x_make_qstorno_2100_3300 478742 478742 0 0.00 0 33725 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2021 19:39:05 to 18-12-2021 19:39:09.

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
17:08:01 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:02 20225 8192000000 9892942448 10201829376 974156640 10.627 100 81 162 0 1001499248 5994320 21348288
17:10:27 33163 8192000000 9938394368 10261807104 1019984976 11.072 100 93 186 0 1052928096 8084272 24858848
17:12:13 12818 8192000000 9976692576 10311725056 1062898208 11.484 100 79 156 2 1092151008 9078032 20174768
17:13:38 9394 8192000000 10025924512 10374033408 1114739952 11.977 100 73 143 2 1141618080 9829600 17048528
17:14:54 6577 8192000000 10086359328 10446204928 1173130128 12.526 100 79 158 1 1203807968 12478656 18199184
17:16:05 4117 8192000000 10131374672 10506571776 1222090144 12.981 100 79 158 1 1252818976 12376976 18351856
17:17:12 8659 8192000000 10163937552 10546253824 1259174080 13.322 100 80 160 0 1289999360 13819296 17005984
17:18:24 2088 8192000000 10202908336 10586140672 1286820656 13.575 100 90 178 2 1320297152 13710368 19766128
17:19:27 1555 8192000000 10212728896 10595074048 1290997136 13.613 100 89 181 1 1322587872 11695712 19895024
17:20:29 4087 8192000000 10197057184 10599927808 1304390976 13.735 100 50 98 1 1322270224 6697552 11181696
17:21:34 46241 8192000000 10192161248 10612363264 1307616784 13.764 100 26 52 0 1319841152 5462240 6762128
17:23:21 6747 8192000000 10215199600 10632855552 1323573152 13.909 100 19 38 1 1332321056 5236736 3511168
17:24:31 6392 8192000000 10218685936 10640392192 1330502560 13.972 100 26 51 3 1343050608 6693088 5854960
17:25:45 66233 8192000000 10214703632 10640375808 1329958224 13.967 100 22 44 0 1340460784 5552208 4950352
17:27:53 7755 8192000000 10212600048 10635087872 1314159456 13.824 99 27 54 0 1330868304 10989328 5719520
17:29:03 16851 8192000000 10170725456 10581196800 1242070688 13.165 96 18 36 1 1256203984 10294784 3838512
17:30:22 78641 8192000000 10139260464 10545274880 1144761248 12.260 90 26 53 1 1155404560 4750000 5893312
17:32:41 4700 8192000000 10136195376 10532773888 1233874464 13.090 99 36 72 2 1248856784 5655968 9326352
17:33:47 6844 8192000000 10169267056 10572226560 1273009808 13.449 100 36 72 3 1287405584 5033808 9361968
17:34:59 16501 8192000000 10172952624 10583838720 1283973808 13.549 100 28 56 3 1296353360 5636672 6742880
17:36:43 11372 8192000000 10179611120 10597896192 1293747680 13.638 100 20 40 1 1304622128 6395184 4479264
17:37:56 17140 8192000000 10211107424 10615648256 1305413808 13.744 100 29 58 0 1318277200 5790992 7072400
17:39:19 2790 8192000000 10253630976 10657890304 1340187536 14.059 100 61 120 2 1367115360 13682960 13244864
17:40:26 17065 8192000000 10273386064 10684424192 1357175056 14.212 100 44 88 0 1382193968 14615472 10403440
17:41:52 56928 8192000000 10284505312 10708176896 1375540320 14.377 100 37 73 4 1401488736 16664432 9283984
17:44:04 31425 8192000000 10288542240 10714963968 1378530592 14.403 100 31 62 2 1403218352 16981504 7706256
17:45:46 2481 8192000000 10330305632 10740891648 1395574144 14.556 100 80 158 2 1433448624 18630752 19243728
17:46:53 6239 8192000000 10312370224 10724605952 1379850288 14.415 99 70 138 1 1420035936 23626432 16559216
17:48:05 8170 8192000000 10310613984 10732064768 1382652016 14.440 100 74 148 3 1425738192 25262272 17823904
17:49:23 7983 8192000000 10323010176 10739503104 1386842192 14.478 100 58 117 1 1428222912 26727488 14653232
17:50:34 1398 8192000000 10328570320 10736803840 1379660896 14.414 99 84 164 1 1429694944 30784432 19249616
17:51:40 2758 8192000000 10307444384 10720800768 1356500144 14.206 98 63 126 1 1393557632 21974832 15082656
17:52:47 15539 8192000000 10287918448 10714451968 1347840512 14.128 98 47 94 2 1378334704 18726608 11767584
17:54:10 2385 8192000000 10308423952 10720366592 1369153760 14.319 100 72 144 0 1412493008 25964768 17374480
17:55:23 4358 8192000000 10300147856 10711318528 1357185296 14.212 100 74 149 2 1402476880 25790352 19501232
17:56:31 7680 8192000000 10290276272 10705997824 1346459392 14.116 100 51 102 1 1386699888 26726864 13513632
17:57:45 6589 8192000000 10273544592 10692403200 1335672800 14.018 99 47 94 1 1370768928 23464048 11632080
17:58:56 11121 8192000000 10276131808 10705965056 1346984864 14.120 99 29 58 2 1379930672 25407168 7538640
18:00:17 21518 8192000000 10281087728 10709475328 1347599824 14.126 99 28 56 1 1380042976 24830224 7612928
18:01:54 10694 8192000000 10282147536 10708910080 1350271440 14.150 99 24 48 0 1381241888 24547376 6423072
18:03:06 24544 8192000000 10300246656 10737336320 1368579104 14.314 100 27 54 0 1410143552 33554864 8009584
18:04:32 26747 8192000000 10307129936 10732138496 1358119776 14.220 100 38 76 1 1406496096 37593264 10783056
18:06:01 20429 8192000000 10273847888 10723917824 1339489536 14.053 98 17 32 2 1369414064 24992224 4932304
18:07:39 62533 8192000000 10281882400 10716262400 1345648592 14.108 99 17 34 0 1369868048 19867056 4352400
18:09:46 59497 8192000000 10280354384 10720735232 1365414528 14.286 100 16 34 0 1382575712 12876624 4284560
18:12:38 71549 8192000000 10288762960 10731122688 1379120064 14.409 100 22 44 0 1395910592 11905072 4885456
18:14:50 64442 8192000000 10298035504 10749321216 1381931072 14.434 99 13 26 0 1395974768 11532336 2511360
18:16:55 53113 8192000000 10311413952 10763526144 1386754288 14.477 99 22 44 0 1410185824 17893712 5537824
18:19:02 36380 8192000000 10290117072 10750881792 1382504688 14.439 99 9 19 0 1399280800 14624800 2151312
18:20:39 79295 8192000000 10287694544 10743021568 1378534192 14.403 99 13 26 0 1394126384 12636592 2955600
18:23:06 67794 8192000000 10284992912 10747219968 1381203584 14.427 99 25 50 0 1395468560 8743760 5521216
18:25:16 62245 8192000000 10295462432 10756202496 1390149776 14.507 100 11 22 0 1407854352 15205248 2499328
18:27:31 35006 8192000000 10305581392 10766344192 1402594608 14.618 100 16 32 1 1422212992 15887520 3730864
18:29:07 69326 8192000000 10294074576 10776129536 1388334752 14.491 99 6 12 0 1414912112 24998096 1579264
18:31:21 93625 8192000000 10294702144 10757795840 1401787008 14.611 100 20 40 0 1412952912 6942800 4223104
18:33:56 90810 8192000000 10286230464 10747936768 1351680592 14.163 97 12 23 0 1363816128 9638304 2497232
18:36:27 65216 8192000000 10307252320 10762371072 1395213360 14.552 100 18 36 0 1408283184 9243376 3826448
18:38:37 57366 8192000000 10312447520 10769997824 1391433136 14.519 99 26 52 1 1414338672 17376960 5528576
18:40:34 70265 8192000000 10275305264 10724241408 1316934752 13.849 95 9 18 1 1331339056 12944000 1460304
18:42:51 67318 8192000000 10289589328 10752806912 1348101040 14.130 98 25 50 0 1375334176 21471792 5761344
18:44:59 129364 8192000000 10253799712 10701078528 1274263408 13.461 93 7 14 0 1294080416 18361392 1455616
18:48:11 61779 8192000000 10263039728 10711474176 1333210640 13.996 98 36 69 1 1353934048 12814704 7908704
18:50:15 91127 8192000000 10251689264 10700263424 1297770544 13.675 95 11 22 0 1309611808 9218032 2623232
18:52:50 108257 8192000000 10264109040 10713321472 1298643440 13.683 95 22 42 1 1318422352 14897680 4881232
18:55:39 28710 8192000000 10280598768 10720243712 1365167824 14.284 100 48 96 0 1392822752 16430336 11224592
18:57:08 92068 8192000000 10256934448 10703958016 1310622448 13.792 95 11 21 1 1326266880 13052672 2591760
18:59:40 49236 8192000000 10228959312 10680279040 1323545520 13.909 98 7 14 1 1335044272 10111760 1386992
19:02:12 53908 8192000000 10258756928 10701918208 1346452112 14.116 99 22 45 2 1357982272 7049280 4480880
19:04:07 75475 8192000000 10267430768 10727211008 1367243824 14.302 100 11 22 0 1377286896 7568688 2474384
19:06:29 72324 8192000000 10277688560 10732990464 1344547296 14.098 98 14 28 1 1365228608 17705872 2975440
19:08:44 46274 8192000000 10285238704 10736029696 1367103584 14.301 99 16 32 1 1380743664 10386832 3253248
19:10:38 53227 8192000000 10304057344 10755911680 1374849920 14.370 98 15 30 2 1389221152 11434208 2937024
19:12:34 65043 8192000000 10318506144 10773442560 1385565456 14.466 98 10 20 0 1401256960 13344112 2347392
19:14:50 102457 8192000000 10313523376 10754936832 1346253712 14.114 96 12 24 0 1360814208 12034288 2526208
19:17:33 46190 8192000000 10299898160 10746425344 1353361056 14.178 97 27 54 0 1370744912 11712624 5671232
19:19:22 106305 8192000000 10295986768 10743898112 1363275024 14.267 98 13 26 0 1380047856 14136816 2636016
19:22:10 91296 8192000000 10298468448 10755706880 1391995808 14.524 100 32 64 1 1406862464 7372672 7493984
19:24:45 59251 8192000000 10284127072 10737438720 1379034496 14.408 98 26 48 1 1394153184 9624576 5494112
19:26:47 55546 8192000000 10292057408 10752843776 1379967136 14.416 99 12 24 1 1398018224 15028784 3022304
19:28:58 52472 8192000000 10275370288 10729914368 1361195872 14.248 98 12 24 0 1380221040 15857152 3168016
19:30:59 63581 8192000000 10266402800 10721529856 1325947216 13.931 96 14 28 0 1346319424 16926224 3445984
19:33:03 31384 8192000000 10269353568 10710380544 1308042736 13.768 95 29 58 1 1323131456 8247232 6841488
19:34:38 51381 8192000000 10250678896 10692702208 1283756320 13.547 95 19 38 1 1299958144 11937936 4263888
19:36:30 54045 8192000000 10271783504 10717585408 1357217424 14.212 100 21 40 1 1372813616 11266720 4329472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-12-2021 19:39:11 to 18-12-2021 19:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 71 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2021 19:39:27 to 18-12-2021 19:39:29.

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          239166
MON$OLDEST_ACTIVE               241333
MON$OLDEST_SNAPSHOT             241333
MON$NEXT_TRANSACTION            241333
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                       2012288
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:06 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
:39: 
:40: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:50: 
:51: 
:52: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:74: 
:75: 
:76: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:78: 
:79: 
:80: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Sat Dec 18 19:38:07 2021
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:86: 
--- end of diff output ---

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

18.12.21 19:39:30. 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
2021-12-18 19:39:32 No severe PSQL-related problems occured
18.12.21 19:39:33. End of report.