Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2021 13:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16543488 kB
MemAvailable 31349668 kB
Buffers 0 kB
Cached 15063848 kB
SwapCached 4864 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.0
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-01-03 13:26:54
DTS_END                         2021-01-03 13:26:54.6450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54904
TRN_ID                          245016
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: 9089.51 24756 1090741
customer order: creation 175.99 414 21119
customer order: refuse 15.68 305 1882
order to supplier: creation 46.19 1318 5543
order to supplier: removal 3.59 103081 431
invoice (draft): creation 47.36 3833 5683
invoice (draft): removal 50.30 920 6036
invoice accept: apply 42.28 17454 5073
invoice accept: cancel 45.25 943 5430
customer reserve: creation 3915.79 170 469895
customer reserve: removal 4448.29 67 533795
realization accept: apply 85.78 174 10294
realization accept: cancel 78.93 127 9471
payment from customer: creation 61.95 98 7434
payment from customer: removal 6.02 86 722
payment to supplier: creation 51.58 115 6189
payment to supplier: removal 3.44 60 413
service: total inventory turnovers 3.91 3487 469
service: total monetary turnovers 6.30 493 756
service: aggregate perf. data 0.85 13082 102
service: refresh index statistics 0.03 348894 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2021 13:29:33 to 03-01-2021 13:29:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2691
TEST_TIME 2 3450
TEST_TIME 3 4863
TEST_TIME 4 4760
TEST_TIME 5 4807
TEST_TIME 6 4955
TEST_TIME 7 4988
TEST_TIME 8 5123
TEST_TIME 9 5443
TEST_TIME 10 5538
TEST_TIME 11 6032
TEST_TIME 12 6225
TEST_TIME 13 6487
TEST_TIME 14 6545
TEST_TIME 15 6774
TEST_TIME 16 7124
TEST_TIME 17 7204
TEST_TIME 18 7584
TEST_TIME 19 7957
TEST_TIME 20 8102
TEST_TIME 21 8342
TEST_TIME 22 8491
TEST_TIME 23 8817
TEST_TIME 24 8762
TEST_TIME 25 8835
TEST_TIME 26 9461
TEST_TIME 27 9753
TEST_TIME 28 10370
TEST_TIME 29 11158
TEST_TIME 30 11026
TEST_TIME 31 10972
TEST_TIME 32 10740
TEST_TIME 33 10578
TEST_TIME 34 10363
TEST_TIME 35 10366
TEST_TIME 36 10215
TEST_TIME 37 10096
TEST_TIME 38 9986
TEST_TIME 39 9898
TEST_TIME 40 9750
TEST_TIME 41 9783
TEST_TIME 42 9766
TEST_TIME 43 9726
TEST_TIME 44 9558
TEST_TIME 45 9480
TEST_TIME 46 9513
TEST_TIME 47 9622
TEST_TIME 48 9577
TEST_TIME 49 9603
TEST_TIME 50 9522
TEST_TIME 51 9590
TEST_TIME 52 9688
TEST_TIME 53 9608
TEST_TIME 54 9591
TEST_TIME 55 9576
TEST_TIME 56 9747
TEST_TIME 57 9735
TEST_TIME 58 9761
TEST_TIME 59 9838
TEST_TIME 60 9714
TEST_TIME 61 9624
TEST_TIME 62 9504
TEST_TIME 63 9498
TEST_TIME 64 9430
TEST_TIME 65 9389
TEST_TIME 66 9333
TEST_TIME 67 9274
TEST_TIME 68 9207
TEST_TIME 69 9153
TEST_TIME 70 9111
TEST_TIME 71 9098
TEST_TIME 72 9107
TEST_TIME 73 9102
TEST_TIME 74 9231
TEST_TIME 75 9226
TEST_TIME 76 9286
TEST_TIME 77 9266
TEST_TIME 78 9296
TEST_TIME 79 9268
TEST_TIME 80 9317
TEST_TIME 81 9347
TEST_TIME 82 9354
TEST_TIME 83 9303
TEST_TIME 84 9197
TEST_TIME 85 9168
TEST_TIME 86 9120
TEST_TIME 87 9063
TEST_TIME 88 9078
TEST_TIME 89 9058
TEST_TIME 90 9052
TEST_TIME 91 9022
TEST_TIME 92 9017
TEST_TIME 93 9008
TEST_TIME 94 9039
TEST_TIME 95 9134
TEST_TIME 96 9179
TEST_TIME 97 9159
TEST_TIME 98 9173
TEST_TIME 99 9218
TEST_TIME 100 9217
TEST_TIME 101 9152
TEST_TIME 102 9096
TEST_TIME 103 9064
TEST_TIME 104 9088
TEST_TIME 105 9065
TEST_TIME 106 9034
TEST_TIME 107 9023
TEST_TIME 108 8996
TEST_TIME 109 9033
TEST_TIME 110 9070
TEST_TIME 111 9073
TEST_TIME 112 9102
TEST_TIME 113 9098
TEST_TIME 114 9068
TEST_TIME 115 9148
TEST_TIME 116 9184
TEST_TIME 117 9169
TEST_TIME 118 9204
TEST_TIME 119 9129
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-01-2021 13:29:36 to 03-01-2021 13:29:40.

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 1525163 1525163 0 0.00 0 20640 6 0 0
doc_list_biud 1525163 1525163 0 0.00 0 88319 30 0 0
sp_add_invoice_to_stock 5166 5073 93 1.80 26 191403 17455 0 93
sp_cancel_adding_invoice 5430 5430 0 0.00 5 85361 944 0 0
sp_cancel_client_order 1882 1882 0 0.00 4 14854 305 0 0
sp_cancel_customer_reserve 533795 533795 0 0.00 1 20503 67 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 3 6679 87 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 967 60 0 0
sp_cancel_supplier_invoice 6042 6036 6 0.09 2 88322 920 0 6
sp_cancel_supplier_order 431 431 0 0.00 9 1190624 103081 0 0
sp_cancel_write_off 9471 9471 0 0.00 1 21830 128 0 0
sp_client_order 21119 21119 0 0.00 4 78120 414 0 0
sp_customer_reserve 469895 469895 0 0.00 0 55640 170 0 0
sp_fill_shopping_cart 32329 32329 0 0.00 1 16652 42 0 0
sp_fill_shopping_cart_clo_res 469892 469892 0 0.00 0 12502 16 0 0
sp_get_clo_for_invoice 5070 5070 0 0.00 0 40421 388 0 0
sp_kill_cost_storno 16036 16036 0 0.00 0 12936 56 0 0
sp_kill_qstorno_ret_qs2qd 1083528 1083528 0 0.00 0 88318 36 0 0
sp_kill_qty_storno 572409 572409 0 0.00 0 88319 78 0 0
sp_lock_selected_doc 21454 21454 0 0.00 0 7383 6 0 0
sp_make_cost_storno 28987 28987 0 0.00 0 10993 48 0 0
sp_multiply_rows_for_pdistr 28987 28987 0 0.00 0 7247 27 0 0
sp_multiply_rows_for_qdistr 21119 21119 0 0.00 0 78088 238 0 0
sp_pay_from_customer 7434 7434 0 0.00 4 9371 99 0 0
sp_pay_to_supplier 6189 6189 0 0.00 4 13341 115 0 0
sp_payment_common 13623 13623 0 0.00 1 8849 20 0 0
sp_qd_handle_on_cancel_clo 1882 1882 0 0.00 0 14848 286 0 0
sp_qd_handle_on_invoice_upd_sts 10500 10500 0 0.00 0 35190 369 0 0
sp_qd_handle_on_reserve_upd_sts 10294 10294 0 0.00 0 15942 64 0 0
sp_reserve_write_off 10294 10294 0 0.00 7 16156 174 0 0
sp_supplier_invoice 5683 5683 0 0.00 7 141617 3833 0 0
sp_supplier_order 5543 5543 0 0.00 6 85765 1318 0 0
srv_aggregate_perf_data 103 102 1 0.97 3256 106440 13082 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 907 127036 43939 0 0
srv_find_qd_qs_mism 1041415 1041415 0 0.00 0 20639 5 0 0
srv_make_invnt_saldo 469 469 0 0.00 29 91844 3487 0 0
srv_make_money_saldo 756 756 0 0.00 1 12645 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 145564 461169 348895 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 5074 5074 0 0.00 0 21659 207 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 2716 110 0 0
t$perf-norm:sp_cancel_client_order 1882 1882 0 0.00 0 7211 15 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 6635 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 757 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 5619 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 4384 24 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 22651 1410 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 2183 7 0 0
t$perf-norm:sp_client_order 21121 21121 0 0.00 0 12249 20 0 0
t$perf-norm:sp_customer_reserve 1113 1113 0 0.00 0 3626 9 0 0
t$perf-norm:sp_pay_from_customer 7434 7434 0 0.00 0 5717 9 0 0
t$perf-norm:sp_pay_to_supplier 6189 6189 0 0.00 0 11689 11 0 0
t$perf-norm:sp_reserve_write_off 10294 10294 0 0.00 0 5941 9 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 5897 18 0 0
t$perf-norm:sp_supplier_order 5543 5543 0 0.00 0 4354 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 245 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 641 14 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 176 3 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 390 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 28 8 0 0
v_add_invoice_to_stock 10326 10140 186 1.80 0 7399 15 0 186
v_all_customers 38902 38902 0 0.00 0 48 1 0 0
v_all_suppliers 31602 31602 0 0.00 0 46 1 0 0
v_all_wares 42238 42238 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 334 6 0 0
v_cancel_client_order 3764 3764 0 0.00 0 951 3 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 3184 8 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 9293 12 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 915 5 0 0
v_cancel_supplier_invoice 886 874 12 1.35 0 5577 22 0 12
v_cancel_supplier_order 862 862 0 0.00 0 348 5 0 0
v_cancel_write_off 2096 2096 0 0.00 0 3436 17 0 0
v_max_id_clo_ord 5543 5543 0 0.00 0 638 1 0 0
v_max_id_clo_res 1113 1113 0 0.00 0 1425 11 0 0
v_max_id_ord_sup 5667 5667 0 0.00 0 4787 5 0 0
v_max_non_paid_invoice 1598 1598 0 0.00 0 1000 2 0 0
v_max_non_paid_realizn 3853 3853 0 0.00 0 845 2 0 0
v_min_id_clo_ord 5543 5543 0 0.00 0 4245 6 0 0
v_min_id_clo_res 1113 1113 0 0.00 0 501 2 0 0
v_min_id_ord_sup 5667 5667 0 0.00 0 9221 9 0 0
v_min_non_paid_invoice 6189 6189 0 0.00 0 8847 12 0 0
v_min_non_paid_realizn 7434 7434 0 0.00 0 6446 15 0 0
v_our_firm 10498 10498 0 0.00 0 19 1 0 0
v_reserve_write_off 20588 20588 0 0.00 0 10235 5 0 0
x_lock_depdocs_on_canc_invoice 11029 11029 0 0.00 0 23149 110 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 10149 353 0 0
x_make_qstorno_1000_1200 5543 5543 0 0.00 2 85728 1243 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 2 141354 3663 0 0
x_make_qstorno_2100_3300 469895 469895 0 0.00 0 55576 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2021 13:29:41 to 03-01-2021 13:29:45.

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
10:56:58 7 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
10:57:59 13975 8192000000 9712716624 10006540288 794834688 8.844 100 90 179 1 824448000 7588224 22025088
10:59:25 22574 8192000000 9764475056 10070585344 841033120 9.310 100 86 172 0 872936704 11585792 20317792
11:00:52 18611 8192000000 9809153120 10124390400 866635184 9.566 100 83 166 0 909951632 23940656 19375792
11:02:14 13715 8192000000 9873889280 10206453760 919086272 10.087 100 84 168 1 984286096 47126944 18072880
11:03:33 5380 8192000000 9912135856 10257076224 954419376 10.434 100 74 148 1 1029451232 59638800 15393056
11:04:41 5454 8192000000 9966219248 10316992512 997174864 10.851 100 82 164 0 1074469904 61002112 16292928
11:05:48 3035 8192000000 10002696384 10364809216 1028905792 11.158 100 88 176 4 1113350288 66918576 17525920
11:06:53 2166 8192000000 10029890096 10399940608 1055584544 11.414 100 87 174 1 1142177296 68707744 17885008
11:07:58 1603 8192000000 10048629600 10418778112 1072808720 11.579 100 92 186 2 1165072944 73339216 18925008
11:09:10 3729 8192000000 10014964560 10411884544 1075947184 11.609 100 38 76 1 1133555264 49661376 7946704
11:10:16 5876 8192000000 9997683856 10406465536 1083815520 11.684 100 26 51 2 1118650336 29340064 5494752
11:11:23 5154 8192000000 10016715696 10413572096 1086962672 11.714 100 36 70 0 1130927520 36719728 7245120
11:12:32 71017 8192000000 10016322960 10411888640 1095001776 11.790 100 42 84 1 1126996288 22746544 9247968
11:14:44 3685 8192000000 10042756304 10440278016 1097325200 11.812 100 43 86 1 1153661920 47429376 8907344
11:15:49 4162 8192000000 10017349056 10412376064 1099112544 11.829 100 46 92 4 1134430640 25018000 10300096
11:16:55 56616 8192000000 10006324224 10423599104 1077084160 11.620 98 33 65 1 1115848576 31836448 6927968
11:18:55 7367 8192000000 9960372576 10351710208 1024500256 11.115 97 27 53 2 1050696000 20646224 5549520
11:20:02 6316 8192000000 9950769328 10339221504 996123440 10.841 95 46 93 1 1023520320 17102928 10293952
11:21:15 9675 8192000000 9931799040 10326798336 1008499472 10.961 98 33 65 3 1043966624 27827248 7639904
11:22:25 35459 8192000000 9930074224 10322489344 1029074224 11.160 100 18 33 0 1049285152 16342832 3868096
11:24:04 9936 8192000000 9981783312 10377564160 1051468528 11.375 100 47 93 2 1091321808 29294656 10558624
11:25:17 5146 8192000000 9992543632 10387156992 1067196736 11.525 100 54 108 2 1100865616 21442272 12226608
11:26:23 19531 8192000000 9989118608 10401796096 1077815616 11.627 100 25 51 2 1102687216 19247936 5623664
11:28:08 2868 8192000000 10019700944 10416373760 1091271952 11.755 100 51 101 1 1126592704 24898496 10422256
11:29:15 6128 8192000000 10052203664 10449272832 1103518576 11.871 100 59 118 3 1166889632 50461808 12909248
11:30:24 10125 8192000000 10069891520 10473091072 1115474800 11.984 100 45 90 0 1176107600 50804496 9828304
11:31:54 15472 8192000000 10104041184 10508853248 1121500848 12.041 100 52 102 2 1217306048 85022288 10782912
11:33:25 25712 8192000000 10113801008 10527830016 1133338976 12.153 100 34 68 2 1219892832 78778688 7775168
11:34:56 3721 8192000000 10159156560 10571440128 1139663856 12.212 100 58 114 1 1265081696 112449904 12967936
11:36:03 15062 8192000000 10196759920 10607886336 1151551248 12.324 100 50 98 5 1297401168 135359344 10490576
11:37:21 5264 8192000000 10209704848 10624028672 1160825408 12.411 100 54 108 3 1308446624 135736736 11884480
11:38:31 3805 8192000000 10206372224 10624167936 1153823184 12.345 100 66 128 1 1317529712 149460864 14245664
11:39:40 9186 8192000000 10181608592 10590199808 1159698096 12.400 100 57 112 1 1281491424 109339200 12454128
11:40:53 2835 8192000000 10256890032 10651918336 1142249504 12.237 99 86 172 0 1342332160 180831632 19251024
11:42:01 2780 8192000000 10248250960 10640515072 1133494144 12.154 99 79 159 2 1347051776 195898240 17659392
11:43:06 902 8192000000 10269093728 10660098048 1138881664 12.205 100 86 173 0 1363510544 205756336 18872544
11:44:13 1627 8192000000 10295765808 10693169152 1152564704 12.334 100 84 163 1 1389210208 218303568 18341936
11:45:23 2591 8192000000 10312459696 10713042944 1160136016 12.405 100 89 175 2 1415114256 233508432 21469808
11:46:28 7399 8192000000 10317488112 10744373248 1160229248 12.405 100 46 92 1 1419665824 247642320 11794256
11:47:41 5437 8192000000 10329759136 10753318912 1146563744 12.277 100 48 94 1 1428703904 270914384 11225776
11:48:52 1814 8192000000 10367916336 10791178240 1161024864 12.413 100 53 104 1 1464447424 290361376 13061184
11:50:03 13972 8192000000 10364077168 10787020800 1155850864 12.364 100 36 70 3 1458869200 294409376 8608960
11:51:21 10779 8192000000 10384063104 10812059648 1139506912 12.211 100 34 68 0 1473669392 325208400 8954080
11:52:39 6622 8192000000 10364610432 10823553024 1133230128 12.152 100 34 68 1 1458708368 316913712 8564528
11:53:47 44468 8192000000 10261939040 10782773248 1100796464 11.845 98 20 40 3 1417577488 311262624 5518400
11:55:54 28716 8192000000 10067298896 10485063680 1113266896 11.963 99 53 106 2 1151913328 27628144 11018288
11:57:24 66177 8192000000 10060449808 10515812352 1121646144 12.043 100 19 38 3 1157371504 31573648 4151712
11:59:33 75018 8192000000 10105650752 10551590912 1131463856 12.135 100 18 36 1 1203003728 67579424 3960448
12:01:51 66538 8192000000 10050107824 10492899328 1104619568 11.881 98 29 57 0 1137528688 26553776 6355344
12:03:58 80097 8192000000 10079385680 10540748800 1133945728 12.159 100 20 40 1 1184339344 46039008 4354608
12:06:19 35477 8192000000 10101433664 10531635200 1117350672 12.002 98 36 71 0 1185630512 60948768 7331072
12:07:56 36519 8192000000 10085106976 10522742784 1127691440 12.100 99 33 66 1 1175923376 41567872 6664064
12:09:46 95655 8192000000 10104529024 10587742208 1145639552 12.269 100 8 16 0 1206122704 58885392 1597760
12:12:25 81966 8192000000 10108704256 10577903616 1145946368 12.271 100 13 26 0 1252174128 103548000 2679760
12:14:49 44746 8192000000 10093078736 10569981952 1140040016 12.216 99 25 50 2 1195982288 50695648 5246624
12:16:37 39172 8192000000 10128303648 10556612608 1147563920 12.287 100 38 76 0 1193766048 38224336 7977792
12:18:20 91011 8192000000 10071835664 10517168128 1070503984 11.557 93 8 16 0 1131365296 59362256 1499056
12:21:02 43005 8192000000 10159398672 10624573440 1129012512 12.112 99 23 46 0 1225437728 91708256 4716960
12:23:07 76393 8192000000 10078123648 10576551936 1134953584 12.168 100 9 18 1 1199360960 62435488 1971888
12:25:40 119392 8192000000 10055221216 10505699328 1096567984 11.805 96 14 28 2 1125032656 25465328 2999344
12:28:43 20068 8192000000 10068567360 10506326016 1119089824 12.018 98 32 64 0 1152023424 26565200 6368400
12:30:06 58993 8192000000 10049885904 10493878272 1118377872 12.012 99 14 28 2 1150638448 29599680 2660896
12:32:05 49081 8192000000 10022466672 10457358336 1072244896 11.574 96 19 38 0 1099715488 23060800 4409792
12:33:59 66303 8192000000 10015924400 10472333312 1086704144 11.711 99 12 24 0 1116111664 26947584 2459936
12:36:26 70306 8192000000 10054440240 10506719232 1068421744 11.537 96 11 22 0 1116355456 45745104 2188608
12:38:37 41961 8192000000 10103296752 10537922560 1090522944 11.748 97 23 46 0 1159231792 63554976 5153872
12:40:20 54215 8192000000 10059110832 10554245120 1121033056 12.037 100 17 34 1 1206729280 82143856 3552368
12:42:18 33709 8192000000 10036664768 10466996224 1065669024 11.511 95 25 48 1 1099709056 28676128 5363904
12:43:52 24339 8192000000 10079716752 10513862656 1103105392 11.867 99 34 68 2 1168461968 58478528 6878048
12:45:23 15161 8192000000 10057422432 10477113344 1090914688 11.751 98 39 78 1 1140409888 42028896 7466304
12:46:39 92431 8192000000 10035238560 10486886400 1097777664 11.817 98 15 30 0 1150366016 49700224 2888128
12:49:15 125509 8192000000 10055706944 10490482688 1116935824 11.998 100 16 31 0 1160069120 39963696 3169600
12:52:21 63687 8192000000 10073312960 10538184704 1126838624 12.092 100 7 14 0 1170692832 42229552 1624656
12:54:25 49932 8192000000 10080140880 10557034496 1120649024 12.033 99 16 32 2 1151826336 27605600 3571712
12:56:15 18196 8192000000 10109674448 10547544064 1143200128 12.246 100 23 46 0 1195363344 47090032 5073184
12:57:36 47520 8192000000 10130593328 10572337152 1133929616 12.158 99 20 40 2 1228571568 90354176 4287776
12:59:39 70983 8192000000 10171001392 10623332352 1133172624 12.151 99 23 46 0 1262073616 123558816 5342176
13:01:57 67804 8192000000 10110379408 10569478144 1134435584 12.163 99 21 42 0 1208696336 69935936 4324816
13:04:57 98578 8192000000 10087554832 10581647360 1087021104 11.714 95 8 16 0 1141381520 52549952 1810464
13:10:05 54428 8192000000 10067455856 10556493824 1092898256 11.770 96 10 20 1 1129185248 34469744 1817248
13:12:02 33145 8192000000 10060444672 10491408384 1123324800 12.058 99 38 74 4 1154513120 23022144 8166176
13:13:53 52580 8192000000 10123204816 10555158528 1099773056 11.835 96 25 51 2 1163304112 58003568 5527488
13:15:45 107242 8192000000 10131982048 10600759296 1130929248 12.130 99 16 32 0 1252120960 117468320 3723392
13:18:33 62116 8192000000 10089592896 10524364800 1112210496 11.953 98 24 48 0 1169533920 52612928 4710496
13:20:40 10831 8192000000 10112169024 10523529216 1122754848 12.053 100 49 95 2 1190744400 57800784 10188768
13:21:53 127036 8192000000 10098472592 10571362304 1077870304 11.627 95 17 34 1 1160863776 79628880 3364592
13:25:00 117362 8192000000 9384865536 9706532864 508902704 5.848 46 13 26 0 525705920 14289472 2513744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2021 13:29:47 to 03-01-2021 13:30:02.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 93 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2021 13:30:03 to 03-01-2021 13:30:05.

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          245292
MON$OLDEST_ACTIVE               245293
MON$OLDEST_SNAPSHOT             245293
MON$NEXT_TRANSACTION            245293
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                       2115584
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             819
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2315 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:120: 
:121: mscs-test-161.budzdorov.ru    Sun Jan  3 13:27:03 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Sun Jan  3 13:27:03 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Sun Jan  3 13:27:05 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Sun Jan  3 13:27:06 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sun Jan  3 13:27:18 2021
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
--- end of diff output ---

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

03.01.21 13:30:06. 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-01-03 13:30:08 No severe PSQL-related problems occured
03.01.21 13:30:09. End of report.