Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2021 01: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 16411280 kB
MemAvailable 31046400 kB
Buffers 0 kB
Cached 15294460 kB
SwapCached 2584 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-08-11 01:37:44
DTS_END                         2021-08-11 01:37:44.9570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42504
TRN_ID                          232076
ATT_ID                          641
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: 8985.65 22584 1078278
customer order: creation 161.70 406 19404
customer order: refuse 14.17 416 1700
order to supplier: creation 43.15 1281 5178
order to supplier: removal 3.26 109016 391
invoice (draft): creation 42.37 3686 5084
invoice (draft): removal 53.43 809 6412
invoice accept: apply 38.70 18819 4644
invoice accept: cancel 48.12 784 5774
customer reserve: creation 3579.12 185 429494
customer reserve: removal 4719.34 58 566321
realization accept: apply 78.70 207 9444
realization accept: cancel 82.80 113 9936
payment from customer: creation 55.48 138 6657
payment from customer: removal 4.95 70 594
payment to supplier: creation 45.62 201 5474
payment to supplier: removal 3.51 126 421
service: total inventory turnovers 4.66 1023 559
service: total monetary turnovers 5.91 38 709
service: aggregate perf. data 0.65 26086 78
service: refresh index statistics 0.03 288227 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 01:40:09 to 11-08-2021 01:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3243
TEST_TIME 2 2264
TEST_TIME 3 1992
TEST_TIME 4 2928
TEST_TIME 5 3942
TEST_TIME 6 3897
TEST_TIME 7 4274
TEST_TIME 8 4824
TEST_TIME 9 4869
TEST_TIME 10 5155
TEST_TIME 11 5258
TEST_TIME 12 5437
TEST_TIME 13 6228
TEST_TIME 14 6332
TEST_TIME 15 6505
TEST_TIME 16 6674
TEST_TIME 17 7295
TEST_TIME 18 7529
TEST_TIME 19 7711
TEST_TIME 20 8121
TEST_TIME 21 8590
TEST_TIME 22 9076
TEST_TIME 23 8900
TEST_TIME 24 9051
TEST_TIME 25 9093
TEST_TIME 26 9429
TEST_TIME 27 9976
TEST_TIME 28 10690
TEST_TIME 29 10435
TEST_TIME 30 10388
TEST_TIME 31 10305
TEST_TIME 32 10407
TEST_TIME 33 10281
TEST_TIME 34 10192
TEST_TIME 35 10019
TEST_TIME 36 9865
TEST_TIME 37 9843
TEST_TIME 38 9803
TEST_TIME 39 9620
TEST_TIME 40 9534
TEST_TIME 41 9438
TEST_TIME 42 9412
TEST_TIME 43 9557
TEST_TIME 44 9417
TEST_TIME 45 9322
TEST_TIME 46 9291
TEST_TIME 47 9303
TEST_TIME 48 9315
TEST_TIME 49 9215
TEST_TIME 50 9139
TEST_TIME 51 9048
TEST_TIME 52 9102
TEST_TIME 53 9038
TEST_TIME 54 9130
TEST_TIME 55 9097
TEST_TIME 56 9138
TEST_TIME 57 9187
TEST_TIME 58 9250
TEST_TIME 59 9282
TEST_TIME 60 9284
TEST_TIME 61 9330
TEST_TIME 62 9293
TEST_TIME 63 9326
TEST_TIME 64 9303
TEST_TIME 65 9358
TEST_TIME 66 9393
TEST_TIME 67 9380
TEST_TIME 68 9250
TEST_TIME 69 9253
TEST_TIME 70 9278
TEST_TIME 71 9211
TEST_TIME 72 9229
TEST_TIME 73 9140
TEST_TIME 74 9107
TEST_TIME 75 9063
TEST_TIME 76 8968
TEST_TIME 77 8974
TEST_TIME 78 8973
TEST_TIME 79 9036
TEST_TIME 80 9047
TEST_TIME 81 9011
TEST_TIME 82 9022
TEST_TIME 83 9150
TEST_TIME 84 9164
TEST_TIME 85 9146
TEST_TIME 86 9103
TEST_TIME 87 9176
TEST_TIME 88 9223
TEST_TIME 89 9219
TEST_TIME 90 9309
TEST_TIME 91 9341
TEST_TIME 92 9244
TEST_TIME 93 9251
TEST_TIME 94 9189
TEST_TIME 95 9138
TEST_TIME 96 9154
TEST_TIME 97 9148
TEST_TIME 98 9112
TEST_TIME 99 9070
TEST_TIME 100 9092
TEST_TIME 101 9119
TEST_TIME 102 9073
TEST_TIME 103 9139
TEST_TIME 104 9107
TEST_TIME 105 9145
TEST_TIME 106 9168
TEST_TIME 107 9201
TEST_TIME 108 9191
TEST_TIME 109 9214
TEST_TIME 110 9244
TEST_TIME 111 9238
TEST_TIME 112 9179
TEST_TIME 113 9151
TEST_TIME 114 9146
TEST_TIME 115 9110
TEST_TIME 116 9129
TEST_TIME 117 9096
TEST_TIME 118 9063
TEST_TIME 119 9030
TEST_TIME 120 8989

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-08-2021 01:40:12 to 11-08-2021 01:40:16.

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 1476526 1476526 0 0.00 0 33438 7 0 0
doc_list_biud 1476523 1476523 0 0.00 0 71490 29 0 0
sp_add_invoice_to_stock 4759 4644 115 2.41 39 234216 18820 0 115
sp_cancel_adding_invoice 5774 5774 0 0.00 4 65281 784 0 0
sp_cancel_client_order 1700 1700 0 0.00 5 23961 417 0 0
sp_cancel_customer_reserve 566321 566321 0 0.00 1 71499 59 0 0
sp_cancel_pay_from_customer 594 594 0 0.00 3 4036 70 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 14007 127 0 0
sp_cancel_supplier_invoice 6415 6412 3 0.04 1 36202 810 0 3
sp_cancel_supplier_order 391 391 0 0.00 6 875027 109017 0 0
sp_cancel_write_off 9936 9936 0 0.00 1 28897 113 0 0
sp_client_order 19404 19404 0 0.00 4 26225 407 0 0
sp_customer_reserve 429494 429494 0 0.00 0 55079 186 0 0
sp_fill_shopping_cart 29661 29661 0 0.00 1 16793 42 0 0
sp_fill_shopping_cart_clo_res 429488 429488 0 0.00 0 16519 15 0 0
sp_get_clo_for_invoice 4637 4637 0 0.00 0 34671 335 0 0
sp_kill_cost_storno 16725 16725 0 0.00 0 15625 60 0 0
sp_kill_qstorno_ret_qs2qd 1149381 1149381 0 0.00 0 71446 32 0 0
sp_kill_qty_storno 604613 604613 0 0.00 0 71489 69 0 0
sp_lock_selected_doc 19480 19480 0 0.00 0 8478 6 0 0
sp_make_cost_storno 26212 26212 0 0.00 0 33414 69 0 0
sp_multiply_rows_for_pdistr 26211 26211 0 0.00 0 19552 35 0 0
sp_multiply_rows_for_qdistr 19404 19404 0 0.00 0 13784 169 0 0
sp_pay_from_customer 6657 6657 0 0.00 4 19156 138 0 0
sp_pay_to_supplier 5474 5474 0 0.00 5 20994 202 0 0
sp_payment_common 12131 12131 0 0.00 1 19148 52 0 0
sp_qd_handle_on_cancel_clo 1700 1700 0 0.00 0 23953 377 0 0
sp_qd_handle_on_invoice_upd_sts 10409 10409 0 0.00 0 21772 340 0 0
sp_qd_handle_on_reserve_upd_sts 9444 9444 0 0.00 0 24548 70 0 0
sp_reserve_write_off 9444 9444 0 0.00 7 24634 207 0 0
sp_supplier_invoice 5084 5084 0 0.00 7 91318 3686 0 0
sp_supplier_order 5178 5178 0 0.00 5 29502 1282 0 0
srv_aggregate_perf_data 78 78 0 0.00 3281 486034 26087 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1646 127504 45378 0 0
srv_find_qd_qs_mism 1034001 1034001 0 0.00 0 26219 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 44 52366 1023 0 0
srv_make_money_saldo 709 709 0 0.00 1 12356 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 116199 523332 288228 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4644 4644 0 0.00 0 16067 163 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 0 3844 101 0 0
t$perf-norm:sp_cancel_client_order 1700 1700 0 0.00 0 8706 23 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 708 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 594 594 0 0.00 0 537 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 313 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 361 8 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 19844 1687 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 6891 11 0 0
t$perf-norm:sp_client_order 19404 19404 0 0.00 0 8238 17 0 0
t$perf-norm:sp_customer_reserve 910 910 0 0.00 0 4031 8 0 0
t$perf-norm:sp_pay_from_customer 6657 6657 0 0.00 0 7151 11 0 0
t$perf-norm:sp_pay_to_supplier 5474 5474 0 0.00 0 4029 9 0 0
t$perf-norm:sp_reserve_write_off 9444 9444 0 0.00 0 15363 14 0 0
t$perf-norm:sp_supplier_invoice 5084 5084 0 0.00 0 5545 18 0 0
t$perf-norm:sp_supplier_order 5178 5178 0 0.00 0 5358 16 0 0
t$perf-norm:srv_aggregate_perf_data 78 78 0 0.00 0 116 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3183 52 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 2116 27 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 1048 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9500 9270 230 2.42 0 12180 22 0 230
v_all_customers 35302 35302 0 0.00 0 55 1 0 0
v_all_suppliers 28360 28360 0 0.00 0 33 1 0 0
v_all_wares 38808 38808 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 8485 20 0 0
v_cancel_client_order 3400 3400 0 0.00 0 10703 12 0 0
v_cancel_customer_prepayment 1188 1188 0 0.00 0 540 4 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 10705 21 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 13686 20 0 0
v_cancel_supplier_invoice 722 716 6 0.83 0 2120 17 0 6
v_cancel_supplier_order 782 782 0 0.00 0 1944 10 0 0
v_cancel_write_off 1874 1874 0 0.00 0 2250 12 0 0
v_max_id_clo_ord 5177 5177 0 0.00 0 1485 1 0 0
v_max_id_clo_res 910 910 0 0.00 0 1854 17 0 0
v_max_id_ord_sup 5080 5080 0 0.00 0 4224 5 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 3459 6 0 0
v_max_non_paid_realizn 3423 3423 0 0.00 0 2695 5 0 0
v_min_id_clo_ord 5177 5177 0 0.00 0 7356 11 0 0
v_min_id_clo_res 910 910 0 0.00 0 14113 33 0 0
v_min_id_ord_sup 5080 5080 0 0.00 0 6833 8 0 0
v_min_non_paid_invoice 5474 5474 0 0.00 0 19093 47 0 0
v_min_non_paid_realizn 6657 6657 0 0.00 0 18911 37 0 0
v_our_firm 9974 9974 0 0.00 0 12 1 0 0
v_reserve_write_off 18888 18888 0 0.00 0 11172 7 0 0
x_lock_depdocs_on_canc_invoice 11828 11828 0 0.00 0 27548 136 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 13676 402 0 0
x_make_qstorno_1000_1200 5178 5178 0 0.00 1 29477 1204 0 0
x_make_qstorno_1200_2000 5084 5084 0 0.00 3 90502 3529 0 0
x_make_qstorno_2100_3300 429494 429494 0 0.00 0 52111 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 01:40:17 to 11-08-2021 01:40:21.

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
23:07:48 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:49 31514 8192000000 10366868672 10684207104 1412103632 14.703 100 91 183 0 1448161856 5435584 30622640
23:10:28 24927 8192000000 10446708304 10774794240 1496734272 15.448 100 90 180 0 1533710896 8076608 28900016
23:12:01 21799 8192000000 10498350464 10851606528 1545959952 15.875 100 67 134 1 1575452576 8593744 20898880
23:13:26 10548 8192000000 10601323760 10964684800 1658983984 16.840 100 82 162 1 1694538704 11769968 23784752
23:14:43 8126 8192000000 10671694304 11041652736 1727659408 17.416 100 87 174 3 1766498192 13480048 25358736
23:15:53 4939 8192000000 10713670800 11090071552 1770120080 17.768 100 87 174 4 1808142608 13296336 24726192
23:16:59 2637 8192000000 10758022960 11139207168 1819076800 18.170 100 84 167 4 1854405392 12066944 23261648
23:18:03 1633 8192000000 10799503360 11187122176 1861639360 18.517 100 92 183 0 1899632944 12781968 25211616
23:19:05 15797 8192000000 10824320240 11217420288 1881460800 18.677 100 74 147 1 1914072112 12131728 20479584
23:20:21 4815 8192000000 10807576048 11221417984 1882395568 18.684 100 47 94 3 1903287984 8539760 12352656
23:21:27 8036 8192000000 10791621968 11221819392 1888717600 18.735 100 22 42 2 1900376096 5524480 6134016
23:22:38 10807 8192000000 10814345952 11240660992 1896811904 18.801 100 23 46 0 1909174480 6166400 6196176
23:23:49 60081 8192000000 10817033680 11254259712 1905082720 18.867 100 23 46 3 1917200320 5229232 6888368
23:25:50 3528 8192000000 10847988400 11267379200 1914153776 18.940 100 59 117 1 1940009632 9568800 16287056
23:26:54 10455 8192000000 10803032304 11236864000 1861897920 18.519 98 19 38 0 1873283104 5868672 5516512
23:28:10 12482 8192000000 10758316928 11182948352 1793890832 17.964 96 18 36 1 1805504384 5525104 6088448
23:29:24 14174 8192000000 10720939680 11144896512 1725237600 17.396 94 21 40 0 1736381760 6020816 5123344
23:30:41 6015 8192000000 10720267824 11134115840 1771917056 17.783 98 22 44 0 1784197264 6082304 6197904
23:31:48 5510 8192000000 10743595152 11169202176 1835566240 18.305 100 31 62 1 1850928624 6265088 9097296
23:32:55 8303 8192000000 10753287568 11181600768 1843804192 18.372 100 27 54 0 1857348656 5641728 7902736
23:34:06 28305 8192000000 10765193888 11189985280 1848077168 18.407 100 21 41 1 1858311200 5225456 5008576
23:35:37 7928 8192000000 10792560208 11223384064 1879014800 18.657 100 29 58 1 1893598064 6162960 8420304
23:36:51 9266 8192000000 10809027248 11237101568 1889224000 18.740 100 21 42 3 1900641152 5119408 6297744
23:38:03 18002 8192000000 10857161456 11276242944 1927808704 19.049 100 39 74 2 1944101024 5036976 11255344
23:39:26 100276 8192000000 10873852864 11312824320 1951653408 19.240 100 42 82 2 1976812016 12798160 12360448
23:42:22 9069 8192000000 10897867696 11328360448 1965424192 19.349 100 42 84 1 1991497056 13251840 12821024
23:43:34 13881 8192000000 10924354624 11357163520 1981678096 19.478 100 54 108 1 2015688480 17857840 16152544
23:45:19 5809 8192000000 10949688928 11379499008 1985888832 19.511 99 64 128 0 2032728000 27229072 19610096
23:46:27 3395 8192000000 10931778272 11355013120 1966705920 19.359 98 72 140 1 2014834736 27810928 20317888
23:47:37 3797 8192000000 10969337392 11396341760 2010244784 19.703 100 66 130 0 2059472000 30219408 19007808
23:48:50 7164 8192000000 10974726304 11403534336 2013014992 19.725 100 64 128 3 2064692528 31833200 19844336
23:50:02 7212 8192000000 10984896128 11404251136 1996037488 19.591 99 65 131 0 2055724112 39651424 20035200
23:51:17 1646 8192000000 10984478544 11402289152 1998799136 19.613 99 88 173 3 2058887120 33892272 26195712
23:52:21 4280 8192000000 10976092512 11402252288 2000898208 19.630 100 73 146 1 2062547008 38167552 23481248
23:53:31 2504 8192000000 10958701296 11382751232 1978439392 19.452 100 74 148 0 2047650656 44570656 24640608
23:54:37 13458 8192000000 10928218000 11354718208 1947699568 19.208 100 37 74 4 2002986304 42744880 12541856
23:55:57 2787 8192000000 10967574784 11398619136 1989878864 19.543 100 66 130 1 2057405680 46753824 20772992
23:57:04 5576 8192000000 10959036720 11389325312 1969143056 19.379 100 58 112 1 2037026432 49936384 17946992
23:58:15 7199 8192000000 10968192992 11408220160 1983616368 19.493 100 56 112 1 2054598240 52691888 18289984
23:59:43 23653 8192000000 10957958512 11418984448 1987508272 19.524 99 25 50 5 2032011408 36177520 8325616
00:01:17 21231 8192000000 10926871232 11380387840 1952099152 19.243 99 36 72 2 2006049312 42313568 11636592
00:02:54 15598 8192000000 10909371632 11366998016 1937434800 19.126 100 20 40 0 1997001824 52121392 7445632
00:04:13 19457 8192000000 10907877776 11372396544 1941535104 19.159 100 21 42 1 2000391744 50990560 7866080
00:05:37 66351 8192000000 10889479328 11347738624 1950247408 19.228 100 15 30 0 1977669072 22853376 4568288
00:09:21 17552 8192000000 10887360784 11349581824 1947463776 19.206 99 32 64 1 1972029056 15432528 9132752
00:11:03 59720 8192000000 10881810816 11333910528 1933438496 19.094 99 38 78 1 1951924992 7985264 10501232
00:13:19 97676 8192000000 10873998896 11336646656 1910055440 18.907 97 11 22 0 1922950720 9872080 3023200
00:16:02 38446 8192000000 10893270576 11356958720 1970483856 19.389 100 32 62 2 1989923936 9776240 9663840
00:18:07 127504 8192000000 10910114416 11379929088 1963047408 19.330 99 27 56 0 1983484096 12263184 8173504
00:21:34 24307 8192000000 10910248864 11364970496 1972668928 19.407 100 28 54 4 1990641008 10054400 7917680
00:22:59 37508 8192000000 10899645072 11369476096 1962856288 19.329 99 36 72 3 1987858864 14068464 10934112
00:24:52 26200 8192000000 10903567744 11385028608 1982972880 19.488 100 9 18 0 2001231920 15702768 2556272
00:26:37 48173 8192000000 10921216496 11403423744 2001429168 19.634 100 12 24 0 2021445408 16625440 3390800
00:28:25 63210 8192000000 10919716496 11409182720 1997497776 19.603 100 14 28 1 2015513056 13493104 4522176
00:30:35 52764 8192000000 10921903600 11414237184 2003330128 19.649 100 11 22 1 2019088128 12301456 3456544
00:32:38 57777 8192000000 10943226880 11406655488 1973486432 19.413 98 21 42 1 1993216320 13766144 5963744
00:34:41 109085 8192000000 10910181152 11369504768 1913806992 18.937 96 17 34 0 1933364656 14315344 5242320
00:37:31 81319 8192000000 10902761552 11373993984 1923634272 19.016 97 20 40 0 1942800624 13833904 5332448
00:39:59 9462 8192000000 10940230896 11384307712 1989888528 19.543 100 49 98 2 2015234384 11047264 14298592
00:41:09 56371 8192000000 10939452752 11386753024 1928938576 19.058 97 35 69 3 1952700608 14165120 9596912
00:43:07 105026 8192000000 10857455728 11322241024 1812943088 18.120 92 17 33 1 1829411248 11926336 4541824
00:45:52 44016 8192000000 10874208560 11334254592 1935631440 19.112 99 51 101 1 1961260272 10388240 15240592
00:48:38 42017 8192000000 10874879808 11346513920 1920296128 18.989 98 23 46 0 1939761504 12930480 6534896
00:50:43 34895 8192000000 10888045920 11355561984 1967350176 19.364 100 18 36 2 1980350864 8044800 4955888
00:52:19 71574 8192000000 10876757616 11351552000 1944100448 19.179 99 17 35 0 1961635872 12535008 5000416
00:54:31 43730 8192000000 10895859696 11363397632 1962832672 19.329 100 22 44 0 1982659568 12988624 6838272
00:56:21 103710 8192000000 10826700384 11287793664 1751664528 17.615 90 11 22 1 1765395392 10644176 3086688
00:59:07 48148 8192000000 10863533376 11328012288 1925737456 19.033 99 22 44 2 1944644336 12358640 6548240
01:01:05 85517 8192000000 10876533792 11330363392 1876463584 18.637 96 19 38 2 1891463968 10058656 4941728
01:03:33 15867 8192000000 10866814832 11313352704 1905166528 18.868 99 39 78 0 1931517152 15372768 10977856
01:04:49 88033 8192000000 10857330448 11331731456 1911141616 18.916 99 11 22 0 1934193312 20006384 3045312
01:07:18 40486 8192000000 10897607456 11371761664 1960799568 19.312 100 34 68 4 1989732400 19376160 9556672
01:10:58 36372 8192000000 10876594304 11331792896 1908130464 18.892 97 26 51 1 1922953008 8077808 6744736
01:12:37 73781 8192000000 10867612144 11346427904 1909373952 18.902 97 17 34 1 1925267040 11130912 4762176
01:14:52 40218 8192000000 10864882528 11345969152 1918501920 18.975 98 4 8 0 1930265408 10917824 845664
01:16:33 56695 8192000000 10893518848 11370160128 1975205920 19.427 100 27 54 0 1993333184 10133824 7993440
01:18:43 109354 8192000000 10918748704 11393642496 1964991008 19.346 99 5 10 0 1982907232 16461936 1454288
01:21:39 68913 8192000000 10882166368 11362697216 1939228624 19.141 98 16 32 0 1957272192 13641360 4402208
01:23:54 60498 8192000000 10886167360 11364741120 1944746528 19.185 99 14 28 1 1959087328 10554704 3786096
01:26:04 109650 8192000000 10886230464 11360907264 1859500608 18.499 94 13 26 1 1873631088 10616976 3513504
01:28:54 101129 8192000000 10887544704 11356696576 1944293296 19.181 99 38 76 0 1963919424 8053728 11572400
01:32:01 22995 8192000000 10879324016 11355410432 1927647600 19.048 99 6 12 2 1946267504 17168768 1451136
01:33:24 69171 8192000000 10861177264 11347755008 1930077184 19.067 99 5 11 0 1942645696 11236496 1332016
01:35:35 48338 8192000000 10875676272 11346907136 1942254752 19.165 99 16 32 1 1955518896 8290672 4973472
01:37:29 33770 8192000000 9865183856 10235015168 1000736224 10.886 51 7 12 1 1010928464 8531872 1660368

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-08-2021 01:40:23 to 11-08-2021 01:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2021 01:40:38 to 11-08-2021 01:40:40.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229973
MON$OLDEST_ACTIVE               232354
MON$OLDEST_SNAPSHOT             232354
MON$NEXT_TRANSACTION            232354
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                       2024576
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:81: 
:82: mscs-test-161.budzdorov.ru    Wed Aug 11 01:38:08 2021
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Wed Aug 11 01:38:14 2021
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Wed Aug 11 01:38:21 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Wed Aug 11 01:38:29 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Wed Aug 11 01:38:29 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
--- end of diff output ---

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

11.08.21 01:40:41. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-11 01:40:44 No severe PSQL-related problems occured
11.08.21 01:40:45. End of report.