Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15453784 kB
MemAvailable 30576108 kB
Buffers 0 kB
Cached 16234472 kB
SwapCached 2132 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-09-25 19:38:39
DTS_END                         2022-09-25 19:38:25.0720
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59486
TRN_ID                          243417
ATT_ID                          274
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: 9656.19 19934 1158743
customer order: creation 177.29 332 21275
customer order: refuse 15.08 329 1810
order to supplier: creation 47.83 1176 5739
order to supplier: removal 3.60 108728 432
invoice (draft): creation 47.94 2871 5753
invoice (draft): removal 54.45 814 6534
invoice accept: apply 43.93 17028 5272
invoice accept: cancel 49.53 783 5944
customer reserve: creation 4062.10 169 487452
customer reserve: removal 4841.81 64 581017
realization accept: apply 86.37 156 10364
realization accept: cancel 91.56 101 10987
payment from customer: creation 61.87 89 7424
payment from customer: removal 5.91 75 709
payment to supplier: creation 50.88 115 6105
payment to supplier: removal 3.37 70 404
service: total inventory turnovers 4.88 1209 585
service: total monetary turnovers 6.93 40 831
service: aggregate perf. data 0.85 21839 102
service: refresh index statistics 0.03 242688 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-09-2022 19:39:07 to 25-09-2022 19:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4325
TEST_TIME 2 6474
TEST_TIME 3 5385
TEST_TIME 4 4951
TEST_TIME 5 5264
TEST_TIME 6 5037
TEST_TIME 7 4887
TEST_TIME 8 5047
TEST_TIME 9 5365
TEST_TIME 10 5743
TEST_TIME 11 6237
TEST_TIME 12 6076
TEST_TIME 13 6444
TEST_TIME 14 6596
TEST_TIME 15 6709
TEST_TIME 16 6794
TEST_TIME 17 7112
TEST_TIME 18 7177
TEST_TIME 19 7443
TEST_TIME 20 7756
TEST_TIME 21 7797
TEST_TIME 22 8488
TEST_TIME 23 8677
TEST_TIME 24 8878
TEST_TIME 25 8930
TEST_TIME 26 8831
TEST_TIME 27 9149
TEST_TIME 28 9437
TEST_TIME 29 11036
TEST_TIME 30 11173
TEST_TIME 31 11112
TEST_TIME 32 10921
TEST_TIME 33 10922
TEST_TIME 34 11044
TEST_TIME 35 10858
TEST_TIME 36 10706
TEST_TIME 37 10737
TEST_TIME 38 10534
TEST_TIME 39 10423
TEST_TIME 40 10461
TEST_TIME 41 10416
TEST_TIME 42 10424
TEST_TIME 43 10239
TEST_TIME 44 10193
TEST_TIME 45 10072
TEST_TIME 46 9987
TEST_TIME 47 10122
TEST_TIME 48 10233
TEST_TIME 49 10139
TEST_TIME 50 10004
TEST_TIME 51 10034
TEST_TIME 52 9878
TEST_TIME 53 9946
TEST_TIME 54 9873
TEST_TIME 55 9789
TEST_TIME 56 9729
TEST_TIME 57 9654
TEST_TIME 58 9672
TEST_TIME 59 9612
TEST_TIME 60 9624
TEST_TIME 61 9652
TEST_TIME 62 9709
TEST_TIME 63 9779
TEST_TIME 64 9861
TEST_TIME 65 9836
TEST_TIME 66 9834
TEST_TIME 67 9905
TEST_TIME 68 9898
TEST_TIME 69 9975
TEST_TIME 70 9967
TEST_TIME 71 9934
TEST_TIME 72 9963
TEST_TIME 73 9931
TEST_TIME 74 9928
TEST_TIME 75 9890
TEST_TIME 76 9870
TEST_TIME 77 9797
TEST_TIME 78 9790
TEST_TIME 79 9727
TEST_TIME 80 9715
TEST_TIME 81 9634
TEST_TIME 82 9617
TEST_TIME 83 9596
TEST_TIME 84 9618
TEST_TIME 85 9653
TEST_TIME 86 9659
TEST_TIME 87 9631
TEST_TIME 88 9676
TEST_TIME 89 9666
TEST_TIME 90 9754
TEST_TIME 91 9747
TEST_TIME 92 9754
TEST_TIME 93 9797
TEST_TIME 94 9787
TEST_TIME 95 9826
TEST_TIME 96 9796
TEST_TIME 97 9806
TEST_TIME 98 9869
TEST_TIME 99 9775
TEST_TIME 100 9759
TEST_TIME 101 9724
TEST_TIME 102 9743
TEST_TIME 103 9697
TEST_TIME 104 9654
TEST_TIME 105 9632
TEST_TIME 106 9582
TEST_TIME 107 9589
TEST_TIME 108 9571
TEST_TIME 109 9568
TEST_TIME 110 9577
TEST_TIME 111 9584
TEST_TIME 112 9582
TEST_TIME 113 9657
TEST_TIME 114 9649
TEST_TIME 115 9634
TEST_TIME 116 9644
TEST_TIME 117 9642
TEST_TIME 118 9696
TEST_TIME 119 9694
TEST_TIME 120 9661

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2022 19:39:11 to 25-09-2022 19:39:15.

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 1609205 1609205 0 0.00 0 25259 6 0 0
doc_list_biud 1609203 1609203 0 0.00 0 26555 29 0 0
sp_add_invoice_to_stock 5344 5272 72 1.34 33 174653 17028 0 72
sp_cancel_adding_invoice 5944 5944 0 0.00 2 86511 784 0 0
sp_cancel_client_order 1810 1810 0 0.00 4 10978 329 0 0
sp_cancel_customer_reserve 581017 581017 0 0.00 0 25743 64 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 3821 75 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 5425 70 0 0
sp_cancel_supplier_invoice 6544 6534 10 0.15 2 18512 814 0 10
sp_cancel_supplier_order 432 432 0 0.00 6 1032728 108728 0 0
sp_cancel_write_off 10987 10987 0 0.00 1 17784 101 0 0
sp_client_order 21275 21275 0 0.00 3 20645 333 0 0
sp_customer_reserve 487452 487452 0 0.00 0 87769 169 0 0
sp_fill_shopping_cart 32756 32756 0 0.00 1 12248 36 0 0
sp_fill_shopping_cart_clo_res 487448 487448 0 0.00 0 17078 14 0 0
sp_get_clo_for_invoice 5267 5267 0 0.00 0 24023 297 0 0
sp_kill_cost_storno 18044 18044 0 0.00 0 9197 56 0 0
sp_kill_qstorno_ret_qs2qd 1179987 1179987 0 0.00 0 25721 34 0 0
sp_kill_qty_storno 622355 622355 0 0.00 0 26555 73 0 0
sp_lock_selected_doc 21559 21559 0 0.00 0 7965 6 0 0
sp_make_cost_storno 29160 29160 0 0.00 0 25070 48 0 0
sp_multiply_rows_for_pdistr 29160 29160 0 0.00 0 7035 24 0 0
sp_multiply_rows_for_qdistr 21275 21275 0 0.00 0 19374 145 0 0
sp_pay_from_customer 7424 7424 0 0.00 4 13519 90 0 0
sp_pay_to_supplier 6105 6105 0 0.00 4 13491 115 0 0
sp_payment_common 13528 13528 0 0.00 1 13288 22 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 0 10971 303 0 0
sp_qd_handle_on_invoice_upd_sts 11211 11211 0 0.00 0 17669 307 0 0
sp_qd_handle_on_reserve_upd_sts 10364 10364 0 0.00 0 26555 51 0 0
sp_reserve_write_off 10364 10364 0 0.00 6 26583 157 0 0
sp_supplier_invoice 5753 5753 0 0.00 8 85161 2871 0 0
sp_supplier_order 5739 5739 0 0.00 5 44593 1176 0 0
srv_aggregate_perf_data 102 102 0 0.00 2673 201254 21839 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1114 138147 45246 0 0
srv_find_qd_qs_mism 1108465 1108465 0 0.00 0 15652 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 35 99887 1210 0 0
srv_make_money_saldo 831 831 0 0.00 1 11546 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 136335 317199 242688 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5272 5272 0 0.00 0 21227 177 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 5157 81 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 2394 13 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 3209 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 618 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 705 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 3015 15 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 30380 1599 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 12940 17 0 0
t$perf-norm:sp_client_order 21275 21275 0 0.00 0 9346 16 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 1179 6 0 0
t$perf-norm:sp_pay_from_customer 7424 7424 0 0.00 0 7113 11 0 0
t$perf-norm:sp_pay_to_supplier 6105 6105 0 0.00 0 12948 13 0 0
t$perf-norm:sp_reserve_write_off 10364 10364 0 0.00 0 4213 9 0 0
t$perf-norm:sp_supplier_invoice 5753 5753 0 0.00 0 6527 16 0 0
t$perf-norm:sp_supplier_order 5739 5739 0 0.00 0 1950 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 1794 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 470 13 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1895 25 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 2251 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10678 10534 144 1.34 0 5470 18 0 144
v_all_customers 39092 39092 0 0.00 0 19 1 0 0
v_all_suppliers 32100 32100 0 0.00 0 30 1 0 0
v_all_wares 42550 42550 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 2602 9 0 0
v_cancel_client_order 3620 3620 0 0.00 0 3634 6 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 2926 10 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1221 6 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1968 9 0 0
v_cancel_supplier_invoice 808 788 20 2.47 0 1333 14 0 20
v_cancel_supplier_order 864 864 0 0.00 0 1856 11 0 0
v_cancel_write_off 2042 2042 0 0.00 0 6524 15 0 0
v_max_id_clo_ord 5738 5738 0 0.00 0 399 1 0 0
v_max_id_clo_res 972 972 0 0.00 0 4938 16 0 0
v_max_id_ord_sup 5742 5742 0 0.00 0 4618 6 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 279 2 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 4103 5 0 0
v_min_id_clo_ord 5738 5738 0 0.00 0 3689 9 0 0
v_min_id_clo_res 972 972 0 0.00 0 4149 9 0 0
v_min_id_ord_sup 5742 5742 0 0.00 0 5072 4 0 0
v_min_non_paid_invoice 6104 6104 0 0.00 0 13284 18 0 0
v_min_non_paid_realizn 7424 7424 0 0.00 0 6165 14 0 0
v_our_firm 10548 10548 0 0.00 0 16 1 0 0
v_reserve_write_off 20726 20726 0 0.00 0 3114 6 0 0
x_lock_depdocs_on_canc_invoice 12084 12084 0 0.00 0 19664 111 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 10520 420 0 0
x_make_qstorno_1000_1200 5739 5739 0 0.00 1 44584 1098 0 0
x_make_qstorno_1200_2000 5753 5753 0 0.00 4 83369 2743 0 0
x_make_qstorno_2100_3300 487452 487452 0 0.00 0 87767 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2022 19:39:16 to 25-09-2022 19:39:20.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:29 25784 8192000000 9900962624 10212741120 978665440 10.671 100 80 160 0 1005106512 5634912 20806160
17:11:08 7885 8192000000 9950706656 10271608832 1035766400 11.224 100 88 176 0 1066760592 8395408 22598784
17:12:22 8530 8192000000 9979478112 10309251072 1061057904 11.467 100 84 168 0 1090616752 9027680 20531168
17:13:37 12408 8192000000 10035506736 10378985472 1116999392 11.999 100 78 156 0 1146124352 9807152 19317808
17:14:55 9578 8192000000 10080323680 10439929856 1168097168 12.479 100 74 146 0 1195321968 10659360 16565440
17:16:11 3312 8192000000 10122661024 10492936192 1213622688 12.903 100 76 152 0 1243108000 12527360 16957952
17:17:17 1865 8192000000 10154558144 10531115008 1246056336 13.202 100 66 132 1 1272791168 12486576 14248256
17:18:22 1671 8192000000 10191672656 10570141696 1279161952 13.505 100 87 175 0 1313107968 14085392 19860624
17:19:25 1409 8192000000 10215197936 10601492480 1299004912 13.686 100 73 146 0 1327792912 12655792 16132208
17:20:29 2550 8192000000 10211927952 10610147328 1308937072 13.776 100 48 94 1 1328336432 9042464 10356896
17:21:33 11224 8192000000 10192284128 10608066560 1311390448 13.799 100 33 66 0 1326806512 8266864 7149200
17:22:46 4944 8192000000 10218333424 10623037440 1317482048 13.854 100 51 99 0 1335644640 6397232 11765360
17:23:52 3377 8192000000 10218715888 10635423744 1328995520 13.958 100 28 55 1 1341968016 6700064 6272432
17:24:55 17030 8192000000 10222742960 10644803584 1335211264 14.014 100 20 40 0 1346320720 6010544 5098912
17:26:15 11452 8192000000 10222703712 10644131840 1324512032 13.918 99 11 22 1 1331789280 5267616 2009632
17:27:27 20903 8192000000 10228998544 10646745088 1337004704 14.030 100 39 77 0 1352055152 6195072 8855376
17:28:51 2331 8192000000 10225314208 10634604544 1303401424 13.726 98 39 78 1 1320614160 7787456 9425280
17:29:55 6726 8192000000 10162984608 10570682368 1255740560 13.291 98 22 43 2 1265911360 4837728 5333072
17:31:04 9982 8192000000 10151743552 10550095872 1213601664 12.902 95 13 27 0 1221984496 4896736 3486096
17:32:16 10909 8192000000 10120907344 10519896064 1170741104 12.504 93 14 28 1 1178808400 4763744 3303552
17:33:29 4537 8192000000 10156058624 10556047360 1249932304 13.238 99 24 49 0 1260605408 5250688 5422416
17:34:36 11387 8192000000 10177841136 10579984384 1277086080 13.486 100 25 50 1 1288528560 5590848 5851632
17:35:48 59546 8192000000 10188456624 10598981632 1297678272 13.674 100 38 71 0 1311670592 4641440 9350880
17:37:53 9449 8192000000 10208820304 10610626560 1303299072 13.725 100 26 52 0 1314123344 5141584 5682688
17:39:08 19272 8192000000 10238549200 10655297536 1335100512 14.013 100 42 82 2 1350682800 6779808 8802480
17:40:33 18351 8192000000 10265768832 10685648896 1354892032 14.191 100 52 101 3 1378141296 11869344 11379920
17:42:00 30749 8192000000 10275613568 10698399744 1369557760 14.323 100 37 74 0 1391982368 13712208 8712400
17:43:40 57514 8192000000 10307059904 10733625344 1386847776 14.478 100 40 81 0 1415598832 18968416 9782640
17:45:46 17970 8192000000 10321621904 10751553536 1401056976 14.604 100 33 66 1 1435307808 26084208 8166624
17:47:10 5755 8192000000 10345949488 10768896000 1410107056 14.685 100 58 115 0 1453152736 29726560 13319120
17:48:19 4159 8192000000 10345135376 10774532096 1413004336 14.711 100 46 92 1 1456302768 30411104 12887328
17:49:26 27462 8192000000 10326774160 10744225792 1385058256 14.462 99 52 100 0 1424875072 27897264 11919552
17:50:59 9540 8192000000 10337236432 10762182656 1401440864 14.608 100 47 93 0 1445228832 32453120 11334848
17:52:14 2637 8192000000 10347712448 10770890752 1407859344 14.665 100 53 105 0 1454546000 34475936 12210720
17:53:34 3985 8192000000 10379804912 10793046016 1424057920 14.809 100 79 158 1 1486027680 42540192 19429568
17:54:42 6169 8192000000 10396364608 10817585152 1427875872 14.842 100 67 133 0 1500059008 55530144 16652992
17:55:54 9706 8192000000 10384463856 10806386688 1423396880 14.803 100 59 117 1 1492859776 53548240 15914656
17:57:09 2777 8192000000 10372175776 10781298688 1387434176 14.483 99 65 130 1 1466745024 62505936 16804912
17:58:20 3150 8192000000 10376591568 10797322240 1403330912 14.625 100 65 130 1 1484327552 63586160 17410480
17:59:30 3280 8192000000 10382820768 10793791488 1403246304 14.624 100 74 145 0 1485591440 62030960 20314176
18:00:39 1113 8192000000 10394553680 10800963584 1409781312 14.682 100 83 163 0 1494475408 62473824 22220272
18:01:44 11279 8192000000 10374435232 10806153216 1412206096 14.704 100 44 88 2 1482367600 57873904 12287600
18:03:02 28300 8192000000 10372099104 10802393088 1402110224 14.614 99 41 80 0 1469020832 55812112 11098496
18:05:32 10827 8192000000 10335425984 10776489984 1373693056 14.360 100 26 50 0 1445954048 64256928 8004064
18:07:59 138147 8192000000 10273378960 10718167040 1297133184 13.669 95 5 10 0 1316617328 18673776 810368
18:11:18 68039 8192000000 10273326176 10722316288 1331849968 13.984 97 8 16 0 1347030896 13245232 1935696
18:13:27 88643 8192000000 10310600256 10750853120 1391033328 14.515 100 25 50 0 1406499424 9735712 5730384
18:16:16 57708 8192000000 10297756064 10740961280 1366482032 14.296 98 44 89 1 1382449200 5667872 10299296
18:18:18 74852 8192000000 10301699872 10748768256 1390031568 14.506 100 21 42 1 1410661120 16181344 4448208
18:20:34 72379 8192000000 10298535968 10751987712 1373274960 14.356 98 13 26 0 1387999712 11917536 2807216
18:22:53 24934 8192000000 10324896000 10773889024 1408851216 14.674 100 20 39 0 1426807392 13130656 4825520
18:24:19 41693 8192000000 10336234800 10787532800 1415058080 14.729 100 47 94 1 1444698864 17745312 11895472
18:26:02 110069 8192000000 10309368608 10775392256 1410618832 14.689 100 13 26 0 1423446000 10185104 2642064
18:28:53 61440 8192000000 10298636288 10769354752 1401787072 14.611 100 9 18 0 1414627488 10649904 2190512
18:30:55 67742 8192000000 10303481552 10756403200 1374789264 14.370 98 24 44 1 1385938144 6827008 4321872
18:33:05 36608 8192000000 10306334784 10767122432 1403252160 14.624 100 14 28 1 1417142464 10424416 3465888
18:34:46 78729 8192000000 10282817776 10756784128 1380648960 14.422 99 12 24 0 1395637792 12180976 2807856
18:37:07 55358 8192000000 10273618112 10735104000 1327226080 13.942 95 11 21 0 1337803600 8093184 2484336
18:39:05 41030 8192000000 10300192256 10766163968 1388114864 14.489 99 18 36 0 1406174208 14618496 3440848
18:40:49 60285 8192000000 10267791264 10729713664 1303624000 13.728 94 19 36 0 1323638864 15841344 4173520
18:43:03 35378 8192000000 10288645776 10731151360 1360313904 14.240 99 18 36 0 1378259216 13134000 4811312
18:44:39 77322 8192000000 10274775552 10737246208 1349154992 14.140 98 22 44 0 1369755312 15316672 5283648
18:47:01 41692 8192000000 10271519424 10722418688 1347162368 14.122 99 36 72 0 1367213584 12015072 8036144
18:48:43 29018 8192000000 10283992464 10723807232 1349430368 14.142 98 33 65 1 1366085040 9908128 6746544
18:50:14 58338 8192000000 10303165456 10729349120 1307874944 13.767 95 31 60 0 1324666336 10001056 6790336
18:52:15 87193 8192000000 10274183776 10722938880 1349917264 14.147 99 24 48 1 1367837728 12502496 5417968
18:54:43 49415 8192000000 10260825776 10698641408 1327612000 13.946 98 32 63 0 1344255712 9685552 6958160
18:56:33 36876 8192000000 10257623344 10708238336 1340404608 14.061 98 19 36 0 1352997984 8337424 4255952
18:58:15 62064 8192000000 10260689488 10720849920 1344880560 14.101 98 6 12 0 1355693360 9486256 1326544
19:00:18 67404 8192000000 10263657584 10722603008 1355175456 14.194 99 12 24 0 1369970512 11651152 3143904
19:02:33 49450 8192000000 10259109088 10720952320 1342290224 14.078 98 7 14 0 1358661440 14473808 1897408
19:04:26 41839 8192000000 10269257072 10711511040 1343528144 14.089 98 22 44 0 1358299568 9962576 4808848
19:06:21 33530 8192000000 10277086752 10719133696 1368570624 14.314 100 24 46 1 1384503840 10363888 5569328
19:08:01 67921 8192000000 10289442496 10742161408 1373978448 14.363 99 17 32 1 1393808864 16502816 3327600
19:10:37 70357 8192000000 10306121376 10763763712 1382343296 14.437 99 20 40 1 1398996304 12513472 4139536
19:12:52 59333 8192000000 10315098720 10769145856 1386975520 14.479 99 24 48 0 1407869280 15858000 5035760
19:14:52 17552 8192000000 10329549408 10757885952 1394676672 14.548 100 51 101 1 1416876608 10772208 11427728
19:16:10 128177 8192000000 10301551568 10750623744 1359995696 14.237 97 18 36 1 1378663168 14288896 4378576
19:19:23 29187 8192000000 10284051856 10740817920 1369131968 14.319 98 17 34 0 1381941776 9332256 3477552
19:20:54 46189 8192000000 10267439872 10735398912 1354143472 14.185 98 6 10 0 1364720832 9541856 1035504
19:22:41 62899 8192000000 10278656416 10736013312 1320643472 13.883 95 12 24 0 1332387808 8996896 2747440
19:24:50 42365 8192000000 10276881424 10743656448 1357356448 14.214 98 12 24 0 1371982208 11409184 3216576
19:26:40 35367 8192000000 10266742688 10720911360 1328823536 13.957 97 15 30 1 1340125744 7868944 3433264
19:28:16 47756 8192000000 10278584320 10735702016 1370460992 14.331 100 13 26 0 1387248080 14025504 2761584
19:30:05 87533 8192000000 10267862880 10724384768 1324725184 13.919 96 24 49 0 1344629504 14367344 5536976
19:32:46 94712 8192000000 10274158880 10717577216 1312316080 13.807 96 31 60 1 1330592304 11359520 6916704
19:35:34 41401 8192000000 10255043696 10698559488 1313892768 13.821 97 28 56 0 1332661008 12366176 6402064
19:37:16 43770 8192000000 10247941072 10692800512 1293738736 13.638 96 15 30 0 1308256400 11110752 3406912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2022 19:39:22 to 25-09-2022 19:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2022 19:39:38 to 25-09-2022 19:39:40.

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

25.09.22 19:39:41. 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-09-25 19:39:44 No severe PSQL-related problems occured
25.09.22 19:39:45. End of report.