Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.05.2022 22: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 16343204 kB
MemAvailable 31563288 kB
Buffers 0 kB
Cached 15393908 kB
SwapCached 3232 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.10
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-05-01 22:38:16
DTS_END                         2022-05-01 22:38:16.1830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33438
TRN_ID                          250142
ATT_ID                          667
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: 9669.75 16839 1160370
customer order: creation 187.78 334 22534
customer order: refuse 16.38 363 1965
order to supplier: creation 48.51 1304 5821
order to supplier: removal 3.37 95570 404
invoice (draft): creation 48.59 3479 5831
invoice (draft): removal 54.91 731 6589
invoice accept: apply 44.61 16378 5353
invoice accept: cancel 48.76 755 5851
customer reserve: creation 4124.04 161 494885
customer reserve: removal 4787.17 53 574460
realization accept: apply 90.80 190 10896
realization accept: cancel 75.78 106 9093
payment from customer: creation 63.92 112 7670
payment from customer: removal 6.19 95 743
payment to supplier: creation 52.54 132 6305
payment to supplier: removal 3.43 80 412
service: total inventory turnovers 4.88 1069 586
service: total monetary turnovers 7.21 29 865
service: aggregate perf. data 0.86 18053 103
service: refresh index statistics 0.03 197782 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-05-2022 22:40:37 to 01-05-2022 22:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4664
TEST_TIME 2 4642
TEST_TIME 3 4372
TEST_TIME 4 4130
TEST_TIME 5 4096
TEST_TIME 6 4416
TEST_TIME 7 5017
TEST_TIME 8 5386
TEST_TIME 9 5334
TEST_TIME 10 5786
TEST_TIME 11 6589
TEST_TIME 12 7000
TEST_TIME 13 7218
TEST_TIME 14 7712
TEST_TIME 15 7953
TEST_TIME 16 8643
TEST_TIME 17 8625
TEST_TIME 18 8797
TEST_TIME 19 9078
TEST_TIME 20 9004
TEST_TIME 21 9212
TEST_TIME 22 9561
TEST_TIME 23 9684
TEST_TIME 24 10300
TEST_TIME 25 10402
TEST_TIME 26 10511
TEST_TIME 27 10827
TEST_TIME 28 11139
TEST_TIME 29 10858
TEST_TIME 30 10793
TEST_TIME 31 10875
TEST_TIME 32 10788
TEST_TIME 33 10683
TEST_TIME 34 10827
TEST_TIME 35 10779
TEST_TIME 36 10721
TEST_TIME 37 10610
TEST_TIME 38 10541
TEST_TIME 39 10573
TEST_TIME 40 10548
TEST_TIME 41 10439
TEST_TIME 42 10277
TEST_TIME 43 10163
TEST_TIME 44 10103
TEST_TIME 45 10142
TEST_TIME 46 9987
TEST_TIME 47 9941
TEST_TIME 48 9825
TEST_TIME 49 9929
TEST_TIME 50 9930
TEST_TIME 51 9995
TEST_TIME 52 9920
TEST_TIME 53 9932
TEST_TIME 54 9924
TEST_TIME 55 10017
TEST_TIME 56 10009
TEST_TIME 57 9977
TEST_TIME 58 10089
TEST_TIME 59 10018
TEST_TIME 60 10037
TEST_TIME 61 10127
TEST_TIME 62 10116
TEST_TIME 63 10114
TEST_TIME 64 10065
TEST_TIME 65 10127
TEST_TIME 66 10238
TEST_TIME 67 10215
TEST_TIME 68 10199
TEST_TIME 69 10131
TEST_TIME 70 10059
TEST_TIME 71 9987
TEST_TIME 72 9893
TEST_TIME 73 9829
TEST_TIME 74 9880
TEST_TIME 75 9901
TEST_TIME 76 9870
TEST_TIME 77 9848
TEST_TIME 78 9784
TEST_TIME 79 9685
TEST_TIME 80 9703
TEST_TIME 81 9774
TEST_TIME 82 9765
TEST_TIME 83 9733
TEST_TIME 84 9804
TEST_TIME 85 9764
TEST_TIME 86 9766
TEST_TIME 87 9814
TEST_TIME 88 9838
TEST_TIME 89 9901
TEST_TIME 90 9917
TEST_TIME 91 9853
TEST_TIME 92 9969
TEST_TIME 93 9957
TEST_TIME 94 9951
TEST_TIME 95 9944
TEST_TIME 96 9880
TEST_TIME 97 9816
TEST_TIME 98 9799
TEST_TIME 99 9773
TEST_TIME 100 9741
TEST_TIME 101 9700
TEST_TIME 102 9686
TEST_TIME 103 9668
TEST_TIME 104 9623
TEST_TIME 105 9569
TEST_TIME 106 9593
TEST_TIME 107 9587
TEST_TIME 108 9648
TEST_TIME 109 9625
TEST_TIME 110 9595
TEST_TIME 111 9675
TEST_TIME 112 9644
TEST_TIME 113 9642
TEST_TIME 114 9684
TEST_TIME 115 9697
TEST_TIME 116 9689
TEST_TIME 117 9717
TEST_TIME 118 9703
TEST_TIME 119 9752
TEST_TIME 120 9674

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-05-2022 22:40:41 to 01-05-2022 22:40:45.

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 1618805 1618805 0 0.00 0 18417 6 0 0
doc_list_biud 1618805 1618805 0 0.00 0 28674 24 0 0
sp_add_invoice_to_stock 5475 5353 122 2.22 32 188840 16379 0 122
sp_cancel_adding_invoice 5851 5851 0 0.00 5 107914 756 0 0
sp_cancel_client_order 1965 1965 0 0.00 4 12696 364 0 0
sp_cancel_customer_reserve 574460 574460 0 0.00 1 17957 53 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 2 7513 96 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 7292 80 0 0
sp_cancel_supplier_invoice 6602 6589 13 0.19 1 18248 732 0 13
sp_cancel_supplier_order 404 404 0 0.00 6 919678 95570 0 0
sp_cancel_write_off 9093 9093 0 0.00 1 15586 107 0 0
sp_client_order 22534 22534 0 0.00 3 19553 335 0 0
sp_customer_reserve 494885 494885 0 0.00 0 156931 161 0 0
sp_fill_shopping_cart 34180 34180 0 0.00 1 11958 38 0 0
sp_fill_shopping_cart_clo_res 494878 494878 0 0.00 0 28281 13 0 0
sp_get_clo_for_invoice 5346 5346 0 0.00 0 36315 271 0 0
sp_kill_cost_storno 16099 16099 0 0.00 0 7597 50 0 0
sp_kill_qstorno_ret_qs2qd 1165006 1165006 0 0.00 0 18238 29 0 0
sp_kill_qty_storno 614604 614604 0 0.00 0 28673 64 0 0
sp_lock_selected_doc 22449 22449 0 0.00 0 8696 6 0 0
sp_make_cost_storno 30217 30217 0 0.00 0 12802 57 0 0
sp_multiply_rows_for_pdistr 30217 30217 0 0.00 0 10715 28 0 0
sp_multiply_rows_for_qdistr 22534 22534 0 0.00 0 15959 153 0 0
sp_pay_from_customer 7670 7670 0 0.00 4 16032 113 0 0
sp_pay_to_supplier 6305 6305 0 0.00 4 10747 132 0 0
sp_payment_common 13975 13975 0 0.00 2 6132 26 0 0
sp_qd_handle_on_cancel_clo 1965 1965 0 0.00 0 12688 332 0 0
sp_qd_handle_on_invoice_upd_sts 11197 11197 0 0.00 0 28673 333 0 0
sp_qd_handle_on_reserve_upd_sts 10896 10896 0 0.00 0 17874 64 0 0
sp_reserve_write_off 10896 10896 0 0.00 6 17902 191 0 0
sp_supplier_invoice 5831 5831 0 0.00 6 81213 3479 0 0
sp_supplier_order 5821 5821 0 0.00 5 32066 1305 0 0
srv_aggregate_perf_data 103 103 0 0.00 2714 149662 18054 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 3307 141397 46973 0 0
srv_find_qd_qs_mism 1109144 1109144 0 0.00 0 18416 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 33 67286 1070 0 0
srv_make_money_saldo 865 865 0 0.00 0 2576 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 141075 245431 197783 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5353 5353 0 0.00 0 9752 156 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 3412 81 0 0
t$perf-norm:sp_cancel_client_order 1965 1965 0 0.00 0 2722 11 0 0
t$perf-norm:sp_cancel_customer_reserve 766 766 0 0.00 0 715 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 1877 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 724 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 465 465 0 0.00 0 4337 25 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 18059 1576 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 6165 12 0 0
t$perf-norm:sp_client_order 22534 22534 0 0.00 0 8023 16 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 5914 15 0 0
t$perf-norm:sp_pay_from_customer 7670 7670 0 0.00 0 5071 10 0 0
t$perf-norm:sp_pay_to_supplier 6305 6305 0 0.00 0 8694 9 0 0
t$perf-norm:sp_reserve_write_off 10896 10896 0 0.00 0 16001 12 0 0
t$perf-norm:sp_supplier_invoice 5831 5831 0 0.00 0 9620 20 0 0
t$perf-norm:sp_supplier_order 5821 5821 0 0.00 0 9020 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 237 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 968 20 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 2837 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10930 10686 244 2.23 0 13683 21 0 244
v_all_customers 40370 40370 0 0.00 0 99 1 0 0
v_all_suppliers 32598 32598 0 0.00 0 96 1 0 0
v_all_wares 45068 45068 0 0.00 0 43 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 1694 12 0 0
v_cancel_client_order 3930 3930 0 0.00 0 4075 7 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 4053 14 0 0
v_cancel_customer_reserve 1532 1532 0 0.00 0 2086 7 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2372 7 0 0
v_cancel_supplier_invoice 956 930 26 2.71 0 2083 18 0 26
v_cancel_supplier_order 808 808 0 0.00 0 1178 7 0 0
v_cancel_write_off 2058 2058 0 0.00 0 5330 19 0 0
v_max_id_clo_ord 5821 5821 0 0.00 0 2499 2 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 4235 16 0 0
v_max_id_ord_sup 5825 5825 0 0.00 0 4938 4 0 0
v_max_non_paid_invoice 1652 1652 0 0.00 0 4398 5 0 0
v_max_non_paid_realizn 4309 4309 0 0.00 0 2659 5 0 0
v_min_id_clo_ord 5821 5821 0 0.00 0 11948 13 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 6560 16 0 0
v_min_id_ord_sup 5825 5825 0 0.00 0 6316 9 0 0
v_min_non_paid_invoice 6305 6305 0 0.00 0 5962 21 0 0
v_min_non_paid_realizn 7670 7670 0 0.00 0 4049 16 0 0
v_our_firm 11420 11420 0 0.00 0 19 1 0 0
v_reserve_write_off 21791 21791 0 0.00 0 4831 7 0 0
x_lock_depdocs_on_canc_invoice 11975 11975 0 0.00 0 22500 108 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 16813 346 0 0
x_make_qstorno_1000_1200 5821 5821 0 0.00 1 31303 1211 0 0
x_make_qstorno_1200_2000 5831 5831 0 0.00 2 75597 3352 0 0
x_make_qstorno_2100_3300 494885 494885 0 0.00 0 156927 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-05-2022 22:40:46 to 01-05-2022 22:40:50.

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
20:08:18 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:18 31044 8192000000 9912538752 10223411200 991561520 10.797 100 91 182 0 1021823328 6146640 24115168
20:10:55 21239 8192000000 9958558464 10284470272 1036788832 11.234 100 87 174 1 1072279216 12994368 22496016
20:12:26 22863 8192000000 10000668656 10334679040 1072071344 11.572 100 90 180 1 1105010544 9713728 23225472
20:13:51 13131 8192000000 10034989168 10385911808 1117693776 12.005 100 73 145 1 1145636464 10698704 17243984
20:15:06 10544 8192000000 10086150480 10445869056 1171083712 12.507 100 79 158 0 1202479952 12850848 18545392
20:16:19 3273 8192000000 10134224592 10501468160 1219471280 12.957 100 87 173 0 1252382096 13528992 19381824
20:17:25 5652 8192000000 10172037232 10550472704 1252674800 13.263 100 71 142 2 1281715984 12320576 16720608
20:18:32 2329 8192000000 10202915008 10586312704 1287839584 13.585 100 78 157 0 1317636864 12815168 16982112
20:19:35 1440 8192000000 10220426912 10606071808 1306260816 13.752 100 94 191 2 1341639248 14346032 21032400
20:20:37 1414 8192000000 10226957200 10618458112 1312358288 13.807 100 82 164 2 1341760096 10643056 18758752
20:21:39 4613 8192000000 10212944864 10627915776 1319925744 13.876 100 37 74 0 1339089392 10571504 8592144
20:22:46 7415 8192000000 10214675696 10635571200 1329182640 13.960 100 14 29 0 1338326992 5799904 3344448
20:23:57 13989 8192000000 10216178192 10638086144 1332198864 13.987 100 15 30 1 1341297904 5902768 3196272
20:25:11 62378 8192000000 10227942416 10648326144 1336066464 14.022 100 22 44 0 1346546048 5398080 5081504
20:27:14 5421 8192000000 10234530848 10653302784 1335952064 14.021 100 25 50 1 1348447216 6288320 6206832
20:28:20 8106 8192000000 10220196576 10638274560 1326399472 13.935 100 18 36 2 1336534400 5891072 4243856
20:29:30 7609 8192000000 10168769776 10558570496 1237828128 13.126 96 21 42 1 1248351984 5760624 4763232
20:30:41 13573 8192000000 10139352944 10537123840 1161762800 12.420 91 29 58 0 1174097664 5821952 6512912
20:31:59 4409 8192000000 10147450208 10546520064 1243635760 13.180 99 31 62 2 1257637408 6130048 7871600
20:33:04 10760 8192000000 10162011936 10559565824 1251526048 13.252 99 25 47 4 1262913376 4940992 6446336
20:34:15 8639 8192000000 10180743824 10591744000 1286333984 13.571 100 31 58 2 1299213328 5475776 7403568
20:35:27 46611 8192000000 10174181680 10593869824 1276333408 13.480 98 21 41 2 1286927616 5410928 5183280
20:37:16 12844 8192000000 10200993936 10619363328 1308473584 13.772 100 21 42 0 1322893072 9407200 5012288
20:38:33 46623 8192000000 10236396464 10649624576 1330860368 13.975 100 34 68 2 1344995408 4952160 9182880
20:40:24 13294 8192000000 10287717584 10692599808 1361081664 14.247 100 64 129 0 1389485808 13148496 15255648
20:41:40 76059 8192000000 10279634528 10698477568 1357176368 14.212 99 51 102 2 1383741216 14031296 12533552
20:44:00 9866 8192000000 10294147040 10713305088 1379466576 14.412 100 37 72 1 1406171360 18143184 8561600
20:45:13 11068 8192000000 10314025232 10733006848 1392095360 14.525 100 50 97 4 1427080320 22207424 12777536
20:46:28 12467 8192000000 10317665584 10744492032 1395399392 14.554 100 51 102 1 1431597840 23570992 12627456
20:47:45 14337 8192000000 10328632096 10755280896 1397454704 14.572 100 44 88 0 1438309184 29196992 11657488
20:49:04 5823 8192000000 10335919488 10756579328 1384747760 14.459 99 59 118 0 1433837840 34492000 14598080
20:50:13 4638 8192000000 10339418224 10756747264 1403466928 14.626 100 67 133 1 1444436256 25364240 15605088
20:51:23 4468 8192000000 10349793792 10765324288 1405074912 14.640 100 81 163 0 1457085104 31173776 20836416
20:52:36 3561 8192000000 10363199440 10780729344 1411162864 14.694 100 86 171 1 1471056528 38864800 21028864
20:53:44 3307 8192000000 10346522448 10757304320 1363327072 14.267 97 87 174 0 1420804112 37203376 20273664
20:54:50 22117 8192000000 10288540752 10714578944 1337068864 14.031 98 26 52 4 1375520576 31952512 6499200
20:56:15 9276 8192000000 10312397600 10733199360 1370033248 14.327 100 42 84 2 1416722688 35576624 11112816
20:57:34 6384 8192000000 10287109040 10710024192 1349519840 14.143 100 40 79 0 1396583232 36833088 10230304
20:58:43 4908 8192000000 10285628160 10695872512 1322901664 13.903 98 63 125 2 1379991408 39789248 17300496
20:59:51 6981 8192000000 10307637536 10723368960 1352450544 14.170 100 50 96 2 1405854848 40597504 12806800
21:01:00 10252 8192000000 10309444608 10735067136 1364947696 14.282 100 34 67 1 1414799600 41251312 8600592
21:02:14 11516 8192000000 10298401776 10724892672 1355492848 14.197 99 23 46 2 1393302272 31226816 6582608
21:03:28 36468 8192000000 10301153680 10730356736 1360031232 14.238 99 36 72 1 1394171488 24701440 9438816
21:05:06 21401 8192000000 10275331680 10721103872 1360657008 14.243 100 23 44 1 1388279152 21718320 5903824
21:06:37 17794 8192000000 10261585664 10715856896 1351702576 14.163 100 17 32 1 1378336672 22191776 4442320
21:07:55 50257 8192000000 10280906304 10722963456 1366318336 14.294 100 13 26 1 1378561872 9260576 2982960
21:09:50 94652 8192000000 10300517120 10748964864 1387991856 14.488 100 15 31 0 1401415664 10529552 2894256
21:12:30 81591 8192000000 10302895520 10744410112 1373060448 14.354 99 9 18 0 1395356896 20159888 2136560
21:14:53 67730 8192000000 10319848688 10754097152 1368800336 14.316 98 29 55 1 1384113376 8339296 6973744
21:17:03 107066 8192000000 10288133936 10739093504 1350868032 14.155 97 8 16 0 1370583728 18031664 1684032
21:19:51 98156 8192000000 10300988608 10742267904 1375075600 14.372 99 42 84 3 1391314304 6122592 10116112
21:22:30 28982 8192000000 10313613472 10754293760 1385956480 14.470 99 40 79 0 1403563456 8126064 9480912
21:24:05 141397 8192000000 10326446768 10776223744 1375048640 14.372 97 30 60 3 1401570032 19394576 7126816
21:27:27 58710 8192000000 10317057840 10769809408 1372684544 14.351 98 48 96 1 1409144944 25116352 11344048
21:29:35 82452 8192000000 10295044880 10753454080 1357564208 14.215 97 16 32 0 1383548704 22155104 3829392
21:31:58 53180 8192000000 10314552400 10773327872 1386875808 14.478 100 12 24 0 1406181968 16460128 2846032
21:33:56 49298 8192000000 10266819936 10718191616 1284355600 13.553 93 14 28 1 1305188576 16821216 4011760
21:35:46 32988 8192000000 10268305216 10721714176 1335857728 14.020 98 30 60 1 1354424832 11901632 6665472
21:37:23 32759 8192000000 10298465088 10741506048 1360387136 14.241 99 32 64 0 1379992928 12545840 7059952
21:39:02 118778 8192000000 10253231152 10691776512 1182598032 12.614 86 14 28 1 1194066624 8576496 2892096
21:42:11 23276 8192000000 10262963728 10693066752 1286746288 13.575 95 35 70 0 1309010720 14854560 7409872
21:43:43 56172 8192000000 10264686672 10713743360 1326612640 13.937 98 13 26 1 1348799264 19057584 3129040
21:45:46 42024 8192000000 10257860240 10701086720 1301652368 13.710 96 17 34 0 1315634080 10285232 3696480
21:47:34 104489 8192000000 10252501184 10703925248 1321200560 13.888 98 24 48 0 1336048096 8557424 6290112
21:50:19 52939 8192000000 10286227664 10725801984 1357031232 14.211 99 23 46 1 1378101344 15612480 5457632
21:52:12 44634 8192000000 10273465968 10736300032 1353950496 14.183 99 21 42 0 1377580224 18430352 5199376
21:54:30 35102 8192000000 10273902416 10730795008 1361088176 14.247 99 16 32 0 1375485472 10618080 3779216
21:56:11 42316 8192000000 10300312608 10761605120 1382862976 14.442 100 15 30 0 1405097408 18575744 3658688
21:57:54 69141 8192000000 10278108208 10749562880 1349145424 14.140 98 16 32 0 1381189488 28167488 3876576
22:00:16 54366 8192000000 10282226160 10741497856 1361499184 14.251 99 11 22 0 1374245600 9923088 2823328
22:02:11 71283 8192000000 10296237440 10746159104 1346292880 14.114 97 14 27 0 1357800208 8851600 2655728
22:04:24 75006 8192000000 10303168800 10763083776 1366920496 14.299 98 16 32 0 1387535536 16770368 3844672
22:06:39 40780 8192000000 10303741376 10766688256 1399959184 14.595 100 28 56 0 1417721568 11699072 6063312
22:08:30 66419 8192000000 10318124704 10763665408 1371768352 14.343 98 37 74 0 1391806928 12248912 7789664
22:10:38 81812 8192000000 10323545216 10787483648 1390378544 14.509 99 4 8 1 1409074400 17980432 715424
22:13:01 97724 8192000000 10307489888 10764513280 1391707408 14.521 99 21 43 2 1409223328 11550400 5965520
22:15:48 10231 8192000000 10319506336 10773684224 1406237456 14.650 100 30 56 1 1419852224 8070976 5543792
22:16:59 40908 8192000000 10285980176 10762977280 1367822128 14.308 97 9 18 0 1378604176 8727552 2054496
22:18:40 81809 8192000000 10294507152 10762645504 1348166304 14.131 96 16 32 1 1364095584 12512720 3416560
22:21:02 50906 8192000000 10288727776 10755186688 1354636448 14.189 97 8 17 0 1363247104 6736976 1873680
22:23:14 47865 8192000000 10299349344 10762375168 1364126240 14.274 97 14 28 0 1383467104 16234256 3106608
22:25:02 60007 8192000000 10297113424 10771140608 1355356080 14.196 97 9 18 0 1378581024 20742720 2482224
22:27:03 51357 8192000000 10276674768 10715598848 1291005600 13.613 94 26 52 1 1307225456 10369392 5850464
22:28:58 74231 8192000000 10243625712 10700861440 1305009376 13.741 97 16 32 0 1321730624 13093728 3627520
22:31:17 24683 8192000000 10269269120 10709233664 1328503888 13.954 98 46 90 0 1350924096 12240368 10179840
22:32:44 56759 8192000000 10256326912 10714996736 1320339040 13.880 98 13 26 0 1341541248 18460832 2741376
22:34:41 54618 8192000000 10269150864 10711367680 1321685328 13.892 98 32 63 1 1340362384 11822432 6854624
22:36:42 91689 8192000000 10255555392 10718715904 1331430288 13.980 99 11 22 0 1350962704 17240992 2291424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-05-2022 22:40:51 to 01-05-2022 22:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-05-2022 22:41:07 to 01-05-2022 22:41:09.

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          249924
MON$OLDEST_ACTIVE               250299
MON$OLDEST_SNAPSHOT             250299
MON$NEXT_TRANSACTION            250299
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                       2065248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33582 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33582 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33582 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33582 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sun May  1 22:38:59 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
--- end of diff output ---

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

01.05.22 22:41:10. 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-05-01 22:41:13 No severe PSQL-related problems occured
01.05.22 22:41:14. End of report.