Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.11.2021 22: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16768956 kB
MemAvailable 31484584 kB
Buffers 0 kB
Cached 14868248 kB
SwapCached 0 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-11-30 22:37:56
DTS_END                         2021-11-30 22:37:56.7240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59406
TRN_ID                          245855
ATT_ID                          623
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: 9057.11 20669 1086853
customer order: creation 178.07 372 21368
customer order: refuse 15.32 352 1838
order to supplier: creation 46.76 1333 5611
order to supplier: removal 3.38 103603 405
invoice (draft): creation 47.30 3754 5676
invoice (draft): removal 48.33 881 5800
invoice accept: apply 43.30 19048 5196
invoice accept: cancel 43.56 808 5227
customer reserve: creation 4014.73 186 481768
customer reserve: removal 4322.23 63 518668
realization accept: apply 86.93 223 10432
realization accept: cancel 72.58 129 8710
payment from customer: creation 60.85 129 7302
payment from customer: removal 5.69 90 683
payment to supplier: creation 50.90 165 6108
payment to supplier: removal 3.76 136 451
service: total inventory turnovers 5.18 1045 622
service: total monetary turnovers 7.21 33 865
service: aggregate perf. data 0.99 14156 119
service: refresh index statistics 0.03 266864 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-11-2021 22:38:47 to 30-11-2021 22:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2156
TEST_TIME 2 3171
TEST_TIME 3 3585
TEST_TIME 4 3925
TEST_TIME 5 4847
TEST_TIME 6 4708
TEST_TIME 7 4833
TEST_TIME 8 4864
TEST_TIME 9 5541
TEST_TIME 10 5566
TEST_TIME 11 5948
TEST_TIME 12 6586
TEST_TIME 13 6568
TEST_TIME 14 6614
TEST_TIME 15 6463
TEST_TIME 16 6787
TEST_TIME 17 6985
TEST_TIME 18 7316
TEST_TIME 19 7303
TEST_TIME 20 7724
TEST_TIME 21 7928
TEST_TIME 22 8480
TEST_TIME 23 8660
TEST_TIME 24 8633
TEST_TIME 25 8715
TEST_TIME 26 8611
TEST_TIME 27 8686
TEST_TIME 28 8671
TEST_TIME 29 9602
TEST_TIME 30 10314
TEST_TIME 31 10748
TEST_TIME 32 10632
TEST_TIME 33 10367
TEST_TIME 34 10192
TEST_TIME 35 10171
TEST_TIME 36 10101
TEST_TIME 37 9961
TEST_TIME 38 9808
TEST_TIME 39 9724
TEST_TIME 40 9626
TEST_TIME 41 9451
TEST_TIME 42 9531
TEST_TIME 43 9694
TEST_TIME 44 9592
TEST_TIME 45 9607
TEST_TIME 46 9465
TEST_TIME 47 9448
TEST_TIME 48 9400
TEST_TIME 49 9355
TEST_TIME 50 9236
TEST_TIME 51 9176
TEST_TIME 52 9274
TEST_TIME 53 9265
TEST_TIME 54 9182
TEST_TIME 55 9089
TEST_TIME 56 9121
TEST_TIME 57 9123
TEST_TIME 58 9118
TEST_TIME 59 9099
TEST_TIME 60 9119
TEST_TIME 61 9115
TEST_TIME 62 9100
TEST_TIME 63 9110
TEST_TIME 64 9172
TEST_TIME 65 9154
TEST_TIME 66 9144
TEST_TIME 67 9239
TEST_TIME 68 9284
TEST_TIME 69 9298
TEST_TIME 70 9311
TEST_TIME 71 9326
TEST_TIME 72 9366
TEST_TIME 73 9351
TEST_TIME 74 9299
TEST_TIME 75 9204
TEST_TIME 76 9192
TEST_TIME 77 9110
TEST_TIME 78 9136
TEST_TIME 79 9096
TEST_TIME 80 9137
TEST_TIME 81 9121
TEST_TIME 82 9131
TEST_TIME 83 9078
TEST_TIME 84 9024
TEST_TIME 85 9018
TEST_TIME 86 9008
TEST_TIME 87 9033
TEST_TIME 88 9020
TEST_TIME 89 9017
TEST_TIME 90 9014
TEST_TIME 91 9003
TEST_TIME 92 9085
TEST_TIME 93 9103
TEST_TIME 94 9198
TEST_TIME 95 9176
TEST_TIME 96 9190
TEST_TIME 97 9199
TEST_TIME 98 9248
TEST_TIME 99 9276
TEST_TIME 100 9316
TEST_TIME 101 9276
TEST_TIME 102 9288
TEST_TIME 103 9235
TEST_TIME 104 9167
TEST_TIME 105 9125
TEST_TIME 106 9132
TEST_TIME 107 9106
TEST_TIME 108 9084
TEST_TIME 109 9073
TEST_TIME 110 9051
TEST_TIME 111 9009
TEST_TIME 112 8976
TEST_TIME 113 8972
TEST_TIME 114 8996
TEST_TIME 115 8968
TEST_TIME 116 9007
TEST_TIME 117 9007
TEST_TIME 118 9078
TEST_TIME 119 9064
TEST_TIME 120 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-11-2021 22:38:51 to 30-11-2021 22:38:54.

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 1530864 1530864 0 0.00 0 54289 7 0 0
doc_list_biud 1530863 1530863 0 0.00 0 36090 27 0 0
sp_add_invoice_to_stock 5278 5196 82 1.55 39 184290 19048 0 82
sp_cancel_adding_invoice 5227 5227 0 0.00 4 45875 808 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 16288 353 0 0
sp_cancel_customer_reserve 518668 518668 0 0.00 1 22031 63 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 3641 91 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 2 7324 136 0 0
sp_cancel_supplier_invoice 5811 5800 11 0.18 1 14239 881 0 11
sp_cancel_supplier_order 405 405 0 0.00 5 1020625 103603 0 0
sp_cancel_write_off 8710 8710 0 0.00 1 28922 129 0 0
sp_client_order 21368 21368 0 0.00 3 39612 373 0 0
sp_customer_reserve 481768 481768 0 0.00 0 89394 187 0 0
sp_fill_shopping_cart 32647 32647 0 0.00 1 31963 46 0 0
sp_fill_shopping_cart_clo_res 481761 481761 0 0.00 0 16873 14 0 0
sp_get_clo_for_invoice 5189 5189 0 0.00 0 40421 331 0 0
sp_kill_cost_storno 15071 15071 0 0.00 0 28000 66 0 0
sp_kill_qstorno_ret_qs2qd 1052251 1052251 0 0.00 0 36089 34 0 0
sp_kill_qty_storno 556269 556269 0 0.00 0 36090 75 0 0
sp_lock_selected_doc 21512 21512 0 0.00 0 7594 6 0 0
sp_make_cost_storno 29031 29031 0 0.00 0 54272 74 0 0
sp_multiply_rows_for_pdistr 29030 29030 0 0.00 0 28071 35 0 0
sp_multiply_rows_for_qdistr 21368 21368 0 0.00 0 9590 146 0 0
sp_pay_from_customer 7302 7302 0 0.00 4 36508 129 0 0
sp_pay_to_supplier 6108 6108 0 0.00 4 36323 165 0 0
sp_payment_common 13409 13409 0 0.00 2 20020 30 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 16282 321 0 0
sp_qd_handle_on_invoice_upd_sts 10416 10416 0 0.00 0 20866 358 0 0
sp_qd_handle_on_reserve_upd_sts 10432 10432 0 0.00 0 22256 73 0 0
sp_reserve_write_off 10432 10432 0 0.00 6 33218 223 0 0
sp_supplier_invoice 5676 5676 0 0.00 7 92816 3754 0 0
sp_supplier_order 5611 5611 0 0.00 5 40852 1334 0 0
srv_aggregate_perf_data 119 119 0 0.00 2584 125369 14157 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2000 143394 44571 0 0
srv_find_qd_qs_mism 1036302 1036302 0 0.00 0 16501 6 0 0
srv_make_invnt_saldo 622 622 0 0.00 26 36631 1045 0 0
srv_make_money_saldo 865 865 0 0.00 0 4638 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 195346 378743 266865 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5196 5196 0 0.00 0 25012 192 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 15370 139 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 5496 15 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 3395 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 466 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 5122 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1124 13 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 27286 1662 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 3303 9 0 0
t$perf-norm:sp_client_order 21368 21368 0 0.00 0 13630 17 0 0
t$perf-norm:sp_customer_reserve 1077 1077 0 0.00 0 5144 15 0 0
t$perf-norm:sp_pay_from_customer 7302 7302 0 0.00 0 5518 12 0 0
t$perf-norm:sp_pay_to_supplier 6108 6108 0 0.00 0 5109 11 0 0
t$perf-norm:sp_reserve_write_off 10432 10432 0 0.00 0 9167 11 0 0
t$perf-norm:sp_supplier_invoice 5677 5677 0 0.00 0 7168 20 0 0
t$perf-norm:sp_supplier_order 5611 5611 0 0.00 0 6660 18 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 483 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 60 1 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 3047 32 0 0
t$perf-norm:srv_make_money_saldo 865 865 0 0.00 0 898 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 29 10 0 0
v_add_invoice_to_stock 10538 10374 164 1.55 0 5350 24 0 164
v_all_customers 38620 38620 0 0.00 0 14 1 0 0
v_all_suppliers 31266 31266 0 0.00 0 29 1 0 0
v_all_wares 42734 42734 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 1689 9 0 0
v_cancel_client_order 3676 3676 0 0.00 0 6966 8 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1716 10 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1838 7 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 6967 20 0 0
v_cancel_supplier_invoice 854 832 22 2.57 0 787 15 0 22
v_cancel_supplier_order 810 810 0 0.00 0 4056 11 0 0
v_cancel_write_off 2086 2086 0 0.00 0 3557 17 0 0
v_max_id_clo_ord 5611 5611 0 0.00 0 9887 4 0 0
v_max_id_clo_res 1077 1077 0 0.00 0 3746 16 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 4214 6 0 0
v_max_non_paid_invoice 1754 1754 0 0.00 0 1136 3 0 0
v_max_non_paid_realizn 4013 4013 0 0.00 0 4202 5 0 0
v_min_id_clo_ord 5611 5611 0 0.00 0 2772 8 0 0
v_min_id_clo_res 1077 1077 0 0.00 0 486 2 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 6651 8 0 0
v_min_non_paid_invoice 6107 6107 0 0.00 0 10668 22 0 0
v_min_non_paid_realizn 7302 7302 0 0.00 0 20005 19 0 0
v_our_firm 10692 10692 0 0.00 0 76 1 0 0
v_reserve_write_off 20862 20862 0 0.00 0 4576 8 0 0
x_lock_depdocs_on_canc_invoice 10611 10611 0 0.00 0 15612 131 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 10038 351 0 0
x_make_qstorno_1000_1200 5611 5611 0 0.00 1 40837 1251 0 0
x_make_qstorno_1200_2000 5676 5676 0 0.00 2 87069 3575 0 0
x_make_qstorno_2100_3300 481768 481768 0 0.00 0 89393 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-11-2021 22:38:55 to 30-11-2021 22:38:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 24757 8192000000 9892178400 10202492928 978688592 10.671 100 91 182 0 1009142768 6230896 24223280
20:10:31 9973 8192000000 9940109936 10264129536 1027487232 11.144 100 83 166 0 1056096384 7346320 21262832
20:11:45 12525 8192000000 9972884256 10304921600 1060797152 11.464 100 85 170 0 1091806576 9281840 21727584
20:13:02 11271 8192000000 10024085744 10368999424 1116908000 11.998 100 83 166 1 1146449504 10157296 19384208
20:14:19 9616 8192000000 10071203840 10426933248 1152361696 12.332 100 77 153 1 1182263648 11879712 18022240
20:15:32 5468 8192000000 10104586848 10470645760 1196078768 12.740 100 83 165 0 1227924480 13256544 18589168
20:16:40 5782 8192000000 10147246368 10520641536 1227246624 13.029 100 83 166 3 1259534032 14141760 18145648
20:17:48 2262 8192000000 10170481312 10551574528 1256635856 13.299 100 88 177 0 1289677440 13091840 19949744
20:18:51 1244 8192000000 10210953824 10594709504 1290490688 13.609 100 94 188 1 1325512672 13908256 21113728
20:19:54 8645 8192000000 10183160240 10594721792 1296131760 13.660 100 49 96 2 1317088064 9929072 11027232
20:21:03 6235 8192000000 10191295184 10602819584 1307879264 13.767 100 12 24 1 1315797968 5294624 2624080
20:22:13 13643 8192000000 10201180192 10621739008 1319140288 13.869 100 9 18 0 1326630288 5696368 1793632
20:23:30 66241 8192000000 10216255072 10639486976 1329796192 13.965 100 32 64 1 1343159616 6229616 7133808
20:25:43 8743 8192000000 10224426464 10650812416 1337678800 14.036 100 23 46 1 1348930000 6164896 5086304
20:26:52 10452 8192000000 10219408576 10645381120 1334929008 14.012 100 12 24 0 1343227440 5672432 2626000
20:28:07 5749 8192000000 10201934576 10614743040 1295675696 13.656 98 14 28 0 1303837312 5108048 3053568
20:29:17 28157 8192000000 10150021776 10561687552 1170455376 12.501 90 15 30 0 1178501504 4878416 3167712
20:30:46 6053 8192000000 10118078256 10514792448 1209070416 12.860 97 24 48 1 1220311520 5696064 5545040
20:31:53 7442 8192000000 10153257440 10553778176 1249997232 13.238 99 35 70 2 1264842880 5953424 8892224
20:33:03 20632 8192000000 10153689840 10563145728 1246496256 13.206 98 22 43 1 1257295856 5601152 5198448
20:34:52 6248 8192000000 10180120528 10589294592 1287066400 13.577 100 37 74 1 1302674368 7705936 7902032
20:36:00 7734 8192000000 10194106528 10601365504 1292237392 13.625 100 40 78 0 1307273600 6243760 8792448
20:37:10 14734 8192000000 10198888224 10615242752 1306428992 13.754 100 21 41 2 1317320096 6107680 4783424
20:38:29 45769 8192000000 10240903344 10649989120 1334484000 14.008 100 48 96 1 1352733152 7323840 10925312
20:40:16 37122 8192000000 10250602800 10669617152 1351420624 14.160 100 38 76 1 1367779632 7057712 9301296
20:41:57 5764 8192000000 10278974672 10691006464 1367378096 14.304 100 64 128 2 1395970096 13780016 14811984
20:43:06 7920 8192000000 10291768016 10710401024 1381073264 14.426 100 49 97 0 1405342688 11982016 12287408
20:44:20 6975 8192000000 10300269232 10725228544 1391203280 14.517 100 43 86 1 1419269248 17499776 10566192
20:45:33 7968 8192000000 10318718576 10734419968 1389134080 14.498 100 70 140 1 1427853488 21785200 16934208
20:46:43 10867 8192000000 10316446352 10740174848 1395829728 14.558 100 50 100 1 1427865056 20339456 11695872
20:48:07 11398 8192000000 10321909440 10742353920 1389398656 14.501 100 66 132 0 1432393920 27243024 15752240
20:49:25 5254 8192000000 10331874976 10752770048 1399446976 14.590 100 59 118 0 1440043696 26230096 14366624
20:50:35 5164 8192000000 10336443488 10748571648 1394166960 14.543 100 69 137 1 1439033584 28274576 16592048
20:51:44 7383 8192000000 10329499328 10746675200 1384610832 14.458 99 56 112 0 1428083872 29048528 14424512
20:53:00 5375 8192000000 10338379104 10743111680 1364928352 14.282 98 71 140 1 1420615264 38217440 17469472
20:54:09 4946 8192000000 10323780304 10731008000 1373273536 14.356 99 65 128 2 1429647392 40395744 15978112
20:55:19 3711 8192000000 10333962480 10740350976 1376176848 14.382 100 72 144 2 1435794672 41767632 17850192
20:56:26 3339 8192000000 10335377632 10739777536 1371475280 14.340 100 74 148 1 1437264352 46978976 18810096
20:57:34 7015 8192000000 10319182368 10731376640 1338155632 14.041 97 57 114 3 1405346944 52028880 15162432
20:58:56 2000 8192000000 10372955072 10784858112 1393917632 14.541 100 80 159 2 1469009280 53841952 21249696
21:00:08 8109 8192000000 10337278448 10761904128 1367887312 14.308 98 56 113 1 1432101888 49371792 14842784
21:01:47 2438 8192000000 10358192064 10773192704 1393278176 14.535 100 68 133 2 1455855408 45625936 16951296
21:03:00 20308 8192000000 10344339920 10778152960 1383802992 14.451 99 27 54 0 1441091952 49738768 7550192
21:04:24 17684 8192000000 10344694064 10778169344 1394995728 14.550 100 29 58 0 1454812000 51825664 7990608
21:05:49 25664 8192000000 10320852736 10766258176 1361861536 14.254 99 22 44 0 1433487632 64468768 7157328
21:07:50 71968 8192000000 10277440528 10728755200 1339407664 14.052 98 8 16 1 1372144608 30845744 1891200
21:10:04 82953 8192000000 10284221824 10723143680 1371597680 14.341 100 38 76 0 1388411936 7610416 9203840
21:12:27 61398 8192000000 10267467648 10725400576 1340483904 14.062 97 6 13 0 1352189760 10600448 1105408
21:14:30 70498 8192000000 10274746688 10718445568 1347889408 14.128 97 32 63 0 1365991664 10508576 7593680
21:17:24 52187 8192000000 10321858992 10770219008 1393059008 14.533 99 19 38 3 1414602752 16786944 4756800
21:19:17 69902 8192000000 10290929296 10760814592 1388638608 14.494 100 23 43 0 1417169696 23670112 4860976
21:21:39 132780 8192000000 10299092864 10747236352 1375468144 14.376 98 29 57 0 1396271664 13675632 7127888
21:24:54 59754 8192000000 10302953776 10757349376 1392310496 14.526 100 24 48 0 1418532432 21011104 5210832
21:27:11 27586 8192000000 10323358848 10767208448 1404578176 14.636 100 49 98 1 1427127344 10939664 11609504
21:29:14 71222 8192000000 10301381232 10773856256 1396785952 14.566 100 11 22 1 1416834304 17421664 2626688
21:31:26 46853 8192000000 10296536000 10767396864 1398958224 14.586 100 9 18 0 1415916608 14734320 2224064
21:33:22 79294 8192000000 10291292032 10763575296 1370975888 14.336 98 9 18 0 1384716048 11642848 2097312
21:35:57 64113 8192000000 10291364736 10738323456 1337202128 14.032 96 26 52 0 1350638144 7166736 6269280
21:38:01 31603 8192000000 10269103280 10718789632 1339429936 14.052 97 21 42 1 1352425536 8566880 4428720
21:39:41 66603 8192000000 10271643296 10723758080 1335845328 14.020 97 26 52 0 1353109040 11876432 5387280
21:41:49 57800 8192000000 10279576912 10723262464 1311363088 13.798 96 15 30 0 1326826688 12266896 3196704
21:44:15 33157 8192000000 10300657168 10740322304 1360148496 14.239 99 28 54 1 1391694688 24426784 7119408
21:45:48 76311 8192000000 10279872256 10732408832 1338655712 14.045 97 23 46 0 1353776640 10039344 5081584
21:48:06 33395 8192000000 10297556352 10737930240 1364696672 14.280 98 32 64 1 1385646480 14053536 6896272
21:49:42 57928 8192000000 10250959520 10688000000 1249111264 13.230 92 11 22 0 1260371696 9027136 2233296
21:51:43 63647 8192000000 10250533968 10691854336 1338804288 14.047 99 40 79 1 1353277776 5465184 9008304
21:53:47 78066 8192000000 10265964176 10710282240 1343764352 14.091 99 24 48 0 1362022208 12286768 5971088
21:56:08 57188 8192000000 10257107248 10714992640 1339898800 14.056 98 12 24 1 1361408448 18442992 3066656
21:58:23 74365 8192000000 10256300288 10709700608 1326449920 13.935 97 16 32 0 1347038176 16069936 4518320
22:00:54 57107 8192000000 10266086992 10711441408 1346886128 14.119 99 24 48 0 1362362544 9847136 5629280
22:02:51 63593 8192000000 10251798912 10710958080 1335906272 14.020 98 18 36 0 1356969616 16866448 4196896
22:04:55 54239 8192000000 10270388864 10721841152 1364481040 14.278 100 17 34 0 1377448816 8957056 4010720
22:06:52 42684 8192000000 10292383472 10733068288 1361080096 14.247 99 28 56 0 1378365744 10604688 6680960
22:08:41 75905 8192000000 10299372560 10754084864 1367182800 14.302 99 14 28 0 1387645056 17339376 3122880
22:11:01 43408 8192000000 10286264192 10747514880 1359006800 14.228 98 18 36 0 1381325040 18150288 4167952
22:12:52 43128 8192000000 10281179840 10724143104 1329426560 13.962 96 41 82 0 1348583488 10050432 9106496
22:15:22 54733 8192000000 10300534656 10748174336 1366107024 14.292 98 28 56 0 1384365504 12295248 5963232
22:17:17 84959 8192000000 10297369056 10738200576 1347494528 14.125 97 13 26 0 1364578768 14206880 2877360
22:19:43 143394 8192000000 10256023968 10699522048 1281888896 13.530 93 38 76 1 1300002736 9298672 8815168
22:23:08 64524 8192000000 10257174784 10717753344 1331054688 13.977 97 10 20 0 1343071424 9605248 2411488
22:25:52 35293 8192000000 10250255680 10702086144 1329014160 13.958 97 16 32 0 1340052976 6651424 4387392
22:27:28 82107 8192000000 10251219536 10709291008 1310597504 13.791 96 14 28 1 1320795360 7331824 2866032
22:29:51 59994 8192000000 10271741792 10722209792 1355853424 14.200 99 19 38 1 1367918288 7256448 4808416
22:32:07 37256 8192000000 10268996576 10710204416 1304763136 13.739 95 32 64 0 1325021520 13034288 7224096
22:33:47 75314 8192000000 10281635776 10723299328 1339131184 14.050 98 13 26 1 1356908960 14689984 3087792
22:36:03 33310 8192000000 10260739904 10697076736 1311042736 13.796 96 34 69 0 1327426000 8438528 7944736
22:37:37 28751 8192000000 9157921392 9415503872 307695616 3.620 23 2 4 0 314530640 6422928 412096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-11-2021 22:39:01 to 30-11-2021 22:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-11-2021 22:39:17 to 30-11-2021 22:39:19.

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          246137
MON$OLDEST_ACTIVE               246138
MON$OLDEST_SNAPSHOT             246138
MON$NEXT_TRANSACTION            246138
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                       2023680
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:628: 
:629: mscs-test-161.budzdorov.ru    Tue Nov 30 22:38:17 2021
:630:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:631: 
--- end of diff output ---

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

30.11.21 22:39:20. 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-11-30 22:39:22 No severe PSQL-related problems occured
30.11.21 22:39:23. End of report.