Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.11.2021 13:37
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 16521480 kB
MemAvailable 31596508 kB
Buffers 0 kB
Cached 15142208 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-11-17 13:37:09
DTS_END                         2021-11-17 13:37:09.4470
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(68:17)
IP                              ::1/33786
TRN_ID                          240249
ATT_ID                          626
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: 9261.94 21137 1111433
customer order: creation 173.86 406 20863
customer order: refuse 14.93 309 1791
order to supplier: creation 46.00 1454 5520
order to supplier: removal 3.31 116024 397
invoice (draft): creation 46.83 3686 5619
invoice (draft): removal 53.15 940 6378
invoice accept: apply 42.43 18355 5091
invoice accept: cancel 47.51 825 5701
customer reserve: creation 3866.23 183 463947
customer reserve: removal 4682.30 64 561876
realization accept: apply 83.13 178 9975
realization accept: cancel 74.55 122 8946
payment from customer: creation 59.75 119 7170
payment from customer: removal 5.28 85 633
payment to supplier: creation 48.67 159 5840
payment to supplier: removal 3.28 112 393
service: total inventory turnovers 3.62 3715 434
service: total monetary turnovers 6.27 589 752
service: aggregate perf. data 0.86 17244 103
service: refresh index statistics 0.03 258174 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 13:38:02 to 17-11-2021 13:38:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9016
TEST_TIME 2 5715
TEST_TIME 3 6180
TEST_TIME 4 6686
TEST_TIME 5 6525
TEST_TIME 6 6986
TEST_TIME 7 6873
TEST_TIME 8 6886
TEST_TIME 9 6904
TEST_TIME 10 6925
TEST_TIME 11 6741
TEST_TIME 12 6689
TEST_TIME 13 7005
TEST_TIME 14 7075
TEST_TIME 15 7153
TEST_TIME 16 7425
TEST_TIME 17 7632
TEST_TIME 18 7849
TEST_TIME 19 7916
TEST_TIME 20 8161
TEST_TIME 21 9178
TEST_TIME 22 9015
TEST_TIME 23 9178
TEST_TIME 24 9923
TEST_TIME 25 9806
TEST_TIME 26 9952
TEST_TIME 27 9907
TEST_TIME 28 11449
TEST_TIME 29 11398
TEST_TIME 30 11305
TEST_TIME 31 11242
TEST_TIME 32 11322
TEST_TIME 33 11228
TEST_TIME 34 11297
TEST_TIME 35 11291
TEST_TIME 36 11005
TEST_TIME 37 10746
TEST_TIME 38 10589
TEST_TIME 39 10596
TEST_TIME 40 10550
TEST_TIME 41 10424
TEST_TIME 42 10432
TEST_TIME 43 10319
TEST_TIME 44 10295
TEST_TIME 45 10194
TEST_TIME 46 10230
TEST_TIME 47 10068
TEST_TIME 48 10027
TEST_TIME 49 10001
TEST_TIME 50 10000
TEST_TIME 51 9913
TEST_TIME 52 10016
TEST_TIME 53 9960
TEST_TIME 54 10026
TEST_TIME 55 9969
TEST_TIME 56 10028
TEST_TIME 57 9931
TEST_TIME 58 10129
TEST_TIME 59 10205
TEST_TIME 60 10216
TEST_TIME 61 10165
TEST_TIME 62 10069
TEST_TIME 63 9936
TEST_TIME 64 9903
TEST_TIME 65 9857
TEST_TIME 66 9788
TEST_TIME 67 9806
TEST_TIME 68 9743
TEST_TIME 69 9680
TEST_TIME 70 9650
TEST_TIME 71 9630
TEST_TIME 72 9611
TEST_TIME 73 9542
TEST_TIME 74 9543
TEST_TIME 75 9588
TEST_TIME 76 9712
TEST_TIME 77 9671
TEST_TIME 78 9644
TEST_TIME 79 9661
TEST_TIME 80 9749
TEST_TIME 81 9777
TEST_TIME 82 9777
TEST_TIME 83 9677
TEST_TIME 84 9612
TEST_TIME 85 9575
TEST_TIME 86 9517
TEST_TIME 87 9523
TEST_TIME 88 9477
TEST_TIME 89 9443
TEST_TIME 90 9406
TEST_TIME 91 9384
TEST_TIME 92 9425
TEST_TIME 93 9424
TEST_TIME 94 9404
TEST_TIME 95 9411
TEST_TIME 96 9444
TEST_TIME 97 9417
TEST_TIME 98 9452
TEST_TIME 99 9500
TEST_TIME 100 9498
TEST_TIME 101 9484
TEST_TIME 102 9563
TEST_TIME 103 9587
TEST_TIME 104 9515
TEST_TIME 105 9448
TEST_TIME 106 9437
TEST_TIME 107 9382
TEST_TIME 108 9353
TEST_TIME 109 9293
TEST_TIME 110 9287
TEST_TIME 111 9257
TEST_TIME 112 9301
TEST_TIME 113 9290
TEST_TIME 114 9266
TEST_TIME 115 9243
TEST_TIME 116 9298
TEST_TIME 117 9338
TEST_TIME 118 9318
TEST_TIME 119 9341
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-11-2021 13:38:05 to 17-11-2021 13:38:09.

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 1540457 1540457 0 0.00 0 14510 7 0 0
doc_list_biud 1540455 1540455 0 0.00 0 39693 30 0 0
sp_add_invoice_to_stock 5223 5091 132 2.52 23 222762 18355 0 132
sp_cancel_adding_invoice 5701 5701 0 0.00 6 50333 826 0 0
sp_cancel_client_order 1791 1791 0 0.00 4 5934 310 0 0
sp_cancel_customer_reserve 561876 561876 0 0.00 1 34310 64 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 3 9765 85 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 6229 113 0 0
sp_cancel_supplier_invoice 6394 6378 16 0.25 2 19618 941 0 16
sp_cancel_supplier_order 397 397 0 0.00 5 1114884 116025 0 0
sp_cancel_write_off 8946 8946 0 0.00 1 11445 122 0 0
sp_client_order 20863 20863 0 0.00 4 32036 407 0 0
sp_customer_reserve 463947 463947 0 0.00 0 169037 184 0 0
sp_fill_shopping_cart 31976 31976 0 0.00 1 29037 49 0 0
sp_fill_shopping_cart_clo_res 463939 463939 0 0.00 0 13567 16 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 19835 316 0 0
sp_kill_cost_storno 15673 15673 0 0.00 0 8598 59 0 0
sp_kill_qstorno_ret_qs2qd 1139473 1139473 0 0.00 0 34172 35 0 0
sp_kill_qty_storno 600146 600146 0 0.00 0 39692 75 0 0
sp_lock_selected_doc 20816 20816 0 0.00 0 6375 4 0 0
sp_make_cost_storno 28067 28067 0 0.00 0 13020 54 0 0
sp_multiply_rows_for_pdistr 28067 28067 0 0.00 0 9609 32 0 0
sp_multiply_rows_for_qdistr 20863 20863 0 0.00 0 16125 213 0 0
sp_pay_from_customer 7170 7170 0 0.00 4 10848 119 0 0
sp_pay_to_supplier 5840 5840 0 0.00 5 16349 160 0 0
sp_payment_common 13010 13010 0 0.00 1 7099 24 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 0 5921 277 0 0
sp_qd_handle_on_invoice_upd_sts 10783 10783 0 0.00 0 39692 370 0 0
sp_qd_handle_on_reserve_upd_sts 9975 9975 0 0.00 0 12433 53 0 0
sp_reserve_write_off 9975 9975 0 0.00 6 20268 179 0 0
sp_supplier_invoice 5619 5619 0 0.00 7 152400 3686 0 0
sp_supplier_order 5520 5520 0 0.00 6 29891 1454 0 0
srv_aggregate_perf_data 104 103 1 0.96 3130 309554 17244 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1463 140263 42003 0 0
srv_find_qd_qs_mism 1063298 1063298 0 0.00 0 14509 6 0 0
srv_make_invnt_saldo 434 434 0 0.00 26 103934 3715 0 0
srv_make_money_saldo 752 752 0 0.00 1 11560 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 141668 475779 258175 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 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 5091 5091 0 0.00 0 8962 166 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 4702 91 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 17478 25 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 5983 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 646 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 8907 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 389 10 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 20619 1640 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 2082 7 0 0
t$perf-norm:sp_client_order 20863 20863 0 0.00 0 8587 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 1164 5 0 0
t$perf-norm:sp_pay_from_customer 7170 7170 0 0.00 0 6414 10 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 2251 6 0 0
t$perf-norm:sp_reserve_write_off 9975 9975 0 0.00 0 17431 17 0 0
t$perf-norm:sp_supplier_invoice 5619 5619 0 0.00 0 4805 18 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 6114 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 245 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 133 3 0 0
t$perf-norm:srv_make_invnt_saldo 434 434 0 0.00 0 1424 8 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 5754 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10428 10164 264 2.53 0 6912 20 0 264
v_all_customers 38086 38086 0 0.00 0 28 1 0 0
v_all_suppliers 30663 30663 0 0.00 0 21 1 0 0
v_all_wares 41724 41724 0 0.00 0 56 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 837 8 0 0
v_cancel_client_order 3582 3582 0 0.00 0 5457 9 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 2950 9 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 4318 9 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 6209 19 0 0
v_cancel_supplier_invoice 888 856 32 3.60 0 3074 21 0 32
v_cancel_supplier_order 794 794 0 0.00 0 824 5 0 0
v_cancel_write_off 2014 2014 0 0.00 0 3667 15 0 0
v_max_id_clo_ord 5514 5514 0 0.00 0 382 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3031 23 0 0
v_max_id_ord_sup 5599 5599 0 0.00 0 4199 4 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 1112 3 0 0
v_max_non_paid_realizn 3804 3804 0 0.00 0 1914 3 0 0
v_min_id_clo_ord 5514 5514 0 0.00 0 4283 7 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1709 5 0 0
v_min_id_ord_sup 5599 5599 0 0.00 0 3502 6 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 6789 16 0 0
v_min_non_paid_realizn 7170 7170 0 0.00 0 6355 18 0 0
v_our_firm 10368 10368 0 0.00 0 82 1 0 0
v_reserve_write_off 19949 19949 0 0.00 0 8309 10 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 9240 106 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 12697 411 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 2 21554 1361 0 0
x_make_qstorno_1200_2000 5619 5619 0 0.00 3 152387 3502 0 0
x_make_qstorno_2100_3300 463946 463946 0 0.00 0 165995 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 13:38:10 to 17-11-2021 13:38:14.

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
11:07:13 2 8192000000 8797079840 8807698432 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 38638 8192000000 9727637776 10023190528 802337408 8.920 100 93 186 1 832684384 7695792 22651184
11:10:05 16873 8192000000 9760245216 10068312064 838436048 9.284 100 88 176 0 872975184 13168544 21370592
11:11:25 3331 8192000000 9790375248 10106183680 862811776 9.528 100 82 165 1 903561200 21492560 19256864
11:12:34 17934 8192000000 9862648288 10194149376 921165072 10.108 100 72 144 0 972389040 36024000 15199968
11:13:56 7149 8192000000 9925598416 10268004352 961836896 10.507 100 81 161 1 1034898128 56026016 17035216
11:15:07 5638 8192000000 9977044240 10333204480 1005914864 10.936 100 84 165 1 1087527232 65082304 16530064
11:16:14 2824 8192000000 10000726928 10362785792 1025368128 11.124 100 81 156 1 1116448880 75714960 15365792
11:17:18 2240 8192000000 10042441376 10407702528 1052433968 11.384 100 90 180 1 1148168912 77531776 18203168
11:18:23 3422 8192000000 10018604688 10393571328 1066735296 11.521 100 79 155 1 1128020128 45612432 15672400
11:19:27 11892 8192000000 10000459840 10401443840 1071054848 11.562 100 56 111 0 1121226320 38395600 11775872
11:20:39 8225 8192000000 10001140768 10382106624 1072770912 11.579 100 74 150 0 1097098544 7994800 16332832
11:21:48 5616 8192000000 10006207040 10409021440 1080991088 11.657 100 29 58 0 1122198304 35372256 5834960
11:22:57 6980 8192000000 10015303792 10420908032 1091960432 11.761 100 33 66 1 1131243888 32799840 6483616
11:24:08 64407 8192000000 10004820128 10417217536 1089147760 11.735 99 29 57 2 1118243216 23380688 5714768
11:26:16 7518 8192000000 10030765744 10442059776 1104472688 11.880 100 35 71 2 1158331120 46737408 7121024
11:27:24 5079 8192000000 10011972320 10406739968 1076442128 11.614 99 48 94 1 1118214816 30130624 11642064
11:28:29 5030 8192000000 9950272240 10333868032 999055008 10.869 95 32 66 0 1028399328 22464336 6879984
11:29:35 23683 8192000000 9962179520 10364817408 1041167664 11.276 100 26 52 2 1076889968 30653664 5068640
11:30:59 4480 8192000000 9934129792 10306805760 993768016 10.818 98 56 113 1 1036073088 29022112 13282960
11:32:05 9478 8192000000 9944681648 10335391744 1018190496 11.055 99 26 52 1 1043961264 19639600 6131168
11:33:18 5842 8192000000 9951969184 10339377152 1044940000 11.312 100 36 75 1 1063803936 9914736 8949200
11:34:26 31764 8192000000 9956727696 10346708992 1040664192 11.271 99 22 42 0 1059052512 13893344 4494976
11:36:01 2751 8192000000 9984028256 10370150400 1059128368 11.448 100 53 103 1 1084725648 12834944 12762336
11:37:04 6888 8192000000 9968204736 10370727936 1061632272 11.472 99 40 80 1 1086627088 15950272 9044544
11:38:18 95334 8192000000 10040018864 10443804672 1099361808 11.832 100 46 92 0 1148117680 38608064 10147808
11:41:05 10118 8192000000 10086343536 10496794624 1117418672 12.003 100 55 107 1 1197144624 67713536 12012416
11:42:19 6124 8192000000 10104335520 10505269248 1132582352 12.146 100 56 112 1 1209325936 64821168 11922416
11:43:29 20541 8192000000 10116630768 10541744128 1137612432 12.193 100 47 91 1 1246199584 99290480 9296672
11:44:54 5090 8192000000 10156928256 10573869056 1147748128 12.288 100 52 103 0 1258456992 99713552 10995312
11:46:04 5515 8192000000 10176523456 10583212032 1158313536 12.387 100 64 128 1 1286778688 114431472 14033680
11:47:15 5683 8192000000 10217288096 10638008320 1157087408 12.376 100 69 138 1 1346223712 173794608 15341696
11:48:30 3371 8192000000 10259536192 10654797824 1146622656 12.278 100 74 146 1 1356981728 194349456 16009616
11:49:36 4458 8192000000 10273197152 10673512448 1126762448 12.091 99 64 125 1 1368831152 227350880 14717824
11:50:45 3384 8192000000 10252552144 10654003200 1111814224 11.950 99 72 139 1 1343872208 215408656 16649328
11:51:54 2894 8192000000 10293434880 10681749504 1118256208 12.011 99 83 165 1 1387566304 250057760 19252336
11:53:03 2161 8192000000 10348583200 10738110464 1144456896 12.257 100 84 167 0 1454356128 290925072 18974160
11:54:11 1460 8192000000 10399189696 10787618816 1146157472 12.273 100 92 183 1 1494250576 326555152 21537952
11:55:16 1610 8192000000 10432222256 10829414400 1148946592 12.300 100 88 174 4 1531113952 360713552 21453808
11:56:21 11882 8192000000 10428778608 10859761664 1133144576 12.151 100 60 121 0 1531723168 382197424 16381168
11:57:38 5323 8192000000 10442613488 10860396544 1128810592 12.110 100 56 111 1 1552087776 409332976 13944208
11:58:47 15372 8192000000 10364892016 10773852160 1132365920 12.144 99 38 75 1 1451054592 309417344 9271328
12:00:05 6446 8192000000 10417505152 10849144832 1134566640 12.164 100 42 84 0 1516880192 371140608 11172944
12:01:13 8681 8192000000 10413962192 10828996608 1127962880 12.102 100 47 94 0 1500899888 361305280 11631728
12:02:24 30305 8192000000 10370526688 10796052480 1123104144 12.056 100 25 50 1 1457155296 327481072 6570080
12:04:00 14413 8192000000 10372626560 10853285888 1120840224 12.035 100 32 63 1 1476537264 347622992 8074048
12:05:15 25171 8192000000 10111387600 10685792256 1106415680 11.898 100 22 44 0 1280577408 168870560 5291168
12:07:41 38515 8192000000 10084137600 10532958208 1117148368 12.000 99 15 30 1 1172997648 52672464 3176816
12:09:25 36789 8192000000 10060508384 10492538880 1119852400 12.026 99 27 52 2 1154696672 29955792 4888480
12:11:08 31167 8192000000 10096450912 10571968512 1136847808 12.186 100 16 32 1 1201848896 61297712 3703376
12:12:41 99391 8192000000 10091880208 10550009856 1115150976 11.981 98 29 58 1 1193038464 71840304 6047184
12:16:11 56899 8192000000 10090808672 10557845504 1136566704 12.183 99 21 42 0 1205417760 64931840 3919216
12:18:12 25994 8192000000 10110286288 10557382656 1142308928 12.237 100 20 40 1 1212302288 65345840 4647520
12:19:52 117696 8192000000 10124133152 10600493056 1123169408 12.057 98 16 32 2 1200350512 73162080 4019024
12:24:11 47641 8192000000 10137684704 10624860160 1150113984 12.311 100 10 20 0 1244312848 92051488 2147376
12:26:05 59282 8192000000 10079464944 10582175744 1148279344 12.293 100 10 20 0 1200217680 49891696 2046640
12:28:21 31991 8192000000 10129898288 10581622784 1159644448 12.400 100 31 62 1 1224323872 57822544 6856880
12:30:07 65457 8192000000 10161137744 10609160192 1139884864 12.214 99 20 40 0 1228704400 84438864 4380672
12:32:22 44209 8192000000 10165262880 10603581440 1134454304 12.163 98 24 48 0 1228393984 88600352 5339328
12:34:09 83413 8192000000 10123530832 10674438144 1124802368 12.072 98 18 37 0 1261746896 132376192 4568336
12:36:33 62739 8192000000 10075462048 10558980096 1104559520 11.881 97 13 26 0 1149203136 41954320 2689296
12:38:36 21590 8192000000 10076351392 10494947328 1116202416 11.991 99 46 92 1 1164259984 39074512 8983056
12:39:59 68761 8192000000 10097716208 10526085120 1120973952 12.036 100 50 100 0 1191750144 60880688 9895504
12:42:10 50352 8192000000 10088650688 10553663488 1136556096 12.183 100 19 38 1 1204952416 64381344 4014976
12:44:06 57758 8192000000 10070063152 10573754368 1128469792 12.107 100 7 14 0 1186860400 56848528 1542080
12:46:15 72442 8192000000 10071131200 10556100608 1119332736 12.021 99 11 22 0 1160839312 39037616 2468960
12:48:53 60799 8192000000 10079696016 10528256000 1118964320 12.017 99 20 40 0 1170497920 47126464 4407136
12:51:06 36918 8192000000 10129115600 10564804608 1101064000 11.848 98 27 54 1 1205688272 98316848 6307424
12:52:47 48800 8192000000 10100982608 10590990336 1108833056 11.921 99 9 18 0 1205603344 94638272 2132016
12:54:36 27558 8192000000 10053486176 10507767808 1081045536 11.657 97 29 58 0 1132493872 45166160 6282176
12:56:10 34304 8192000000 10161736592 10584162304 1094718160 11.787 98 43 86 0 1212330880 108786896 8825824
12:57:47 57859 8192000000 10065472176 10530897920 1085117600 11.696 97 34 69 0 1161371296 69410944 6842752
13:00:36 70953 8192000000 10066743792 10520809472 1099619584 11.834 98 25 50 0 1166661408 61580016 5461808
13:02:47 63740 8192000000 10068585408 10543222784 1109022192 11.923 100 6 12 0 1178078208 67930096 1125920
13:05:03 76219 8192000000 10030280560 10494894080 1108913152 11.922 100 19 38 0 1145576768 32042832 4620784
13:07:19 28973 8192000000 10054859232 10490585088 1126459504 12.088 100 25 51 0 1161989808 30571152 4959152
13:08:56 60363 8192000000 10089141808 10585976832 1140618640 12.221 100 25 50 1 1220312160 74600080 5093440
13:10:57 52407 8192000000 10136128864 10580824064 1149013648 12.300 100 30 60 1 1212266704 57166832 6086224
13:13:15 41136 8192000000 10085551808 10520096768 1128910560 12.111 99 21 42 1 1160957744 28087728 3959456
13:15:01 99001 8192000000 10073863296 10586075136 1090106976 11.744 95 35 69 2 1186341360 89759584 6474800
13:17:41 67854 8192000000 10144087504 10597863424 1119867952 12.026 98 27 54 0 1187630976 62325440 5437584
13:19:54 140263 8192000000 10071521312 10540191744 1119341552 12.021 98 6 12 0 1172258560 51751360 1165648
13:23:19 28502 8192000000 10087974000 10551459840 1152255840 12.331 100 27 54 0 1222711280 65344800 5110640
13:27:25 66954 8192000000 10097808112 10580082688 1116540256 11.994 99 13 26 0 1190680304 71361120 2778928
13:29:32 53061 8192000000 10041789456 10492665856 1070937328 11.561 94 22 44 1 1109303632 33312112 5054192
13:31:40 62801 8192000000 10151225904 10609672192 1136191184 12.180 99 22 44 3 1244356624 103258336 4907104
13:33:43 103628 8192000000 10067836848 10517803008 1074492448 11.595 94 17 34 0 1110288048 32371168 3424432
13:36:27 41696 8192000000 10100488544 10534678528 1101253872 11.850 97 39 78 0 1168114672 59342688 7518112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 17-11-2021 13:38:16 to 17-11-2021 13:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 132 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2021 13:38:31 to 17-11-2021 13:38:33.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          238106
MON$OLDEST_ACTIVE               240899
MON$OLDEST_SNAPSHOT             240899
MON$NEXT_TRANSACTION            240899
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-30 07:30:08.6030 GMT
MON$PAGES                       2071840
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2667 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2667 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2667 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2667 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

17.11.21 13:38:34. There are 3 logs in /var/tmp/logs-oltp40 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-11-17 13:38:36 No severe PSQL-related problems occured
17.11.21 13:38:38. End of report.