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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16351252 kB
MemAvailable 31313044 kB
Buffers 0 kB
Cached 15363472 kB
SwapCached 2448 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-06-07 22:38:22
DTS_END                         2022-06-07 22:38:22.3800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33140
TRN_ID                          238881
ATT_ID                          652
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: 9106.33 20371 1092759
customer order: creation 173.78 349 20854
customer order: refuse 15.20 353 1824
order to supplier: creation 45.48 1297 5458
order to supplier: removal 3.37 112240 404
invoice (draft): creation 46.02 3258 5522
invoice (draft): removal 52.11 868 6253
invoice accept: apply 41.18 18252 4942
invoice accept: cancel 46.98 757 5638
customer reserve: creation 3756.51 183 450781
customer reserve: removal 4622.54 64 554705
realization accept: apply 84.40 166 10128
realization accept: cancel 88.44 120 10613
payment from customer: creation 59.41 115 7129
payment from customer: removal 5.43 107 652
payment to supplier: creation 49.50 119 5940
payment to supplier: removal 3.38 94 405
service: total inventory turnovers 5.06 1121 607
service: total monetary turnovers 6.70 25 804
service: aggregate perf. data 0.80 22330 96
service: refresh index statistics 0.03 245610 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-06-2022 22:40:11 to 07-06-2022 22:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7070
TEST_TIME 2 6587
TEST_TIME 3 6508
TEST_TIME 4 5840
TEST_TIME 5 6045
TEST_TIME 6 5892
TEST_TIME 7 5966
TEST_TIME 8 6149
TEST_TIME 9 6056
TEST_TIME 10 6273
TEST_TIME 11 6408
TEST_TIME 12 6893
TEST_TIME 13 7157
TEST_TIME 14 7326
TEST_TIME 15 7509
TEST_TIME 16 7501
TEST_TIME 17 7583
TEST_TIME 18 7580
TEST_TIME 19 7589
TEST_TIME 20 7923
TEST_TIME 21 8172
TEST_TIME 22 8402
TEST_TIME 23 8465
TEST_TIME 24 8797
TEST_TIME 25 9167
TEST_TIME 26 9062
TEST_TIME 27 9115
TEST_TIME 28 9863
TEST_TIME 29 10803
TEST_TIME 30 10708
TEST_TIME 31 10572
TEST_TIME 32 10508
TEST_TIME 33 10527
TEST_TIME 34 10344
TEST_TIME 35 10167
TEST_TIME 36 10041
TEST_TIME 37 9928
TEST_TIME 38 9922
TEST_TIME 39 9832
TEST_TIME 40 9751
TEST_TIME 41 9618
TEST_TIME 42 9511
TEST_TIME 43 9599
TEST_TIME 44 9415
TEST_TIME 45 9308
TEST_TIME 46 9304
TEST_TIME 47 9354
TEST_TIME 48 9246
TEST_TIME 49 9117
TEST_TIME 50 9129
TEST_TIME 51 9144
TEST_TIME 52 9220
TEST_TIME 53 9074
TEST_TIME 54 8997
TEST_TIME 55 9028
TEST_TIME 56 8994
TEST_TIME 57 8960
TEST_TIME 58 8901
TEST_TIME 59 8949
TEST_TIME 60 9090
TEST_TIME 61 9276
TEST_TIME 62 9251
TEST_TIME 63 9214
TEST_TIME 64 9319
TEST_TIME 65 9311
TEST_TIME 66 9343
TEST_TIME 67 9313
TEST_TIME 68 9270
TEST_TIME 69 9282
TEST_TIME 70 9289
TEST_TIME 71 9253
TEST_TIME 72 9148
TEST_TIME 73 9122
TEST_TIME 74 9119
TEST_TIME 75 9111
TEST_TIME 76 9052
TEST_TIME 77 9027
TEST_TIME 78 9019
TEST_TIME 79 8984
TEST_TIME 80 8944
TEST_TIME 81 8910
TEST_TIME 82 8929
TEST_TIME 83 8980
TEST_TIME 84 9004
TEST_TIME 85 9003
TEST_TIME 86 9042
TEST_TIME 87 9025
TEST_TIME 88 9053
TEST_TIME 89 9048
TEST_TIME 90 9038
TEST_TIME 91 9106
TEST_TIME 92 9165
TEST_TIME 93 9205
TEST_TIME 94 9173
TEST_TIME 95 9215
TEST_TIME 96 9189
TEST_TIME 97 9126
TEST_TIME 98 9121
TEST_TIME 99 9120
TEST_TIME 100 9082
TEST_TIME 101 9067
TEST_TIME 102 9019
TEST_TIME 103 8988
TEST_TIME 104 9004
TEST_TIME 105 8969
TEST_TIME 106 8928
TEST_TIME 107 8974
TEST_TIME 108 9037
TEST_TIME 109 9037
TEST_TIME 110 9036
TEST_TIME 111 9098
TEST_TIME 112 9089
TEST_TIME 113 9075
TEST_TIME 114 9071
TEST_TIME 115 9136
TEST_TIME 116 9173
TEST_TIME 117 9174
TEST_TIME 118 9169
TEST_TIME 119 9181
TEST_TIME 120 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-06-2022 22:40:15 to 07-06-2022 22:40:18.

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 1512199 1512199 0 0.00 0 15292 6 0 0
doc_list_biud 1512199 1512199 0 0.00 0 40389 29 0 0
sp_add_invoice_to_stock 4983 4942 41 0.82 33 183202 18253 0 41
sp_cancel_adding_invoice 5638 5638 0 0.00 5 44297 757 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 10877 353 0 0
sp_cancel_customer_reserve 554705 554705 0 0.00 1 40391 64 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 2 5713 108 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 3783 94 0 0
sp_cancel_supplier_invoice 6262 6253 9 0.14 1 16870 868 0 9
sp_cancel_supplier_order 404 404 0 0.00 11 972590 112241 0 0
sp_cancel_write_off 10613 10613 0 0.00 1 10182 120 0 0
sp_client_order 20854 20854 0 0.00 3 17906 349 0 0
sp_customer_reserve 450781 450781 0 0.00 0 68609 183 0 0
sp_fill_shopping_cart 31831 31831 0 0.00 1 12658 39 0 0
sp_fill_shopping_cart_clo_res 450776 450776 0 0.00 0 12233 12 0 0
sp_get_clo_for_invoice 4935 4935 0 0.00 0 15948 269 0 0
sp_kill_cost_storno 17308 17308 0 0.00 0 7532 57 0 0
sp_kill_qstorno_ret_qs2qd 1126680 1126680 0 0.00 0 40384 35 0 0
sp_kill_qty_storno 594500 594500 0 0.00 0 40389 74 0 0
sp_lock_selected_doc 20877 20877 0 0.00 0 9213 6 0 0
sp_make_cost_storno 28132 28132 0 0.00 0 13671 50 0 0
sp_multiply_rows_for_pdistr 28132 28132 0 0.00 0 6847 28 0 0
sp_multiply_rows_for_qdistr 20854 20854 0 0.00 0 14091 165 0 0
sp_pay_from_customer 7129 7129 0 0.00 4 16639 115 0 0
sp_pay_to_supplier 5940 5940 0 0.00 4 13127 119 0 0
sp_payment_common 13069 13069 0 0.00 2 15375 25 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 10870 323 0 0
sp_qd_handle_on_invoice_upd_sts 10573 10573 0 0.00 0 22347 321 0 0
sp_qd_handle_on_reserve_upd_sts 10128 10128 0 0.00 0 24631 51 0 0
sp_reserve_write_off 10128 10128 0 0.00 7 24652 166 0 0
sp_supplier_invoice 5522 5522 0 0.00 6 109005 3258 0 0
sp_supplier_order 5458 5458 0 0.00 5 31628 1297 0 0
srv_aggregate_perf_data 96 96 0 0.00 2648 513702 22331 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 804 186170 45433 0 0
srv_find_qd_qs_mism 1046289 1046289 0 0.00 0 8904 5 0 0
srv_make_invnt_saldo 607 607 0 0.00 34 129162 1121 0 0
srv_make_money_saldo 804 804 0 0.00 0 798 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 98241 380543 245611 0 0
t$perf-abend:sp_add_invoice_to_stock 41 41 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4942 4942 0 0.00 0 16172 175 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 17105 135 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 1627 11 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 4569 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 8339 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1719 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 8504 40 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 46134 1756 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 3983 12 0 0
t$perf-norm:sp_client_order 20854 20854 0 0.00 0 8695 20 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 442 3 0 0
t$perf-norm:sp_pay_from_customer 7129 7129 0 0.00 0 10792 11 0 0
t$perf-norm:sp_pay_to_supplier 5940 5940 0 0.00 0 16156 12 0 0
t$perf-norm:sp_reserve_write_off 10128 10128 0 0.00 0 5306 10 0 0
t$perf-norm:sp_supplier_invoice 5522 5522 0 0.00 0 5208 19 0 0
t$perf-norm:sp_supplier_order 5458 5458 0 0.00 0 3407 18 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 189 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 286 6 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 8273 53 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 702 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 275 70 0 0
v_add_invoice_to_stock 9944 9862 82 0.82 0 18627 19 0 82
v_all_customers 38282 38282 0 0.00 0 24 1 0 0
v_all_suppliers 30340 30340 0 0.00 0 19 1 0 0
v_all_wares 41708 41708 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 4446 14 0 0
v_cancel_client_order 3648 3648 0 0.00 0 3385 6 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 5601 17 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 5948 18 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 3774 15 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 3039 17 0 18
v_cancel_supplier_order 808 808 0 0.00 0 3051 10 0 0
v_cancel_write_off 2094 2094 0 0.00 0 3768 18 0 0
v_max_id_clo_ord 5457 5457 0 0.00 0 249 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 1570 11 0 0
v_max_id_ord_sup 5520 5520 0 0.00 0 6956 5 0 0
v_max_non_paid_invoice 1747 1747 0 0.00 0 2357 4 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 4291 5 0 0
v_min_id_clo_ord 5457 5457 0 0.00 0 2171 5 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 1616 4 0 0
v_min_id_ord_sup 5520 5520 0 0.00 0 11225 10 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 6601 12 0 0
v_min_non_paid_realizn 7129 7129 0 0.00 0 15371 22 0 0
v_our_firm 10250 10250 0 0.00 0 13 1 0 0
v_reserve_write_off 20254 20254 0 0.00 0 6654 6 0 0
x_lock_depdocs_on_canc_invoice 11475 11475 0 0.00 0 18783 124 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 28262 549 0 0
x_make_qstorno_1000_1200 5458 5458 0 0.00 1 31609 1218 0 0
x_make_qstorno_1200_2000 5522 5522 0 0.00 2 105283 3113 0 0
x_make_qstorno_2100_3300 450781 450781 0 0.00 0 68587 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-06-2022 22:40:19 to 07-06-2022 22:40:23.

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:23 7 8192000000 8805775808 8819150848 10819440 0.131 3 3 4 0 11003376 76800 107136
20:09:24 28245 8192000000 9914607120 10225315840 994040848 10.821 100 84 168 0 1021509280 5950656 21517776
20:11:00 17420 8192000000 9960922016 10281988096 1034352736 11.210 100 88 176 1 1066025888 9017200 22655952
20:12:31 21113 8192000000 10001220992 10336894976 1072090048 11.572 100 85 170 0 1108080576 15215888 20774640
20:13:58 7883 8192000000 10038910464 10387402752 1123171888 12.057 100 76 152 0 1151944800 11266368 17506544
20:15:08 11433 8192000000 10093234128 10452271104 1178628016 12.577 100 83 166 1 1210970896 12959728 19383152
20:16:23 4145 8192000000 10129746416 10500198400 1217849232 12.942 100 79 155 2 1247934800 12428320 17657248
20:17:31 2709 8192000000 10168831136 10547044352 1254533184 13.280 100 81 162 0 1285204544 12619488 18051872
20:18:36 2057 8192000000 10205267232 10585567232 1287783168 13.584 100 88 175 2 1320555264 13674320 19097776
20:19:41 21263 8192000000 10201845024 10599772160 1301081264 13.705 100 64 129 0 1327345968 11826112 14438592
20:21:05 4453 8192000000 10197352752 10606063616 1304451856 13.736 100 40 80 0 1326364864 12485504 9427504
20:22:16 14030 8192000000 10218966624 10627661824 1324368064 13.916 100 29 58 0 1337167264 6350896 6448304
20:23:33 13732 8192000000 10226257040 10643320832 1331534496 13.981 100 43 85 2 1349242256 8049968 9657792
20:24:48 4365 8192000000 10234650864 10655039488 1339071984 14.049 100 38 76 1 1359080160 10984832 9023344
20:25:56 5053 8192000000 10239678032 10655944704 1344025392 14.094 100 27 54 1 1357149696 7222512 5901792
20:27:05 25738 8192000000 10210837696 10630750208 1300466848 13.699 97 18 36 1 1309748656 5235376 4046432
20:29:07 6278 8192000000 10202522096 10612756480 1300358336 13.699 98 31 60 4 1312136496 5076864 6701296
20:30:15 5763 8192000000 10151645024 10549534720 1226150816 13.019 96 30 60 0 1239521968 6708016 6663136
20:31:21 7691 8192000000 10159130448 10556309504 1246370320 13.205 99 27 54 0 1258319152 5147072 6801760
20:32:31 27060 8192000000 10160690080 10536554496 1242294208 13.167 100 72 152 1 1265843072 5039280 18509584
20:33:59 6727 8192000000 10187969808 10584227840 1280818400 13.520 100 37 72 1 1295608144 5410080 9379664
20:35:05 6284 8192000000 10181050000 10590056448 1289007072 13.595 100 25 50 1 1300473168 5036160 6429936
20:36:12 8039 8192000000 10189193488 10603102208 1300885872 13.703 100 22 44 0 1312017152 5812304 5318976
20:37:22 50704 8192000000 10208025968 10624106496 1312650896 13.810 100 23 46 1 1322971584 5149248 5171440
20:39:22 2008 8192000000 10250881776 10657927168 1342294480 14.078 100 66 132 2 1367821648 10127472 15399696
20:40:27 28391 8192000000 10262079056 10681794560 1358591808 14.225 100 56 112 1 1382629456 11817440 12220208
20:42:39 22291 8192000000 10283888480 10704945152 1375827728 14.379 100 46 92 1 1397583856 10827664 10928464
20:44:06 17629 8192000000 10288523264 10717048832 1382604336 14.440 100 40 82 1 1403054352 10876944 9573072
20:45:29 19809 8192000000 10297101648 10727018496 1380000384 14.417 99 44 88 0 1404124704 12821968 11302352
20:46:53 13031 8192000000 10300652592 10724917248 1379201168 14.409 99 48 96 0 1404073856 13475296 11397392
20:48:11 9886 8192000000 10328283888 10757369856 1407430528 14.661 100 47 94 1 1434489904 16195472 10863904
20:49:24 3821 8192000000 10324812480 10739855360 1388924224 14.496 99 72 143 1 1427030576 20499968 17606384
20:50:33 4615 8192000000 10346118592 10769559552 1416318352 14.740 100 66 130 4 1457077712 24624816 16134544
20:51:42 2367 8192000000 10354879184 10773925888 1412643056 14.707 100 78 153 1 1458725824 27000592 19082176
20:52:49 4218 8192000000 10350039760 10769981440 1408075360 14.667 100 79 153 1 1455966144 28424928 19465856
20:54:00 6950 8192000000 10347482928 10766671872 1400736192 14.602 99 66 132 0 1442678464 25228512 16713760
20:55:11 1211 8192000000 10352144048 10763075584 1403832544 14.629 100 82 163 0 1456336080 32644336 19859200
20:56:19 1471 8192000000 10360331456 10772959232 1406192160 14.650 100 76 148 0 1466295696 41251200 18852336
20:57:27 4031 8192000000 10372014560 10778697728 1398938576 14.586 100 86 172 0 1472968560 52327392 21702592
20:58:35 14563 8192000000 10355250096 10766872576 1387000528 14.479 100 68 134 2 1458487024 53358528 18127968
20:59:58 803 8192000000 10360012496 10764668928 1381509136 14.430 99 88 176 1 1458709232 54262544 22937552
21:01:05 11070 8192000000 10345705680 10770776064 1382047312 14.435 99 39 78 1 1442663776 49851184 10765280
21:02:22 12646 8192000000 10349516928 10777878528 1394886288 14.549 100 44 87 2 1458238240 51622176 11729776
21:03:37 18730 8192000000 10324554640 10762043392 1368761504 14.316 99 20 40 0 1419615872 45143024 5711344
21:05:01 19864 8192000000 10326760736 10761048064 1367461904 14.304 100 29 58 1 1431772480 55557616 8752960
21:06:23 29799 8192000000 10321446864 10771050496 1349140928 14.140 99 28 56 0 1425832096 68208976 8482192
21:07:57 59715 8192000000 10255075024 10681757696 1316690784 13.847 98 38 74 2 1337430224 11788784 8950656
21:09:57 8215 8192000000 10262669088 10685186048 1335441616 14.016 99 52 103 0 1354781264 8051216 11288432
21:11:06 26188 8192000000 10247439840 10692108288 1343559088 14.089 100 19 38 0 1356803728 9166368 4078272
21:12:33 77812 8192000000 10266575744 10707791872 1352344496 14.169 99 13 26 0 1363119680 7940608 2834576
21:15:25 98942 8192000000 10270796928 10728607744 1351646800 14.162 98 13 25 1 1363185168 8739872 2798496
21:18:06 48976 8192000000 10274457872 10722066432 1358822240 14.227 99 29 56 1 1375976560 10773952 6380368
21:20:34 102511 8192000000 10279923360 10740826112 1380707584 14.423 100 20 40 0 1396791728 10834336 5249808
21:23:24 80905 8192000000 10308145120 10755002368 1389863824 14.505 100 34 69 0 1413017408 15425472 7728112
21:26:30 13044 8192000000 10317216656 10765221888 1406918288 14.657 100 54 108 3 1427223584 7250992 13054304
21:27:45 51994 8192000000 10306261168 10762702848 1389377744 14.500 98 25 50 0 1408513216 12906144 6229328
21:29:37 56669 8192000000 10289846240 10755448832 1391112208 14.516 100 9 18 1 1407485568 14062304 2311056
21:32:46 63659 8192000000 10284688768 10751365120 1366791984 14.298 98 6 11 0 1381125408 12745008 1588416
21:34:51 49000 8192000000 10295883440 10753249280 1382955872 14.443 99 18 36 1 1399947344 12898160 4093312
21:36:40 18929 8192000000 10301945696 10743148544 1361387360 14.250 98 44 88 1 1388461024 16735632 10338032
21:38:18 102971 8192000000 10285777136 10761109504 1345414480 14.106 97 11 22 2 1370239872 22155904 2669488
21:41:01 48918 8192000000 10269967328 10715557888 1323169328 13.905 96 18 36 0 1337407424 10194400 4043696
21:42:57 68678 8192000000 10293080512 10731327488 1349869536 14.146 98 23 46 0 1363747376 9330560 4547280
21:45:09 52019 8192000000 10285392736 10718593024 1349632432 14.144 99 33 66 0 1368175552 10589840 7953280
21:47:07 20231 8192000000 10269094496 10700988416 1326231952 13.933 98 48 96 2 1351148144 14144624 10771568
21:48:27 51292 8192000000 10260302960 10691547136 1287199040 13.579 95 26 52 0 1304633552 11783808 5650704
21:50:25 116971 8192000000 10249943680 10694361088 1297241152 13.670 96 15 30 1 1320824688 20249472 3334064
21:53:23 23559 8192000000 10254808704 10703589376 1354149648 14.185 100 17 34 0 1366663312 8405728 4107936
21:54:51 83213 8192000000 10258128976 10710822912 1338600688 14.045 98 10 20 0 1350866688 9861264 2404736
21:57:14 95232 8192000000 10239038368 10695778304 1331447216 13.980 98 14 28 0 1345815584 10719936 3648432
21:59:57 80052 8192000000 10260101504 10714841088 1319226480 13.870 97 14 28 0 1335169664 12730384 3212800
22:02:17 91833 8192000000 10270715744 10712555520 1317372544 13.853 96 24 48 1 1335197616 12546224 5278848
22:04:49 18808 8192000000 10288008672 10732371968 1373771552 14.361 100 41 82 1 1390488096 6965792 9750752
22:06:09 38584 8192000000 10286400128 10733039616 1377373952 14.393 100 32 64 1 1400323968 15824128 7125888
22:07:51 32848 8192000000 10293963312 10736160768 1377589152 14.395 100 20 40 2 1390673248 8937456 4146640
22:09:25 62991 8192000000 10304261904 10759884800 1358873872 14.227 98 23 46 0 1379014960 15217072 4924016
22:11:32 67069 8192000000 10295421088 10742460416 1354094000 14.184 97 12 24 0 1365696336 9157712 2444624
22:13:42 186170 8192000000 10295833360 10747002880 1380585824 14.422 99 25 48 2 1400967952 15529168 4852960
22:17:50 20967 8192000000 10282113600 10736828416 1377574096 14.395 100 22 43 0 1391819712 9192688 5052928
22:19:11 88108 8192000000 10289639984 10753007616 1385559984 14.466 100 7 14 0 1398107712 11116592 1431136
22:22:07 60230 8192000000 10280729728 10739556352 1348585024 14.135 97 12 24 2 1367455824 16111520 2759280
22:24:27 106512 8192000000 10294810368 10765049856 1379248544 14.410 99 14 28 0 1403436224 20464816 3722864
22:27:26 55385 8192000000 10305578448 10762182656 1389869488 14.505 100 11 23 2 1405014800 12690224 2455088
22:29:25 94183 8192000000 10293527776 10743517184 1298387136 13.681 93 16 31 0 1313560016 11929936 3242944
22:32:09 27190 8192000000 10311017904 10747518976 1380635008 14.422 100 47 94 1 1407163808 16011072 10517728
22:33:45 65185 8192000000 10281974592 10739363840 1361000960 14.246 99 18 36 1 1381677744 16012240 4664544
22:35:56 97185 8192000000 10279548384 10724499456 1293498576 13.636 94 16 32 1 1306617024 8859968 4258480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-06-2022 22:40:25 to 07-06-2022 22:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 41 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-06-2022 22:40:41 to 07-06-2022 22:40:43.

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          236853
MON$OLDEST_ACTIVE               239039
MON$OLDEST_SNAPSHOT             239039
MON$NEXT_TRANSACTION            239039
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                       2031200
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:160: 
:161: mscs-test-161.budzdorov.ru    Tue Jun  7 22:38:57 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
--- end of diff output ---

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

07.06.22 22:40:44. 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-06-07 22:40:46 No severe PSQL-related problems occured
07.06.22 22:40:48. End of report.