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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16253588 kB
MemAvailable 30825296 kB
Buffers 0 kB
Cached 15421340 kB
SwapCached 4792 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-27 19:37:55
DTS_END                         2021-10-27 19:37:55.8440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48008
TRN_ID                          240280
ATT_ID                          684
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: 9083.08 18306 1089970
customer order: creation 173.83 352 20859
customer order: refuse 14.83 357 1779
order to supplier: creation 45.58 1247 5470
order to supplier: removal 3.45 110876 414
invoice (draft): creation 45.33 3482 5439
invoice (draft): removal 52.82 821 6338
invoice accept: apply 41.33 17754 4959
invoice accept: cancel 47.11 738 5653
customer reserve: creation 3774.30 177 452916
customer reserve: removal 4592.06 66 551047
realization accept: apply 83.10 182 9972
realization accept: cancel 80.98 123 9718
payment from customer: creation 58.86 99 7063
payment from customer: removal 5.34 55 641
payment to supplier: creation 48.79 127 5855
payment to supplier: removal 3.49 70 419
service: total inventory turnovers 4.47 1337 537
service: total monetary turnovers 6.41 49 769
service: aggregate perf. data 0.98 16104 118
service: refresh index statistics 0.03 212105 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2021 19:39:11 to 27-10-2021 19:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2523
TEST_TIME 2 2567
TEST_TIME 3 2691
TEST_TIME 4 3157
TEST_TIME 5 3452
TEST_TIME 6 4235
TEST_TIME 7 4171
TEST_TIME 8 4551
TEST_TIME 9 4418
TEST_TIME 10 4325
TEST_TIME 11 4355
TEST_TIME 12 4476
TEST_TIME 13 4618
TEST_TIME 14 4817
TEST_TIME 15 5501
TEST_TIME 16 6063
TEST_TIME 17 6016
TEST_TIME 18 6185
TEST_TIME 19 6489
TEST_TIME 20 6748
TEST_TIME 21 6898
TEST_TIME 22 7297
TEST_TIME 23 7453
TEST_TIME 24 8323
TEST_TIME 25 8856
TEST_TIME 26 9110
TEST_TIME 27 10062
TEST_TIME 28 10412
TEST_TIME 29 10254
TEST_TIME 30 10081
TEST_TIME 31 9965
TEST_TIME 32 9969
TEST_TIME 33 9918
TEST_TIME 34 9792
TEST_TIME 35 9613
TEST_TIME 36 9370
TEST_TIME 37 9516
TEST_TIME 38 9430
TEST_TIME 39 9326
TEST_TIME 40 9249
TEST_TIME 41 9147
TEST_TIME 42 9156
TEST_TIME 43 9159
TEST_TIME 44 9298
TEST_TIME 45 9295
TEST_TIME 46 9243
TEST_TIME 47 9134
TEST_TIME 48 9038
TEST_TIME 49 8989
TEST_TIME 50 8902
TEST_TIME 51 8968
TEST_TIME 52 8983
TEST_TIME 53 8978
TEST_TIME 54 9003
TEST_TIME 55 9079
TEST_TIME 56 9055
TEST_TIME 57 9047
TEST_TIME 58 9156
TEST_TIME 59 9109
TEST_TIME 60 9121
TEST_TIME 61 9141
TEST_TIME 62 9191
TEST_TIME 63 9210
TEST_TIME 64 9210
TEST_TIME 65 9137
TEST_TIME 66 9154
TEST_TIME 67 9085
TEST_TIME 68 9051
TEST_TIME 69 9117
TEST_TIME 70 9076
TEST_TIME 71 9035
TEST_TIME 72 9006
TEST_TIME 73 8949
TEST_TIME 74 8918
TEST_TIME 75 8900
TEST_TIME 76 8884
TEST_TIME 77 8914
TEST_TIME 78 8987
TEST_TIME 79 9047
TEST_TIME 80 9038
TEST_TIME 81 9018
TEST_TIME 82 9061
TEST_TIME 83 9059
TEST_TIME 84 9148
TEST_TIME 85 9177
TEST_TIME 86 9181
TEST_TIME 87 9151
TEST_TIME 88 9088
TEST_TIME 89 9071
TEST_TIME 90 9104
TEST_TIME 91 9052
TEST_TIME 92 9011
TEST_TIME 93 9030
TEST_TIME 94 9014
TEST_TIME 95 9073
TEST_TIME 96 9060
TEST_TIME 97 9055
TEST_TIME 98 9119
TEST_TIME 99 9101
TEST_TIME 100 9175
TEST_TIME 101 9138
TEST_TIME 102 9105
TEST_TIME 103 9145
TEST_TIME 104 9183
TEST_TIME 105 9227
TEST_TIME 106 9277
TEST_TIME 107 9285
TEST_TIME 108 9208
TEST_TIME 109 9179
TEST_TIME 110 9155
TEST_TIME 111 9151
TEST_TIME 112 9140
TEST_TIME 113 9116
TEST_TIME 114 9092
TEST_TIME 115 9061
TEST_TIME 116 9045
TEST_TIME 117 9067
TEST_TIME 118 9114
TEST_TIME 119 9095
TEST_TIME 120 9083

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-10-2021 19:39:14 to 27-10-2021 19:39: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 1511227 1511227 0 0.00 0 39038 6 0 0
doc_list_biud 1511200 1511200 0 0.00 0 54709 30 0 0
sp_add_invoice_to_stock 5070 4959 111 2.18 14 172902 17754 0 111
sp_cancel_adding_invoice 5653 5653 0 0.00 5 73758 739 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 13685 357 0 0
sp_cancel_customer_reserve 551047 551047 0 0.00 1 49317 66 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 2440 56 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 3088 71 0 0
sp_cancel_supplier_invoice 6344 6338 6 0.09 2 26367 821 0 6
sp_cancel_supplier_order 414 414 0 0.00 5 1340438 110877 0 0
sp_cancel_write_off 9718 9718 0 0.00 1 52073 123 0 0
sp_client_order 20859 20859 0 0.00 3 24474 353 0 0
sp_customer_reserve 452916 452916 0 0.00 0 76315 177 0 0
sp_fill_shopping_cart 31766 31766 0 0.00 1 20796 41 0 0
sp_fill_shopping_cart_clo_res 452916 452916 0 0.00 0 16051 14 0 0
sp_get_clo_for_invoice 4959 4959 0 0.00 0 32343 340 0 0
sp_kill_cost_storno 16431 16431 0 0.00 0 9283 58 0 0
sp_kill_qstorno_ret_qs2qd 1118564 1118564 0 0.00 0 52019 36 0 0
sp_kill_qty_storno 589862 589862 0 0.00 0 54709 76 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 7279 7 0 0
sp_make_cost_storno 27848 27848 0 0.00 0 38932 58 0 0
sp_multiply_rows_for_pdistr 27822 27822 0 0.00 0 6410 26 0 0
sp_multiply_rows_for_qdistr 20859 20859 0 0.00 0 12128 162 0 0
sp_pay_from_customer 7063 7063 0 0.00 4 20822 100 0 0
sp_pay_to_supplier 5855 5855 0 0.00 5 39042 127 0 0
sp_payment_common 12909 12909 0 0.00 1 9795 23 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 9380 319 0 0
sp_qd_handle_on_invoice_upd_sts 10601 10601 0 0.00 0 54708 329 0 0
sp_qd_handle_on_reserve_upd_sts 9965 9965 0 0.00 0 19796 53 0 0
sp_reserve_write_off 9972 9972 0 0.00 6 22891 182 0 0
sp_supplier_invoice 5439 5439 0 0.00 6 104156 3482 0 0
sp_supplier_order 5470 5470 0 0.00 5 34273 1248 0 0
srv_aggregate_perf_data 118 118 0 0.00 2575 191179 16105 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1082 159860 46631 0 0
srv_find_qd_qs_mism 1043547 1043547 0 0.00 0 29381 5 0 0
srv_make_invnt_saldo 537 537 0 0.00 53 188765 1338 0 0
srv_make_money_saldo 769 769 0 0.00 1 18102 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 58885 332096 212105 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4959 4959 0 0.00 0 15667 172 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 0 2909 71 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 4144 15 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 3777 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 805 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 3207 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 631 10 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 31415 1744 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 2041 8 0 0
t$perf-norm:sp_client_order 20859 20859 0 0.00 0 11544 22 0 0
t$perf-norm:sp_customer_reserve 974 974 0 0.00 0 4451 12 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 6283 9 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 11637 13 0 0
t$perf-norm:sp_reserve_write_off 9972 9972 0 0.00 0 6239 14 0 0
t$perf-norm:sp_supplier_invoice 5439 5439 0 0.00 0 9082 21 0 0
t$perf-norm:sp_supplier_order 5470 5470 0 0.00 0 5194 17 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 176 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 3149 27 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 2640 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10118 9896 222 2.19 0 13552 21 0 222
v_all_customers 38124 38124 0 0.00 0 18 1 0 0
v_all_suppliers 30124 30124 0 0.00 0 17 1 0 0
v_all_wares 41718 41718 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 8057 17 0 0
v_cancel_client_order 3558 3558 0 0.00 0 13554 8 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 1948 5 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 149 3 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 974 6 0 0
v_cancel_supplier_invoice 874 862 12 1.37 0 349 13 0 12
v_cancel_supplier_order 828 828 0 0.00 0 6467 12 0 0
v_cancel_write_off 2052 2052 0 0.00 0 6076 16 0 0
v_max_id_clo_ord 5469 5469 0 0.00 0 258 1 0 0
v_max_id_clo_res 974 974 0 0.00 0 2905 14 0 0
v_max_id_ord_sup 5436 5436 0 0.00 0 2912 3 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 290 2 0 0
v_max_non_paid_realizn 3750 3750 0 0.00 0 2568 5 0 0
v_min_id_clo_ord 5469 5469 0 0.00 0 2934 7 0 0
v_min_id_clo_res 974 974 0 0.00 0 4710 15 0 0
v_min_id_ord_sup 5436 5436 0 0.00 0 6656 11 0 0
v_min_non_paid_invoice 5850 5850 0 0.00 0 9790 16 0 0
v_min_non_paid_realizn 7058 7058 0 0.00 0 4528 14 0 0
v_our_firm 10212 10212 0 0.00 0 18 1 0 0
v_reserve_write_off 19930 19930 0 0.00 0 22761 11 0 0
x_lock_depdocs_on_canc_invoice 11560 11560 0 0.00 0 13992 114 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 26151 420 0 0
x_make_qstorno_1000_1200 5470 5470 0 0.00 2 34264 1170 0 0
x_make_qstorno_1200_2000 5439 5439 0 0.00 2 97862 3322 0 0
x_make_qstorno_2100_3300 452916 452916 0 0.00 0 76314 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 19:39:19 to 27-10-2021 19:39: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
17:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 28840 8192000000 9905389584 10215112704 977271920 10.658 100 83 166 1 1005088656 5711120 22105616
17:10:32 20881 8192000000 9938892656 10259390464 1021098816 11.083 100 90 180 0 1052769712 8336752 23334144
17:12:00 9248 8192000000 9976071632 10307338240 1063967120 11.494 100 85 170 0 1094820288 9912336 20940832
17:13:13 13786 8192000000 10032760192 10380468224 1118862432 12.016 100 79 158 1 1148077344 10197616 19017296
17:14:29 14432 8192000000 10079876128 10441093120 1171865472 12.514 100 75 149 1 1200436256 11899632 16671152
17:15:48 6484 8192000000 10127042992 10495840256 1211592192 12.884 100 79 159 1 1242030432 12834144 17604096
17:16:57 2436 8192000000 10156735344 10532126720 1241951072 13.164 100 79 158 2 1272295568 12940800 17403696
17:18:01 2943 8192000000 10192446240 10574352384 1276686512 13.483 100 91 181 1 1311175472 14141024 20347936
17:19:05 1797 8192000000 10210765472 10596384768 1298053040 13.678 100 95 191 0 1332226576 12800480 21373056
17:20:08 1983 8192000000 10212419728 10603159552 1300657792 13.701 100 76 152 3 1328445952 10708880 17079280
17:21:11 4186 8192000000 10198162656 10608963584 1306832960 13.757 100 35 69 0 1322331584 8055008 7443616
17:22:16 15433 8192000000 10193059280 10612973568 1311656528 13.801 100 13 26 1 1321157840 6581280 2920032
17:23:31 76643 8192000000 10211433824 10636537856 1325865872 13.930 100 14 27 1 1333967488 4759152 3342464
17:25:50 4962 8192000000 10230403936 10646368256 1332466432 13.989 100 43 86 1 1349090528 6904096 9720000
17:26:58 9921 8192000000 10216544016 10630402048 1290421984 13.608 97 34 68 1 1309114576 10951536 7741056
17:28:08 6440 8192000000 10191631920 10607480832 1297283360 13.671 99 28 54 2 1310372128 5569296 7519472
17:29:15 20760 8192000000 10152692848 10564415488 1174059968 12.535 90 16 32 0 1182470272 4916176 3494128
17:30:36 4866 8192000000 10161655360 10565582848 1259868432 13.329 99 34 66 1 1273880128 5458064 8553632
17:31:43 8749 8192000000 10140265136 10536091648 1200774848 12.784 95 24 43 0 1210451184 4443856 5232480
17:32:54 56187 8192000000 10159312368 10551123968 1238779344 13.135 98 32 64 2 1252625600 5795920 8050336
17:34:57 5524 8192000000 10175989488 10575884288 1277445040 13.490 100 37 74 1 1293345008 7860912 8039056
17:36:04 8023 8192000000 10173165888 10588749824 1289166208 13.597 100 24 48 0 1300323264 5414128 5742928
17:37:12 10487 8192000000 10201753984 10615627776 1311039728 13.796 100 17 33 1 1320178256 5369744 3768784
17:38:30 21521 8192000000 10240264928 10645143552 1325025728 13.922 100 59 120 2 1346713776 8162864 13525184
17:39:54 114083 8192000000 10253529872 10676195328 1344746160 14.100 100 42 82 3 1365106176 10540224 9819792
17:42:52 22402 8192000000 10267016704 10684145664 1345097376 14.103 99 52 103 2 1376050624 19329808 11623440
17:44:24 10133 8192000000 10289101728 10709688320 1373917824 14.362 100 51 100 1 1406523776 19781424 12824528
17:45:39 9495 8192000000 10317169840 10738827264 1391169808 14.516 100 51 101 2 1424257488 20895104 12192576
17:46:53 7936 8192000000 10336854976 10757156864 1404304544 14.633 100 64 128 1 1452082592 31845616 15932432
17:48:13 6871 8192000000 10333992912 10758815744 1406064848 14.649 100 45 89 0 1449298672 31563328 11670496
17:49:24 3889 8192000000 10342735680 10764742656 1393337152 14.536 99 65 125 1 1443353408 33774864 16241392
17:50:32 2835 8192000000 10360060112 10771144704 1404635264 14.636 100 80 161 2 1464391040 40264000 19491776
17:51:39 4046 8192000000 10357413440 10770968576 1401058464 14.604 100 70 139 0 1464782864 45585200 18139200
17:52:48 8871 8192000000 10360886336 10775547904 1401513360 14.608 100 63 126 0 1460783536 43175008 16095168
17:54:01 16388 8192000000 10344218000 10763673600 1394433680 14.545 100 59 117 2 1446650544 37479856 14737008
17:55:21 1077 8192000000 10371210736 10778030080 1400064352 14.596 100 92 186 4 1466701968 43978800 22658816
17:56:24 6423 8192000000 10345397168 10760314880 1382907296 14.443 100 63 126 1 1455029232 56158592 15963344
17:57:41 5406 8192000000 10358977680 10769317888 1393522144 14.537 100 64 127 2 1460375984 50323872 16529968
17:58:54 6651 8192000000 10353500320 10777337856 1389133040 14.498 100 55 110 0 1462992736 58197648 15662048
18:00:05 13812 8192000000 10362220800 10783289344 1392312240 14.526 100 48 94 2 1464525392 58867712 13345440
18:01:26 9883 8192000000 10339459984 10775011328 1367000176 14.300 99 39 77 0 1441756976 63842944 10913856
18:02:41 25742 8192000000 10300856880 10737565696 1350103424 14.148 98 31 59 1 1405696144 47100048 8492672
18:04:09 50674 8192000000 10255083376 10707410944 1311813504 13.803 98 22 42 0 1364254176 46705248 5735424
18:06:04 16941 8192000000 10252097408 10696192000 1348610672 14.135 100 16 31 1 1360438544 8150928 3676944
18:07:21 109069 8192000000 10258080944 10704932864 1292807360 13.630 94 18 36 1 1306412512 9658320 3946832
18:10:11 29841 8192000000 10264519648 10703200256 1338756320 14.046 98 29 57 1 1353171408 8382448 6032640
18:11:51 44351 8192000000 10278766256 10719137792 1366704640 14.298 100 16 30 1 1377245440 7090256 3450544
18:13:37 159860 8192000000 10288259216 10745483264 1354948736 14.192 98 27 54 0 1376776896 15432592 6395568
18:17:43 22296 8192000000 10301554640 10747518976 1381373824 14.429 99 34 68 2 1401652368 11740896 8537648
18:19:07 47742 8192000000 10294756080 10754883584 1373801504 14.361 98 11 22 0 1390921584 14284112 2835968
18:21:18 26387 8192000000 10313117600 10754670592 1384029792 14.453 99 48 96 2 1405003712 10497088 10476832
18:22:48 62708 8192000000 10295126688 10764857344 1397341360 14.571 100 15 30 0 1413951152 13220096 3389696
18:25:08 51411 8192000000 10297095920 10764107776 1403332240 14.625 100 23 46 0 1418661728 10078656 5250832
18:27:09 61889 8192000000 10301631632 10764759040 1384647072 14.458 98 18 36 1 1399870160 10922160 4300928
18:29:13 52344 8192000000 10323637984 10776018944 1409992192 14.684 100 29 58 0 1424218864 8448640 5778032
18:31:14 65323 8192000000 10321550928 10794807296 1420705120 14.779 100 21 42 0 1436053472 10700896 4647456
18:33:26 54518 8192000000 10334509552 10791182336 1397005984 14.568 98 35 69 0 1415865552 11397088 7462480
18:35:20 76024 8192000000 10307905696 10753347584 1319233072 13.870 93 15 29 1 1333583744 11018640 3332032
18:37:39 39257 8192000000 10306423152 10747351040 1350175216 14.149 97 37 74 2 1373622480 15477968 7969296
18:39:18 68155 8192000000 10303558656 10759880704 1383804448 14.451 99 11 22 0 1397500528 11315520 2380560
18:41:29 64000 8192000000 10271574336 10724421632 1363468320 14.268 98 33 66 1 1383281024 12751904 7060800
18:43:47 71107 8192000000 10280952384 10745872384 1358159536 14.221 98 18 36 1 1382539392 19844880 4534976
18:46:03 49511 8192000000 10268224592 10744193024 1370156768 14.328 100 5 10 0 1386116896 14788112 1172016
18:47:53 75836 8192000000 10246289248 10701619200 1317817328 13.857 96 15 30 1 1329342832 8553440 2972064
18:50:10 39874 8192000000 10221426768 10670948352 1295455600 13.654 96 14 28 1 1304557168 5966416 3135152
18:51:51 34333 8192000000 10262900128 10708025344 1348237200 14.132 99 14 28 1 1357918480 6121296 3559984
18:53:28 56027 8192000000 10263896480 10718097408 1315668736 13.837 96 23 46 0 1337571472 16221360 5681376
18:55:28 56192 8192000000 10252428176 10701742080 1334024256 14.003 98 24 48 0 1359450256 19375984 6050016
18:57:29 40339 8192000000 10276183776 10702168064 1307137504 13.760 96 44 88 1 1326345872 9422368 9786000
18:59:11 31111 8192000000 10261682272 10704748544 1332665072 13.991 98 41 82 3 1355590416 13609072 9316272
19:00:48 33902 8192000000 10270522240 10708033536 1338488736 14.044 99 29 58 1 1362388480 17030768 6868976
19:02:24 98034 8192000000 10265797296 10706771968 1291937888 13.622 95 9 16 0 1310643712 16756240 1949584
19:05:03 109517 8192000000 10273804608 10715725824 1361142000 14.248 100 31 62 0 1381454896 13372704 6940192
19:07:53 39977 8192000000 10271458448 10732032000 1374695904 14.369 100 8 16 0 1389076688 12755056 1625728
19:09:34 45546 8192000000 10286646864 10749571072 1389730368 14.503 100 9 18 0 1400727760 9064480 1932912
19:11:21 76273 8192000000 10302743840 10765668352 1384510016 14.457 99 17 35 0 1399578480 11315456 3753008
19:14:13 89322 8192000000 10301172016 10768412672 1402757760 14.620 100 16 32 0 1420207792 13734576 3715456
19:16:46 82119 8192000000 10296140256 10760503296 1370737792 14.334 98 11 22 0 1390162592 16877632 2547168
19:19:23 37023 8192000000 10316812016 10763821056 1386334912 14.473 99 32 64 0 1407155136 13734352 7085872
19:21:02 69835 8192000000 10288331984 10761928704 1354525472 14.188 97 20 40 0 1370554400 11688576 4340352
19:23:18 66511 8192000000 10311378256 10750828544 1355232400 14.195 97 14 28 0 1367878384 9621872 3024112
19:25:26 100217 8192000000 10284801440 10742067200 1344230400 14.096 97 31 61 1 1363342736 12369152 6743184
19:28:12 22206 8192000000 10280871776 10728075264 1343717664 14.091 97 18 36 0 1359549072 11741664 4089744
19:29:34 67901 8192000000 10282161056 10747834368 1382236080 14.437 100 9 18 1 1398869088 14401104 2231904
19:31:43 73416 8192000000 10277971680 10730536960 1348377088 14.133 98 18 36 1 1365442896 13031504 4034304
19:35:03 98061 8192000000 10275813680 10731962368 1314448848 13.826 95 18 37 0 1334952288 16135408 4368032
19:37:41 19920 8192000000 8931748960 9110740992 104403328 1.258 8 3 3 1 106579760 1395632 780800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-10-2021 19:39:25 to 27-10-2021 19:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2021 19:39:40 to 27-10-2021 19:39: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          240571
MON$OLDEST_ACTIVE               240572
MON$OLDEST_SNAPSHOT             240572
MON$NEXT_TRANSACTION            240572
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                       2008960
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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.10.21 19:39: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
2021-10-27 19:39:46 No severe PSQL-related problems occured
27.10.21 19:39:47. End of report.