Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2021 07: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15780012 kB
MemAvailable 30760980 kB
Buffers 0 kB
Cached 15944984 kB
SwapCached 4668 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-10-05 07:37:08
DTS_END                         2021-10-05 07:37:08.4990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40910
TRN_ID                          241380
ATT_ID                          691
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: 9006.80 24897 1080816
customer order: creation 170.27 376 20432
customer order: refuse 14.60 321 1752
order to supplier: creation 45.01 1382 5401
order to supplier: removal 3.33 126602 400
invoice (draft): creation 47.04 3429 5645
invoice (draft): removal 52.50 1032 6300
invoice accept: apply 41.07 18407 4928
invoice accept: cancel 47.15 830 5658
customer reserve: creation 3716.18 185 445942
customer reserve: removal 4564.92 72 547790
realization accept: apply 82.82 177 9938
realization accept: cancel 91.75 124 11010
payment from customer: creation 60.26 115 7231
payment from customer: removal 6.40 90 768
payment to supplier: creation 49.57 137 5948
payment to supplier: removal 3.34 136 401
service: total inventory turnovers 3.96 3599 475
service: total monetary turnovers 5.83 614 699
service: aggregate perf. data 0.78 18290 94
service: refresh index statistics 0.03 322024 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2021 07:39:13 to 05-10-2021 07:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3198
TEST_TIME 2 3449
TEST_TIME 3 4909
TEST_TIME 4 4895
TEST_TIME 5 5402
TEST_TIME 6 5986
TEST_TIME 7 6001
TEST_TIME 8 5993
TEST_TIME 9 6172
TEST_TIME 10 6125
TEST_TIME 11 6596
TEST_TIME 12 6634
TEST_TIME 13 6830
TEST_TIME 14 7057
TEST_TIME 15 7379
TEST_TIME 16 7681
TEST_TIME 17 7930
TEST_TIME 18 8112
TEST_TIME 19 8451
TEST_TIME 20 9069
TEST_TIME 21 9121
TEST_TIME 22 9073
TEST_TIME 23 9194
TEST_TIME 24 9266
TEST_TIME 25 9176
TEST_TIME 26 10529
TEST_TIME 27 11723
TEST_TIME 28 11423
TEST_TIME 29 11315
TEST_TIME 30 11092
TEST_TIME 31 10941
TEST_TIME 32 10736
TEST_TIME 33 10674
TEST_TIME 34 10508
TEST_TIME 35 10442
TEST_TIME 36 10282
TEST_TIME 37 10146
TEST_TIME 38 10060
TEST_TIME 39 10052
TEST_TIME 40 9899
TEST_TIME 41 9879
TEST_TIME 42 9780
TEST_TIME 43 9802
TEST_TIME 44 9602
TEST_TIME 45 9523
TEST_TIME 46 9620
TEST_TIME 47 9587
TEST_TIME 48 9707
TEST_TIME 49 9672
TEST_TIME 50 9646
TEST_TIME 51 9571
TEST_TIME 52 9569
TEST_TIME 53 9643
TEST_TIME 54 9870
TEST_TIME 55 9849
TEST_TIME 56 9795
TEST_TIME 57 9762
TEST_TIME 58 9622
TEST_TIME 59 9484
TEST_TIME 60 9512
TEST_TIME 61 9468
TEST_TIME 62 9366
TEST_TIME 63 9336
TEST_TIME 64 9270
TEST_TIME 65 9216
TEST_TIME 66 9248
TEST_TIME 67 9195
TEST_TIME 68 9198
TEST_TIME 69 9140
TEST_TIME 70 9221
TEST_TIME 71 9210
TEST_TIME 72 9257
TEST_TIME 73 9354
TEST_TIME 74 9296
TEST_TIME 75 9279
TEST_TIME 76 9298
TEST_TIME 77 9357
TEST_TIME 78 9384
TEST_TIME 79 9303
TEST_TIME 80 9219
TEST_TIME 81 9130
TEST_TIME 82 9154
TEST_TIME 83 9123
TEST_TIME 84 9079
TEST_TIME 85 9027
TEST_TIME 86 9061
TEST_TIME 87 9036
TEST_TIME 88 9009
TEST_TIME 89 9004
TEST_TIME 90 9059
TEST_TIME 91 9057
TEST_TIME 92 9078
TEST_TIME 93 9135
TEST_TIME 94 9110
TEST_TIME 95 9125
TEST_TIME 96 9101
TEST_TIME 97 9133
TEST_TIME 98 9190
TEST_TIME 99 9138
TEST_TIME 100 9095
TEST_TIME 101 9043
TEST_TIME 102 9007
TEST_TIME 103 8944
TEST_TIME 104 8927
TEST_TIME 105 8929
TEST_TIME 106 8993
TEST_TIME 107 8973
TEST_TIME 108 8987
TEST_TIME 109 8968
TEST_TIME 110 9064
TEST_TIME 111 9041
TEST_TIME 112 9019
TEST_TIME 113 9037
TEST_TIME 114 9053
TEST_TIME 115 9091
TEST_TIME 116 9142
TEST_TIME 117 9131
TEST_TIME 118 9127
TEST_TIME 119 9082
TEST_TIME 120 9007

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-10-2021 07:39:17 to 05-10-2021 07:39:20.

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 1494743 1494743 0 0.00 0 16568 7 0 0
doc_list_biud 1494743 1494743 0 0.00 0 27370 33 0 0
sp_add_invoice_to_stock 5040 4928 112 2.22 19 261979 18407 0 112
sp_cancel_adding_invoice 5658 5658 0 0.00 5 45012 831 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 11532 321 0 0
sp_cancel_customer_reserve 547790 547790 0 0.00 1 24582 73 0 0
sp_cancel_pay_from_customer 768 768 0 0.00 2 6175 91 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 8063 137 0 0
sp_cancel_supplier_invoice 6313 6300 13 0.20 3 27377 1032 0 13
sp_cancel_supplier_order 400 400 0 0.00 6 1019184 126602 0 0
sp_cancel_write_off 11010 11010 0 0.00 1 16122 124 0 0
sp_client_order 20432 20432 0 0.00 4 39686 377 0 0
sp_customer_reserve 445942 445942 0 0.00 0 47419 186 0 0
sp_fill_shopping_cart 31467 31467 0 0.00 1 21596 47 0 0
sp_fill_shopping_cart_clo_res 445942 445942 0 0.00 0 13688 17 0 0
sp_get_clo_for_invoice 4928 4928 0 0.00 0 35175 397 0 0
sp_kill_cost_storno 17837 17837 0 0.00 0 8624 58 0 0
sp_kill_qstorno_ret_qs2qd 1113290 1113290 0 0.00 0 27369 40 0 0
sp_kill_qty_storno 587776 587776 0 0.00 0 27370 84 0 0
sp_lock_selected_doc 20687 20687 0 0.00 0 10463 7 0 0
sp_make_cost_storno 28045 28045 0 0.00 0 15570 49 0 0
sp_multiply_rows_for_pdistr 28045 28045 0 0.00 0 10254 34 0 0
sp_multiply_rows_for_qdistr 20432 20432 0 0.00 0 39677 212 0 0
sp_pay_from_customer 7231 7231 0 0.00 4 12195 115 0 0
sp_pay_to_supplier 5948 5948 0 0.00 5 11638 137 0 0
sp_payment_common 13179 13179 0 0.00 1 8449 23 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 11524 284 0 0
sp_qd_handle_on_invoice_upd_sts 10586 10586 0 0.00 0 11530 348 0 0
sp_qd_handle_on_reserve_upd_sts 9938 9938 0 0.00 0 10597 47 0 0
sp_reserve_write_off 9938 9938 0 0.00 6 12759 178 0 0
sp_supplier_invoice 5645 5645 0 0.00 7 100954 3430 0 0
sp_supplier_order 5401 5401 0 0.00 5 61174 1383 0 0
srv_aggregate_perf_data 95 94 1 1.05 2782 120534 18290 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1910 111632 48394 0 0
srv_find_qd_qs_mism 1034091 1034091 0 0.00 0 16568 6 0 0
srv_make_invnt_saldo 475 475 0 0.00 78 108743 3600 0 0
srv_make_money_saldo 699 699 0 0.00 1 17788 614 0 0
srv_recalc_idx_stat 4 4 0 0.00 192592 507427 322024 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 4928 4928 0 0.00 0 18239 151 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 11469 122 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 2352 16 0 0
t$perf-norm:sp_cancel_customer_reserve 620 620 0 0.00 0 642 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 768 768 0 0.00 0 2666 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 958 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4909 27 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 20497 1648 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 4205 12 0 0
t$perf-norm:sp_client_order 20432 20432 0 0.00 0 12886 17 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 464 3 0 0
t$perf-norm:sp_pay_from_customer 7231 7231 0 0.00 0 5103 8 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 12749 11 0 0
t$perf-norm:sp_reserve_write_off 9938 9938 0 0.00 0 3268 7 0 0
t$perf-norm:sp_supplier_invoice 5645 5645 0 0.00 0 6637 17 0 0
t$perf-norm:sp_supplier_order 5401 5401 0 0.00 0 5721 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 138 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5856 96 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1057 10 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 2322 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 85 27 0 0
v_add_invoice_to_stock 10078 9854 224 2.22 0 7563 17 0 224
v_all_customers 37214 37214 0 0.00 0 99 1 0 0
v_all_suppliers 30908 30908 0 0.00 0 25 1 0 0
v_all_wares 40864 40864 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 2640 11 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3222 7 0 0
v_cancel_customer_prepayment 1536 1536 0 0.00 0 6146 12 0 0
v_cancel_customer_reserve 1240 1240 0 0.00 0 1188 5 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 2296 10 0 0
v_cancel_supplier_invoice 822 796 26 3.16 0 636 12 0 26
v_cancel_supplier_order 800 800 0 0.00 0 2962 7 0 0
v_cancel_write_off 2042 2042 0 0.00 0 12242 22 0 0
v_max_id_clo_ord 5401 5401 0 0.00 0 1478 2 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 5188 16 0 0
v_max_id_ord_sup 5634 5634 0 0.00 0 6509 6 0 0
v_max_non_paid_invoice 1529 1529 0 0.00 0 1144 4 0 0
v_max_non_paid_realizn 3920 3920 0 0.00 0 558 2 0 0
v_min_id_clo_ord 5401 5401 0 0.00 0 4175 8 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 5240 10 0 0
v_min_id_ord_sup 5634 5634 0 0.00 0 5993 12 0 0
v_min_non_paid_invoice 5948 5948 0 0.00 0 4066 13 0 0
v_min_non_paid_realizn 7231 7231 0 0.00 0 7589 15 0 0
v_our_firm 10272 10272 0 0.00 0 17 1 0 0
v_reserve_write_off 19872 19872 0 0.00 0 7215 8 0 0
x_lock_depdocs_on_canc_invoice 11560 11560 0 0.00 0 24066 111 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 7509 359 0 0
x_make_qstorno_1000_1200 5401 5401 0 0.00 1 61162 1301 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 4 100924 3248 0 0
x_make_qstorno_2100_3300 445942 445942 0 0.00 0 47418 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-10-2021 07:39:21 to 05-10-2021 07:39:25.

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
05:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 41486 8192000000 9723508672 10021937152 793060032 8.826 100 87 174 0 822612496 7964848 21587616
05:10:01 22829 8192000000 9764544496 10072014848 836031088 9.260 100 89 178 0 871172144 13804080 21336976
05:11:36 12491 8192000000 9810988288 10133094400 876637872 9.666 100 84 168 1 923723808 27943456 19142480
05:12:56 12153 8192000000 9891588064 10225356800 919623792 10.092 100 81 160 1 999006256 62228208 17154256
05:14:12 8560 8192000000 9925631584 10273841152 967689664 10.564 100 82 165 0 1050019472 65104832 17224976
05:15:22 3830 8192000000 9980422480 10333310976 1003383216 10.911 100 82 165 2 1079838000 60477616 15977168
05:16:28 2508 8192000000 10017768624 10379509760 1035306192 11.220 100 90 176 2 1123858272 71373328 17178752
05:17:31 1149 8192000000 10033094672 10404343808 1058276480 11.440 100 94 188 3 1141707120 64862080 18568560
05:18:33 5278 8192000000 9983179472 10360033280 1064513008 11.500 100 79 157 0 1092715408 10693152 17509248
05:19:39 4081 8192000000 10006095888 10389495808 1074169728 11.592 100 54 109 2 1110931728 25151216 11610784
05:20:44 6181 8192000000 9987842480 10389479424 1085820848 11.703 100 34 68 2 1121735280 29036464 6877968
05:21:51 8236 8192000000 10009160880 10408034304 1086227168 11.707 100 40 76 2 1107442944 12879952 8335824
05:23:01 29795 8192000000 10016112448 10415771648 1096129856 11.801 100 46 92 0 1117426736 10980768 10316112
05:24:32 5826 8192000000 10008717344 10410536960 1087831248 11.722 99 49 99 1 1125446912 26599888 11015776
05:25:40 6300 8192000000 10012554016 10412232704 1100850160 11.846 100 25 51 1 1142593136 35850256 5892720
05:26:47 5956 8192000000 10007013088 10403405824 1070551024 11.557 99 49 90 4 1098672128 18016416 10104688
05:27:55 23059 8192000000 9991687888 10397102080 1048394368 11.345 96 30 60 0 1080315184 25379904 6540912
05:29:22 4945 8192000000 9979848832 10365579264 995992960 10.840 94 54 108 3 1052459152 44853712 11612480
05:30:28 6833 8192000000 9940405136 10314457088 1016017200 11.034 99 37 73 4 1043876768 19979600 7879968
05:31:36 66665 8192000000 9941649792 10339028992 1013937536 11.013 98 44 88 2 1038218176 15330368 8950272
05:33:44 5319 8192000000 9978995984 10369581056 1050620336 11.367 100 48 93 0 1082816816 21346304 10850176
05:34:50 7178 8192000000 9980232128 10378711040 1058830512 11.445 100 40 80 0 1090561744 22265568 9465664
05:36:01 13744 8192000000 9973978576 10384625664 1067340608 11.527 100 23 46 1 1086359984 13566000 5453376
05:37:17 68206 8192000000 9996054592 10406711296 1081851104 11.665 100 24 48 0 1110202048 23176672 5174272
05:39:32 30345 8192000000 10073538368 10481811456 1109707264 11.930 100 38 76 0 1168059184 50088944 8262976
05:41:06 30955 8192000000 10120715728 10528329728 1119441424 12.022 100 58 114 2 1223753472 91843728 12468320
05:42:42 28749 8192000000 10146506512 10562183168 1119814800 12.025 99 50 101 0 1240790032 109264752 11710480
05:44:18 7061 8192000000 10157558864 10573430784 1141498272 12.230 100 45 90 2 1268675488 117763568 9413648
05:45:31 22851 8192000000 10176571728 10599010304 1154325792 12.350 100 47 94 0 1291013504 124926160 11761552
05:47:00 2444 8192000000 10252193056 10661306368 1163953408 12.440 100 76 149 0 1352763040 172140672 16668960
05:48:10 2324 8192000000 10300214768 10702225408 1163666400 12.438 100 87 172 0 1396086864 213526848 18893616
05:49:17 12024 8192000000 10283913600 10704293888 1168094656 12.479 100 57 111 2 1377678480 196206928 13376896
05:50:36 4815 8192000000 10322564320 10728493056 1154308160 12.350 100 73 143 2 1423145648 251932336 16905152
05:51:44 4804 8192000000 10366858176 10771124224 1171468560 12.511 100 83 166 1 1461297456 270194016 19634880
05:52:53 2936 8192000000 10345271600 10773569536 1159719840 12.401 99 71 141 1 1443284080 266784352 16779888
05:54:00 3527 8192000000 10410457072 10814980096 1175791856 12.551 100 73 146 2 1507289280 314633280 16864144
05:55:07 7124 8192000000 10413622688 10836279296 1173716144 12.532 100 45 89 0 1505603776 320791504 11096128
05:56:17 1908 8192000000 10430767488 10860019712 1170798544 12.504 100 61 121 0 1530357184 345103104 14455536
05:57:27 2952 8192000000 10420676560 10856443904 1167761296 12.476 100 56 108 1 1519128176 338140288 13226592
05:58:33 5553 8192000000 10380586000 10810462208 1174762128 12.541 100 46 91 1 1484422928 298100736 11560064
05:59:44 12014 8192000000 10462995088 10915807232 1183686320 12.625 100 41 80 2 1556420624 362535984 10198320
06:01:06 19161 8192000000 10482643616 10948775936 1167063664 12.469 100 31 61 0 1587171840 411585632 8522544
06:02:28 18641 8192000000 10518562544 10986901504 1152837184 12.336 100 30 60 1 1617518800 455770096 8911520
06:04:14 80468 8192000000 10094169424 10582134784 1125697408 12.081 98 11 22 0 1258835312 130168768 2969136
06:06:36 42781 8192000000 10077504368 10540888064 1140511632 12.220 100 16 32 1 1198450448 54722416 3216400
06:08:58 81973 8192000000 10117235808 10586374144 1134260208 12.162 99 10 20 0 1208752576 72584848 1907520
06:11:20 63439 8192000000 10089864528 10536972288 1128874464 12.111 99 17 35 1 1183306800 50568800 3863536
06:13:24 48836 8192000000 10063856656 10492526592 1134156992 12.161 100 53 105 0 1157349760 11384448 11808320
06:15:19 87078 8192000000 10058241952 10529828864 1119482896 12.022 98 11 22 1 1166706224 44938144 2285184
06:17:52 16589 8192000000 10073125744 10520932352 1136790128 12.185 99 32 64 1 1174085680 31102624 6192928
06:19:10 50001 8192000000 10121799152 10568081408 1119399552 12.021 97 41 82 1 1190127088 62015616 8711920
06:21:01 35442 8192000000 10105869184 10593087488 1116822928 11.997 98 11 22 1 1190806656 71830592 2153136
06:22:43 83061 8192000000 10132470464 10659270656 1124286048 12.067 98 20 40 1 1256876240 127347872 5242320
06:25:21 81497 8192000000 10098816448 10556198912 1097690640 11.816 96 15 30 0 1152194064 51551744 2951680
06:28:05 60768 8192000000 10185429808 10634723328 1138352192 12.200 99 24 48 0 1267788768 124079744 5356832
06:30:06 73696 8192000000 10135434512 10619760640 1124324096 12.068 98 19 38 1 1240412576 111871152 4217328
06:32:25 102906 8192000000 10080289664 10508898304 1023401728 11.105 91 29 58 1 1076524592 47178032 5944832
06:35:14 109256 8192000000 10107663424 10533289984 1109104112 11.924 99 54 109 1 1196878384 76766080 11008192
06:38:05 54457 8192000000 10049095840 10498756608 1113244832 11.963 99 26 52 1 1153247888 34294400 5708656
06:40:00 42009 8192000000 10065220640 10536947712 1103971040 11.875 98 10 21 0 1163347536 57119840 2256656
06:41:44 57754 8192000000 10073137424 10521337856 1088112624 11.725 97 12 24 0 1164354768 73520528 2721616
06:43:55 65693 8192000000 10078710544 10511880192 1101073584 11.848 98 23 44 0 1150888688 44864592 4950512
06:46:13 104439 8192000000 10075803552 10525736960 1084497088 11.690 96 11 22 1 1161274272 74263968 2513216
06:48:58 104752 8192000000 10075807936 10601783296 1090396352 11.746 96 10 20 0 1154641984 62192288 2053344
06:51:44 20737 8192000000 10068372416 10496843776 1104656480 11.882 98 39 78 0 1140320128 27897104 7766544
06:53:09 34058 8192000000 10101292672 10538180608 1110184000 11.934 98 35 70 1 1171869936 54717840 6968096
06:54:45 111632 8192000000 10082950832 10531094528 1086006896 11.705 96 9 18 0 1147729472 59922096 1800480
06:57:45 76453 8192000000 10082490672 10524831744 1116832048 11.997 99 45 89 1 1182626928 56432704 9362176
07:00:09 66230 8192000000 10071504656 10530656256 1127285792 12.096 100 11 22 0 1193532720 63269648 2977280
07:02:15 67180 8192000000 10106288064 10561011712 1116777776 11.997 99 15 29 0 1194572160 74261504 3532880
07:04:23 26739 8192000000 10069849520 10526097408 1122482128 12.050 99 19 38 0 1161219664 34260064 4477472
07:05:54 51548 8192000000 10120819808 10573533184 1117667776 12.005 98 22 44 1 1219960000 97315920 4976304
07:07:46 72718 8192000000 10113527632 10581700608 1116653312 11.995 99 20 40 0 1223756752 102572592 4530848
07:10:02 62996 8192000000 10085672016 10546298880 1137007248 12.187 100 25 50 0 1207341072 65115728 5218096
07:12:06 13360 8192000000 10090683760 10525351936 1133260624 12.152 100 43 86 0 1183818736 41852320 8705792
07:13:24 18052 8192000000 10089837568 10524524544 1106666960 11.901 98 41 82 1 1161463792 46400640 8396192
07:14:48 71278 8192000000 10092181616 10549432320 1099941232 11.837 97 21 42 0 1159488432 55379776 4167424
07:17:04 88137 8192000000 10086651648 10530410496 1139290896 12.209 100 14 28 0 1166085040 24261648 2532496
07:19:33 75678 8192000000 10129033872 10588946432 1109939088 11.932 97 15 30 0 1209663600 96979072 2745440
07:23:08 96529 8192000000 10069507344 10521796608 1102884224 11.865 97 20 39 0 1168126176 60712720 4529232
07:25:59 59153 8192000000 10127041984 10569428992 1101135504 11.848 96 21 42 0 1197233104 90900176 5197424
07:28:07 38615 8192000000 10074943888 10529726464 1091371888 11.756 96 21 41 1 1143152784 47683072 4097824
07:29:50 19540 8192000000 10110829536 10538377216 1128006000 12.103 99 45 89 1 1171855856 35169600 8680256
07:31:13 88962 8192000000 10132491232 10614067200 1112887072 11.960 98 21 39 2 1183867648 67104640 3875936
07:33:42 72791 8192000000 10097568848 10577137664 1128166304 12.104 99 18 36 0 1192634928 61018400 3450224
07:35:57 80985 8192000000 9681587728 10057519104 774392720 8.636 68 18 36 0 794253200 16317664 3542816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-10-2021 07:39:27 to 05-10-2021 07:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 112 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2021 07:39:43 to 05-10-2021 07:39:45.

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          240785
MON$OLDEST_ACTIVE               241680
MON$OLDEST_SNAPSHOT             241680
MON$NEXT_TRANSACTION            241680
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                       2059680
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2622 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2622 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2622 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2622 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 ---
:86: 
:87: mscs-test-161.budzdorov.ru    Tue Oct  5 07:37:26 2021
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Tue Oct  5 07:37:57 2021
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:93: 
--- end of diff output ---

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

05.10.21 07:39:46. 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-10-05 07:39:48 No severe PSQL-related problems occured
05.10.21 07:39:49. End of report.