Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.07.2022 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 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 15752564 kB
MemAvailable 31000876 kB
Buffers 0 kB
Cached 15937732 kB
SwapCached 2516 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.2
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 2022-07-28 07:37:18
DTS_END                         2022-07-28 07:37:18.0670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(151:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54886
TRN_ID                          223841
ATT_ID                          609
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: 8842.48 25104 1061098
customer order: creation 173.41 469 20809
customer order: refuse 14.93 394 1791
order to supplier: creation 46.78 1391 5614
order to supplier: removal 3.31 162478 397
invoice (draft): creation 46.14 3039 5537
invoice (draft): removal 48.19 1118 5783
invoice accept: apply 41.40 26740 4968
invoice accept: cancel 43.40 1344 5208
customer reserve: creation 3850.42 256 462050
customer reserve: removal 4278.48 98 513418
realization accept: apply 85.82 274 10298
realization accept: cancel 76.68 165 9201
payment from customer: creation 61.81 167 7417
payment from customer: removal 5.77 132 692
payment to supplier: creation 49.63 186 5956
payment to supplier: removal 3.70 115 444
service: total inventory turnovers 3.97 3733 476
service: total monetary turnovers 7.81 445 937
service: aggregate perf. data 0.81 15704 97
service: refresh index statistics 0.04 283827 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-07-2022 07:38:27 to 28-07-2022 07:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8282
TEST_TIME 2 5919
TEST_TIME 3 5731
TEST_TIME 4 6062
TEST_TIME 5 6334
TEST_TIME 6 6699
TEST_TIME 7 6456
TEST_TIME 8 6832
TEST_TIME 9 6846
TEST_TIME 10 7260
TEST_TIME 11 7264
TEST_TIME 12 7278
TEST_TIME 13 7228
TEST_TIME 14 7359
TEST_TIME 15 7539
TEST_TIME 16 7600
TEST_TIME 17 7703
TEST_TIME 18 7845
TEST_TIME 19 7981
TEST_TIME 20 7963
TEST_TIME 21 8028
TEST_TIME 22 8006
TEST_TIME 23 8125
TEST_TIME 24 8362
TEST_TIME 25 8292
TEST_TIME 26 8300
TEST_TIME 27 8889
TEST_TIME 28 9661
TEST_TIME 29 9843
TEST_TIME 30 10227
TEST_TIME 31 10674
TEST_TIME 32 11071
TEST_TIME 33 10974
TEST_TIME 34 11055
TEST_TIME 35 11229
TEST_TIME 36 11111
TEST_TIME 37 10973
TEST_TIME 38 10811
TEST_TIME 39 10722
TEST_TIME 40 10458
TEST_TIME 41 10225
TEST_TIME 42 10244
TEST_TIME 43 10073
TEST_TIME 44 10081
TEST_TIME 45 10070
TEST_TIME 46 10028
TEST_TIME 47 9914
TEST_TIME 48 9843
TEST_TIME 49 9882
TEST_TIME 50 9870
TEST_TIME 51 9801
TEST_TIME 52 9664
TEST_TIME 53 9660
TEST_TIME 54 9655
TEST_TIME 55 9645
TEST_TIME 56 9669
TEST_TIME 57 9630
TEST_TIME 58 9597
TEST_TIME 59 9636
TEST_TIME 60 9600
TEST_TIME 61 9694
TEST_TIME 62 9711
TEST_TIME 63 9726
TEST_TIME 64 9691
TEST_TIME 65 9632
TEST_TIME 66 9712
TEST_TIME 67 9601
TEST_TIME 68 9607
TEST_TIME 69 9522
TEST_TIME 70 9499
TEST_TIME 71 9449
TEST_TIME 72 9376
TEST_TIME 73 9288
TEST_TIME 74 9223
TEST_TIME 75 9172
TEST_TIME 76 9160
TEST_TIME 77 9160
TEST_TIME 78 9126
TEST_TIME 79 9078
TEST_TIME 80 9147
TEST_TIME 81 9182
TEST_TIME 82 9171
TEST_TIME 83 9144
TEST_TIME 84 9175
TEST_TIME 85 9273
TEST_TIME 86 9317
TEST_TIME 87 9304
TEST_TIME 88 9342
TEST_TIME 89 9296
TEST_TIME 90 9229
TEST_TIME 91 9191
TEST_TIME 92 9189
TEST_TIME 93 9128
TEST_TIME 94 9100
TEST_TIME 95 9069
TEST_TIME 96 9095
TEST_TIME 97 9072
TEST_TIME 98 9084
TEST_TIME 99 9068
TEST_TIME 100 9120
TEST_TIME 101 9144
TEST_TIME 102 9095
TEST_TIME 103 9085
TEST_TIME 104 9129
TEST_TIME 105 9145
TEST_TIME 106 9130
TEST_TIME 107 9160
TEST_TIME 108 9138
TEST_TIME 109 9171
TEST_TIME 110 9095
TEST_TIME 111 9027
TEST_TIME 112 9000
TEST_TIME 113 8993
TEST_TIME 114 8937
TEST_TIME 115 8944
TEST_TIME 116 8917
TEST_TIME 117 8900
TEST_TIME 118 8907
TEST_TIME 119 8935
TEST_TIME 120 8866

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-07-2022 07:38:30 to 28-07-2022 07:38:33.

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 1487738 1487738 0 0.00 0 17122 14 0 0
doc_list_biud 1487734 1487734 0 0.00 0 75265 40 0 0
sp_add_invoice_to_stock 5109 4968 141 2.75 56 215450 26740 0 141
sp_cancel_adding_invoice 5208 5208 0 0.00 5 87589 1345 0 0
sp_cancel_client_order 1791 1791 0 0.00 6 10971 395 0 0
sp_cancel_customer_reserve 513418 513418 0 0.00 1 32325 98 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 5054 132 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 4 1502 115 0 0
sp_cancel_supplier_invoice 5798 5783 15 0.25 2 45692 1118 0 15
sp_cancel_supplier_order 397 397 0 0.00 9 1389540 162478 0 0
sp_cancel_write_off 9201 9201 0 0.00 2 11852 165 0 0
sp_client_order 20809 20809 0 0.00 5 25705 469 0 0
sp_customer_reserve 462050 462050 0 0.00 1 90980 256 0 0
sp_fill_shopping_cart 31959 31959 0 0.00 1 11641 81 0 0
sp_fill_shopping_cart_clo_res 462032 462032 0 0.00 0 14490 18 0 0
sp_get_clo_for_invoice 4924 4924 0 0.00 0 31049 318 0 0
sp_kill_cost_storno 15545 15545 0 0.00 0 17086 73 0 0
sp_kill_qstorno_ret_qs2qd 1042217 1042217 0 0.00 0 45674 43 0 0
sp_kill_qty_storno 551019 551019 0 0.00 0 75265 99 0 0
sp_lock_selected_doc 21079 21079 0 0.00 0 9753 8 0 0
sp_make_cost_storno 28595 28595 0 0.00 0 11095 72 0 0
sp_multiply_rows_for_pdistr 28595 28595 0 0.00 0 7433 36 0 0
sp_multiply_rows_for_qdistr 20809 20809 0 0.00 0 12192 174 0 0
sp_pay_from_customer 7417 7417 0 0.00 6 13937 168 0 0
sp_pay_to_supplier 5956 5956 0 0.00 7 11836 186 0 0
sp_payment_common 13373 13373 0 0.00 2 9368 44 0 0
sp_qd_handle_on_cancel_clo 1791 1791 0 0.00 1 10958 326 0 0
sp_qd_handle_on_invoice_upd_sts 10131 10131 0 0.00 0 75264 499 0 0
sp_qd_handle_on_reserve_upd_sts 10298 10298 0 0.00 0 21300 66 0 0
sp_reserve_write_off 10298 10298 0 0.00 8 21319 274 0 0
sp_supplier_invoice 5537 5537 0 0.00 8 102225 3039 0 0
sp_supplier_order 5614 5614 0 0.00 7 66217 1392 0 0
srv_aggregate_perf_data 98 97 1 1.02 2690 153515 15705 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 695 104200 32820 0 0
srv_find_qd_qs_mism 1012131 1012131 0 0.00 0 13160 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 58 49967 3734 0 0
srv_make_money_saldo 937 937 0 0.00 1 39934 445 0 0
srv_recalc_idx_stat 5 5 0 0.00 83385 374087 283828 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 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 4969 4969 0 0.00 1 13455 896 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 6773 512 0 0
t$perf-norm:sp_cancel_client_order 1791 1791 0 0.00 0 5497 32 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1315 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 756 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 14252 48 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1614 32 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 77624 6535 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 2924 21 0 0
t$perf-norm:sp_client_order 20809 20809 0 0.00 0 14565 38 0 0
t$perf-norm:sp_customer_reserve 1071 1071 0 0.00 0 4705 20 0 0
t$perf-norm:sp_pay_from_customer 7417 7417 0 0.00 0 5676 21 0 0
t$perf-norm:sp_pay_to_supplier 5956 5956 0 0.00 0 4221 20 0 0
t$perf-norm:sp_reserve_write_off 10298 10298 0 0.00 0 15564 29 0 0
t$perf-norm:sp_supplier_invoice 5537 5537 0 0.00 0 9449 29 0 0
t$perf-norm:sp_supplier_order 5614 5614 0 0.00 0 4683 30 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 352 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 29 3 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 4236 24 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 4014 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 171 65 0 0
v_add_invoice_to_stock 10128 9846 282 2.78 0 4137 25 0 282
v_all_customers 38212 38212 0 0.00 0 88 4 0 0
v_all_suppliers 31192 31192 0 0.00 0 70 4 0 0
v_all_wares 41618 41618 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2018 16 0 0
v_cancel_client_order 3582 3582 0 0.00 0 4091 11 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 4336 21 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 786 10 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 739 11 0 0
v_cancel_supplier_invoice 874 844 30 3.43 0 4982 31 0 30
v_cancel_supplier_order 794 794 0 0.00 0 340 10 0 0
v_cancel_write_off 2040 2040 0 0.00 0 4041 28 0 0
v_max_id_clo_ord 5614 5614 0 0.00 0 7080 6 0 0
v_max_id_clo_res 1071 1071 0 0.00 0 1915 14 0 0
v_max_id_ord_sup 5536 5536 0 0.00 0 4637 10 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 229 8 0 0
v_max_non_paid_realizn 3821 3821 0 0.00 0 813 7 0 0
v_min_id_clo_ord 5614 5614 0 0.00 0 3199 9 0 0
v_min_id_clo_res 1071 1071 0 0.00 0 3839 14 0 0
v_min_id_ord_sup 5536 5536 0 0.00 0 4802 11 0 0
v_min_non_paid_invoice 5956 5956 0 0.00 0 9362 18 0 0
v_min_non_paid_realizn 7417 7417 0 0.00 0 5805 15 0 0
v_our_firm 10598 10598 0 0.00 0 74 4 0 0
v_reserve_write_off 20594 20594 0 0.00 0 4632 13 0 0
x_lock_depdocs_on_canc_invoice 10569 10569 0 0.00 0 27874 126 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 10666 369 0 0
x_make_qstorno_1000_1200 5614 5614 0 0.00 3 66197 1228 0 0
x_make_qstorno_1200_2000 5537 5537 0 0.00 3 101021 2814 0 0
x_make_qstorno_2100_3300 462050 462050 0 0.00 0 90956 226 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 28-07-2022 07:38:34 to 28-07-2022 07:38:39.

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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 34194 8192000000 9726872832 10025558016 802235648 8.919 100 89 178 0 831645024 7859728 21549648
05:10:03 18412 8192000000 9769967200 10081001472 844224336 9.342 100 88 176 0 877777008 13087376 20465296
05:11:29 10041 8192000000 9813943296 10137219072 878166208 9.681 100 84 168 0 917407264 20237424 19003632
05:12:42 9856 8192000000 9865440624 10200064000 913014784 10.027 100 88 175 0 984054496 51290576 19749136
05:13:56 3402 8192000000 9922396464 10266357760 955748080 10.447 100 93 180 0 1034771424 59038656 19984688
05:15:04 3455 8192000000 9951778400 10307366912 985184816 10.735 100 87 169 1 1069392752 67198880 17009056
05:16:10 1743 8192000000 10003736080 10364719104 1019544880 11.068 100 89 173 0 1116565744 80270640 16750224
05:17:13 1091 8192000000 10012137056 10381922304 1046888352 11.331 100 94 185 0 1127802112 62244992 18668768
05:18:17 1002 8192000000 10042021744 10416627712 1064684304 11.501 100 100 192 1 1163284288 79493376 19106608
05:19:19 743 8192000000 10037378064 10416574464 1071518032 11.567 100 99 188 0 1158449392 67577712 19353648
05:20:20 1463 8192000000 10057834688 10436788224 1080686096 11.654 100 97 192 1 1186021376 86074144 19261136
05:21:23 12177 8192000000 10038527296 10423459840 1079612880 11.644 100 69 128 1 1163313840 71754144 11946816
05:22:36 1241 8192000000 10032984384 10426679296 1082900048 11.675 100 79 152 1 1152776896 54097520 15779328
05:23:38 1405 8192000000 10045808544 10434076672 1089329168 11.736 100 82 156 2 1166718512 62338512 15050832
05:24:40 5411 8192000000 10040806320 10433859584 1093702752 11.778 100 67 131 0 1158089424 51496240 12890432
05:25:47 1215 8192000000 10052093984 10438856704 1100580560 11.843 100 92 176 0 1173776624 55395456 17800608
05:26:49 5793 8192000000 10048039200 10443735040 1099643664 11.834 100 67 134 0 1180468512 67339072 13485776
05:27:56 1351 8192000000 10041753184 10435461120 1089732848 11.740 100 77 150 0 1163561856 57863184 15965824
05:28:59 2147 8192000000 10032999264 10415951872 1076103984 11.610 99 81 152 2 1138379040 47198864 15076192
05:30:02 981 8192000000 10083772032 10471854080 1096992128 11.809 100 93 180 1 1203199984 87731120 18476736
05:31:05 1040 8192000000 10041472976 10424774656 1084003824 11.686 100 92 177 2 1161328016 60254400 17069792
05:32:07 6997 8192000000 9992012784 10371686400 1055085056 11.409 100 69 129 4 1115112816 46965360 13062400
05:33:15 1152 8192000000 10003937152 10385555456 1051594192 11.376 100 80 149 2 1134814832 65993120 17227520
05:34:18 1264 8192000000 10007239136 10379853824 1037545952 11.241 100 91 175 3 1132578880 76668400 18364528
05:35:20 1031 8192000000 9990270784 10366660608 1041292976 11.277 100 93 179 3 1109853728 51045584 17515168
05:36:22 4850 8192000000 9991282704 10364600320 1043613872 11.299 100 84 156 3 1113557440 53133984 16809584
05:37:30 19800 8192000000 10006361904 10383630336 1052269120 11.382 100 72 123 1 1120765232 57652736 10843376
05:38:55 14435 8192000000 10022733808 10410348544 1062831520 11.484 100 65 120 2 1148603488 73639072 12132896
05:40:15 1392 8192000000 10046175760 10427658240 1081772624 11.664 100 89 163 4 1163797360 65835264 16189472
05:41:23 2848 8192000000 10063814576 10460000256 1092549408 11.767 100 65 126 0 1189700848 83995888 13155552
05:42:30 2950 8192000000 10108503440 10496598016 1096548176 11.805 100 77 148 0 1228666528 116900368 15217984
05:43:39 5788 8192000000 10112022080 10510557184 1101721184 11.854 100 86 166 0 1226002656 107009200 17272272
05:44:47 828 8192000000 10154690608 10553282560 1117781920 12.006 100 94 179 2 1270605472 133268640 19554912
05:45:51 5809 8192000000 10155703440 10573004800 1127056688 12.094 100 70 138 0 1277348992 135166832 15125472
05:47:04 2253 8192000000 10214582816 10613465088 1141403008 12.229 100 87 168 1 1328366288 168455088 18508192
05:48:08 1431 8192000000 10222678880 10619404288 1153599504 12.343 100 90 173 0 1336360992 162199936 20561552
05:49:13 958 8192000000 10257021280 10655862784 1163572032 12.437 100 92 173 5 1369726736 186509136 19645568
05:50:16 1592 8192000000 10304542864 10703167488 1162822528 12.430 100 92 182 2 1409276736 226051040 20403168
05:51:21 1383 8192000000 10339162928 10740453376 1169677136 12.494 100 89 165 0 1439962400 251046336 19238928
05:52:25 1073 8192000000 10356050944 10773663744 1183040016 12.619 100 94 180 2 1463288880 258918560 21330304
05:53:29 954 8192000000 10375013280 10797629440 1189163600 12.676 100 97 190 0 1485975488 273356864 23455024
05:54:35 656 8192000000 10402974240 10805342208 1176482480 12.557 99 96 187 0 1511857168 313237312 22137376
05:55:39 1054 8192000000 10447513936 10877681664 1190269920 12.686 100 94 182 0 1553730048 342681008 20779120
05:56:44 979 8192000000 10470218352 10882129920 1185482064 12.641 100 96 181 1 1578666992 371472480 21712448
05:57:49 1489 8192000000 10514992768 10934190080 1191115200 12.694 100 92 178 1 1617148448 403797728 22235520
05:58:54 1394 8192000000 10556698144 10962718720 1197901888 12.757 100 95 188 0 1662210480 442138832 22169760
06:00:00 807 8192000000 10551357296 11000700928 1198562656 12.763 100 100 192 0 1656223792 434079872 23581264
06:01:05 1619 8192000000 10583585264 11023720448 1185363952 12.640 100 76 148 0 1685824240 480347632 20112656
06:02:08 6029 8192000000 10593499536 11023228928 1176642240 12.559 100 59 116 0 1698198336 506081184 15474912
06:03:46 11673 8192000000 10555714272 11083268096 1152906192 12.337 99 44 88 0 1678623744 512951104 12766448
06:05:05 9222 8192000000 10480444352 10976428032 1155947728 12.365 100 38 76 0 1595418976 428920032 10551216
06:06:23 16155 8192000000 10319146160 10762731520 1144836080 12.261 99 36 70 0 1428964400 274734640 9393680
06:07:47 41955 8192000000 10193010192 10699743232 1147433088 12.285 100 26 52 0 1339203376 185269728 6500560
06:09:50 60495 8192000000 10095327424 10542489600 1099879808 11.836 97 14 26 1 1142693104 39576944 3236352
06:11:55 69454 8192000000 10099166976 10635411456 1122944176 12.055 99 9 18 0 1221567424 96748864 1874384
06:14:06 36565 8192000000 10082356256 10518016000 1139094816 12.207 99 42 84 0 1187721232 39707888 8918528
06:15:48 19278 8192000000 10132137072 10563293184 1148658160 12.297 100 57 106 0 1227900848 68450176 10792512
06:17:28 104195 8192000000 10120639600 10576343040 1139956752 12.215 99 38 64 1 1215093920 68622416 6514752
06:20:13 68379 8192000000 10128220784 10593849344 1128852656 12.111 98 41 81 0 1211017136 73109712 9054768
06:22:23 85797 8192000000 10126735344 10619334656 1128934672 12.111 99 25 48 0 1234357920 100642032 4781216
06:25:27 63887 8192000000 10145620688 10610524160 1131145936 12.132 99 13 26 0 1239736944 105763344 2827664
06:27:36 40320 8192000000 10101204352 10581012480 1131417584 12.135 99 11 20 0 1197743888 64405952 1920352
06:29:18 47312 8192000000 10131011408 10608119808 1143873120 12.252 100 7 14 0 1218324320 73113280 1337920
06:32:36 69688 8192000000 10091575728 10568466432 1128085040 12.103 99 25 46 0 1201073104 68398704 4589360
06:34:54 15299 8192000000 10097764464 10533126144 1127591200 12.099 99 47 94 0 1194721520 57943904 9186416
06:36:10 74742 8192000000 10116199104 10568699904 1135562000 12.174 99 25 48 0 1218782624 78397920 4822704
06:38:25 46247 8192000000 10059801440 10501369856 1065500288 11.509 95 24 49 1 1174254112 102955952 5797872
06:40:18 68552 8192000000 10081563328 10507100160 1083418336 11.680 97 20 40 0 1133706272 46693248 3594688
06:42:27 40766 8192000000 10122042144 10583560192 1104121248 11.877 99 31 62 0 1212722336 102068704 6532384
06:44:08 60936 8192000000 10089735728 10524925952 1120196192 12.029 100 23 46 0 1199918560 74878048 4844320
06:46:10 23395 8192000000 10060499920 10492723200 1121782400 12.044 99 26 52 1 1162000512 35769376 4448736
06:47:34 35852 8192000000 10045219120 10503499776 1132432000 12.144 100 12 24 0 1162047440 27052784 2562656
06:49:13 50549 8192000000 10048463536 10490920960 1092017568 11.762 96 16 32 0 1131763008 36558464 3186976
06:51:15 49360 8192000000 10105257584 10563092480 1147326512 12.284 100 15 27 0 1216047376 65957216 2763648
06:53:05 62713 8192000000 10117304512 10570735616 1135055520 12.169 99 21 42 0 1191859104 52299472 4504112
06:55:46 45197 8192000000 10116630160 10554109952 1112776960 11.959 97 18 35 0 1191532000 74583776 4171264
06:57:36 57052 8192000000 10140116112 10602811392 1134283952 12.162 99 29 58 0 1217971872 76681360 7006560
06:59:47 15573 8192000000 10155059552 10591309824 1131712288 12.138 100 48 95 0 1234115392 92173712 10229392
07:01:16 19745 8192000000 10176848160 10622906368 1097344336 11.812 97 49 91 2 1261256608 154060224 9852048
07:02:39 42360 8192000000 10128863424 10563416064 1118419984 12.012 100 40 76 0 1227947120 101570032 7957104
07:04:23 24544 8192000000 10126019920 10563760128 1119348704 12.021 99 42 83 0 1209045712 81479360 8217648
07:05:50 38551 8192000000 10081811680 10520244224 1126222176 12.086 99 39 78 0 1171814816 37684880 7907760
07:07:59 11328 8192000000 10083478816 10526953472 1138374784 12.200 100 49 97 0 1189684288 41646176 9663328
07:09:11 55287 8192000000 10069785488 10533965824 1131695536 12.137 99 9 18 0 1178553824 44421264 2437024
07:12:56 59779 8192000000 10095066176 10606997504 1146261808 12.274 100 8 16 0 1186289312 38496912 1530592
07:14:56 52202 8192000000 10076890320 10544205824 1093116528 11.772 95 23 44 0 1159137584 61248864 4772192
07:16:58 23237 8192000000 10109152768 10549735424 1120483376 12.032 99 35 66 1 1189651616 62606816 6561424
07:18:21 54175 8192000000 10064421824 10537521152 1105637728 11.891 98 17 34 0 1142975264 33834448 3503088
07:20:16 34804 8192000000 10109577728 10556583936 1121475520 12.041 100 46 92 0 1227784080 97071232 9237328
07:21:51 57122 8192000000 10046780944 10496655360 1075518176 11.605 96 32 64 0 1128658480 46490368 6649936
07:23:51 29486 8192000000 10111219392 10541817856 1099048032 11.829 98 42 84 1 1187398176 79822832 8527312
07:25:21 22956 8192000000 10074161632 10501013504 1086144544 11.706 97 51 100 1 1147195296 50178896 10871856
07:26:46 95444 8192000000 10040782336 10482237440 1104569104 11.881 99 31 62 0 1131676816 20891712 6216000
07:29:54 57608 8192000000 10067788608 10561871872 1118175968 12.010 100 12 24 1 1184948800 63890608 2882224
07:31:57 55926 8192000000 10073482144 10557136896 1108744048 11.921 99 19 38 0 1186592112 73783024 4065040
07:33:57 76311 8192000000 10078509120 10538434560 1111581456 11.947 99 17 34 0 1189389280 73544720 4263104
07:36:21 50933 8192000000 10068983584 10507739136 1087116992 11.715 97 25 50 0 1136307632 44102656 5087984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-07-2022 07:38:40 to 28-07-2022 07:38:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-07-2022 07:38:57 to 28-07-2022 07:38:59.

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          221250
MON$OLDEST_ACTIVE               224057
MON$OLDEST_SNAPSHOT             224057
MON$NEXT_TRANSACTION            224057
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                       2060960
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:118: 
:119: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:20 2022
:120:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:121: 
:122: 
:123: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:20 2022
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:21 2022
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:23 2022
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:133: 
:134: 
:135: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:24 2022
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Thu Jul 28 07:37:24 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
--- end of diff output ---

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

28.07.22 07:39:00. 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
2022-07-28 07:39:03 No severe PSQL-related problems occured
28.07.22 07:39:04. End of report.