Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2022 19:40
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 15320500 kB
MemAvailable 30497708 kB
Buffers 0 kB
Cached 16420804 kB
SwapCached 2040 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-22 19:38:29
DTS_END                         2022-10-22 19:38:29.5450
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(57:17)
IP                              ::1/51492
TRN_ID                          249403
ATT_ID                          653
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: 9572.83 16689 1148739
customer order: creation 184.53 356 22143
customer order: refuse 15.68 326 1881
order to supplier: creation 48.99 1286 5879
order to supplier: removal 3.77 80654 452
invoice (draft): creation 49.94 3607 5993
invoice (draft): removal 53.72 747 6446
invoice accept: apply 44.59 17495 5351
invoice accept: cancel 48.04 786 5765
customer reserve: creation 4073.38 176 488806
customer reserve: removal 4740.15 50 568818
realization accept: apply 88.89 173 10667
realization accept: cancel 83.43 115 10012
payment from customer: creation 63.08 95 7570
payment from customer: removal 6.13 77 736
payment to supplier: creation 52.07 125 6248
payment to supplier: removal 4.01 48 481
service: total inventory turnovers 4.71 1188 565
service: total monetary turnovers 6.85 49 822
service: aggregate perf. data 0.83 21573 100
service: refresh index statistics 0.03 204854 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2022 19:41:02 to 22-10-2022 19:41:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2775
TEST_TIME 2 3283
TEST_TIME 3 3576
TEST_TIME 4 3782
TEST_TIME 5 3953
TEST_TIME 6 4346
TEST_TIME 7 4484
TEST_TIME 8 4836
TEST_TIME 9 4827
TEST_TIME 10 4899
TEST_TIME 11 5316
TEST_TIME 12 5328
TEST_TIME 13 5468
TEST_TIME 14 5765
TEST_TIME 15 5801
TEST_TIME 16 6120
TEST_TIME 17 6661
TEST_TIME 18 7236
TEST_TIME 19 7395
TEST_TIME 20 7760
TEST_TIME 21 8121
TEST_TIME 22 8412
TEST_TIME 23 8766
TEST_TIME 24 9355
TEST_TIME 25 9750
TEST_TIME 26 9651
TEST_TIME 27 10189
TEST_TIME 28 10254
TEST_TIME 29 10341
TEST_TIME 30 10123
TEST_TIME 31 10110
TEST_TIME 32 10144
TEST_TIME 33 10416
TEST_TIME 34 10473
TEST_TIME 35 10571
TEST_TIME 36 10592
TEST_TIME 37 10511
TEST_TIME 38 10348
TEST_TIME 39 10203
TEST_TIME 40 10123
TEST_TIME 41 10083
TEST_TIME 42 10080
TEST_TIME 43 9986
TEST_TIME 44 9987
TEST_TIME 45 9950
TEST_TIME 46 9915
TEST_TIME 47 9855
TEST_TIME 48 9913
TEST_TIME 49 9799
TEST_TIME 50 9911
TEST_TIME 51 9851
TEST_TIME 52 9789
TEST_TIME 53 9731
TEST_TIME 54 9703
TEST_TIME 55 9739
TEST_TIME 56 9790
TEST_TIME 57 9841
TEST_TIME 58 9866
TEST_TIME 59 9807
TEST_TIME 60 9819
TEST_TIME 61 9860
TEST_TIME 62 9999
TEST_TIME 63 10067
TEST_TIME 64 10042
TEST_TIME 65 10141
TEST_TIME 66 10087
TEST_TIME 67 10140
TEST_TIME 68 10148
TEST_TIME 69 10142
TEST_TIME 70 10030
TEST_TIME 71 9970
TEST_TIME 72 9921
TEST_TIME 73 9887
TEST_TIME 74 9881
TEST_TIME 75 9889
TEST_TIME 76 9826
TEST_TIME 77 9826
TEST_TIME 78 9828
TEST_TIME 79 9772
TEST_TIME 80 9774
TEST_TIME 81 9772
TEST_TIME 82 9759
TEST_TIME 83 9780
TEST_TIME 84 9816
TEST_TIME 85 9764
TEST_TIME 86 9810
TEST_TIME 87 9859
TEST_TIME 88 9889
TEST_TIME 89 9876
TEST_TIME 90 9889
TEST_TIME 91 9874
TEST_TIME 92 9871
TEST_TIME 93 9879
TEST_TIME 94 9794
TEST_TIME 95 9826
TEST_TIME 96 9791
TEST_TIME 97 9750
TEST_TIME 98 9753
TEST_TIME 99 9696
TEST_TIME 100 9658
TEST_TIME 101 9612
TEST_TIME 102 9659
TEST_TIME 103 9624
TEST_TIME 104 9619
TEST_TIME 105 9630
TEST_TIME 106 9649
TEST_TIME 107 9666
TEST_TIME 108 9672
TEST_TIME 109 9691
TEST_TIME 110 9680
TEST_TIME 111 9662
TEST_TIME 112 9702
TEST_TIME 113 9672
TEST_TIME 114 9782
TEST_TIME 115 9773
TEST_TIME 116 9745
TEST_TIME 117 9686
TEST_TIME 118 9647
TEST_TIME 119 9646
TEST_TIME 120 9574

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-10-2022 19:41:05 to 22-10-2022 19:41:09.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1601959 1601959 0 0.00 0 16922 6 0 0
doc_list_biud 1601957 1601957 0 0.00 0 47495 23 0 0
sp_add_invoice_to_stock 5466 5351 115 2.10 37 193744 17495 0 115
sp_cancel_adding_invoice 5765 5765 0 0.00 4 68367 786 0 0
sp_cancel_client_order 1881 1881 0 0.00 4 11514 326 0 0
sp_cancel_customer_reserve 568818 568818 0 0.00 0 47496 50 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 6831 77 0 0
sp_cancel_pay_to_supplier 481 481 0 0.00 3 1931 48 0 0
sp_cancel_supplier_invoice 6451 6446 5 0.07 1 22939 747 0 5
sp_cancel_supplier_order 452 452 0 0.00 8 977972 80655 0 0
sp_cancel_write_off 10012 10012 0 0.00 1 11545 116 0 0
sp_client_order 22143 22143 0 0.00 3 30322 356 0 0
sp_customer_reserve 488806 488806 0 0.00 0 89400 177 0 0
sp_fill_shopping_cart 34000 34000 0 0.00 1 17867 39 0 0
sp_fill_shopping_cart_clo_res 488801 488801 0 0.00 0 23748 13 0 0
sp_get_clo_for_invoice 5343 5343 0 0.00 0 34029 270 0 0
sp_kill_cost_storno 16994 16994 0 0.00 0 8947 53 0 0
sp_kill_qstorno_ret_qs2qd 1154546 1154546 0 0.00 0 30546 28 0 0
sp_kill_qty_storno 609384 609384 0 0.00 0 47494 60 0 0
sp_lock_selected_doc 22229 22229 0 0.00 0 8866 6 0 0
sp_make_cost_storno 29828 29828 0 0.00 0 16870 53 0 0
sp_multiply_rows_for_pdistr 29828 29828 0 0.00 0 6847 24 0 0
sp_multiply_rows_for_qdistr 22143 22143 0 0.00 0 25197 150 0 0
sp_pay_from_customer 7570 7570 0 0.00 4 15930 95 0 0
sp_pay_to_supplier 6248 6248 0 0.00 5 15331 126 0 0
sp_payment_common 13818 13818 0 0.00 1 6982 23 0 0
sp_qd_handle_on_cancel_clo 1881 1881 0 0.00 0 9163 299 0 0
sp_qd_handle_on_invoice_upd_sts 11108 11108 0 0.00 0 18869 297 0 0
sp_qd_handle_on_reserve_upd_sts 10667 10667 0 0.00 0 17724 62 0 0
sp_reserve_write_off 10667 10667 0 0.00 6 17752 174 0 0
sp_supplier_invoice 5993 5993 0 0.00 6 105437 3607 0 0
sp_supplier_order 5879 5879 0 0.00 4 29481 1287 0 0
srv_aggregate_perf_data 101 100 1 0.99 3097 530547 21574 0 1
srv_fill_mon_cache_memory 68 68 0 0.00 1611 124237 41785 0 0
srv_find_qd_qs_mism 1098163 1098163 0 0.00 0 11514 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 41 64090 1189 0 0
srv_make_money_saldo 822 822 0 0.00 1 11213 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 148519 343431 204855 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 5351 5351 0 0.00 0 35542 172 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 7726 139 0 0
t$perf-norm:sp_cancel_client_order 1881 1881 0 0.00 0 2553 15 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 14632 48 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 2525 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 481 481 0 0.00 0 1394 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2408 17 0 0
t$perf-norm:sp_cancel_supplier_order 452 452 0 0.00 0 28140 1546 0 0
t$perf-norm:sp_cancel_write_off 1073 1073 0 0.00 0 3742 16 0 0
t$perf-norm:sp_client_order 22143 22143 0 0.00 0 18332 18 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 6209 11 0 0
t$perf-norm:sp_pay_from_customer 7570 7570 0 0.00 0 19424 15 0 0
t$perf-norm:sp_pay_to_supplier 6248 6248 0 0.00 0 5230 10 0 0
t$perf-norm:sp_reserve_write_off 10667 10667 0 0.00 0 7950 12 0 0
t$perf-norm:sp_supplier_invoice 5993 5993 0 0.00 0 3756 16 0 0
t$perf-norm:sp_supplier_order 5879 5879 0 0.00 0 6316 15 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 475 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 424 7 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 750 16 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 3671 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10916 10686 230 2.10 0 9897 17 0 230
v_all_customers 40422 40422 0 0.00 0 80 1 0 0
v_all_suppliers 32516 32516 0 0.00 0 34 1 0 0
v_all_wares 44286 44286 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 1381 9 0 0
v_cancel_client_order 3762 3762 0 0.00 0 11460 7 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 6810 13 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 1846 7 0 0
v_cancel_payment_to_supplier 962 962 0 0.00 0 1923 4 0 0
v_cancel_supplier_invoice 812 802 10 1.23 0 10675 37 0 10
v_cancel_supplier_order 904 904 0 0.00 0 573 4 0 0
v_cancel_write_off 2146 2146 0 0.00 0 11498 21 0 0
v_max_id_clo_ord 5879 5879 0 0.00 0 450 1 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 2139 14 0 0
v_max_id_ord_sup 5977 5977 0 0.00 0 4745 6 0 0
v_max_non_paid_invoice 1846 1846 0 0.00 0 1996 5 0 0
v_max_non_paid_realizn 3978 3978 0 0.00 0 2033 4 0 0
v_min_id_clo_ord 5879 5879 0 0.00 0 1543 5 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 2898 16 0 0
v_min_id_ord_sup 5977 5977 0 0.00 0 9448 7 0 0
v_min_non_paid_invoice 6248 6248 0 0.00 0 6978 16 0 0
v_min_non_paid_realizn 7570 7570 0 0.00 0 5892 16 0 0
v_our_firm 11048 11048 0 0.00 0 30 1 0 0
v_reserve_write_off 21334 21334 0 0.00 0 10684 6 0 0
x_lock_depdocs_on_canc_invoice 11810 11810 0 0.00 0 17542 110 0 0
x_lock_depdocs_on_canc_sup_ord 452 452 0 0.00 0 3615 256 0 0
x_make_qstorno_1000_1200 5879 5879 0 0.00 1 29474 1218 0 0
x_make_qstorno_1200_2000 5993 5993 0 0.00 3 101438 3452 0 0
x_make_qstorno_2100_3300 488806 488806 0 0.00 0 89397 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2022 19:41:10 to 22-10-2022 19:41:14.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:33 217 8192000000 8844592096 8872108032 15603584 0.190 3 3 6 0 15886784 94128 189072
17:09:34 26641 8192000000 9903856672 10214449152 975935328 10.645 100 93 186 0 1006296624 6093824 24267472
17:11:06 22745 8192000000 9946610272 10270371840 1026389136 11.134 100 85 170 0 1056866032 8847264 21629632
17:12:38 14150 8192000000 9978913824 10315759616 1067785488 11.531 100 86 170 0 1098896336 9880576 21230272
17:13:57 11517 8192000000 10054625792 10401628160 1130333216 12.125 100 84 165 0 1161009616 11033216 19643184
17:15:13 4285 8192000000 10100128144 10459754496 1182970944 12.618 100 84 168 0 1214921696 12883440 19067312
17:16:20 2491 8192000000 10135320416 10504986624 1224215920 13.001 100 81 159 2 1254875344 12987296 17672128
17:17:26 1542 8192000000 10169319600 10546601984 1256010880 13.293 100 88 175 1 1289136992 13036448 20089664
17:18:30 1728 8192000000 10192971232 10573852672 1277332144 13.489 100 85 167 0 1310084784 13959440 18793200
17:19:33 26514 8192000000 10196938880 10594856960 1299047936 13.687 100 57 113 0 1322267680 10471184 12748560
17:21:03 3874 8192000000 10206631920 10607722496 1306088784 13.751 100 51 99 0 1326548864 8902192 11557888
17:22:10 11171 8192000000 10206518208 10614751232 1314293664 13.825 100 23 47 0 1326309360 6370512 5645184
17:23:24 12553 8192000000 10207508528 10625540096 1323996544 13.913 100 15 30 0 1333139584 5911664 3231376
17:24:43 48100 8192000000 10220091696 10641416192 1331046064 13.977 100 28 56 0 1342641552 4968848 6626640
17:26:31 2893 8192000000 10237679040 10656210944 1339891344 14.056 100 32 65 0 1353780352 6591760 7297248
17:27:36 5314 8192000000 10238702384 10658799616 1344475040 14.098 100 22 45 0 1354751824 5141216 5135568
17:28:42 18196 8192000000 10192835152 10603507712 1268534368 13.408 97 26 51 0 1279621584 5491760 5595456
17:30:01 21977 8192000000 10175545904 10586296320 1220968784 12.971 93 28 55 0 1233141488 6445936 5726768
17:31:24 4272 8192000000 10155622288 10555502592 1252490320 13.261 99 23 46 0 1264654832 6365616 5798896
17:32:30 8029 8192000000 10150520928 10547486720 1226375776 13.021 97 27 54 0 1242061248 9433984 6251488
17:33:38 7842 8192000000 10170223888 10563387392 1263586384 13.363 100 47 93 1 1280919472 6117904 11215184
17:35:14 5881 8192000000 10173666400 10585198592 1281669872 13.528 100 11 22 0 1293584960 9676080 2239008
17:36:20 9330 8192000000 10192202496 10605584384 1300795680 13.702 100 27 54 0 1313040688 5938240 6306768
17:37:32 8553 8192000000 10199405040 10610229248 1306231456 13.752 100 22 41 1 1317319792 5554512 5533824
17:38:42 69527 8192000000 10224816240 10641981440 1327668640 13.946 100 42 81 0 1341656208 4175456 9812112
17:41:01 24253 8192000000 10268790128 10680455168 1351696848 14.163 100 55 109 0 1376682896 12632352 12353696
17:43:38 27768 8192000000 10289638944 10709356544 1372277168 14.347 100 49 97 0 1401140768 17271584 11592016
17:45:08 23035 8192000000 10299218432 10723487744 1385124048 14.462 100 45 91 0 1416736464 20558784 11053632
17:46:36 9446 8192000000 10317971888 10742796288 1398817936 14.584 100 49 98 0 1434527920 23892656 11817328
17:47:50 8071 8192000000 10326059136 10748473344 1396961312 14.568 100 50 98 2 1435645824 27052336 11632176
17:49:00 1768 8192000000 10332400352 10745663488 1396195952 14.561 100 61 118 1 1437897584 28204608 13497024
17:50:05 1825 8192000000 10336179424 10745683968 1394849552 14.549 100 68 135 0 1442265376 30549184 16866640
17:51:10 7022 8192000000 10348422080 10767544320 1398418368 14.581 99 59 118 0 1452321984 39203440 14700176
17:52:23 14999 8192000000 10350623216 10770907136 1395430656 14.554 99 58 116 1 1453488848 42887936 15170256
17:53:48 3518 8192000000 10347718864 10760294400 1386681760 14.476 100 68 136 1 1453324384 49211328 17431296
17:54:57 3011 8192000000 10327700000 10739359744 1360713600 14.244 98 58 115 0 1426890688 51257744 14919344
17:56:11 1610 8192000000 10333364112 10734317568 1366071072 14.292 100 82 164 1 1433910768 47521136 20318560
17:57:21 2953 8192000000 10297845712 10700251136 1337531472 14.035 99 67 134 2 1396619952 42034320 17054160
17:58:26 14003 8192000000 10290979344 10718076928 1356606096 14.207 100 35 69 1 1403189312 37195408 9387808
17:59:45 2339 8192000000 10332433216 10743164928 1365459424 14.286 100 72 140 1 1432478960 48242080 18777456
18:00:52 17729 8192000000 10304590000 10736480256 1360008720 14.237 100 32 64 0 1411430224 42053312 9368192
18:02:12 18356 8192000000 10294031152 10728910848 1350458496 14.152 100 24 48 0 1404424416 46834992 7130928
18:03:32 42242 8192000000 10293778656 10721599488 1351098960 14.157 99 35 69 0 1383723536 23951296 8673280
18:05:16 34753 8192000000 10273902400 10722795520 1348250288 14.132 99 21 42 0 1378141312 23986720 5904304
18:06:52 104232 8192000000 10266532544 10693771264 1292350800 13.626 95 17 32 0 1305793504 10041200 3401504
18:09:41 44862 8192000000 10283625440 10718736384 1356802032 14.209 99 32 64 0 1380902976 16472256 7628688
18:11:39 88128 8192000000 10299210400 10757828608 1355657536 14.198 98 10 20 0 1377297728 19455824 2184368
18:14:09 50336 8192000000 10302414784 10765742080 1387796704 14.486 100 12 25 1 1407540784 16390160 3353920
18:16:00 40015 8192000000 10302811984 10743336960 1380173232 14.418 99 30 60 1 1398229264 11481952 6574080
18:17:40 70977 8192000000 10298527632 10747555840 1374300400 14.366 99 26 52 0 1391078896 9920464 6858032
18:19:54 37130 8192000000 10296697040 10748895232 1395486864 14.555 100 27 51 0 1411941216 10572032 5882320
18:21:36 20995 8192000000 10300444112 10735149056 1386538720 14.475 99 57 115 1 1406372304 7026752 12806832
18:22:58 30894 8192000000 10287482576 10739769344 1376886992 14.389 99 27 54 0 1391790480 8512896 6390592
18:24:32 22123 8192000000 10329146144 10771066880 1407455888 14.661 100 56 112 0 1433994448 13632624 12905936
18:25:58 34886 8192000000 10308397072 10770358272 1387501600 14.484 99 21 42 0 1409020112 16091312 5427200
18:27:34 30169 8192000000 10291669104 10752131072 1370766768 14.334 98 13 26 0 1389467664 15433680 3267216
18:29:05 59752 8192000000 10282976064 10743431168 1378070624 14.399 99 15 30 0 1389656832 7783936 3802272
18:31:21 20511 8192000000 10282198048 10736771072 1369706160 14.324 99 37 74 0 1385588000 7846224 8035616
18:32:43 53083 8192000000 10280559616 10751975424 1378158208 14.400 100 12 24 0 1395862560 14803680 2900672
18:34:36 54472 8192000000 10257930496 10713165824 1279469760 13.508 92 13 26 0 1290255312 7753888 3031664
18:36:37 63018 8192000000 10263540464 10710978560 1313240688 13.815 96 22 44 0 1326819200 8577568 5000944
18:38:40 16618 8192000000 10270638816 10713137152 1306393888 13.753 95 32 64 1 1326408048 12957136 7057024
18:40:04 72895 8192000000 10272339456 10735165440 1345742800 14.109 99 10 20 0 1366261472 18296208 2222464
18:42:18 48456 8192000000 10267050528 10723905536 1318098960 13.859 96 12 24 0 1334209328 13355824 2754544
18:44:07 38046 8192000000 10261527776 10699759616 1326737536 13.938 98 27 54 0 1341637344 8556768 6343040
18:45:50 61882 8192000000 10236604720 10679816192 1261227968 13.341 94 22 44 1 1275177344 9052048 4897328
18:47:54 67219 8192000000 10229147952 10660163584 1303783760 13.730 98 31 62 0 1321924352 11267408 6873184
18:50:02 26792 8192000000 10259175024 10696888320 1347749792 14.127 100 43 82 1 1368069792 11436688 8883312
18:51:40 19523 8192000000 10266607968 10702704640 1353524576 14.179 100 48 95 0 1375986832 11281408 11180848
18:53:05 36620 8192000000 10268356496 10724978688 1359925664 14.237 100 5 10 0 1375248272 14297376 1025232
18:54:43 49876 8192000000 10258033360 10707300352 1343790160 14.092 98 23 44 0 1360313248 11221840 5301248
18:56:41 36212 8192000000 10268274272 10719117312 1365058928 14.283 100 18 36 0 1376377040 7732768 3585344
18:58:21 65735 8192000000 10256254800 10718261248 1327338672 13.943 97 6 12 1 1337801472 9047328 1415472
19:00:29 28525 8192000000 10272614512 10719485952 1346808592 14.119 98 24 48 0 1360571552 8060976 5701984
19:02:02 29123 8192000000 10273430288 10721988608 1356543600 14.206 99 32 64 1 1371677520 8357744 6776176
19:03:35 43019 8192000000 10298435584 10743758848 1377125888 14.391 100 29 58 0 1395453440 12105216 6222336
19:05:18 112249 8192000000 10302544880 10760261632 1389062720 14.498 100 13 26 1 1409469664 17487152 2919792
19:08:11 76037 8192000000 10306802944 10764021760 1357556720 14.215 97 17 34 0 1369665520 8615072 3493728
19:10:27 35787 8192000000 10314064592 10758041600 1376554848 14.386 98 30 60 0 1393576576 10554528 6467200
19:12:03 111552 8192000000 10310077296 10758959104 1371793840 14.343 98 21 40 1 1394541760 17233376 5514544
19:14:57 35522 8192000000 10263995728 10718056448 1346421712 14.115 97 18 36 0 1360780336 10396848 3961776
19:16:43 42910 8192000000 10257823360 10713436160 1331360160 13.979 96 10 18 0 1340200080 6865376 1974544
19:18:27 55125 8192000000 10288103392 10748973056 1374017328 14.363 99 24 48 0 1390247456 10902176 5327952
19:20:30 51982 8192000000 10283572400 10741604352 1375282064 14.374 99 21 40 1 1391404048 11587936 4534048
19:22:23 47260 8192000000 10286255472 10743242752 1346070320 14.112 97 18 36 0 1367554928 17007424 4477184
19:24:11 37493 8192000000 10281124032 10735849472 1354871152 14.191 98 22 44 1 1367941008 8325008 4744848
19:25:52 56627 8192000000 10296362912 10752118784 1359845840 14.236 98 23 46 1 1375662352 10500976 5315536
19:27:57 51731 8192000000 10285471904 10726682624 1349907888 14.147 98 15 28 0 1363288768 10222656 3158224
19:30:07 67911 8192000000 10265508624 10713133056 1312724704 13.811 97 30 60 1 1339996256 20311568 6959984
19:32:19 69549 8192000000 10260415552 10721214464 1300727360 13.702 96 15 30 0 1322368496 18259296 3381840
19:34:30 124237 8192000000 10245008080 10701381632 1343025840 14.085 99 21 42 0 1356136208 8713712 4396656
19:37:35 70967 8192000000 9958743408 10371547136 1076803120 11.617 79 5 10 0 1087271104 9372544 1095440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-10-2022 19:41:16 to 22-10-2022 19:41:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 115 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2022 19:41:32 to 22-10-2022 19:41:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          249712
MON$OLDEST_ACTIVE               249713
MON$OLDEST_SNAPSHOT             249713
MON$NEXT_TRANSACTION            249713
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2043680
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

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

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

22.10.22 19:41:35. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-10-22 19:41:38 No severe PSQL-related problems occured
22.10.22 19:41:39. End of report.