Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2022 16: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 15479424 kB
MemAvailable 30550936 kB
Buffers 0 kB
Cached 16268240 kB
SwapCached 2180 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-12 16:38:31
DTS_END                         2022-10-12 16:38:31.7100
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/38190
TRN_ID                          248162
ATT_ID                          662
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: 9655.13 17721 1158616
customer order: creation 184.99 316 22199
customer order: refuse 15.38 289 1845
order to supplier: creation 49.17 1167 5900
order to supplier: removal 3.69 84992 443
invoice (draft): creation 49.58 2954 5949
invoice (draft): removal 55.98 691 6717
invoice accept: apply 44.53 16897 5343
invoice accept: cancel 49.68 691 5962
customer reserve: creation 4081.36 170 489763
customer reserve: removal 4805.83 51 576700
realization accept: apply 89.17 167 10700
realization accept: cancel 85.98 89 10317
payment from customer: creation 63.50 127 7620
payment from customer: removal 6.19 68 743
payment to supplier: creation 53.22 110 6386
payment to supplier: removal 3.78 93 454
service: total inventory turnovers 5.01 976 601
service: total monetary turnovers 7.21 25 865
service: aggregate perf. data 0.88 21595 105
service: refresh index statistics 0.03 222960 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2022 16:41:03 to 12-10-2022 16:41:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5336
TEST_TIME 2 5474
TEST_TIME 3 5742
TEST_TIME 4 5425
TEST_TIME 5 5690
TEST_TIME 6 5643
TEST_TIME 7 6127
TEST_TIME 8 5999
TEST_TIME 9 6009
TEST_TIME 10 6458
TEST_TIME 11 6744
TEST_TIME 12 6587
TEST_TIME 13 7004
TEST_TIME 14 6945
TEST_TIME 15 7175
TEST_TIME 16 8335
TEST_TIME 17 8222
TEST_TIME 18 8444
TEST_TIME 19 9039
TEST_TIME 20 9191
TEST_TIME 21 9132
TEST_TIME 22 9822
TEST_TIME 23 9910
TEST_TIME 24 10233
TEST_TIME 25 10174
TEST_TIME 26 10398
TEST_TIME 27 10512
TEST_TIME 28 11272
TEST_TIME 29 11182
TEST_TIME 30 10987
TEST_TIME 31 10906
TEST_TIME 32 10926
TEST_TIME 33 10968
TEST_TIME 34 10988
TEST_TIME 35 10987
TEST_TIME 36 10986
TEST_TIME 37 10746
TEST_TIME 38 10567
TEST_TIME 39 10395
TEST_TIME 40 10335
TEST_TIME 41 10230
TEST_TIME 42 10241
TEST_TIME 43 10203
TEST_TIME 44 10127
TEST_TIME 45 10086
TEST_TIME 46 9991
TEST_TIME 47 9906
TEST_TIME 48 9899
TEST_TIME 49 9844
TEST_TIME 50 9831
TEST_TIME 51 9773
TEST_TIME 52 9751
TEST_TIME 53 9723
TEST_TIME 54 9621
TEST_TIME 55 9588
TEST_TIME 56 9559
TEST_TIME 57 9580
TEST_TIME 58 9582
TEST_TIME 59 9614
TEST_TIME 60 9601
TEST_TIME 61 9714
TEST_TIME 62 9715
TEST_TIME 63 9695
TEST_TIME 64 9795
TEST_TIME 65 9806
TEST_TIME 66 9891
TEST_TIME 67 9862
TEST_TIME 68 9926
TEST_TIME 69 9875
TEST_TIME 70 10005
TEST_TIME 71 10071
TEST_TIME 72 9975
TEST_TIME 73 9926
TEST_TIME 74 9935
TEST_TIME 75 9875
TEST_TIME 76 9835
TEST_TIME 77 9830
TEST_TIME 78 9778
TEST_TIME 79 9771
TEST_TIME 80 9765
TEST_TIME 81 9698
TEST_TIME 82 9750
TEST_TIME 83 9707
TEST_TIME 84 9727
TEST_TIME 85 9688
TEST_TIME 86 9664
TEST_TIME 87 9699
TEST_TIME 88 9788
TEST_TIME 89 9756
TEST_TIME 90 9730
TEST_TIME 91 9772
TEST_TIME 92 9836
TEST_TIME 93 9892
TEST_TIME 94 9904
TEST_TIME 95 9912
TEST_TIME 96 9895
TEST_TIME 97 9829
TEST_TIME 98 9768
TEST_TIME 99 9748
TEST_TIME 100 9749
TEST_TIME 101 9726
TEST_TIME 102 9671
TEST_TIME 103 9635
TEST_TIME 104 9625
TEST_TIME 105 9579
TEST_TIME 106 9557
TEST_TIME 107 9565
TEST_TIME 108 9564
TEST_TIME 109 9536
TEST_TIME 110 9553
TEST_TIME 111 9584
TEST_TIME 112 9649
TEST_TIME 113 9643
TEST_TIME 114 9620
TEST_TIME 115 9650
TEST_TIME 116 9690
TEST_TIME 117 9668
TEST_TIME 118 9671
TEST_TIME 119 9744
TEST_TIME 120 9668

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2022 16:41:07 to 12-10-2022 16:41:10.

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 1612654 1612654 0 0.00 0 19256 6 0 0
doc_list_biud 1612651 1612651 0 0.00 0 27348 23 0 0
sp_add_invoice_to_stock 5425 5343 82 1.51 48 171341 16897 0 82
sp_cancel_adding_invoice 5962 5962 0 0.00 5 50364 692 0 0
sp_cancel_client_order 1845 1845 0 0.00 4 8584 289 0 0
sp_cancel_customer_reserve 576700 576700 0 0.00 0 20877 51 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 4958 68 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 2 8965 93 0 0
sp_cancel_supplier_invoice 6723 6717 6 0.08 1 12775 692 0 6
sp_cancel_supplier_order 443 443 0 0.00 5 1085456 84992 0 0
sp_cancel_write_off 10317 10317 0 0.00 1 6315 90 0 0
sp_client_order 22199 22199 0 0.00 3 18060 316 0 0
sp_customer_reserve 489763 489763 0 0.00 0 48005 170 0 0
sp_fill_shopping_cart 34048 34048 0 0.00 1 10707 33 0 0
sp_fill_shopping_cart_clo_res 489742 489742 0 0.00 0 13409 11 0 0
sp_get_clo_for_invoice 5320 5320 0 0.00 0 29497 262 0 0
sp_kill_cost_storno 17476 17476 0 0.00 0 6485 45 0 0
sp_kill_qstorno_ret_qs2qd 1170877 1170877 0 0.00 0 27347 28 0 0
sp_kill_qty_storno 618004 618004 0 0.00 0 27348 60 0 0
sp_lock_selected_doc 22138 22138 0 0.00 0 8521 6 0 0
sp_make_cost_storno 30026 30026 0 0.00 0 19251 47 0 0
sp_multiply_rows_for_pdistr 30026 30026 0 0.00 0 18667 25 0 0
sp_multiply_rows_for_qdistr 22199 22199 0 0.00 0 10565 142 0 0
sp_pay_from_customer 7620 7620 0 0.00 3 18673 127 0 0
sp_pay_to_supplier 6386 6386 0 0.00 4 37240 111 0 0
sp_payment_common 14006 14006 0 0.00 1 13937 26 0 0
sp_qd_handle_on_cancel_clo 1845 1845 0 0.00 0 8534 269 0 0
sp_qd_handle_on_invoice_upd_sts 11282 11282 0 0.00 0 22255 286 0 0
sp_qd_handle_on_reserve_upd_sts 10700 10700 0 0.00 0 18362 51 0 0
sp_reserve_write_off 10700 10700 0 0.00 5 19267 167 0 0
sp_supplier_invoice 5949 5949 0 0.00 5 87068 2955 0 0
sp_supplier_order 5900 5900 0 0.00 5 36592 1168 0 0
srv_aggregate_perf_data 106 105 1 0.94 2754 381059 21596 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1885 153422 44975 0 0
srv_find_qd_qs_mism 1107731 1107731 0 0.00 0 10417 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 30 84449 977 0 0
srv_make_money_saldo 865 865 0 0.00 1 2012 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 149924 378516 222961 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 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 5344 5344 0 0.00 0 12065 160 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 5117 118 0 0
t$perf-norm:sp_cancel_client_order 1845 1845 0 0.00 0 8572 16 0 0
t$perf-norm:sp_cancel_customer_reserve 752 752 0 0.00 0 4512 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 1374 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 224 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2279 17 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 25712 1718 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 9240 18 0 0
t$perf-norm:sp_client_order 22199 22199 0 0.00 0 16713 16 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 9294 14 0 0
t$perf-norm:sp_pay_from_customer 7620 7620 0 0.00 0 11775 16 0 0
t$perf-norm:sp_pay_to_supplier 6386 6386 0 0.00 0 8696 12 0 0
t$perf-norm:sp_reserve_write_off 10700 10700 0 0.00 0 14359 15 0 0
t$perf-norm:sp_supplier_invoice 5949 5949 0 0.00 0 8675 22 0 0
t$perf-norm:sp_supplier_order 5900 5900 0 0.00 0 9486 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 838 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 6600 111 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 5119 32 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 1213 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 7 0 0
v_add_invoice_to_stock 10798 10634 164 1.51 0 11159 20 0 164
v_all_customers 40012 40012 0 0.00 0 37 0 0 0
v_all_suppliers 33038 33038 0 0.00 0 25 1 0 0
v_all_wares 44398 44398 0 0.00 0 57 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 2163 9 0 0
v_cancel_client_order 3690 3690 0 0.00 0 2537 5 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 4089 8 0 0
v_cancel_customer_reserve 1504 1504 0 0.00 0 9020 10 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 7067 16 0 0
v_cancel_supplier_invoice 854 842 12 1.40 0 2175 16 0 12
v_cancel_supplier_order 886 886 0 0.00 0 1524 5 0 0
v_cancel_write_off 2068 2068 0 0.00 0 4771 16 0 0
v_max_id_clo_ord 5900 5900 0 0.00 0 2270 1 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 3205 24 0 0
v_max_id_ord_sup 5949 5949 0 0.00 0 9241 6 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 179 2 0 0
v_max_non_paid_realizn 4216 4216 0 0.00 0 6588 5 0 0
v_min_id_clo_ord 5900 5900 0 0.00 0 1650 4 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 1581 5 0 0
v_min_id_ord_sup 5949 5949 0 0.00 0 6429 7 0 0
v_min_non_paid_invoice 6386 6386 0 0.00 0 8055 13 0 0
v_min_non_paid_realizn 7620 7620 0 0.00 0 11783 21 0 0
v_our_firm 11194 11194 0 0.00 0 18 1 0 0
v_reserve_write_off 21400 21400 0 0.00 0 11173 8 0 0
x_lock_depdocs_on_canc_invoice 12258 12258 0 0.00 0 21441 105 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 20498 403 0 0
x_make_qstorno_1000_1200 5900 5900 0 0.00 1 36574 1102 0 0
x_make_qstorno_1200_2000 5949 5949 0 0.00 2 84389 2835 0 0
x_make_qstorno_2100_3300 489763 489763 0 0.00 0 48003 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 16:41:11 to 12-10-2022 16:41:15.

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
14:08:35 3 8192000000 8801222080 8813252608 7247952 0.088 2 2 3 0 7389040 66144 74944
14:09:36 12256 8192000000 9874170192 10177490944 956194736 10.452 100 88 176 0 984928576 5408400 23325440
14:11:02 21702 8192000000 9929426720 10248011776 1014924768 11.023 100 91 182 0 1046703632 7827808 23951056
14:12:29 16657 8192000000 9961745664 10286252032 1047037744 11.332 100 88 176 1 1078967584 9402816 22527024
14:13:53 3484 8192000000 10010794192 10353451008 1095120720 11.791 100 79 158 0 1124414352 10696880 18596752
14:15:00 4394 8192000000 10059964608 10415038464 1151056336 12.319 100 72 140 1 1179272704 12016240 16200128
14:16:09 3517 8192000000 10095323440 10457812992 1187683824 12.662 100 80 158 0 1222555344 17027600 17843920
14:17:15 1370 8192000000 10134593744 10506104832 1227631488 13.032 100 84 168 0 1258355504 12131360 18592656
14:18:19 4931 8192000000 10167347312 10544529408 1254774432 13.282 100 75 149 1 1285037488 14345040 15918016
14:19:27 1100 8192000000 10190995072 10573848576 1281193600 13.524 100 80 159 1 1310503280 11490544 17819136
14:20:29 4690 8192000000 10194703296 10590457856 1292552944 13.627 100 61 121 0 1320673312 14345424 13774944
14:21:36 6452 8192000000 10191338496 10602614784 1306570624 13.755 100 26 52 0 1319123488 7112320 5440544
14:22:46 22720 8192000000 10195305984 10617114624 1311507312 13.800 100 14 28 0 1320431776 5511536 3412928
14:24:11 10228 8192000000 10205054432 10622009344 1315992688 13.840 100 18 36 0 1325842592 5821920 4027984
14:25:24 7093 8192000000 10211048752 10628972544 1306439760 13.754 99 19 38 0 1320709760 10075392 4194608
14:26:31 11907 8192000000 10217980272 10638016512 1325093504 13.923 100 14 29 0 1333773088 5659824 3019760
14:27:46 66028 8192000000 10207735264 10627215360 1310685792 13.792 99 23 44 0 1320472320 4880720 4905808
14:29:53 7432 8192000000 10188721904 10602590208 1281693664 13.528 98 24 48 0 1293283264 6036704 5552896
14:31:02 12032 8192000000 10127794912 10519150592 1155067376 12.357 90 20 40 0 1164859184 5174848 4616960
14:32:15 11576 8192000000 10131451232 10513715200 1215553264 12.921 97 18 35 1 1224895120 5403616 3938240
14:33:29 18610 8192000000 10127204304 10528894976 1209645456 12.866 97 13 26 0 1217532608 4917808 2969344
14:34:47 6764 8192000000 10167149056 10571497472 1274315136 13.461 100 24 49 0 1285106912 4973232 5818544
14:35:56 9191 8192000000 10186854720 10596913152 1294403488 13.644 100 27 52 0 1306121472 5434448 6283536
14:37:06 27019 8192000000 10191086528 10610434048 1303457168 13.727 100 24 46 0 1318354240 9617232 5279840
14:38:46 35817 8192000000 10228897360 10636693504 1321581184 13.891 100 38 73 0 1336446016 6770240 8094592
14:40:29 16637 8192000000 10264905376 10679607296 1354881984 14.191 100 42 82 1 1377274832 12902704 9490144
14:41:51 8077 8192000000 10279093696 10697883648 1367335168 14.303 100 51 100 0 1394097984 15577824 11184992
14:43:07 6028 8192000000 10291593712 10707120128 1368898992 14.317 99 47 90 2 1395537392 15744336 10894064
14:44:22 11880 8192000000 10297182896 10722488320 1386304944 14.473 100 41 79 1 1413627552 17087648 10234960
14:45:37 17723 8192000000 10294912848 10725355520 1375183024 14.373 99 39 78 0 1405129168 20105008 9841136
14:46:58 12139 8192000000 10326900736 10745249792 1398833584 14.585 100 53 106 0 1433739696 22601424 12304688
14:48:13 9911 8192000000 10334200784 10765795328 1410600992 14.689 100 39 77 1 1445360736 25126224 9633520
14:49:27 3670 8192000000 10357004976 10777153536 1421097744 14.782 100 70 135 0 1465029728 27394704 16537280
14:50:35 5345 8192000000 10363424528 10787004416 1412433344 14.706 99 55 107 0 1459583312 34312048 12837920
14:51:45 1884 8192000000 10357786096 10775420928 1412330320 14.705 100 62 121 0 1463037552 35572032 15135200
14:52:50 5380 8192000000 10367755712 10785824768 1416019872 14.737 100 73 144 0 1474374896 40824144 17530880
14:53:59 3299 8192000000 10364125216 10786414592 1407179280 14.659 100 68 136 2 1470793472 45966576 17647616
14:55:05 3627 8192000000 10332725456 10760409088 1397217440 14.570 100 43 83 2 1439689680 31298400 11173840
14:56:12 1947 8192000000 10350222896 10767773696 1395595536 14.556 100 66 132 1 1453703680 41348240 16759904
14:57:20 2724 8192000000 10357998624 10775445504 1400930144 14.603 100 73 142 0 1459128560 40633136 17565280
14:58:26 13736 8192000000 10340187760 10766344192 1400891808 14.603 100 34 68 0 1444857696 34762272 9203616
14:59:49 2429 8192000000 10335370032 10763546624 1379367072 14.411 99 42 84 0 1432035568 41396000 11272496
15:01:05 41401 8192000000 10288177360 10722373632 1330889824 13.975 97 20 40 0 1370602512 33840144 5872544
15:03:03 33733 8192000000 10294629856 10729357312 1353529408 14.179 99 13 26 0 1387524368 30528576 3466384
15:04:49 31418 8192000000 10251197264 10689523712 1287628800 13.583 96 15 30 0 1321514512 29760416 4125296
15:06:22 29667 8192000000 10234575984 10684612608 1315624352 13.837 99 10 20 0 1339222624 20952544 2645728
15:07:52 132605 8192000000 10248701616 10702888960 1275182880 13.469 95 15 30 1 1287955120 9807040 2965200
15:11:23 92075 8192000000 10270331296 10716413952 1349699056 14.145 99 12 24 1 1363837376 11147456 2990864
15:13:57 153422 8192000000 10286879616 10722914304 1337624624 14.036 97 19 35 0 1352431824 10229168 4578032
15:17:32 22796 8192000000 10280571040 10720972800 1363460672 14.268 99 35 70 1 1379978880 8587296 7930912
15:18:58 15436 8192000000 10274059744 10723110912 1366139424 14.292 99 12 24 0 1376222336 7434992 2647920
15:20:19 21189 8192000000 10277926416 10730975232 1364364544 14.277 99 52 103 0 1385583008 10549968 10668496
15:21:41 24950 8192000000 10281513840 10730905600 1379899696 14.416 100 10 20 0 1388775744 6663392 2212656
15:23:46 29793 8192000000 10287918768 10749665280 1392357648 14.527 100 11 22 0 1401552656 6789232 2405776
15:25:22 54327 8192000000 10307993792 10761814016 1390742960 14.512 99 11 21 0 1402180224 9029232 2408032
15:27:21 63185 8192000000 10298036208 10765205504 1390369856 14.509 99 18 36 0 1407889600 12563968 4955776
15:29:44 79730 8192000000 10290571344 10751070208 1378022016 14.399 98 10 20 0 1388455552 8336208 2097328
15:32:04 72558 8192000000 10288157616 10757201920 1385811888 14.468 99 21 40 0 1398699824 8186672 4701264
15:34:24 73343 8192000000 10286547712 10758090752 1358065504 14.220 97 7 14 0 1369939264 10680160 1193600
15:36:37 48335 8192000000 10295742384 10755555328 1357355040 14.214 97 12 24 0 1372096768 11775584 2966144
15:38:29 34370 8192000000 10302817248 10753781760 1368440384 14.313 98 28 56 0 1388300752 13934480 5925888
15:40:06 22014 8192000000 10287907024 10735792128 1348048048 14.130 97 17 32 0 1357848032 6685568 3114416
15:41:28 26179 8192000000 10309940224 10749771776 1363460864 14.268 98 44 86 1 1383821008 10006480 10353664
15:42:56 89657 8192000000 10252785728 10692431872 1262498768 13.353 92 20 37 0 1282854848 16250624 4105456
15:45:27 55092 8192000000 10242024480 10684919808 1275540736 13.472 94 21 42 0 1290927072 10966128 4420208
15:47:24 37237 8192000000 10267179968 10707206144 1348481984 14.134 100 33 65 0 1367660160 11837504 7340672
15:49:10 65744 8192000000 10247962800 10677002240 1267705184 13.401 94 20 40 0 1287188384 14963824 4519376
15:51:16 80528 8192000000 10246128576 10702184448 1345138352 14.104 100 29 58 0 1361900896 10063152 6699392
15:53:48 53415 8192000000 10263807472 10714124288 1333822256 14.002 98 28 56 1 1353083248 12743872 6517120
15:55:45 69632 8192000000 10244880272 10703360000 1332837392 13.993 98 14 28 0 1348566048 11947744 3780912
15:57:55 55830 8192000000 10256895472 10711171072 1355347664 14.196 100 10 20 1 1370966368 13023184 2595520
15:59:51 53426 8192000000 10253999376 10718842880 1351959376 14.165 100 10 20 0 1366602544 12335616 2307552
16:01:48 16773 8192000000 10269547344 10715676672 1364665488 14.279 100 39 77 2 1380927520 6704832 9557200
16:03:13 59173 8192000000 10298824304 10755084288 1377213328 14.392 99 28 55 0 1394482624 10782464 6486832
16:05:12 45412 8192000000 10293184992 10747539456 1383580368 14.449 100 30 60 0 1406754368 16107616 7066384
16:07:02 47815 8192000000 10287578736 10741207040 1370388272 14.331 99 23 46 0 1384042240 8579328 5074640
16:08:51 40293 8192000000 10295464848 10740002816 1375265888 14.374 100 39 78 1 1399427264 15898144 8263232
16:10:31 106025 8192000000 10286056816 10750791680 1335578384 14.018 96 11 22 0 1357196224 19108736 2509104
16:13:19 82965 8192000000 10301336256 10752761856 1372934080 14.353 99 14 28 0 1386741440 11150144 2657216
16:15:53 107189 8192000000 10284365552 10742169600 1347925712 14.129 97 28 56 0 1366006928 11500624 6580592
16:18:50 62168 8192000000 10259653520 10723696640 1355619376 14.198 99 10 21 0 1367640496 9777968 2243152
16:20:53 27633 8192000000 10276530848 10733223936 1353514384 14.179 98 24 48 1 1370811312 11394912 5902016
16:22:23 87640 8192000000 10263707072 10731421696 1351321600 14.159 98 19 38 0 1367778224 11865792 4590832
16:24:52 64461 8192000000 10278972384 10743898112 1365490736 14.287 99 17 34 2 1389688816 20119600 4078480
16:26:56 43133 8192000000 10283921536 10738024448 1365217920 14.284 99 29 58 0 1383451376 11672224 6561232
16:28:43 135484 8192000000 10283357760 10747162624 1340478784 14.062 97 8 16 0 1363479568 21060320 1940464
16:31:59 18757 8192000000 10306240176 10754822144 1390323024 14.509 100 39 78 0 1409099760 10069440 8707296
16:33:21 103878 8192000000 10291607408 10745610240 1351881968 14.164 97 19 38 0 1371519392 15346880 4290544
16:36:07 12612 8192000000 10280520592 10708017152 1301516992 13.709 95 58 117 1 1328103408 13667696 12918720
16:37:23 81493 8192000000 9847472016 10240942080 961150608 10.500 71 14 26 0 972379648 8850784 2378256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 16:41:17 to 12-10-2022 16:41:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 82 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 12-10-2022 16:41:34 to 12-10-2022 16:41:36.

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          248485
MON$OLDEST_ACTIVE               248486
MON$OLDEST_SNAPSHOT             248486
MON$NEXT_TRANSACTION            248486
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                       2032352
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:262: 
:263: mscs-test-161.budzdorov.ru    Wed Oct 12 16:39:01 2022
:264:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:265: 
:266: 
:267: mscs-test-161.budzdorov.ru    Wed Oct 12 16:39:07 2022
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:269: 
--- end of diff output ---

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

12.10.22 16:41:37. 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-12 16:41:39 No severe PSQL-related problems occured
12.10.22 16:41:41. End of report.