Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2023 01:39
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 15105028 kB
MemAvailable 30434948 kB
Buffers 0 kB
Cached 16621616 kB
SwapCached 3352 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 2023-02-13 01:38:40
DTS_END                         2023-02-13 01:38:40.0340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42924
TRN_ID                          248807
ATT_ID                          698
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: 9463.92 17067 1135670
customer order: creation 183.70 343 22044
customer order: refuse 15.53 375 1863
order to supplier: creation 48.96 1299 5875
order to supplier: removal 3.47 95893 416
invoice (draft): creation 47.91 3289 5749
invoice (draft): removal 51.76 737 6211
invoice accept: apply 43.78 17173 5253
invoice accept: cancel 47.38 826 5686
customer reserve: creation 4047.03 171 485643
customer reserve: removal 4665.83 57 559900
realization accept: apply 88.46 179 10615
realization accept: cancel 81.97 110 9836
payment from customer: creation 63.91 97 7669
payment from customer: removal 6.15 85 738
payment to supplier: creation 52.03 126 6244
payment to supplier: removal 3.56 74 427
service: total inventory turnovers 4.58 1279 550
service: total monetary turnovers 7.03 42 844
service: aggregate perf. data 0.86 18657 103
service: refresh index statistics 0.03 200534 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2023 01:40:13 to 13-02-2023 01:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4313
TEST_TIME 2 4120
TEST_TIME 3 4408
TEST_TIME 4 4527
TEST_TIME 5 4611
TEST_TIME 6 4438
TEST_TIME 7 4497
TEST_TIME 8 4739
TEST_TIME 9 5045
TEST_TIME 10 5158
TEST_TIME 11 5670
TEST_TIME 12 5849
TEST_TIME 13 6091
TEST_TIME 14 6884
TEST_TIME 15 6797
TEST_TIME 16 6934
TEST_TIME 17 7398
TEST_TIME 18 7576
TEST_TIME 19 7915
TEST_TIME 20 8180
TEST_TIME 21 8468
TEST_TIME 22 8546
TEST_TIME 23 8730
TEST_TIME 24 9195
TEST_TIME 25 9098
TEST_TIME 26 9266
TEST_TIME 27 9175
TEST_TIME 28 9228
TEST_TIME 29 9398
TEST_TIME 30 9571
TEST_TIME 31 9850
TEST_TIME 32 9906
TEST_TIME 33 10720
TEST_TIME 34 10769
TEST_TIME 35 10687
TEST_TIME 36 10531
TEST_TIME 37 10466
TEST_TIME 38 10251
TEST_TIME 39 10164
TEST_TIME 40 10151
TEST_TIME 41 10127
TEST_TIME 42 10084
TEST_TIME 43 10027
TEST_TIME 44 9983
TEST_TIME 45 9935
TEST_TIME 46 9839
TEST_TIME 47 9999
TEST_TIME 48 10005
TEST_TIME 49 9970
TEST_TIME 50 9887
TEST_TIME 51 9727
TEST_TIME 52 9762
TEST_TIME 53 9828
TEST_TIME 54 9830
TEST_TIME 55 9834
TEST_TIME 56 9857
TEST_TIME 57 10045
TEST_TIME 58 10039
TEST_TIME 59 9991
TEST_TIME 60 10023
TEST_TIME 61 10153
TEST_TIME 62 10142
TEST_TIME 63 10066
TEST_TIME 64 10126
TEST_TIME 65 10335
TEST_TIME 66 10307
TEST_TIME 67 10176
TEST_TIME 68 10129
TEST_TIME 69 10196
TEST_TIME 70 10090
TEST_TIME 71 10065
TEST_TIME 72 10005
TEST_TIME 73 9915
TEST_TIME 74 9896
TEST_TIME 75 9909
TEST_TIME 76 9873
TEST_TIME 77 9850
TEST_TIME 78 9860
TEST_TIME 79 9844
TEST_TIME 80 9860
TEST_TIME 81 9798
TEST_TIME 82 9832
TEST_TIME 83 9874
TEST_TIME 84 9864
TEST_TIME 85 9890
TEST_TIME 86 9831
TEST_TIME 87 9868
TEST_TIME 88 9880
TEST_TIME 89 9888
TEST_TIME 90 9911
TEST_TIME 91 9942
TEST_TIME 92 9914
TEST_TIME 93 9822
TEST_TIME 94 9800
TEST_TIME 95 9758
TEST_TIME 96 9726
TEST_TIME 97 9675
TEST_TIME 98 9614
TEST_TIME 99 9571
TEST_TIME 100 9568
TEST_TIME 101 9521
TEST_TIME 102 9553
TEST_TIME 103 9519
TEST_TIME 104 9516
TEST_TIME 105 9573
TEST_TIME 106 9559
TEST_TIME 107 9573
TEST_TIME 108 9606
TEST_TIME 109 9584
TEST_TIME 110 9584
TEST_TIME 111 9670
TEST_TIME 112 9724
TEST_TIME 113 9755
TEST_TIME 114 9678
TEST_TIME 115 9612
TEST_TIME 116 9593
TEST_TIME 117 9563
TEST_TIME 118 9502
TEST_TIME 119 9514
TEST_TIME 120 9466

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-02-2023 01:40:16 to 13-02-2023 01:40:20.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1585272 1585272 0 0.00 0 23179 6 0 0
doc_list_biud 1585272 1585272 0 0.00 0 55652 26 0 0
sp_add_invoice_to_stock 5402 5253 149 2.75 29 189698 17173 0 149
sp_cancel_adding_invoice 5686 5686 0 0.00 4 65905 826 0 0
sp_cancel_client_order 1863 1863 0 0.00 4 9835 375 0 0
sp_cancel_customer_reserve 559900 559900 0 0.00 0 55653 58 0 0
sp_cancel_pay_from_customer 738 738 0 0.00 3 5784 85 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 3827 75 0 0
sp_cancel_supplier_invoice 6225 6211 14 0.22 1 27425 737 0 14
sp_cancel_supplier_order 416 416 0 0.00 6 847094 95894 0 0
sp_cancel_write_off 9836 9836 0 0.00 0 30499 111 0 0
sp_client_order 22044 22044 0 0.00 3 28004 343 0 0
sp_customer_reserve 485643 485643 0 0.00 0 36991 171 0 0
sp_fill_shopping_cart 33658 33658 0 0.00 1 20015 38 0 0
sp_fill_shopping_cart_clo_res 485640 485640 0 0.00 0 19729 14 0 0
sp_get_clo_for_invoice 5250 5250 0 0.00 0 31851 287 0 0
sp_kill_cost_storno 16687 16687 0 0.00 0 13761 55 0 0
sp_kill_qstorno_ret_qs2qd 1136263 1136263 0 0.00 0 55649 31 0 0
sp_kill_qty_storno 599777 599777 0 0.00 0 55651 67 0 0
sp_lock_selected_doc 22006 22006 0 0.00 0 8162 6 0 0
sp_make_cost_storno 29778 29778 0 0.00 0 23172 50 0 0
sp_multiply_rows_for_pdistr 29778 29778 0 0.00 0 7678 25 0 0
sp_multiply_rows_for_qdistr 22044 22044 0 0.00 0 23317 161 0 0
sp_pay_from_customer 7669 7669 0 0.00 4 7970 97 0 0
sp_pay_to_supplier 6244 6244 0 0.00 5 16264 127 0 0
sp_payment_common 13913 13913 0 0.00 2 7364 22 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 9808 345 0 0
sp_qd_handle_on_invoice_upd_sts 10936 10936 0 0.00 0 25462 315 0 0
sp_qd_handle_on_reserve_upd_sts 10615 10615 0 0.00 0 16782 63 0 0
sp_reserve_write_off 10615 10615 0 0.00 6 16824 179 0 0
sp_supplier_invoice 5749 5749 0 0.00 6 101244 3290 0 0
sp_supplier_order 5875 5875 0 0.00 5 29556 1300 0 0
srv_aggregate_perf_data 104 103 1 0.96 2927 200088 18657 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 1719 159367 38934 0 0
srv_find_qd_qs_mism 1084992 1084992 0 0.00 0 9610 5 0 0
srv_make_invnt_saldo 550 550 0 0.00 13 55525 1279 0 0
srv_make_money_saldo 844 844 0 0.00 0 9467 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 78585 299069 200534 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 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 5253 5253 0 0.00 0 12394 166 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 4105 114 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 2992 12 0 0
t$perf-norm:sp_cancel_customer_reserve 715 715 0 0.00 0 3679 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 738 738 0 0.00 0 5267 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 5083 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 432 432 0 0.00 0 3626 31 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 27110 1574 0 0
t$perf-norm:sp_cancel_write_off 1109 1109 0 0.00 0 1895 8 0 0
t$perf-norm:sp_client_order 22044 22044 0 0.00 0 12968 18 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 7314 15 0 0
t$perf-norm:sp_pay_from_customer 7669 7669 0 0.00 0 5267 10 0 0
t$perf-norm:sp_pay_to_supplier 6244 6244 0 0.00 0 8151 11 0 0
t$perf-norm:sp_reserve_write_off 10615 10615 0 0.00 0 10254 16 0 0
t$perf-norm:sp_supplier_invoice 5749 5749 0 0.00 0 7525 20 0 0
t$perf-norm:sp_supplier_order 5875 5875 0 0.00 0 10275 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 202 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 208 7 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 735 22 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 1724 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10798 10500 298 2.75 0 12697 25 0 298
v_all_customers 40534 40534 0 0.00 0 122 1 0 0
v_all_suppliers 32312 32312 0 0.00 0 37 1 0 0
v_all_wares 44086 44086 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1638 8 0 0
v_cancel_client_order 3726 3726 0 0.00 0 6075 10 0 0
v_cancel_customer_prepayment 1476 1476 0 0.00 0 2306 8 0 0
v_cancel_customer_reserve 1430 1430 0 0.00 0 2785 9 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 3814 12 0 0
v_cancel_supplier_invoice 892 864 28 3.13 0 10398 41 0 28
v_cancel_supplier_order 832 832 0 0.00 0 1290 5 0 0
v_cancel_write_off 2218 2218 0 0.00 0 13886 25 0 0
v_max_id_clo_ord 5875 5875 0 0.00 0 1627 2 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 2363 18 0 0
v_max_id_ord_sup 5740 5740 0 0.00 0 2835 3 0 0
v_max_non_paid_invoice 1703 1703 0 0.00 0 1562 4 0 0
v_max_non_paid_realizn 3998 3998 0 0.00 0 3865 5 0 0
v_min_id_clo_ord 5875 5875 0 0.00 0 4990 7 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 4577 7 0 0
v_min_id_ord_sup 5740 5740 0 0.00 0 4616 11 0 0
v_min_non_paid_invoice 6244 6244 0 0.00 0 7361 12 0 0
v_min_non_paid_realizn 7669 7669 0 0.00 0 4078 15 0 0
v_our_firm 10894 10894 0 0.00 0 122 1 0 0
v_reserve_write_off 21230 21230 0 0.00 0 9311 10 0 0
x_lock_depdocs_on_canc_invoice 11465 11465 0 0.00 0 12569 102 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 8522 283 0 0
x_make_qstorno_1000_1200 5875 5875 0 0.00 1 29534 1221 0 0
x_make_qstorno_1200_2000 5749 5749 0 0.00 3 100814 3149 0 0
x_make_qstorno_2100_3300 485643 485643 0 0.00 0 36990 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2023 01:40:21 to 13-02-2023 01:40:25.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:44 34 8192000000 8831801984 8854740992 29880880 0.363 11 4 5 0 30157792 106480 170432
23:09:45 13438 8192000000 9893722736 10202103808 977199200 10.657 100 89 178 0 1007019344 6303600 23516544
23:11:20 14483 8192000000 9949481344 10272739328 1030587696 11.174 100 82 164 0 1060243312 8527088 21128528
23:12:41 14689 8192000000 9983765056 10316439552 1070027584 11.552 100 83 166 0 1100047360 9974976 20044800
23:14:06 9221 8192000000 10027189360 10370805760 1113034704 11.961 100 83 166 0 1144175168 11589888 19550576
23:15:18 7092 8192000000 10070795936 10423545856 1151128608 12.320 100 80 160 0 1183043008 13413360 18501040
23:16:28 5725 8192000000 10098810816 10465173504 1191521936 12.698 100 71 142 0 1218651648 11417504 15712208
23:17:36 2767 8192000000 10149091152 10519941120 1235681488 13.106 100 88 177 0 1268956064 13417184 19857392
23:18:41 1442 8192000000 10178713632 10563534848 1272403456 13.444 100 76 152 0 1302400336 12754912 17241968
23:19:44 882 8192000000 10213065088 10594299904 1294026960 13.641 100 91 180 1 1328648880 14393248 20228672
23:20:47 38701 8192000000 10199811040 10605449216 1306013728 13.750 100 38 76 1 1322438656 7862720 8562208
23:22:33 6378 8192000000 10199370096 10615742464 1318615328 13.864 100 25 51 0 1330921648 6553136 5753184
23:23:40 8465 8192000000 10210920832 10628100096 1325146688 13.923 100 30 60 0 1338862224 6688480 7027056
23:24:49 14631 8192000000 10216390144 10635399168 1334395776 14.007 100 32 64 1 1347930016 5416800 8117440
23:26:04 30867 8192000000 10225392112 10649042944 1335324112 14.015 100 28 57 1 1347708608 6275744 6108752
23:27:37 4549 8192000000 10230614928 10651803648 1336922400 14.030 100 34 65 2 1350698928 5695856 8080672
23:28:45 7115 8192000000 10209226064 10626940928 1315031264 13.832 100 28 55 2 1327786256 6521520 6233472
23:29:53 30906 8192000000 10174799488 10587324416 1259640768 13.327 97 12 24 0 1267685984 5186096 2859120
23:31:53 8365 8192000000 10152308144 10553958400 1239536432 13.142 98 29 56 0 1253098960 7095360 6467168
23:33:02 6113 8192000000 10161957888 10566770688 1260000512 13.330 99 28 55 1 1271604592 5489712 6114368
23:34:08 8127 8192000000 10163164816 10570145792 1266881744 13.393 100 27 52 1 1279702048 6308752 6511552
23:35:17 20595 8192000000 10182669840 10588876800 1275098064 13.468 99 21 42 1 1285030992 5030112 4902816
23:36:38 6723 8192000000 10198522960 10612936704 1307451168 13.763 100 18 37 1 1317181936 5187888 4542880
23:37:45 10220 8192000000 10208303696 10623049728 1317643792 13.855 100 23 46 0 1328293888 5715440 4934656
23:38:57 10926 8192000000 10241899504 10652864512 1339523552 14.053 100 42 82 1 1355904928 6104912 10276464
23:40:15 41453 8192000000 10243794672 10670030848 1350884704 14.155 100 29 57 2 1365100176 6648688 7566784
23:42:00 50093 8192000000 10268328000 10694041600 1365733040 14.289 100 33 66 0 1383360368 9787424 7839904
23:43:57 11312 8192000000 10294222448 10721452032 1384966992 14.461 100 39 78 1 1408309840 13897792 9445056
23:45:18 12775 8192000000 10310758016 10734297088 1394744128 14.548 100 45 90 0 1422447760 16631536 11072096
23:46:43 12160 8192000000 10319562144 10748276736 1401107872 14.605 100 40 79 1 1432118928 21041840 9969216
23:47:57 3687 8192000000 10330788448 10753736704 1408650880 14.672 100 63 121 2 1442309616 19202816 14455920
23:49:09 6070 8192000000 10333603968 10763276288 1408691344 14.672 100 56 112 2 1445386800 23057664 13637792
23:50:24 1921 8192000000 10353661104 10769645568 1417120864 14.747 100 83 156 1 1456740368 21216912 18402592
23:51:30 4312 8192000000 10318536912 10736480256 1381119632 14.427 99 68 137 2 1421839296 23839888 16879776
23:52:39 3976 8192000000 10315957520 10735927296 1383962880 14.452 100 62 122 2 1422807552 23671776 15172896
23:53:53 4040 8192000000 10332727904 10748592128 1390492336 14.510 100 71 140 0 1439390064 31677872 17219856
23:55:06 2059 8192000000 10333767520 10747564032 1387098336 14.480 99 73 146 1 1439596512 34856128 17642048
23:56:18 4813 8192000000 10346751520 10762612736 1399880208 14.594 100 72 139 3 1451699088 34108704 17710176
23:57:29 2320 8192000000 10336418608 10761150464 1392807776 14.531 100 62 122 1 1439564800 32053680 14703344
23:58:38 7992 8192000000 10302474640 10728943616 1374346672 14.366 99 41 82 0 1412803184 28357872 10098640
23:59:57 13913 8192000000 10295885840 10727727104 1356253104 14.204 98 31 62 0 1391041168 26703424 8084640
00:01:17 3793 8192000000 10320884800 10742394880 1355199168 14.194 98 57 114 0 1405058048 34805424 15053456
00:02:28 1719 8192000000 10356015136 10778267648 1402797648 14.620 100 72 144 0 1460345168 39591936 17955584
00:03:30 21476 8192000000 10339530144 10767204352 1380020800 14.417 99 33 66 0 1426979440 38082976 8875664
00:05:01 13825 8192000000 10339220288 10762416128 1391769392 14.522 100 43 85 1 1437344512 34599168 10975952
00:06:21 8115 8192000000 10332840960 10759630848 1377022064 14.390 99 49 97 0 1430822496 40660320 13140112
00:07:40 16059 8192000000 10330695920 10768195584 1370862368 14.335 99 24 48 0 1426092144 48506800 6722976
00:08:59 14518 8192000000 10318928688 10767572992 1380196688 14.418 100 16 32 0 1422507680 38032256 4278736
00:10:42 31395 8192000000 10311927792 10753310720 1368268368 14.312 100 28 56 0 1417725104 42436960 7019776
00:12:15 100735 8192000000 10278285744 10718085120 1327146896 13.941 96 19 39 1 1345025520 12907008 4971616
00:15:04 42716 8192000000 10293096880 10738319360 1380589136 14.422 100 20 39 0 1392976080 8413184 3973760
00:16:48 24733 8192000000 10307798320 10746683392 1388019216 14.488 100 50 96 1 1405890224 6089168 11781840
00:18:16 63524 8192000000 10312758928 10754523136 1394012976 14.542 100 34 68 0 1413714672 12227488 7474208
00:20:23 60626 8192000000 10308414512 10771771392 1401000416 14.604 100 19 38 0 1417393280 12232768 4160096
00:22:25 81579 8192000000 10320416464 10773225472 1408328496 14.669 100 17 34 0 1420495984 8283472 3884016
00:24:49 48093 8192000000 10316313488 10779123712 1408928000 14.674 99 26 53 1 1432515296 16741232 6846064
00:26:52 25332 8192000000 10327426592 10775908352 1414213152 14.721 100 23 43 0 1430925504 11294000 5418352
00:28:21 42999 8192000000 10290664608 10751934464 1378938192 14.407 98 12 24 0 1394012848 12517088 2557568
00:30:04 27576 8192000000 10299989872 10758193152 1376107840 14.382 98 21 42 0 1403867328 22988000 4771488
00:31:32 36681 8192000000 10286760512 10747883520 1362370224 14.259 98 12 21 0 1385446544 20854832 2221488
00:33:19 12775 8192000000 10285149136 10738593792 1370773872 14.334 99 31 62 0 1393306080 14962688 7569520
00:34:39 68657 8192000000 10275019904 10729435136 1348081488 14.130 98 13 26 0 1371247312 19841568 3324256
00:36:53 53726 8192000000 10288947760 10742575104 1380075584 14.417 100 13 26 0 1396830464 13658096 3096784
00:38:47 65971 8192000000 10283336128 10739740672 1375417312 14.376 99 20 40 2 1394214560 13717360 5079888
00:40:58 46228 8192000000 10309762400 10762047488 1395480448 14.555 100 18 36 0 1413599088 14258736 3859904
00:42:45 83082 8192000000 10297361824 10763161600 1334066400 14.004 96 16 32 0 1357681456 20251600 3363456
00:45:11 159367 8192000000 10274544816 10731474944 1316712256 13.847 95 20 38 1 1333793696 12058896 5022544
00:48:56 26626 8192000000 10273377968 10708205568 1314806656 13.830 96 38 72 1 1330327936 6901360 8619920
00:50:24 60643 8192000000 10284004272 10736160768 1374935312 14.371 100 18 37 0 1392260496 13097680 4227504
00:52:26 46281 8192000000 10281370880 10739236864 1365388880 14.286 99 31 62 0 1388773088 16163840 7220368
00:54:15 56543 8192000000 10265581120 10728153088 1348199536 14.131 99 8 16 0 1367943264 17831744 1911984
00:56:13 33407 8192000000 10269684960 10721083392 1350146656 14.149 99 30 63 1 1366027920 8198048 7683216
00:57:46 71435 8192000000 10238667712 10692239360 1268540976 13.408 93 12 24 0 1282700896 11325136 2834784
01:00:01 77301 8192000000 10269192432 10721779712 1358170992 14.221 100 13 25 0 1368776960 7881328 2724640
01:02:18 29621 8192000000 10266787168 10713391104 1357084288 14.211 100 16 32 0 1367705664 7101776 3519600
01:03:54 49042 8192000000 10291878432 10732847104 1359355664 14.232 99 36 70 0 1378608240 11461072 7791504
01:05:52 29740 8192000000 10291812896 10737459200 1373293024 14.357 100 32 64 1 1389186544 9066256 6827264
01:07:22 23010 8192000000 10306834448 10754895872 1387218144 14.481 100 34 65 1 1405795232 11625968 6951120
01:08:51 26938 8192000000 10301752144 10748698624 1360702704 14.244 98 38 74 0 1382903536 14109776 8091056
01:10:23 71114 8192000000 10303265296 10746593280 1389032848 14.497 100 32 61 0 1403481088 8041792 6406448
01:12:43 36859 8192000000 10257440256 10721583104 1342066096 14.076 97 16 30 0 1351049328 5888320 3094912
01:14:25 44273 8192000000 10269415952 10732662784 1366593584 14.297 99 22 42 0 1378743632 7076784 5073264
01:16:10 57143 8192000000 10277482528 10740219904 1369614240 14.324 99 11 21 1 1381203712 9501104 2088368
01:18:08 77895 8192000000 10254950528 10717483008 1271810864 13.438 92 13 26 0 1288363344 13854528 2697952
01:20:27 66882 8192000000 10288366160 10728972288 1334253536 14.006 97 26 51 1 1349061568 9167744 5640288
01:22:39 58700 8192000000 10274751872 10728845312 1345975440 14.111 98 13 26 0 1363170688 13786656 3408592
01:24:39 66274 8192000000 10278840960 10742386688 1365270976 14.285 99 15 31 0 1379471552 11004544 3196032
01:26:46 40260 8192000000 10254681872 10698063872 1282529968 13.536 94 30 56 0 1299178544 10442544 6206032
01:28:27 75253 8192000000 10286618384 10744909824 1336256000 14.024 98 25 50 0 1364196016 22215728 5724288
01:31:09 104822 8192000000 10273913184 10739064832 1353453280 14.179 99 8 16 0 1375827248 20425232 1948736
01:34:46 45374 8192000000 10244666512 10706100224 1319065664 13.868 97 8 16 0 1328968832 8106880 1796288
01:36:38 56300 8192000000 10247773472 10713923584 1344878832 14.101 99 8 15 0 1355733520 9276656 1578032
01:38:39 5460 8192000000 9032650112 9259687936 196340496 2.340 15 3 2 1 196737264 280864 115904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-02-2023 01:40:27 to 13-02-2023 01:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 149 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-02-2023 01:40:44 to 13-02-2023 01:40:46.

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          249116
MON$OLDEST_ACTIVE               249117
MON$OLDEST_SNAPSHOT             249117
MON$NEXT_TRANSACTION            249117
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                       2054368
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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'

13.02.23 01:40:47. 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
2023-02-13 01:40:49 No severe PSQL-related problems occured
13.02.23 01:40:51. End of report.