Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.12.2021 22: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 16710496 kB
MemAvailable 31440428 kB
Buffers 0 kB
Cached 14929156 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-08 22:37:55
DTS_END                         2021-12-08 22:37:55.1790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(601:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43790
TRN_ID                          236204
ATT_ID                          624
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: 9285.52 19905 1114262
customer order: creation 167.72 341 20126
customer order: refuse 14.26 329 1711
order to supplier: creation 44.43 1359 5332
order to supplier: removal 3.48 103900 417
invoice (draft): creation 44.94 3228 5393
invoice (draft): removal 54.44 778 6533
invoice accept: apply 40.90 19253 4908
invoice accept: cancel 49.33 795 5920
customer reserve: creation 3768.30 192 452196
customer reserve: removal 4806.08 59 576730
realization accept: apply 80.95 162 9714
realization accept: cancel 83.74 110 10049
payment from customer: creation 58.54 101 7025
payment from customer: removal 5.56 77 667
payment to supplier: creation 48.08 117 5770
payment to supplier: removal 3.32 59 398
service: total inventory turnovers 4.34 1269 521
service: total monetary turnovers 6.27 38 752
service: aggregate perf. data 0.80 20621 96
service: refresh index statistics 0.03 245314 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2021 22:38:35 to 08-12-2021 22:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3567
TEST_TIME 2 4044
TEST_TIME 3 4233
TEST_TIME 4 5418
TEST_TIME 5 5702
TEST_TIME 6 5588
TEST_TIME 7 5481
TEST_TIME 8 5938
TEST_TIME 9 5668
TEST_TIME 10 5742
TEST_TIME 11 5689
TEST_TIME 12 5843
TEST_TIME 13 6022
TEST_TIME 14 6099
TEST_TIME 15 6329
TEST_TIME 16 6828
TEST_TIME 17 7050
TEST_TIME 18 7238
TEST_TIME 19 7114
TEST_TIME 20 7325
TEST_TIME 21 7696
TEST_TIME 22 7806
TEST_TIME 23 8449
TEST_TIME 24 8314
TEST_TIME 25 8638
TEST_TIME 26 8837
TEST_TIME 27 9361
TEST_TIME 28 9458
TEST_TIME 29 10410
TEST_TIME 30 10302
TEST_TIME 31 10347
TEST_TIME 32 10179
TEST_TIME 33 10095
TEST_TIME 34 9987
TEST_TIME 35 9870
TEST_TIME 36 9793
TEST_TIME 37 9686
TEST_TIME 38 9568
TEST_TIME 39 9574
TEST_TIME 40 9551
TEST_TIME 41 9505
TEST_TIME 42 9494
TEST_TIME 43 9368
TEST_TIME 44 9273
TEST_TIME 45 9214
TEST_TIME 46 9192
TEST_TIME 47 9268
TEST_TIME 48 9148
TEST_TIME 49 9137
TEST_TIME 50 9128
TEST_TIME 51 9072
TEST_TIME 52 9070
TEST_TIME 53 8944
TEST_TIME 54 8997
TEST_TIME 55 8973
TEST_TIME 56 9041
TEST_TIME 57 9016
TEST_TIME 58 9191
TEST_TIME 59 9187
TEST_TIME 60 9213
TEST_TIME 61 9243
TEST_TIME 62 9237
TEST_TIME 63 9278
TEST_TIME 64 9309
TEST_TIME 65 9374
TEST_TIME 66 9498
TEST_TIME 67 9451
TEST_TIME 68 9638
TEST_TIME 69 9561
TEST_TIME 70 9460
TEST_TIME 71 9406
TEST_TIME 72 9442
TEST_TIME 73 9376
TEST_TIME 74 9386
TEST_TIME 75 9353
TEST_TIME 76 9363
TEST_TIME 77 9365
TEST_TIME 78 9305
TEST_TIME 79 9254
TEST_TIME 80 9250
TEST_TIME 81 9187
TEST_TIME 82 9163
TEST_TIME 83 9166
TEST_TIME 84 9142
TEST_TIME 85 9175
TEST_TIME 86 9175
TEST_TIME 87 9141
TEST_TIME 88 9174
TEST_TIME 89 9240
TEST_TIME 90 9247
TEST_TIME 91 9280
TEST_TIME 92 9292
TEST_TIME 93 9315
TEST_TIME 94 9310
TEST_TIME 95 9342
TEST_TIME 96 9372
TEST_TIME 97 9433
TEST_TIME 98 9400
TEST_TIME 99 9374
TEST_TIME 100 9303
TEST_TIME 101 9277
TEST_TIME 102 9245
TEST_TIME 103 9276
TEST_TIME 104 9238
TEST_TIME 105 9234
TEST_TIME 106 9177
TEST_TIME 107 9129
TEST_TIME 108 9148
TEST_TIME 109 9187
TEST_TIME 110 9192
TEST_TIME 111 9173
TEST_TIME 112 9190
TEST_TIME 113 9176
TEST_TIME 114 9235
TEST_TIME 115 9254
TEST_TIME 116 9235
TEST_TIME 117 9234
TEST_TIME 118 9267
TEST_TIME 119 9321
TEST_TIME 120 9287

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2021 22:38:39 to 08-12-2021 22:38:42.

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 1534923 1534923 0 0.00 0 18354 6 0 0
doc_list_biud 1534920 1534920 0 0.00 0 44425 28 0 0
sp_add_invoice_to_stock 4994 4908 86 1.72 36 164490 19253 0 86
sp_cancel_adding_invoice 5920 5920 0 0.00 4 70898 795 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 10419 330 0 0
sp_cancel_customer_reserve 576730 576730 0 0.00 1 44427 60 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 4410 78 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 1962 60 0 0
sp_cancel_supplier_invoice 6542 6533 9 0.13 2 17604 779 0 9
sp_cancel_supplier_order 417 417 0 0.00 9 1003831 103900 0 0
sp_cancel_write_off 10049 10049 0 0.00 1 9316 110 0 0
sp_client_order 20126 20126 0 0.00 3 15472 341 0 0
sp_customer_reserve 452196 452196 0 0.00 0 65469 193 0 0
sp_fill_shopping_cart 30847 30847 0 0.00 1 12308 35 0 0
sp_fill_shopping_cart_clo_res 452194 452194 0 0.00 0 16231 14 0 0
sp_get_clo_for_invoice 4905 4905 0 0.00 0 21926 278 0 0
sp_kill_cost_storno 17034 17034 0 0.00 0 18293 57 0 0
sp_kill_qstorno_ret_qs2qd 1170459 1170459 0 0.00 0 44421 32 0 0
sp_kill_qty_storno 615976 615976 0 0.00 0 44425 69 0 0
sp_lock_selected_doc 20203 20203 0 0.00 0 5886 3 0 0
sp_make_cost_storno 27414 27414 0 0.00 0 11680 47 0 0
sp_multiply_rows_for_pdistr 27411 27411 0 0.00 0 7703 28 0 0
sp_multiply_rows_for_qdistr 20126 20126 0 0.00 0 14428 152 0 0
sp_pay_from_customer 7025 7025 0 0.00 4 7757 101 0 0
sp_pay_to_supplier 5770 5770 0 0.00 4 9326 118 0 0
sp_payment_common 12795 12795 0 0.00 2 6981 22 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 10413 302 0 0
sp_qd_handle_on_invoice_upd_sts 10823 10823 0 0.00 0 25392 341 0 0
sp_qd_handle_on_reserve_upd_sts 9713 9713 0 0.00 0 10362 56 0 0
sp_reserve_write_off 9714 9714 0 0.00 6 14523 162 0 0
sp_supplier_invoice 5393 5393 0 0.00 5 72271 3229 0 0
sp_supplier_order 5332 5332 0 0.00 5 45616 1360 0 0
srv_aggregate_perf_data 97 96 1 1.03 3059 191561 20622 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2551 103693 43833 0 0
srv_find_qd_qs_mism 1068521 1068521 0 0.00 0 8512 6 0 0
srv_make_invnt_saldo 521 521 0 0.00 41 93587 1269 0 0
srv_make_money_saldo 752 752 0 0.00 1 4897 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 145197 405108 245315 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 4908 4908 0 0.00 0 16995 188 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 3016 84 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 1646 11 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 4269 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 10335 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 10254 30 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 3543 27 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 21151 1958 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1029 5 0 0
t$perf-norm:sp_client_order 20126 20126 0 0.00 0 14940 22 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 2226 9 0 0
t$perf-norm:sp_pay_from_customer 7025 7025 0 0.00 0 11225 13 0 0
t$perf-norm:sp_pay_to_supplier 5770 5770 0 0.00 0 4237 11 0 0
t$perf-norm:sp_reserve_write_off 9714 9714 0 0.00 0 8772 11 0 0
t$perf-norm:sp_supplier_invoice 5393 5393 0 0.00 0 12080 29 0 0
t$perf-norm:sp_supplier_order 5332 5332 0 0.00 0 10956 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 724 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1310 21 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 1060 22 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 3146 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 9970 9798 172 1.72 0 6044 19 0 172
v_all_customers 36806 36806 0 0.00 0 18 1 0 0
v_all_suppliers 29902 29902 0 0.00 0 40 1 0 0
v_all_wares 40248 40248 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1813 8 0 0
v_cancel_client_order 3422 3422 0 0.00 0 4401 8 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 3380 8 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 4393 6 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1316 5 0 0
v_cancel_supplier_invoice 786 768 18 2.29 0 1695 18 0 18
v_cancel_supplier_order 834 834 0 0.00 0 275 3 0 0
v_cancel_write_off 1904 1904 0 0.00 0 1571 13 0 0
v_max_id_clo_ord 5332 5332 0 0.00 0 7455 3 0 0
v_max_id_clo_res 928 928 0 0.00 0 1321 13 0 0
v_max_id_ord_sup 5391 5391 0 0.00 0 3679 4 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 1598 3 0 0
v_max_non_paid_realizn 3687 3687 0 0.00 0 808 3 0 0
v_min_id_clo_ord 5332 5332 0 0.00 0 3690 8 0 0
v_min_id_clo_res 928 928 0 0.00 0 3587 10 0 0
v_min_id_ord_sup 5391 5391 0 0.00 0 4614 4 0 0
v_min_non_paid_invoice 5770 5770 0 0.00 0 6977 19 0 0
v_min_non_paid_realizn 7025 7025 0 0.00 0 6129 15 0 0
v_our_firm 10118 10118 0 0.00 0 13 1 0 0
v_reserve_write_off 19426 19426 0 0.00 0 5550 8 0 0
x_lock_depdocs_on_canc_invoice 12069 12069 0 0.00 0 21356 114 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 6499 366 0 0
x_make_qstorno_1000_1200 5332 5332 0 0.00 1 45605 1276 0 0
x_make_qstorno_1200_2000 5393 5393 0 0.00 2 72259 3112 0 0
x_make_qstorno_2100_3300 452196 452196 0 0.00 0 65467 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2021 22:38:43 to 08-12-2021 22:38:47.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:07:58 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 26891 8192000000 9913241760 10226135040 994616816 10.826 100 89 178 0 1023205808 5856928 22732064
20:10:32 14532 8192000000 9948926336 10272305152 1032692784 11.194 100 88 176 0 1063778416 8675152 22410480
20:11:51 19609 8192000000 9991428672 10327937024 1069190960 11.544 100 86 174 1 1100303024 9927968 21184096
20:13:17 8576 8192000000 10036755488 10386272256 1123557904 12.061 100 77 154 0 1153130608 11303360 18269344
20:14:28 6098 8192000000 10076970448 10436194304 1163616384 12.437 100 79 159 1 1193937280 12246032 18074864
20:15:39 7840 8192000000 10117539568 10482913280 1211485744 12.883 100 80 160 0 1242358816 13564624 17308448
20:16:50 8583 8192000000 10156152080 10530979840 1244032656 13.183 100 82 163 2 1276977888 14895184 18050048
20:18:01 2425 8192000000 10201726032 10584186880 1284479936 13.554 100 88 175 0 1316564288 11523760 20560592
20:19:05 9547 8192000000 10214980224 10602401792 1298735072 13.684 100 79 160 1 1329721792 13294784 17691936
20:20:15 3817 8192000000 10200634704 10607910912 1307739600 13.766 100 40 80 0 1322987584 5887504 9360480
20:21:22 9922 8192000000 10206188144 10616958976 1315943824 13.840 100 29 58 1 1328286672 5986912 6355936
20:22:33 23342 8192000000 10215571440 10641076224 1327484912 13.944 100 26 52 2 1339620672 5479280 6656480
20:24:39 5205 8192000000 10238582096 10657214464 1339323696 14.051 100 36 72 2 1355047136 7555216 8168224
20:25:47 8287 8192000000 10233832816 10653872128 1330302512 13.970 99 26 51 0 1340898208 5109872 5485824
20:26:56 16356 8192000000 10197892688 10617085952 1267930368 13.403 95 15 31 0 1276571696 5370992 3270336
20:28:17 17585 8192000000 10201446784 10623610880 1307500016 13.763 99 50 99 2 1325687008 6300144 11886848
20:29:35 6465 8192000000 10158161920 10561159168 1245002560 13.192 98 29 58 0 1257937648 6831344 6103744
20:30:43 7753 8192000000 10142757168 10546532352 1225321968 13.011 97 28 56 0 1237664784 5445328 6897488
20:31:52 7640 8192000000 10132249248 10532401152 1221348112 12.974 98 17 34 0 1230754080 5661040 3744928
20:33:03 20964 8192000000 10136308112 10541350912 1237342992 13.122 98 25 50 0 1248340576 5214672 5782912
20:34:25 7396 8192000000 10168198256 10580815872 1269433232 13.416 99 22 43 2 1280800272 5921792 5445248
20:35:34 9169 8192000000 10197347552 10603991040 1300887632 13.703 100 21 42 1 1311538912 4965696 5685584
20:36:44 62610 8192000000 10214186800 10631479296 1318114960 13.860 100 30 61 1 1334917856 10114560 6688336
20:38:55 18154 8192000000 10253576800 10667532288 1346861408 14.119 100 48 96 2 1367361328 9565200 10934720
20:40:19 20912 8192000000 10272593968 10686898176 1362347712 14.258 100 51 102 0 1384259824 10196112 11716000
20:41:43 10818 8192000000 10283012720 10703368192 1377298560 14.392 100 53 105 1 1400254720 10622000 12334160
20:43:00 6440 8192000000 10290449376 10712788992 1381978000 14.434 100 48 94 0 1403244432 10641472 10624960
20:44:12 15772 8192000000 10304319536 10733858816 1391812960 14.522 100 46 91 2 1420406880 17619584 10974336
20:45:33 16129 8192000000 10322163008 10748764160 1403596640 14.627 100 51 100 2 1432475888 16879824 11999424
20:46:57 29310 8192000000 10332505712 10770206720 1416023456 14.737 100 39 78 2 1447152976 21511152 9618368
20:48:32 3269 8192000000 10349974592 10776649728 1414093840 14.720 99 58 115 2 1450968320 22819776 14054704
20:49:40 2793 8192000000 10359712416 10773118976 1417736272 14.753 100 84 168 0 1465882256 28491952 19654032
20:50:47 9271 8192000000 10353914160 10784030720 1425300976 14.820 100 47 93 1 1462616720 25968800 11346944
20:52:03 5534 8192000000 10359141120 10776469504 1413109776 14.712 100 76 149 0 1460327040 28203104 19014160
20:53:14 5160 8192000000 10355318896 10774847488 1401403792 14.607 99 69 138 0 1450243248 30561136 18278320
20:54:25 7285 8192000000 10366799520 10779774976 1417295280 14.749 100 73 146 2 1466525056 31138688 18091088
20:55:34 3684 8192000000 10315085392 10731290624 1378998256 14.408 100 45 89 0 1423654512 32409808 12246448
20:56:44 3560 8192000000 10340755040 10745016320 1382338656 14.437 100 81 158 3 1441629056 38561056 20729344
20:57:54 2547 8192000000 10351740592 10758512640 1390635824 14.512 100 85 167 1 1453377920 41629728 21112368
20:59:00 15593 8192000000 10328406544 10746400768 1374059232 14.363 99 45 89 0 1429724960 43133360 12532368
21:00:34 15491 8192000000 10313799728 10744561664 1357102192 14.211 98 43 86 2 1409297248 40562112 11632944
21:01:52 12471 8192000000 10339785536 10760314880 1387125552 14.480 100 44 88 1 1442769296 43350352 12293392
21:03:12 14021 8192000000 10319364688 10751041536 1374526464 14.368 100 32 64 0 1431346240 47129312 9690464
21:04:35 17581 8192000000 10280922048 10714480640 1325411920 13.926 98 30 61 2 1372302096 38937584 7952592
21:05:59 85307 8192000000 10285372704 10737860608 1350540656 14.152 99 14 28 1 1394847216 40512544 3794016
21:08:47 50671 8192000000 10266448256 10702602240 1324766176 13.920 97 13 26 0 1337256800 9340000 3150624
21:10:46 53370 8192000000 10270523728 10708635648 1347491280 14.125 99 43 84 3 1363685600 5865200 10329120
21:12:54 17495 8192000000 10259863920 10712309760 1364223504 14.275 100 5 10 0 1373024208 8010144 790560
21:14:12 51501 8192000000 10281673472 10725158912 1364468048 14.277 99 35 68 1 1383458880 11088912 7901920
21:16:06 75026 8192000000 10283360640 10742300672 1375833568 14.379 100 13 26 0 1401892192 23072304 2986320
21:18:21 56033 8192000000 10301647312 10744438784 1383615568 14.449 100 26 52 0 1404027200 14251936 6159696
21:20:26 63976 8192000000 10297904144 10746736640 1388846800 14.496 100 29 59 0 1413568544 18297808 6423936
21:22:31 61023 8192000000 10287786624 10752585728 1372278496 14.347 99 23 46 0 1395094208 17601232 5214480
21:24:35 53444 8192000000 10294811360 10762469376 1386356720 14.473 100 12 23 3 1411140608 22225488 2558400
21:26:33 73583 8192000000 10294348992 10761244672 1397670640 14.574 100 8 16 0 1409269888 9682896 1916352
21:29:15 40172 8192000000 10290203808 10763161600 1395188240 14.552 100 20 40 0 1410322304 10696576 4437488
21:30:58 74139 8192000000 10292102608 10769297408 1386597472 14.475 99 11 22 0 1406915072 17677120 2640480
21:33:22 72283 8192000000 10300915152 10765086720 1368566160 14.314 98 10 20 1 1387670704 16306032 2798512
21:35:40 48866 8192000000 10301395168 10748452864 1359391536 14.232 97 36 68 2 1376602928 8665280 8546112
21:37:31 58375 8192000000 10310833216 10762768384 1388475360 14.492 99 29 58 1 1403703216 9154736 6073120
21:39:31 97250 8192000000 10283845552 10744356864 1315735152 13.838 94 13 26 0 1330259520 11935648 2588720
21:42:53 41459 8192000000 10271082416 10736267264 1341331088 14.069 98 11 22 1 1368626512 24854112 2441312
21:44:36 84148 8192000000 10292719408 10762870784 1369112752 14.319 99 12 24 0 1397359600 25408352 2838496
21:48:06 72917 8192000000 10290145152 10738376704 1367023840 14.300 99 20 40 2 1391647424 19868192 4755392
21:50:20 70290 8192000000 10238687360 10701570048 1273733616 13.456 93 11 22 0 1295109376 18583392 2792368
21:52:51 77633 8192000000 10250659856 10714791936 1323310384 13.907 97 12 24 0 1342878592 16363824 3204384
21:55:14 82745 8192000000 10262459632 10701070336 1299063104 13.687 95 21 42 1 1312429344 8149264 5216976
21:57:49 51963 8192000000 10264263296 10711560192 1362641056 14.261 100 11 22 1 1372328880 7385536 2302288
21:59:41 55191 8192000000 10261900432 10721738752 1359298288 14.231 99 38 75 1 1387662208 18984560 9379360
22:01:37 50328 8192000000 10253638432 10719895552 1345974624 14.111 99 22 44 1 1366157584 14677584 5505376
22:03:29 38368 8192000000 10268035264 10720702464 1351906080 14.165 99 18 36 1 1367552736 11333136 4313520
22:05:22 59918 8192000000 10244552208 10702311424 1291234656 13.615 95 15 30 0 1308228704 13034000 3960048
22:07:27 24669 8192000000 10280207888 10716635136 1350770672 14.154 99 43 86 0 1373779344 14247312 8761360
22:08:51 64734 8192000000 10279078912 10723135488 1339811616 14.056 98 22 44 0 1355882592 11762224 4308752
22:10:57 26496 8192000000 10287260912 10724929536 1359886688 14.236 99 32 64 2 1374554208 7873728 6793792
22:12:24 103693 8192000000 10303329552 10759094272 1369940288 14.327 99 24 48 1 1391850864 16472544 5438032
22:15:08 72254 8192000000 10295133584 10741219328 1344839552 14.101 97 13 26 0 1360381808 12684640 2857616
22:17:20 78957 8192000000 10276765440 10721812480 1366559216 14.296 99 40 80 0 1386752320 11051168 9141936
22:19:41 16811 8192000000 10295616832 10741649408 1384127520 14.453 100 35 69 1 1399817200 7913920 7775760
22:21:05 63840 8192000000 10288419296 10750779392 1382080880 14.435 99 18 35 1 1396563408 10362544 4119984
22:23:08 50846 8192000000 10300219088 10753249280 1383560592 14.448 99 11 22 0 1398612096 12562640 2488864
22:25:00 81211 8192000000 10278833744 10738987008 1343811296 14.092 97 21 43 1 1365338656 16469360 5058000
22:27:22 51458 8192000000 10295484416 10751598592 1374297584 14.366 99 23 46 2 1388704048 8538272 5868192
22:29:13 22372 8192000000 10289825552 10739482624 1366347056 14.294 98 27 54 0 1381636064 9120480 6168528
22:30:49 76020 8192000000 10294981440 10762522624 1370040032 14.327 99 11 22 0 1392205088 19634960 2530096
22:33:08 24844 8192000000 10297484256 10741563392 1373804576 14.361 99 23 46 1 1387065408 8136272 5124560
22:34:36 84234 8192000000 10285291552 10733297664 1318918064 13.867 95 17 34 0 1336024944 13203152 3903728
22:37:06 53399 8192000000 9206600080 9459924992 346034000 4.052 25 10 20 0 353924384 5933184 1957200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2021 22:38:49 to 08-12-2021 22:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 86 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2021 22:39:05 to 08-12-2021 22:39:07.

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          237016
MON$OLDEST_ACTIVE               237017
MON$OLDEST_SNAPSHOT             237017
MON$NEXT_TRANSACTION            237017
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                       2023808
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.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:124: 
:125: mscs-test-161.budzdorov.ru    Wed Dec  8 22:38:05 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Wed Dec  8 22:38:05 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Wed Dec  8 22:38:05 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

08.12.21 22:39:08. 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-08 22:39:10 No severe PSQL-related problems occured
08.12.21 22:39:11. End of report.