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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16191132 kB
MemAvailable 31166200 kB
Buffers 0 kB
Cached 15490840 kB
SwapCached 2500 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-28 22:38:21
DTS_END                         2022-06-28 22:38:21.0220
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/45356
TRN_ID                          241103
ATT_ID                          642
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: 9315.58 20483 1117870
customer order: creation 172.99 392 20759
customer order: refuse 14.94 372 1793
order to supplier: creation 45.80 1237 5496
order to supplier: removal 3.43 106852 412
invoice (draft): creation 46.89 3267 5627
invoice (draft): removal 52.20 823 6264
invoice accept: apply 42.64 18428 5117
invoice accept: cancel 47.18 812 5661
customer reserve: creation 3933.65 183 472038
customer reserve: removal 4660.68 62 559281
realization accept: apply 84.63 170 10155
realization accept: cancel 80.40 100 9648
payment from customer: creation 59.23 130 7108
payment from customer: removal 5.53 82 663
payment to supplier: creation 49.57 127 5948
payment to supplier: removal 3.47 90 416
service: total inventory turnovers 4.73 1064 568
service: total monetary turnovers 6.78 22 813
service: aggregate perf. data 0.83 19227 99
service: refresh index statistics 0.03 256223 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-06-2022 22:39:29 to 28-06-2022 22:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5099
TEST_TIME 2 4056
TEST_TIME 3 3572
TEST_TIME 4 4564
TEST_TIME 5 5676
TEST_TIME 6 5599
TEST_TIME 7 5735
TEST_TIME 8 5673
TEST_TIME 9 5732
TEST_TIME 10 5748
TEST_TIME 11 5713
TEST_TIME 12 6052
TEST_TIME 13 6205
TEST_TIME 14 6331
TEST_TIME 15 6753
TEST_TIME 16 6772
TEST_TIME 17 6857
TEST_TIME 18 7321
TEST_TIME 19 7428
TEST_TIME 20 7953
TEST_TIME 21 7857
TEST_TIME 22 7694
TEST_TIME 23 8400
TEST_TIME 24 8331
TEST_TIME 25 8973
TEST_TIME 26 9075
TEST_TIME 27 9801
TEST_TIME 28 10744
TEST_TIME 29 10622
TEST_TIME 30 10886
TEST_TIME 31 10835
TEST_TIME 32 10930
TEST_TIME 33 10981
TEST_TIME 34 10678
TEST_TIME 35 10563
TEST_TIME 36 10303
TEST_TIME 37 10294
TEST_TIME 38 10118
TEST_TIME 39 10179
TEST_TIME 40 10133
TEST_TIME 41 10075
TEST_TIME 42 9969
TEST_TIME 43 9925
TEST_TIME 44 9917
TEST_TIME 45 10020
TEST_TIME 46 9949
TEST_TIME 47 9773
TEST_TIME 48 9821
TEST_TIME 49 9722
TEST_TIME 50 9637
TEST_TIME 51 9576
TEST_TIME 52 9559
TEST_TIME 53 9483
TEST_TIME 54 9472
TEST_TIME 55 9591
TEST_TIME 56 9558
TEST_TIME 57 9715
TEST_TIME 58 9683
TEST_TIME 59 9848
TEST_TIME 60 9853
TEST_TIME 61 9855
TEST_TIME 62 9905
TEST_TIME 63 9861
TEST_TIME 64 9737
TEST_TIME 65 9701
TEST_TIME 66 9607
TEST_TIME 67 9581
TEST_TIME 68 9587
TEST_TIME 69 9539
TEST_TIME 70 9468
TEST_TIME 71 9362
TEST_TIME 72 9330
TEST_TIME 73 9410
TEST_TIME 74 9487
TEST_TIME 75 9454
TEST_TIME 76 9444
TEST_TIME 77 9444
TEST_TIME 78 9534
TEST_TIME 79 9532
TEST_TIME 80 9557
TEST_TIME 81 9533
TEST_TIME 82 9643
TEST_TIME 83 9632
TEST_TIME 84 9609
TEST_TIME 85 9549
TEST_TIME 86 9492
TEST_TIME 87 9515
TEST_TIME 88 9495
TEST_TIME 89 9486
TEST_TIME 90 9420
TEST_TIME 91 9389
TEST_TIME 92 9329
TEST_TIME 93 9322
TEST_TIME 94 9354
TEST_TIME 95 9343
TEST_TIME 96 9416
TEST_TIME 97 9394
TEST_TIME 98 9459
TEST_TIME 99 9432
TEST_TIME 100 9445
TEST_TIME 101 9432
TEST_TIME 102 9493
TEST_TIME 103 9470
TEST_TIME 104 9499
TEST_TIME 105 9496
TEST_TIME 106 9557
TEST_TIME 107 9573
TEST_TIME 108 9512
TEST_TIME 109 9446
TEST_TIME 110 9434
TEST_TIME 111 9403
TEST_TIME 112 9369
TEST_TIME 113 9321
TEST_TIME 114 9326
TEST_TIME 115 9352
TEST_TIME 116 9335
TEST_TIME 117 9339
TEST_TIME 118 9342
TEST_TIME 119 9355
TEST_TIME 120 9321

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-06-2022 22:39:33 to 28-06-2022 22:39:36.

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 1555005 1555005 0 0.00 0 22501 6 0 0
doc_list_biud 1555004 1555004 0 0.00 0 31552 28 0 0
sp_add_invoice_to_stock 5200 5117 83 1.59 35 225458 18428 0 83
sp_cancel_adding_invoice 5661 5661 0 0.00 4 73621 813 0 0
sp_cancel_client_order 1793 1793 0 0.00 3 15234 373 0 0
sp_cancel_customer_reserve 559281 559281 0 0.00 0 23954 62 0 0
sp_cancel_pay_from_customer 663 663 0 0.00 2 4909 83 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 2 4855 90 0 0
sp_cancel_supplier_invoice 6270 6264 6 0.09 1 31554 823 0 6
sp_cancel_supplier_order 412 412 0 0.00 9 1239959 106853 0 0
sp_cancel_write_off 9648 9648 0 0.00 1 10049 100 0 0
sp_client_order 20759 20759 0 0.00 3 26447 392 0 0
sp_customer_reserve 472038 472038 0 0.00 0 52485 184 0 0
sp_fill_shopping_cart 31881 31881 0 0.00 1 10625 36 0 0
sp_fill_shopping_cart_clo_res 472031 472031 0 0.00 0 14586 14 0 0
sp_get_clo_for_invoice 5110 5110 0 0.00 0 29267 307 0 0
sp_kill_cost_storno 16388 16388 0 0.00 0 18549 54 0 0
sp_kill_qstorno_ret_qs2qd 1134886 1134886 0 0.00 0 31551 34 0 0
sp_kill_qty_storno 598324 598324 0 0.00 0 31552 72 0 0
sp_lock_selected_doc 21027 21027 0 0.00 0 8145 6 0 0
sp_make_cost_storno 28321 28321 0 0.00 0 14271 50 0 0
sp_multiply_rows_for_pdistr 28321 28321 0 0.00 0 14326 30 0 0
sp_multiply_rows_for_qdistr 20759 20759 0 0.00 0 12449 175 0 0
sp_pay_from_customer 7108 7108 0 0.00 4 16730 130 0 0
sp_pay_to_supplier 5948 5948 0 0.00 5 14409 128 0 0
sp_payment_common 13056 13056 0 0.00 1 14635 41 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 15227 316 0 0
sp_qd_handle_on_invoice_upd_sts 10771 10771 0 0.00 0 16278 317 0 0
sp_qd_handle_on_reserve_upd_sts 10155 10155 0 0.00 0 15572 50 0 0
sp_reserve_write_off 10155 10155 0 0.00 6 20906 171 0 0
sp_supplier_invoice 5627 5627 0 0.00 6 93622 3268 0 0
sp_supplier_order 5496 5496 0 0.00 5 32337 1238 0 0
srv_aggregate_perf_data 99 99 0 0.00 2190 263937 19228 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2649 137406 41614 0 0
srv_find_qd_qs_mism 1069598 1069598 0 0.00 0 13647 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 58 36885 1065 0 0
srv_make_money_saldo 813 813 0 0.00 0 757 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 144003 357967 256224 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 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 5117 5117 0 0.00 0 35332 192 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 2715 80 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 27133 40 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 5791 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 663 663 0 0.00 0 3144 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2020 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 672 14 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 25257 1651 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 10353 37 0 0
t$perf-norm:sp_client_order 20759 20759 0 0.00 0 16331 21 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 3900 11 0 0
t$perf-norm:sp_pay_from_customer 7108 7108 0 0.00 0 6780 10 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 8512 13 0 0
t$perf-norm:sp_reserve_write_off 10155 10155 0 0.00 0 7488 14 0 0
t$perf-norm:sp_supplier_invoice 5627 5627 0 0.00 0 12259 29 0 0
t$perf-norm:sp_supplier_order 5496 5496 0 0.00 0 4369 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 877 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 16919 52 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 1813 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 13 8 0 0
v_add_invoice_to_stock 10386 10220 166 1.59 0 11922 21 0 166
v_all_customers 38002 38002 0 0.00 0 21 1 0 0
v_all_suppliers 30718 30718 0 0.00 0 22 1 0 0
v_all_wares 41518 41518 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 73 5 0 0
v_cancel_client_order 3586 3586 0 0.00 0 12308 19 0 0
v_cancel_customer_prepayment 1326 1326 0 0.00 0 196 3 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 5740 11 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2622 7 0 0
v_cancel_supplier_invoice 774 762 12 1.55 0 330 11 0 12
v_cancel_supplier_order 824 824 0 0.00 0 1158 4 0 0
v_cancel_write_off 2022 2022 0 0.00 0 853 12 0 0
v_max_id_clo_ord 5496 5496 0 0.00 0 838 1 0 0
v_max_id_clo_res 997 997 0 0.00 0 2923 18 0 0
v_max_id_ord_sup 5626 5626 0 0.00 0 4776 5 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 3239 6 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 2533 4 0 0
v_min_id_clo_ord 5496 5496 0 0.00 0 2809 6 0 0
v_min_id_clo_res 997 997 0 0.00 0 3180 10 0 0
v_min_id_ord_sup 5626 5626 0 0.00 0 7197 9 0 0
v_min_non_paid_invoice 5948 5948 0 0.00 0 14371 25 0 0
v_min_non_paid_realizn 7108 7108 0 0.00 1 14618 42 0 0
v_our_firm 10336 10336 0 0.00 0 18 1 0 0
v_reserve_write_off 20310 20310 0 0.00 0 8427 8 0 0
x_lock_depdocs_on_canc_invoice 11544 11544 0 0.00 0 31069 129 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 4279 340 0 0
x_make_qstorno_1000_1200 5496 5496 0 0.00 1 32324 1159 0 0
x_make_qstorno_1200_2000 5627 5627 0 0.00 3 93601 3144 0 0
x_make_qstorno_2100_3300 472038 472038 0 0.00 0 44713 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 22:39:37 to 28-06-2022 22:39:41.

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:24 2 8192000000 8801711424 8813842432 7737296 0.094 2 2 3 0 7878384 66144 74944
20:09:25 28009 8192000000 9899809312 10206588928 978133264 10.666 100 91 182 0 1007452768 5596192 23723312
20:10:59 22963 8192000000 9950891120 10272739328 1021400944 11.086 100 81 162 0 1049398032 7393264 20603824
20:12:28 15305 8192000000 9977744848 10308984832 1063282112 11.488 100 87 174 0 1094870688 9804704 21783872
20:13:46 11015 8192000000 10026413200 10374103040 1113057376 11.961 100 77 154 0 1141633728 10366976 18209376
20:15:00 6894 8192000000 10079707232 10440843264 1168459936 12.482 100 71 142 0 1195094048 10404368 16229744
20:16:11 3448 8192000000 10126043760 10494357504 1212597520 12.893 100 87 171 1 1244854448 13169536 19087392
20:17:17 3087 8192000000 10155282592 10529751040 1241010592 13.156 100 85 170 0 1274683616 14905360 18767664
20:18:22 3248 8192000000 10173987952 10550681600 1261802880 13.347 100 88 176 1 1294325200 13067552 19454768
20:19:26 956 8192000000 10204437200 10588667904 1291182576 13.615 100 84 163 1 1321867984 12466256 18219152
20:20:28 1494 8192000000 10208891744 10603143168 1303518880 13.727 100 79 157 1 1332861568 11720704 17621984
20:21:33 4598 8192000000 10204801600 10612428800 1312411648 13.808 100 36 73 0 1332141200 12247584 7481968
20:22:42 9282 8192000000 10193063936 10616913920 1318009440 13.859 100 15 30 0 1327350640 6091344 3249856
20:23:52 11657 8192000000 10209045936 10630017024 1326960144 13.940 100 19 38 0 1337575808 6553520 4062144
20:25:08 23819 8192000000 10235750160 10647347200 1338605936 14.045 100 51 103 1 1356939984 5892208 12441840
20:26:34 6649 8192000000 10233352560 10654208000 1338323376 14.042 100 36 70 0 1352180064 6289584 7567104
20:27:41 7509 8192000000 10230338144 10647363584 1323645824 13.910 99 26 52 1 1335490736 5947936 5896976
20:28:50 18853 8192000000 10202000896 10602811392 1289782720 13.602 98 15 30 0 1298404400 5308992 3312688
20:30:10 17280 8192000000 10203824304 10621374464 1288720976 13.593 98 13 25 0 1296383520 4923328 2739216
20:31:31 6082 8192000000 10144041024 10549428224 1238336816 13.131 98 38 76 1 1253041600 5805920 8898864
20:32:39 8176 8192000000 10138057552 10536804352 1214819680 12.914 97 31 62 0 1227955440 5529136 7606624
20:33:49 69756 8192000000 10150412624 10563923968 1268456384 13.407 100 43 84 0 1283985696 4531504 10997808
20:35:59 8097 8192000000 10172508176 10580377600 1272042752 13.440 99 24 46 0 1282768704 5655680 5070272
20:37:11 6334 8192000000 10187094368 10599714816 1297031648 13.668 100 21 43 0 1307404640 5473616 4899376
20:38:19 15357 8192000000 10216898704 10625241088 1319536048 13.873 100 22 43 1 1328834016 5006992 4290976
20:39:44 40412 8192000000 10243514976 10650185728 1333611840 14.000 100 62 123 0 1356365872 8592864 14161168
20:41:33 17546 8192000000 10252002848 10664484864 1344045344 14.094 100 50 100 1 1368263808 12522976 11695488
20:42:55 26086 8192000000 10265955536 10686795776 1359102848 14.229 100 34 69 1 1381944272 14910832 7930592
20:44:29 11361 8192000000 10288202576 10707902464 1372465088 14.349 100 57 113 1 1401318464 15207264 13646112
20:45:43 17024 8192000000 10291409584 10710110208 1375405440 14.375 100 49 98 0 1404698848 18109648 11183760
20:47:08 15424 8192000000 10291630608 10712330240 1364472256 14.277 99 43 86 0 1396035024 20280256 11282512
20:48:36 8625 8192000000 10307926192 10727600128 1382549840 14.439 100 49 96 0 1414002224 19976688 11475696
20:49:50 8219 8192000000 10313571648 10731048960 1363862240 14.272 98 53 106 1 1403851584 26542896 13446448
20:51:03 3176 8192000000 10336784688 10755559424 1400353744 14.598 100 62 122 0 1445787504 31175728 14258032
20:52:12 4737 8192000000 10326255904 10741305344 1380712368 14.423 99 64 126 1 1424977088 28766176 15498544
20:53:22 6841 8192000000 10348681728 10765443072 1404930224 14.639 100 66 130 2 1457874240 36587104 16356912
20:54:32 5068 8192000000 10338962240 10740703232 1385272336 14.464 100 73 144 2 1437056976 33568688 18215952
20:55:42 3519 8192000000 10341742752 10744565760 1373553312 14.359 99 75 142 0 1433514144 42681760 17279072
20:56:50 2918 8192000000 10349873088 10763345920 1392424464 14.527 100 66 131 1 1447349872 39218848 15706560
20:57:58 3419 8192000000 10361694064 10776412160 1404804464 14.638 100 64 124 1 1467188624 46366512 16017648
20:59:12 4072 8192000000 10357354944 10773397504 1399882432 14.594 99 60 111 1 1454383200 39813840 14686928
21:00:21 2648 8192000000 10377112720 10788536320 1405806752 14.647 100 67 131 0 1476289088 53607584 16874752
21:01:30 6554 8192000000 10372846544 10805080064 1403179840 14.623 99 41 82 1 1473260912 58574864 11506208
21:02:40 7752 8192000000 10362237856 10791108608 1400119776 14.596 100 40 80 0 1464151712 52829376 11202560
21:04:26 25023 8192000000 10312853600 10754441216 1346831728 14.119 99 21 42 0 1410495808 57212160 6451920
21:06:06 8670 8192000000 10287657216 10719469568 1328553104 13.954 98 26 50 0 1383329280 47245440 7530736
21:07:19 53064 8192000000 10284950864 10720243712 1359965328 14.237 100 18 36 1 1387024624 22761552 4297744
21:10:00 29100 8192000000 10285264592 10719535104 1362611456 14.261 99 19 38 0 1382207136 15074016 4521664
21:11:30 82736 8192000000 10281187488 10732793856 1371549152 14.341 100 16 32 0 1395131264 20280368 3301744
21:13:54 75255 8192000000 10310667488 10743783424 1365966848 14.291 99 45 88 0 1385066224 7428688 11670688
21:16:11 36171 8192000000 10300466304 10738774016 1372480704 14.349 99 31 62 1 1392585488 12299056 7805728
21:17:55 6060 8192000000 10298321936 10739671040 1379691840 14.414 100 57 112 0 1407082768 14088560 13302368
21:19:02 42318 8192000000 10302164976 10758135808 1390942720 14.514 100 13 26 0 1404025376 9947088 3135568
21:20:50 37708 8192000000 10305625936 10763108352 1389062560 14.498 99 12 24 0 1408137712 15710864 3364288
21:22:28 51305 8192000000 10306668752 10755989504 1397451184 14.572 100 14 27 0 1418612544 17701088 3460272
21:24:23 79964 8192000000 10288620352 10749952000 1368167808 14.311 98 12 24 0 1380196400 9441456 2587136
21:26:48 27908 8192000000 10300532048 10756845568 1384197616 14.454 99 22 39 0 1394272784 6133872 3941296
21:28:17 59296 8192000000 10291646368 10761908224 1398721888 14.584 100 12 24 1 1408973008 7377296 2873824
21:30:19 75681 8192000000 10309683680 10773958656 1392031824 14.524 99 12 24 0 1409936576 15118112 2786640
21:32:35 44953 8192000000 10325499040 10781933568 1394835968 14.549 99 35 70 0 1419753504 16999584 7917952
21:34:31 53080 8192000000 10317304016 10775937024 1364467776 14.277 97 24 48 0 1393162400 22656240 6038384
21:36:40 137405 8192000000 10296700848 10740219904 1296335936 13.662 93 17 32 0 1313441808 13406352 3699520
21:40:00 106126 8192000000 10283638352 10731847680 1333118640 13.995 96 16 32 0 1346671616 10117904 3435072
21:42:47 97417 8192000000 10268236144 10714132480 1356662720 14.207 98 31 61 1 1371761152 7458848 7639584
21:45:26 58410 8192000000 10276356864 10748526592 1370967408 14.336 99 17 34 0 1389370736 14872480 3530848
21:47:31 87892 8192000000 10266472880 10718068736 1307604288 13.764 95 12 25 0 1319922272 9726144 2591840
21:50:02 62450 8192000000 10248496624 10706513920 1306139856 13.751 95 24 48 0 1329354784 17195840 6019088
21:52:14 31875 8192000000 10248591712 10689953792 1305054048 13.741 97 21 41 0 1321897840 12123504 4720288
21:53:52 45927 8192000000 10264189648 10705981440 1319823248 13.875 97 30 59 0 1342063456 15356928 6883280
21:55:47 54832 8192000000 10285618432 10717310976 1346613648 14.117 99 30 62 0 1361225584 7166640 7445296
21:57:43 11661 8192000000 10273150272 10712715264 1357130864 14.212 100 41 82 1 1377773200 11604992 9037344
21:58:59 23174 8192000000 10292961616 10726912000 1366498512 14.296 100 57 112 1 1393369072 13967552 12903008
22:00:23 98111 8192000000 10263309600 10708160512 1316513232 13.845 97 12 24 0 1335520736 16688448 2319056
22:03:08 126941 8192000000 10272963088 10731520000 1350708512 14.154 99 31 63 0 1372697712 15097328 6891872
22:07:27 61971 8192000000 10264061712 10727841792 1370760960 14.334 100 7 14 0 1379781936 7665584 1355392
22:09:29 49927 8192000000 10268761856 10733260800 1356725440 14.208 98 14 28 0 1367708784 8103184 2880160
22:11:29 29040 8192000000 10282952976 10740707328 1384912352 14.460 100 17 34 0 1396998192 8695408 3390432
22:13:06 27234 8192000000 10298406416 10755063808 1386323568 14.473 100 22 44 0 1404397600 13576960 4497072
22:14:37 42160 8192000000 10297526416 10747465728 1378132176 14.400 99 31 62 1 1396909168 11194112 7582880
22:16:27 73076 8192000000 10290003680 10742251520 1327782192 13.947 95 19 38 0 1340496784 8651184 4063408
22:18:46 56894 8192000000 10298250080 10761646080 1352813584 14.173 97 14 28 0 1373041824 17072320 3155920
22:20:44 23655 8192000000 10291734784 10735529984 1366965232 14.300 99 33 66 0 1382165008 7994128 7205648
22:22:08 43854 8192000000 10307504016 10759057408 1360960544 14.246 98 23 46 0 1379874384 13979264 4934576
22:23:55 93876 8192000000 10293988608 10748198912 1319726160 13.874 95 13 26 1 1334701696 11972912 3002624
22:26:40 56709 8192000000 10287355152 10743545856 1380827616 14.424 100 10 20 1 1394572608 11524256 2220736
22:28:37 19473 8192000000 10282282368 10743427072 1376105056 14.382 99 32 61 0 1394957568 12131056 6721456
22:29:57 66523 8192000000 10281823328 10750078976 1388271488 14.490 100 12 24 0 1399614832 8410944 2932400
22:32:04 45245 8192000000 10280748144 10734833664 1351330224 14.159 97 15 31 0 1368844384 14102992 3411168
22:33:50 77881 8192000000 10261978704 10736820224 1317808080 13.857 96 15 30 0 1341714928 20470704 3436144
22:36:08 54126 8192000000 10267757232 10723262464 1344880592 14.101 98 35 68 1 1362874080 10049008 7944480
22:38:03 25175 8192000000 9121177552 9363492864 282884448 3.337 21 2 4 0 289943632 6721712 337472

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-06-2022 22:39:43 to 28-06-2022 22:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 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 28-06-2022 22:39:59 to 28-06-2022 22:40:01.

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          239634
MON$OLDEST_ACTIVE               241370
MON$OLDEST_SNAPSHOT             241370
MON$NEXT_TRANSACTION            241370
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2029920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33605 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33605 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33605 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33605 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Tue Jun 28 22:38:33 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:31: 
--- end of diff output ---

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

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