Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15918260 kB
MemAvailable 30668576 kB
Buffers 0 kB
Cached 15803828 kB
SwapCached 3704 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.8
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 2021-10-17 22:37:53
DTS_END                         2021-10-17 22:37:53.5450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33880
TRN_ID                          235246
ATT_ID                          630
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: 9095.39 18656 1091447
customer order: creation 168.93 407 20271
customer order: refuse 14.28 359 1713
order to supplier: creation 44.33 1419 5319
order to supplier: removal 3.34 109913 401
invoice (draft): creation 45.08 3643 5409
invoice (draft): removal 51.33 821 6159
invoice accept: apply 40.16 19106 4819
invoice accept: cancel 46.41 905 5569
customer reserve: creation 3802.23 183 456268
customer reserve: removal 4593.09 63 551171
realization accept: apply 79.84 217 9581
realization accept: cancel 78.64 109 9437
payment from customer: creation 58.43 104 7012
payment from customer: removal 5.80 79 696
payment to supplier: creation 47.90 134 5748
payment to supplier: removal 3.43 87 412
service: total inventory turnovers 4.51 1203 541
service: total monetary turnovers 6.82 29 818
service: aggregate perf. data 0.83 16636 99
service: refresh index statistics 0.03 217706 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-10-2021 22:39:56 to 17-10-2021 22:39:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3944
TEST_TIME 2 3949
TEST_TIME 3 5603
TEST_TIME 4 5648
TEST_TIME 5 5401
TEST_TIME 6 5358
TEST_TIME 7 5814
TEST_TIME 8 5762
TEST_TIME 9 5605
TEST_TIME 10 5920
TEST_TIME 11 6196
TEST_TIME 12 6313
TEST_TIME 13 6932
TEST_TIME 14 6899
TEST_TIME 15 7049
TEST_TIME 16 7285
TEST_TIME 17 7877
TEST_TIME 18 8260
TEST_TIME 19 8546
TEST_TIME 20 8729
TEST_TIME 21 8800
TEST_TIME 22 8858
TEST_TIME 23 9104
TEST_TIME 24 9029
TEST_TIME 25 9622
TEST_TIME 26 9490
TEST_TIME 27 9971
TEST_TIME 28 10268
TEST_TIME 29 10134
TEST_TIME 30 10619
TEST_TIME 31 10967
TEST_TIME 32 10954
TEST_TIME 33 10882
TEST_TIME 34 10594
TEST_TIME 35 10368
TEST_TIME 36 10365
TEST_TIME 37 10317
TEST_TIME 38 10100
TEST_TIME 39 9953
TEST_TIME 40 9879
TEST_TIME 41 9747
TEST_TIME 42 9713
TEST_TIME 43 9678
TEST_TIME 44 9565
TEST_TIME 45 9446
TEST_TIME 46 9417
TEST_TIME 47 9275
TEST_TIME 48 9298
TEST_TIME 49 9112
TEST_TIME 50 9069
TEST_TIME 51 9284
TEST_TIME 52 9182
TEST_TIME 53 9253
TEST_TIME 54 9172
TEST_TIME 55 9185
TEST_TIME 56 9163
TEST_TIME 57 9101
TEST_TIME 58 9244
TEST_TIME 59 9286
TEST_TIME 60 9328
TEST_TIME 61 9315
TEST_TIME 62 9334
TEST_TIME 63 9366
TEST_TIME 64 9301
TEST_TIME 65 9399
TEST_TIME 66 9384
TEST_TIME 67 9291
TEST_TIME 68 9234
TEST_TIME 69 9314
TEST_TIME 70 9241
TEST_TIME 71 9184
TEST_TIME 72 9150
TEST_TIME 73 9133
TEST_TIME 74 9097
TEST_TIME 75 9107
TEST_TIME 76 9057
TEST_TIME 77 9005
TEST_TIME 78 9031
TEST_TIME 79 9082
TEST_TIME 80 9091
TEST_TIME 81 9076
TEST_TIME 82 9116
TEST_TIME 83 9181
TEST_TIME 84 9183
TEST_TIME 85 9256
TEST_TIME 86 9251
TEST_TIME 87 9349
TEST_TIME 88 9370
TEST_TIME 89 9369
TEST_TIME 90 9305
TEST_TIME 91 9245
TEST_TIME 92 9245
TEST_TIME 93 9203
TEST_TIME 94 9158
TEST_TIME 95 9101
TEST_TIME 96 9056
TEST_TIME 97 9064
TEST_TIME 98 9094
TEST_TIME 99 9080
TEST_TIME 100 9126
TEST_TIME 101 9135
TEST_TIME 102 9100
TEST_TIME 103 9138
TEST_TIME 104 9217
TEST_TIME 105 9198
TEST_TIME 106 9243
TEST_TIME 107 9221
TEST_TIME 108 9248
TEST_TIME 109 9232
TEST_TIME 110 9287
TEST_TIME 111 9258
TEST_TIME 112 9195
TEST_TIME 113 9200
TEST_TIME 114 9188
TEST_TIME 115 9170
TEST_TIME 116 9172
TEST_TIME 117 9156
TEST_TIME 118 9130
TEST_TIME 119 9091
TEST_TIME 120 9105

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-10-2021 22:40:00 to 17-10-2021 22:40:03.

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 1514148 1514148 0 0.00 0 24632 7 0 0
doc_list_biud 1514141 1514141 0 0.00 0 39193 29 0 0
sp_add_invoice_to_stock 4998 4819 179 3.58 42 180978 19106 0 179
sp_cancel_adding_invoice 5569 5569 0 0.00 6 86666 905 0 0
sp_cancel_client_order 1713 1713 0 0.00 5 8985 359 0 0
sp_cancel_customer_reserve 551171 551171 0 0.00 1 39196 63 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 7111 80 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 4409 88 0 0
sp_cancel_supplier_invoice 6173 6159 14 0.22 2 34211 822 0 14
sp_cancel_supplier_order 401 401 0 0.00 6 942931 109914 0 0
sp_cancel_write_off 9437 9437 0 0.00 1 14395 109 0 0
sp_client_order 20271 20271 0 0.00 3 21982 408 0 0
sp_customer_reserve 456268 456268 0 0.00 0 96872 184 0 0
sp_fill_shopping_cart 30988 30988 0 0.00 1 16773 42 0 0
sp_fill_shopping_cart_clo_res 456246 456246 0 0.00 0 14145 14 0 0
sp_get_clo_for_invoice 4796 4796 0 0.00 0 37709 329 0 0
sp_kill_cost_storno 16114 16114 0 0.00 0 7053 57 0 0
sp_kill_qstorno_ret_qs2qd 1118339 1118339 0 0.00 0 39191 34 0 0
sp_kill_qty_storno 588820 588820 0 0.00 0 39193 74 0 0
sp_lock_selected_doc 20044 20044 0 0.00 0 8372 6 0 0
sp_make_cost_storno 27137 27137 0 0.00 0 22061 63 0 0
sp_multiply_rows_for_pdistr 27136 27136 0 0.00 0 24614 31 0 0
sp_multiply_rows_for_qdistr 20271 20271 0 0.00 0 16406 183 0 0
sp_pay_from_customer 7012 7012 0 0.00 3 8282 105 0 0
sp_pay_to_supplier 5748 5748 0 0.00 5 24635 134 0 0
sp_payment_common 12760 12760 0 0.00 1 8276 24 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 8978 328 0 0
sp_qd_handle_on_invoice_upd_sts 10361 10361 0 0.00 0 28202 384 0 0
sp_qd_handle_on_reserve_upd_sts 9578 9578 0 0.00 0 16946 83 0 0
sp_reserve_write_off 9581 9581 0 0.00 7 16969 217 0 0
sp_supplier_invoice 5409 5409 0 0.00 6 79559 3644 0 0
sp_supplier_order 5319 5319 0 0.00 5 34293 1420 0 0
srv_aggregate_perf_data 99 99 0 0.00 2793 121759 16636 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1298 130805 46830 0 0
srv_find_qd_qs_mism 1044553 1044553 0 0.00 0 16224 6 0 0
srv_make_invnt_saldo 541 541 0 0.00 42 66060 1204 0 0
srv_make_money_saldo 818 818 0 0.00 1 5484 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 59813 381938 217706 0 0
t$perf-abend:sp_add_invoice_to_stock 179 179 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4819 4819 0 0.00 0 11864 189 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 5671 111 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 9659 21 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 4146 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 1801 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 3079 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 611 13 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 32749 1856 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 5870 18 0 0
t$perf-norm:sp_client_order 20271 20271 0 0.00 0 10236 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 2546 6 0 0
t$perf-norm:sp_pay_from_customer 7012 7012 0 0.00 0 13740 14 0 0
t$perf-norm:sp_pay_to_supplier 5748 5748 0 0.00 0 8533 12 0 0
t$perf-norm:sp_reserve_write_off 9581 9581 0 0.00 0 8084 12 0 0
t$perf-norm:sp_supplier_invoice 5409 5409 0 0.00 0 6044 21 0 0
t$perf-norm:sp_supplier_order 5319 5319 0 0.00 0 3181 13 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 273 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3575 94 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 1396 25 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 925 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9938 9580 358 3.60 0 7017 23 0 358
v_all_customers 36900 36900 0 0.00 0 14 1 0 0
v_all_suppliers 29948 29948 0 0.00 0 109 1 0 0
v_all_wares 40540 40540 0 0.00 0 53 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 5049 12 0 0
v_cancel_client_order 3426 3426 0 0.00 0 4751 6 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 6976 12 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2448 6 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 3131 12 0 0
v_cancel_supplier_invoice 842 814 28 3.32 0 1300 18 0 28
v_cancel_supplier_order 802 802 0 0.00 0 5572 12 0 0
v_cancel_write_off 1998 1998 0 0.00 0 7208 19 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 1951 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 3814 11 0 0
v_max_id_ord_sup 5402 5402 0 0.00 0 2362 3 0 0
v_max_non_paid_invoice 1491 1491 0 0.00 0 3620 5 0 0
v_max_non_paid_realizn 3705 3705 0 0.00 0 5756 6 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 15859 16 0 0
v_min_id_clo_res 990 990 0 0.00 0 4116 14 0 0
v_min_id_ord_sup 5402 5402 0 0.00 0 6812 6 0 0
v_min_non_paid_invoice 5748 5748 0 0.00 0 6772 17 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 8272 15 0 0
v_our_firm 10254 10254 0 0.00 0 14 1 0 0
v_reserve_write_off 19156 19156 0 0.00 0 9868 6 0 0
x_lock_depdocs_on_canc_invoice 11321 11321 0 0.00 0 32281 135 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 31494 502 0 0
x_make_qstorno_1000_1200 5319 5319 0 0.00 1 34234 1324 0 0
x_make_qstorno_1200_2000 5409 5409 0 0.00 3 79545 3504 0 0
x_make_qstorno_2100_3300 456268 456268 0 0.00 0 96869 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2021 22:40:04 to 17-10-2021 22:40:08.

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:07:57 12 8192000000 8804356464 8817639424 8601792 0.104 3 2 3 0 8745648 68912 74944
20:08:58 19363 8192000000 9892590448 10200739840 973052624 10.616 100 87 174 0 1001217632 5328720 22836288
20:10:25 13356 8192000000 9940967264 10260500480 1023660224 11.107 100 94 188 0 1055713936 7363456 24690256
20:11:43 26266 8192000000 9977471440 10312290304 1061512496 11.471 100 84 168 1 1091245488 8619248 21113744
20:13:11 17562 8192000000 10022059968 10376577024 1117854352 12.007 100 70 140 0 1142987760 8630336 16503072
20:14:32 9896 8192000000 10093278960 10455052288 1181934880 12.608 100 78 156 0 1210959520 11028032 17996608
20:15:44 5719 8192000000 10133333536 10501652480 1218144032 12.945 100 83 166 2 1250557856 13926640 18487184
20:16:51 4202 8192000000 10162832832 10538393600 1253745968 13.273 100 86 169 1 1285100336 13049648 18304720
20:17:56 2052 8192000000 10193041344 10570727424 1272448064 13.444 100 93 186 0 1308509024 15822048 20238912
20:19:00 1455 8192000000 10213155904 10600964096 1300764144 13.702 100 97 194 2 1335859904 12871808 22223952
20:20:03 12970 8192000000 10212061856 10608607232 1308140384 13.769 100 64 127 0 1333073072 11190512 13742176
20:21:17 4843 8192000000 10212886720 10627018752 1323997552 13.913 100 39 78 1 1340711904 8461392 8252960
20:22:23 5239 8192000000 10215811312 10634858496 1330133280 13.968 100 19 38 0 1340516672 6175408 4207984
20:23:30 13241 8192000000 10235129520 10652946432 1345415712 14.106 100 41 82 2 1364463920 9702480 9345728
20:24:50 11794 8192000000 10260254192 10662608896 1344922128 14.102 100 77 159 1 1374390688 10879856 18588704
20:26:02 6760 8192000000 10240504656 10664792064 1346815760 14.119 100 17 34 1 1356764128 6066288 3882080
20:27:09 47890 8192000000 10215300992 10633863168 1311407968 13.799 98 27 55 1 1323271520 5197728 6665824
20:29:00 6994 8192000000 10196327536 10609856512 1298467568 13.681 100 28 56 0 1315308880 10925728 5915584
20:30:09 7143 8192000000 10172973504 10579341312 1251523248 13.252 97 22 44 0 1261926800 5534880 4868672
20:31:17 65053 8192000000 10128612384 10523844608 1167715408 12.475 93 25 50 0 1178709792 4744400 6249984
20:33:22 9125 8192000000 10147070688 10544742400 1220137808 12.963 96 26 52 2 1231967328 6439072 5390448
20:34:34 7441 8192000000 10183883600 10587373568 1283649488 13.546 100 30 59 0 1296318896 5177232 7492176
20:35:42 68786 8192000000 10185571424 10601635840 1298703920 13.683 100 41 84 1 1317708032 8968368 10035744
20:37:55 4657 8192000000 10233962976 10639503360 1329216960 13.960 100 56 111 1 1347748880 6633360 11898560
20:39:06 7083 8192000000 10255040464 10660642816 1342089600 14.076 100 65 129 2 1367956256 11965808 13900848
20:40:25 5133 8192000000 10289494336 10688536576 1358201872 14.221 100 81 160 1 1393251936 16023744 19026320
20:41:47 45671 8192000000 10282187600 10702897152 1367876800 14.308 100 48 95 1 1396848720 18089024 10882896
20:43:50 19481 8192000000 10288399008 10713616384 1369209328 14.320 99 46 92 3 1398234944 17923104 11102512
20:45:14 4083 8192000000 10316967248 10734919680 1393426704 14.536 100 53 105 3 1422220032 17213296 11580032
20:46:22 4505 8192000000 10338721776 10756759552 1409655584 14.681 100 67 134 3 1447371264 21822864 15892816
20:47:30 6313 8192000000 10341600224 10766106624 1413127648 14.712 100 55 110 4 1451020928 24597456 13295824
20:48:40 8798 8192000000 10321518960 10747904000 1379478400 14.412 98 59 117 1 1424617648 30927248 14212000
20:49:54 15467 8192000000 10354422080 10772422656 1405354928 14.643 100 64 128 0 1452882112 32039408 15487776
20:51:13 1924 8192000000 10346810512 10760261632 1398380352 14.581 100 76 151 0 1448227872 30483248 19364272
20:52:18 3827 8192000000 10344135264 10761347072 1394527280 14.546 100 69 138 1 1451773344 39185984 18060080
20:53:28 2366 8192000000 10364133152 10772971520 1396831216 14.567 100 83 167 3 1464971456 47250112 20890128
20:54:35 5875 8192000000 10324135056 10746609664 1362283920 14.258 98 52 103 1 1415027552 39140080 13603552
20:55:45 5604 8192000000 10323967584 10741948416 1374905872 14.371 100 67 135 1 1431127520 39114896 17106752
20:56:58 12721 8192000000 10307974000 10743877632 1373278016 14.356 100 32 64 1 1420211488 37906976 9026496
20:58:17 4414 8192000000 10320339216 10740715520 1354881744 14.191 98 51 99 4 1408461088 40641328 12938016
20:59:26 1297 8192000000 10327306768 10725621760 1349048368 14.139 98 92 185 0 1418315600 45800752 23466480
21:00:30 19355 8192000000 10312319968 10735906816 1345129856 14.104 98 40 80 0 1406224624 50442112 10652656
21:01:56 3292 8192000000 10351871888 10759462912 1375882000 14.380 100 74 147 1 1447106416 51979184 19245232
21:03:04 9840 8192000000 10335268400 10763669504 1384289072 14.455 100 31 62 0 1439455232 46028800 9137360
21:04:18 23766 8192000000 10335526432 10772779008 1384686112 14.458 100 36 72 1 1447329968 52638256 10005600
21:05:43 23049 8192000000 10294570112 10732658688 1352675616 14.172 99 17 34 1 1394183008 36506304 5001088
21:07:29 12935 8192000000 10294162880 10744696832 1358056416 14.220 100 22 44 0 1405113904 41223648 5833840
21:09:08 70710 8192000000 10279106944 10730668032 1372185552 14.347 100 19 38 0 1388299712 12074400 4039760
21:11:20 93701 8192000000 10278942608 10725294080 1358586336 14.225 98 37 73 0 1378934752 11536368 8812048
21:13:54 111448 8192000000 10295674624 10750173184 1374762880 14.370 99 16 32 1 1390055760 11258448 4034432
21:17:10 85192 8192000000 10309753408 10756132864 1395963840 14.559 100 33 64 2 1415135184 11453472 7717872
21:19:46 41825 8192000000 10292809936 10753777664 1394495472 14.546 100 8 16 0 1405787520 9270608 2021440
21:21:29 122402 8192000000 10303984912 10759561216 1355227392 14.194 96 16 32 1 1366200944 7443360 3530192
21:24:37 57875 8192000000 10321665312 10782052352 1408530432 14.671 100 14 28 0 1430570144 18364192 3675520
21:26:36 130805 8192000000 10302665984 10792210432 1403334480 14.625 99 29 58 1 1430888144 20092016 7461648
21:30:52 61075 8192000000 10308100768 10772602880 1397919552 14.576 99 7 14 0 1408848976 9535664 1393760
21:33:04 48466 8192000000 10301803968 10768236544 1379366896 14.411 98 18 36 4 1401152064 17771408 4013760
21:34:53 47691 8192000000 10309803120 10770280448 1391690608 14.521 99 21 42 0 1416048416 19213008 5144800
21:36:47 70530 8192000000 10289604608 10750484480 1370308416 14.330 98 16 31 1 1385151280 10969824 3873040
21:38:58 29131 8192000000 10327862160 10764660736 1381406160 14.429 98 51 102 2 1401165920 7968368 11791392
21:40:29 75342 8192000000 10303827488 10751320064 1325882784 13.930 94 13 26 1 1336912128 8589392 2439952
21:42:49 44494 8192000000 10293441456 10735853568 1324212128 13.915 95 15 30 1 1336551408 8697648 3641632
21:44:34 128455 8192000000 10259103888 10705481728 1249719152 13.236 90 19 38 1 1270282176 15975488 4587536
21:47:43 32454 8192000000 10263000736 10725498880 1354673552 14.190 100 10 20 2 1378772976 21941536 2157888
21:49:18 57433 8192000000 10265300352 10723614720 1362970560 14.264 99 11 22 1 1373712256 7898704 2842992
21:51:16 81518 8192000000 10259809520 10715893760 1356338896 14.204 99 12 24 2 1366125040 7224144 2562000
21:53:53 51984 8192000000 10274849680 10731806720 1347923456 14.129 98 16 32 2 1372431152 20876672 3631024
21:55:45 49871 8192000000 10261041456 10705960960 1323992928 13.913 97 13 25 1 1340768704 13603392 3172384
21:57:59 42175 8192000000 10234361968 10669178880 1283142496 13.542 95 23 46 0 1301171584 12453008 5576080
21:59:46 60643 8192000000 10243256304 10683191296 1320313696 13.880 98 14 28 0 1337566048 14299728 2952624
22:01:47 18609 8192000000 10273174752 10710171648 1347673200 14.127 99 44 88 0 1371201152 13837808 9690144
22:03:29 58339 8192000000 10277636160 10735951872 1333245664 13.996 98 38 76 0 1370602160 28949360 8407136
22:05:31 100925 8192000000 10293948336 10729443328 1370936944 14.335 100 15 30 0 1386695392 13094688 2663760
22:08:13 101710 8192000000 10284294112 10738810880 1370587872 14.332 99 38 74 0 1389411632 10694016 8129744
22:11:51 74466 8192000000 10287201168 10740363264 1387478752 14.483 100 12 25 0 1400946384 10331904 3135728
22:14:09 47420 8192000000 10298490864 10759540736 1391825136 14.522 100 19 38 0 1424061600 27311216 4925248
22:16:17 68128 8192000000 10287206992 10745659392 1349432256 14.142 97 19 38 1 1368506848 14383008 4691584
22:18:25 40746 8192000000 10296129408 10741805056 1365052544 14.283 98 16 32 0 1377869744 8554208 4262992
22:20:08 85768 8192000000 10305237968 10771263488 1397169328 14.570 100 20 40 0 1421282816 19316720 4796768
22:22:34 79247 8192000000 10289863456 10751135744 1362064160 14.256 98 15 29 1 1377643344 12481472 3097712
22:25:12 77773 8192000000 10290180080 10737856512 1338234352 14.041 96 13 26 0 1356296816 15476752 2585712
22:27:39 78550 8192000000 10279015616 10718068736 1307732304 13.765 95 20 39 0 1324875952 12346976 4796672
22:30:08 83152 8192000000 10292447648 10749030400 1384762992 14.459 100 25 50 0 1403791472 13446528 5581952
22:32:43 56635 8192000000 10268785456 10729123840 1343236544 14.087 97 17 34 0 1359651728 13193696 3221488
22:34:40 56604 8192000000 10257522880 10709295104 1331668912 13.982 97 6 12 0 1339615088 6774672 1171504
22:36:38 75432 8192000000 10207458656 10664140800 1305233536 13.743 95 22 44 1 1319166144 8670880 5261728

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2021 22:40:10 to 17-10-2021 22:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 179 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2021 22:40:26 to 17-10-2021 22:40:28.

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          234628
MON$OLDEST_ACTIVE               235366
MON$OLDEST_SNAPSHOT             235366
MON$NEXT_TRANSACTION            235366
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                       2029920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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'

17.10.21 22:40:29. 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
2021-10-17 22:40:31 No severe PSQL-related problems occured
17.10.21 22:40:32. End of report.