Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 16:43
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 16456172 kB
MemAvailable 31406360 kB
Buffers 0 kB
Cached 15286424 kB
SwapCached 2464 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-27 16:42:09
DTS_END                         2022-05-27 16:42:09.2620
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/57720
TRN_ID                          235807
ATT_ID                          687
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: 9005.35 19515 1080642
customer order: creation 167.38 394 20085
customer order: refuse 14.69 324 1763
order to supplier: creation 43.93 1491 5272
order to supplier: removal 3.52 105859 422
invoice (draft): creation 45.39 3633 5447
invoice (draft): removal 52.01 843 6241
invoice accept: apply 40.74 20628 4889
invoice accept: cancel 46.67 999 5600
customer reserve: creation 3744.39 206 449327
customer reserve: removal 4557.10 65 546852
realization accept: apply 81.88 179 9826
realization accept: cancel 83.04 121 9965
payment from customer: creation 57.12 117 6854
payment from customer: removal 5.48 77 658
payment to supplier: creation 47.25 126 5670
payment to supplier: removal 3.33 79 400
service: total inventory turnovers 4.66 1042 559
service: total monetary turnovers 6.04 26 725
service: aggregate perf. data 0.69 27437 83
service: refresh index statistics 0.03 226663 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5349
TEST_TIME 2 4990
TEST_TIME 3 4758
TEST_TIME 4 4471
TEST_TIME 5 4262
TEST_TIME 6 4369
TEST_TIME 7 4216
TEST_TIME 8 4896
TEST_TIME 9 5075
TEST_TIME 10 5534
TEST_TIME 11 5581
TEST_TIME 12 5597
TEST_TIME 13 5581
TEST_TIME 14 5537
TEST_TIME 15 5667
TEST_TIME 16 5695
TEST_TIME 17 5809
TEST_TIME 18 5791
TEST_TIME 19 5786
TEST_TIME 20 6527
TEST_TIME 21 6928
TEST_TIME 22 7225
TEST_TIME 23 7450
TEST_TIME 24 7751
TEST_TIME 25 7754
TEST_TIME 26 7913
TEST_TIME 27 8067
TEST_TIME 28 8629
TEST_TIME 29 8468
TEST_TIME 30 8556
TEST_TIME 31 9341
TEST_TIME 32 9843
TEST_TIME 33 9849
TEST_TIME 34 9842
TEST_TIME 35 9821
TEST_TIME 36 9567
TEST_TIME 37 9433
TEST_TIME 38 9530
TEST_TIME 39 9341
TEST_TIME 40 9317
TEST_TIME 41 9346
TEST_TIME 42 9232
TEST_TIME 43 9192
TEST_TIME 44 9418
TEST_TIME 45 9307
TEST_TIME 46 9176
TEST_TIME 47 9196
TEST_TIME 48 9090
TEST_TIME 49 9024
TEST_TIME 50 8973
TEST_TIME 51 8983
TEST_TIME 52 8893
TEST_TIME 53 8890
TEST_TIME 54 8897
TEST_TIME 55 8829
TEST_TIME 56 8827
TEST_TIME 57 8790
TEST_TIME 58 8786
TEST_TIME 59 8810
TEST_TIME 60 8848
TEST_TIME 61 8821
TEST_TIME 62 8919
TEST_TIME 63 8969
TEST_TIME 64 8979
TEST_TIME 65 9025
TEST_TIME 66 9149
TEST_TIME 67 9221
TEST_TIME 68 9224
TEST_TIME 69 9247
TEST_TIME 70 9266
TEST_TIME 71 9275
TEST_TIME 72 9223
TEST_TIME 73 9183
TEST_TIME 74 9147
TEST_TIME 75 9110
TEST_TIME 76 9111
TEST_TIME 77 9075
TEST_TIME 78 9048
TEST_TIME 79 9041
TEST_TIME 80 9004
TEST_TIME 81 9027
TEST_TIME 82 8978
TEST_TIME 83 9000
TEST_TIME 84 9068
TEST_TIME 85 9036
TEST_TIME 86 9085
TEST_TIME 87 9099
TEST_TIME 88 9088
TEST_TIME 89 9091
TEST_TIME 90 9051
TEST_TIME 91 9072
TEST_TIME 92 9287
TEST_TIME 93 9275
TEST_TIME 94 9245
TEST_TIME 95 9208
TEST_TIME 96 9136
TEST_TIME 97 9112
TEST_TIME 98 9147
TEST_TIME 99 9132
TEST_TIME 100 9112
TEST_TIME 101 9071
TEST_TIME 102 9071
TEST_TIME 103 9040
TEST_TIME 104 9005
TEST_TIME 105 8999
TEST_TIME 106 9022
TEST_TIME 107 9004
TEST_TIME 108 9039
TEST_TIME 109 9063
TEST_TIME 110 9040
TEST_TIME 111 9048
TEST_TIME 112 9022
TEST_TIME 113 9056
TEST_TIME 114 9130
TEST_TIME 115 9111
TEST_TIME 116 9157
TEST_TIME 117 9168
TEST_TIME 118 9130
TEST_TIME 119 9085
TEST_TIME 120 9009

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 16:44:14 to 27-05-2022 16:44:17.

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 1496988 1496988 0 0.00 0 16724 7 0 0
doc_list_biud 1496984 1496984 0 0.00 0 39292 30 0 0
sp_add_invoice_to_stock 4983 4889 94 1.88 33 240027 20629 0 94
sp_cancel_adding_invoice 5600 5600 0 0.00 3 89057 1000 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 6988 324 0 0
sp_cancel_customer_reserve 546852 546852 0 0.00 1 39294 65 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 5357 77 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 3072 79 0 0
sp_cancel_supplier_invoice 6249 6241 8 0.12 1 30214 843 0 8
sp_cancel_supplier_order 422 422 0 0.00 6 1015456 105859 0 0
sp_cancel_write_off 9965 9965 0 0.00 1 18540 121 0 0
sp_client_order 20085 20085 0 0.00 3 18960 394 0 0
sp_customer_reserve 449327 449327 0 0.00 0 65910 207 0 0
sp_fill_shopping_cart 30798 30798 0 0.00 1 18198 45 0 0
sp_fill_shopping_cart_clo_res 449316 449316 0 0.00 0 14446 14 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 19533 307 0 0
sp_kill_cost_storno 16623 16623 0 0.00 0 6844 60 0 0
sp_kill_qstorno_ret_qs2qd 1110332 1110332 0 0.00 0 39289 35 0 0
sp_kill_qty_storno 585543 585543 0 0.00 0 39292 76 0 0
sp_lock_selected_doc 20399 20399 0 0.00 0 7193 4 0 0
sp_make_cost_storno 27228 27228 0 0.00 0 11611 50 0 0
sp_multiply_rows_for_pdistr 27224 27224 0 0.00 0 7490 27 0 0
sp_multiply_rows_for_qdistr 20085 20085 0 0.00 0 18552 174 0 0
sp_pay_from_customer 6854 6854 0 0.00 4 14273 117 0 0
sp_pay_to_supplier 5670 5670 0 0.00 5 19435 126 0 0
sp_payment_common 12524 12524 0 0.00 1 19387 25 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 6980 302 0 0
sp_qd_handle_on_invoice_upd_sts 10476 10476 0 0.00 0 27148 361 0 0
sp_qd_handle_on_reserve_upd_sts 9824 9824 0 0.00 0 20058 61 0 0
sp_reserve_write_off 9826 9826 0 0.00 6 20082 180 0 0
sp_supplier_invoice 5447 5447 0 0.00 6 77899 3633 0 0
sp_supplier_order 5272 5272 0 0.00 5 60458 1492 0 0
srv_aggregate_perf_data 83 83 0 0.00 2142 505686 27437 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2243 110721 44691 0 0
srv_find_qd_qs_mism 1034477 1034477 0 0.00 0 16723 6 0 0
srv_make_invnt_saldo 559 559 0 0.00 34 69848 1043 0 0
srv_make_money_saldo 725 725 0 0.00 1 2002 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 143318 345185 226663 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4890 4890 0 0.00 0 18035 184 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 5259 107 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 3493 12 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2174 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 4008 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 2105 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 4475 26 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 25660 1639 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1441 6 0 0
t$perf-norm:sp_client_order 20085 20085 0 0.00 0 7848 20 0 0
t$perf-norm:sp_customer_reserve 920 920 0 0.00 0 5220 17 0 0
t$perf-norm:sp_pay_from_customer 6854 6854 0 0.00 0 5215 11 0 0
t$perf-norm:sp_pay_to_supplier 5670 5670 0 0.00 0 12945 11 0 0
t$perf-norm:sp_reserve_write_off 9826 9826 0 0.00 0 12707 18 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 6897 18 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 9162 18 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 142 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2112 38 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 5214 29 0 0
t$perf-norm:srv_make_money_saldo 725 725 0 0.00 0 5219 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9940 9752 188 1.89 0 9420 17 0 188
v_all_customers 36714 36714 0 0.00 0 173 1 0 0
v_all_suppliers 29662 29662 0 0.00 0 18 1 0 0
v_all_wares 40170 40170 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 4228 16 0 0
v_cancel_client_order 3526 3526 0 0.00 0 1229 3 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 4500 8 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 4486 12 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2638 7 0 0
v_cancel_supplier_invoice 818 802 16 1.95 0 3945 23 0 16
v_cancel_supplier_order 844 844 0 0.00 0 2684 8 0 0
v_cancel_write_off 2010 2010 0 0.00 0 9125 18 0 0
v_max_id_clo_ord 5270 5270 0 0.00 0 1776 3 0 0
v_max_id_clo_res 920 920 0 0.00 0 2332 14 0 0
v_max_id_ord_sup 5443 5443 0 0.00 0 4163 5 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 207 2 0 0
v_max_non_paid_realizn 3642 3642 0 0.00 0 2305 5 0 0
v_min_id_clo_ord 5270 5270 0 0.00 0 9238 18 0 0
v_min_id_clo_res 920 920 0 0.00 0 5536 17 0 0
v_min_id_ord_sup 5443 5443 0 0.00 0 6422 10 0 0
v_min_non_paid_invoice 5670 5670 0 0.00 0 19382 16 0 0
v_min_non_paid_realizn 6854 6854 0 0.00 0 14266 21 0 0
v_our_firm 9880 9880 0 0.00 0 14 1 0 0
v_reserve_write_off 19648 19648 0 0.00 0 8882 11 0 0
x_lock_depdocs_on_canc_invoice 11440 11440 0 0.00 0 18932 126 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 12668 376 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 1 59721 1392 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 2 73877 3478 0 0
x_make_qstorno_2100_3300 449327 449327 0 0.00 0 65906 190 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 16:44:18 to 27-05-2022 16:44:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:13 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:13 37947 8192000000 9906029312 10216767488 980112624 10.685 100 84 168 0 1008052000 5725680 22213696
14:14:58 24160 8192000000 9951984656 10276024320 1031774528 11.186 100 87 174 0 1062395504 8154032 22466944
14:16:25 20188 8192000000 9993326384 10324815872 1071988928 11.571 100 93 186 0 1105183184 9749664 23444592
14:17:54 13754 8192000000 10046063472 10391539712 1126493168 12.088 100 84 167 1 1157964048 11265856 20205024
14:19:11 7188 8192000000 10088604464 10449178624 1180281648 12.593 100 72 145 1 1208899568 11293424 17324496
14:20:21 5673 8192000000 10126578288 10499362816 1214644304 12.912 100 76 151 0 1244464624 12857264 16963056
14:21:29 3680 8192000000 10166498176 10544037888 1253113008 13.267 100 87 174 0 1285945680 14134832 18697840
14:22:35 1780 8192000000 10208651312 10588086272 1286466400 13.572 100 87 171 0 1317294064 11497456 19330208
14:23:38 13580 8192000000 10205613088 10598690816 1300064832 13.696 100 72 143 0 1325905760 10190224 15650704
14:24:52 6712 8192000000 10193369840 10605023232 1306419440 13.754 100 53 106 1 1327892416 9050000 12422976
14:25:59 9362 8192000000 10203330688 10619535360 1318875616 13.867 100 20 40 1 1330036656 6463520 4697520
14:27:11 6625 8192000000 10217316464 10635120640 1329558256 13.963 100 30 60 1 1346851408 10291872 7001280
14:28:23 47649 8192000000 10224362048 10648735744 1337155872 14.032 100 34 69 0 1350864224 5561744 8146608
14:30:11 2416 8192000000 10236089008 10653147136 1336989536 14.030 100 33 66 1 1352757168 7949888 7817744
14:31:16 5314 8192000000 10230034400 10650107904 1336847904 14.029 100 25 48 1 1349049808 6708896 5493008
14:32:21 23054 8192000000 10201340272 10621710336 1283931536 13.549 97 28 55 1 1296768800 5480800 7356464
14:33:47 5862 8192000000 10185791136 10598649856 1275130400 13.469 97 29 59 0 1287446592 5119776 7196416
14:34:56 7827 8192000000 10150472992 10546298880 1195775312 12.737 93 26 52 0 1207262544 5574496 5912736
14:36:06 6756 8192000000 10136831632 10526101504 1202282064 12.798 96 26 52 1 1213788688 4820224 6686400
14:37:14 70858 8192000000 10147304256 10549886976 1214296032 12.909 96 30 61 0 1227213872 5572928 7344912
14:39:25 5091 8192000000 10187224272 10593452032 1285185200 13.560 100 35 70 0 1300477328 7686016 7606112
14:40:31 7833 8192000000 10189384048 10604920832 1301257040 13.707 100 22 44 0 1312531632 6130720 5143872
14:41:41 11337 8192000000 10201046464 10620133376 1311666864 13.801 100 30 60 0 1324614624 6006864 6940896
14:42:56 53381 8192000000 10246346656 10659078144 1341282560 14.069 100 49 98 0 1360217312 6640864 12293888
14:44:56 30746 8192000000 10265738080 10684628992 1354201344 14.185 100 48 96 1 1373114288 8076016 10836928
14:46:33 9354 8192000000 10285284080 10705391616 1377514096 14.394 100 51 102 0 1401306080 12073776 11718208
14:47:47 33098 8192000000 10291638176 10718801920 1382654208 14.440 100 36 72 3 1404998208 13722720 8621280
14:49:34 18157 8192000000 10312150992 10733211648 1391822640 14.522 100 54 108 0 1421165472 17365568 11977264
14:50:59 33000 8192000000 10316697776 10748272640 1405938048 14.648 100 46 94 2 1431315264 14850720 10526496
14:52:57 4240 8192000000 10313910560 10735607808 1395404928 14.554 99 54 108 0 1424587040 15944752 13237360
14:54:07 5867 8192000000 10334462560 10748235776 1402568144 14.618 100 67 134 1 1438301520 19674944 16058432
14:55:17 17638 8192000000 10324345472 10748194816 1387451536 14.483 98 47 94 1 1417602720 19058576 11092608
14:56:41 4692 8192000000 10339895920 10757042176 1403925776 14.630 100 65 128 3 1444381456 25077856 15377824
14:57:51 3756 8192000000 10319119424 10728984576 1382032032 14.435 99 73 144 4 1422922096 23629008 17261056
14:59:03 2383 8192000000 10305891520 10703253504 1355499088 14.197 98 82 160 3 1402921696 28037872 19384736
15:00:11 10237 8192000000 10329947440 10739634176 1379466896 14.412 100 72 145 1 1435549488 37700480 18382112
15:01:31 10084 8192000000 10346287008 10756718592 1383395376 14.447 100 71 141 2 1448821120 46092224 19333520
15:02:48 4379 8192000000 10329623152 10745622528 1377752160 14.396 99 68 134 0 1435985152 41512576 16720416
15:03:59 2242 8192000000 10359343312 10770108416 1397848608 14.576 100 86 170 2 1458443904 39210432 21384864
15:05:07 4291 8192000000 10344221904 10755534848 1390475040 14.510 100 69 138 1 1449759680 41729824 17554816
15:06:22 32056 8192000000 10345255408 10776498176 1387301488 14.482 99 40 80 1 1445867344 47280560 11285296
15:07:59 8192 8192000000 10329785888 10757705728 1366145952 14.293 98 36 72 1 1422475280 46219680 10109648
15:09:09 10509 8192000000 10318006048 10750660608 1362561728 14.260 99 29 58 1 1414981280 44346720 8072832
15:10:53 10973 8192000000 10320299120 10764152832 1379946448 14.416 99 22 43 1 1418097936 32365872 5785616
15:12:22 22514 8192000000 10327241328 10764967936 1371475664 14.340 99 17 34 0 1422128640 44994368 5658608
15:13:47 38218 8192000000 10296760240 10734120960 1352913552 14.174 98 17 33 0 1379023888 22010304 4100032
15:15:31 6169 8192000000 10290637808 10721153024 1368933552 14.317 100 38 76 0 1385986912 8764848 8288512
15:16:42 71994 8192000000 10287035440 10735321088 1365681808 14.288 99 17 35 0 1381918272 12278016 3958448
15:18:55 95572 8192000000 10292622944 10740285440 1374451504 14.367 100 34 68 0 1399548128 16775792 8320832
15:21:36 61104 8192000000 10307740736 10761150464 1390251840 14.508 100 42 85 0 1415230336 13902496 11076000
15:23:38 56874 8192000000 10297212432 10753839104 1390943536 14.514 100 11 22 1 1405119664 11587936 2588192
15:25:49 14753 8192000000 10302490368 10752802816 1393774992 14.540 100 30 60 0 1416770432 16126672 6868768
15:27:18 41588 8192000000 10279859760 10744705024 1372542080 14.350 99 9 18 0 1385595760 10957392 2096288
15:29:00 51531 8192000000 10278295120 10739580928 1379753456 14.414 100 13 27 0 1390928336 8606544 2568336
15:30:51 70580 8192000000 10277439008 10740633600 1372745552 14.352 99 24 46 0 1389294272 11167696 5381024
15:33:06 58091 8192000000 10274425440 10741231616 1385148672 14.463 100 13 26 1 1395701552 7898512 2654368
15:35:05 48557 8192000000 10301926480 10759147520 1396641952 14.565 100 29 58 0 1411167536 8445392 6080192
15:36:58 89196 8192000000 10283667488 10754002944 1370803136 14.334 98 14 28 1 1385281344 11131904 3346304
15:39:29 30089 8192000000 10290158032 10749448192 1383419856 14.447 99 28 56 0 1398325792 8616512 6289424
15:40:59 47450 8192000000 10298994752 10755526656 1373030960 14.354 98 17 34 1 1386449488 9468528 3950000
15:42:49 41094 8192000000 10275774528 10724954112 1324221888 13.915 96 29 58 2 1339560544 9206240 6132416
15:45:16 37577 8192000000 10288608928 10734084096 1352169520 14.167 98 28 54 1 1369262656 11070576 6022560
15:46:59 87448 8192000000 10280518608 10759499776 1352511632 14.170 98 16 32 0 1377146912 20880128 3755152
15:49:35 67617 8192000000 10284000528 10731638784 1350163552 14.149 98 15 30 2 1367053728 13668544 3221632
15:51:48 67756 8192000000 10274686832 10725986304 1292723344 13.629 94 18 36 2 1308645184 12154496 3767344
15:54:07 100489 8192000000 10268598528 10714275840 1351085296 14.157 99 26 52 0 1370354560 12509248 6760016
15:57:10 26699 8192000000 10275815008 10710790144 1353096256 14.175 99 43 86 0 1378911568 16382528 9432784
15:58:48 59934 8192000000 10250959216 10707988480 1355854304 14.200 100 12 24 1 1375502192 16654272 2993616
16:00:49 77992 8192000000 10273657824 10727907328 1358986528 14.228 99 17 34 1 1376260624 13142448 4131648
16:03:14 59939 8192000000 10271113680 10735652864 1363553328 14.269 100 11 21 1 1377525184 11840352 2131504
16:05:36 51092 8192000000 10263454800 10701258752 1311464896 13.799 96 30 60 2 1330965344 11788064 7712384
16:07:31 39060 8192000000 10269958416 10725490688 1351934240 14.165 99 12 24 1 1367908480 13291136 2683104
16:09:28 55996 8192000000 10286364384 10734051328 1374399168 14.366 100 28 56 0 1390477184 10351936 5726080
16:11:24 13467 8192000000 10294443488 10732052480 1374874688 14.371 100 44 88 0 1397186048 12780816 9530544
16:12:43 52480 8192000000 10315961008 10764095488 1374127280 14.364 99 26 52 0 1405559952 25745568 5687104
16:14:46 108234 8192000000 10294533328 10732412928 1345528688 14.107 97 30 62 2 1373906416 21924768 6452960
16:17:37 110721 8192000000 10310090384 10753753088 1378366400 14.402 99 23 43 2 1398740112 15147472 5226240
16:20:32 54705 8192000000 10285667088 10748088320 1368973920 14.318 98 15 30 0 1389747008 17318512 3454576
16:22:32 54878 8192000000 10282318288 10750623744 1373579840 14.359 99 7 14 1 1386354912 11282640 1492432
16:24:33 74763 8192000000 10279901728 10738040832 1375684496 14.378 99 15 30 1 1387431008 8289712 3456800
16:26:54 64832 8192000000 10292972640 10758115328 1387714480 14.485 100 9 18 1 1402732144 12751648 2266016
16:29:05 52805 8192000000 10290301152 10742210560 1356485824 14.206 97 15 30 0 1369449968 9039904 3924240
16:30:57 97895 8192000000 10290709232 10748006400 1356517520 14.206 97 15 30 1 1371515392 11520960 3476912
16:33:37 62892 8192000000 10324364480 10780237824 1394660800 14.547 99 19 38 1 1411337808 12611424 4065584
16:36:21 65595 8192000000 10306438640 10757140480 1382786576 14.441 98 17 34 0 1405088352 18752080 3549696
16:38:35 70553 8192000000 10308084400 10755784704 1344080816 14.094 96 17 34 0 1358280080 10558576 3640688
16:40:50 90111 8192000000 9433354144 9746255872 572480784 6.531 41 13 27 0 579697456 4356176 2860496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-05-2022 16:44:24 to 27-05-2022 16:44:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 16:44:40 to 27-05-2022 16:44:42.

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

27.05.22 16:44:43. 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-27 16:44:45 No severe PSQL-related problems occured
27.05.22 16:44:46. End of report.