Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.04.2022 01:40
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 16387256 kB
MemAvailable 31570376 kB
Buffers 0 kB
Cached 15357212 kB
SwapCached 2688 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-04-12 01:38:15
DTS_END                         2022-04-12 01:38:15.4050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41096
TRN_ID                          248927
ATT_ID                          681
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: 9758.43 17389 1171012
customer order: creation 184.50 323 22140
customer order: refuse 16.40 337 1968
order to supplier: creation 48.23 1235 5788
order to supplier: removal 3.55 94386 426
invoice (draft): creation 47.79 2996 5735
invoice (draft): removal 55.33 680 6640
invoice accept: apply 44.13 17739 5295
invoice accept: cancel 50.65 808 6078
customer reserve: creation 4046.50 177 485580
customer reserve: removal 4939.67 54 592760
realization accept: apply 88.07 165 10568
realization accept: cancel 98.68 92 11842
payment from customer: creation 61.71 103 7405
payment from customer: removal 6.22 70 746
payment to supplier: creation 50.84 125 6101
payment to supplier: removal 3.66 65 439
service: total inventory turnovers 4.94 1051 593
service: total monetary turnovers 6.63 20 795
service: aggregate perf. data 0.91 17707 109
service: refresh index statistics 0.03 209640 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-04-2022 01:40:55 to 12-04-2022 01:40:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8870
TEST_TIME 2 8094
TEST_TIME 3 7071
TEST_TIME 4 7033
TEST_TIME 5 6612
TEST_TIME 6 6228
TEST_TIME 7 6003
TEST_TIME 8 5879
TEST_TIME 9 5703
TEST_TIME 10 5970
TEST_TIME 11 6073
TEST_TIME 12 6182
TEST_TIME 13 6406
TEST_TIME 14 6983
TEST_TIME 15 7320
TEST_TIME 16 7987
TEST_TIME 17 8163
TEST_TIME 18 8117
TEST_TIME 19 8268
TEST_TIME 20 8688
TEST_TIME 21 8795
TEST_TIME 22 9556
TEST_TIME 23 9892
TEST_TIME 24 10109
TEST_TIME 25 10150
TEST_TIME 26 10453
TEST_TIME 27 10994
TEST_TIME 28 10954
TEST_TIME 29 10817
TEST_TIME 30 11028
TEST_TIME 31 10890
TEST_TIME 32 10692
TEST_TIME 33 10815
TEST_TIME 34 10830
TEST_TIME 35 10755
TEST_TIME 36 10793
TEST_TIME 37 10738
TEST_TIME 38 10598
TEST_TIME 39 10367
TEST_TIME 40 10240
TEST_TIME 41 10386
TEST_TIME 42 10237
TEST_TIME 43 10255
TEST_TIME 44 10236
TEST_TIME 45 10203
TEST_TIME 46 10271
TEST_TIME 47 10220
TEST_TIME 48 10279
TEST_TIME 49 10162
TEST_TIME 50 10137
TEST_TIME 51 10058
TEST_TIME 52 10011
TEST_TIME 53 10011
TEST_TIME 54 9898
TEST_TIME 55 10084
TEST_TIME 56 10097
TEST_TIME 57 10109
TEST_TIME 58 10121
TEST_TIME 59 10191
TEST_TIME 60 10129
TEST_TIME 61 10124
TEST_TIME 62 10074
TEST_TIME 63 10370
TEST_TIME 64 10312
TEST_TIME 65 10350
TEST_TIME 66 10318
TEST_TIME 67 10293
TEST_TIME 68 10458
TEST_TIME 69 10419
TEST_TIME 70 10281
TEST_TIME 71 10201
TEST_TIME 72 10189
TEST_TIME 73 10135
TEST_TIME 74 10139
TEST_TIME 75 10074
TEST_TIME 76 10072
TEST_TIME 77 9990
TEST_TIME 78 9918
TEST_TIME 79 9925
TEST_TIME 80 10012
TEST_TIME 81 9950
TEST_TIME 82 10075
TEST_TIME 83 10101
TEST_TIME 84 10088
TEST_TIME 85 10057
TEST_TIME 86 10063
TEST_TIME 87 10023
TEST_TIME 88 10012
TEST_TIME 89 10005
TEST_TIME 90 10103
TEST_TIME 91 10105
TEST_TIME 92 10139
TEST_TIME 93 10135
TEST_TIME 94 10134
TEST_TIME 95 10065
TEST_TIME 96 9992
TEST_TIME 97 9959
TEST_TIME 98 9889
TEST_TIME 99 9956
TEST_TIME 100 9913
TEST_TIME 101 9884
TEST_TIME 102 9848
TEST_TIME 103 9864
TEST_TIME 104 9789
TEST_TIME 105 9794
TEST_TIME 106 9748
TEST_TIME 107 9750
TEST_TIME 108 9735
TEST_TIME 109 9703
TEST_TIME 110 9772
TEST_TIME 111 9814
TEST_TIME 112 9807
TEST_TIME 113 9796
TEST_TIME 114 9792
TEST_TIME 115 9812
TEST_TIME 116 9809
TEST_TIME 117 9860
TEST_TIME 118 9823
TEST_TIME 119 9837
TEST_TIME 120 9765

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-04-2022 01:40:59 to 12-04-2022 01:41:02.

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 1621470 1621470 0 0.00 0 18144 6 0 0
doc_list_biud 1621470 1621470 0 0.00 0 31139 25 0 0
sp_add_invoice_to_stock 5380 5295 85 1.57 22 207508 17739 0 85
sp_cancel_adding_invoice 6078 6078 0 0.00 5 68807 809 0 0
sp_cancel_client_order 1968 1968 0 0.00 4 13661 338 0 0
sp_cancel_customer_reserve 592760 592760 0 0.00 1 18532 55 0 0
sp_cancel_pay_from_customer 746 746 0 0.00 3 5723 70 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 1715 65 0 0
sp_cancel_supplier_invoice 6653 6640 13 0.19 2 23209 680 0 13
sp_cancel_supplier_order 426 426 0 0.00 4 875928 94387 0 0
sp_cancel_write_off 11842 11842 0 0.00 1 31145 93 0 0
sp_client_order 22140 22140 0 0.00 3 30370 324 0 0
sp_customer_reserve 485580 485580 0 0.00 0 64233 178 0 0
sp_fill_shopping_cart 33660 33660 0 0.00 1 12965 33 0 0
sp_fill_shopping_cart_clo_res 485558 485558 0 0.00 0 29909 12 0 0
sp_get_clo_for_invoice 5270 5270 0 0.00 0 29430 282 0 0
sp_kill_cost_storno 19105 19105 0 0.00 0 8081 46 0 0
sp_kill_qstorno_ret_qs2qd 1204428 1204428 0 0.00 0 31137 29 0 0
sp_kill_qty_storno 635551 635551 0 0.00 0 31137 64 0 0
sp_lock_selected_doc 22033 22033 0 0.00 0 6334 4 0 0
sp_make_cost_storno 29343 29343 0 0.00 0 18126 54 0 0
sp_multiply_rows_for_pdistr 29343 29343 0 0.00 0 9860 28 0 0
sp_multiply_rows_for_qdistr 22140 22140 0 0.00 0 30360 163 0 0
sp_pay_from_customer 7405 7405 0 0.00 4 13246 104 0 0
sp_pay_to_supplier 6101 6101 0 0.00 5 12403 126 0 0
sp_payment_common 13506 13506 0 0.00 1 13239 27 0 0
sp_qd_handle_on_cancel_clo 1968 1968 0 0.00 0 13654 318 0 0
sp_qd_handle_on_invoice_upd_sts 11347 11347 0 0.00 0 19166 307 0 0
sp_qd_handle_on_reserve_upd_sts 10568 10568 0 0.00 0 18618 51 0 0
sp_reserve_write_off 10568 10568 0 0.00 6 18689 165 0 0
sp_supplier_invoice 5735 5735 0 0.00 6 88619 2996 0 0
sp_supplier_order 5788 5788 0 0.00 5 36306 1235 0 0
srv_aggregate_perf_data 109 109 0 0.00 2689 152619 17707 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1793 118871 45002 0 0
srv_find_qd_qs_mism 1121177 1121177 0 0.00 0 9043 6 0 0
srv_make_invnt_saldo 593 593 0 0.00 44 36112 1051 0 0
srv_make_money_saldo 795 795 0 0.00 1 376 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 94966 407952 209640 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5295 5295 0 0.00 0 13517 153 0 0
t$perf-norm:sp_cancel_adding_invoice 455 455 0 0.00 0 6235 116 0 0
t$perf-norm:sp_cancel_client_order 1968 1968 0 0.00 0 2660 12 0 0
t$perf-norm:sp_cancel_customer_reserve 776 776 0 0.00 0 3548 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 746 746 0 0.00 0 1374 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 3327 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 958 14 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 19294 1389 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 3106 8 0 0
t$perf-norm:sp_client_order 22140 22140 0 0.00 0 22629 17 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 6130 9 0 0
t$perf-norm:sp_pay_from_customer 7405 7405 0 0.00 0 5342 9 0 0
t$perf-norm:sp_pay_to_supplier 6101 6101 0 0.00 0 5343 9 0 0
t$perf-norm:sp_reserve_write_off 10568 10568 0 0.00 0 8001 15 0 0
t$perf-norm:sp_supplier_invoice 5735 5735 0 0.00 0 10078 18 0 0
t$perf-norm:sp_supplier_order 5788 5788 0 0.00 0 4893 13 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 485 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 357 10 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 2910 18 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 2302 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 47 13 0 0
v_add_invoice_to_stock 10708 10538 170 1.58 0 5752 15 0 170
v_all_customers 40076 40076 0 0.00 0 17 0 0 0
v_all_suppliers 31676 31676 0 0.00 0 14 1 0 0
v_all_wares 44280 44280 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 910 910 0 0.00 0 2574 9 0 0
v_cancel_client_order 3936 3936 0 0.00 0 2753 5 0 0
v_cancel_customer_prepayment 1492 1492 0 0.00 0 257 2 0 0
v_cancel_customer_reserve 1552 1552 0 0.00 0 4179 5 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 1450 6 0 0
v_cancel_supplier_invoice 836 810 26 3.11 0 155 10 0 26
v_cancel_supplier_order 852 852 0 0.00 0 810 4 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2341 11 0 0
v_max_id_clo_ord 5788 5788 0 0.00 0 1212 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 2032 10 0 0
v_max_id_ord_sup 5732 5732 0 0.00 0 5945 3 0 0
v_max_non_paid_invoice 1783 1783 0 0.00 0 6891 8 0 0
v_max_non_paid_realizn 3955 3955 0 0.00 0 4299 5 0 0
v_min_id_clo_ord 5788 5788 0 0.00 0 3975 9 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 2886 6 0 0
v_min_id_ord_sup 5732 5732 0 0.00 0 6653 8 0 0
v_min_non_paid_invoice 6101 6101 0 0.00 0 4093 14 0 0
v_min_non_paid_realizn 7405 7405 0 0.00 0 13236 21 0 0
v_our_firm 11104 11104 0 0.00 0 11 1 0 0
v_reserve_write_off 21136 21136 0 0.00 0 6085 6 0 0
x_lock_depdocs_on_canc_invoice 12313 12313 0 0.00 0 28374 109 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 18149 387 0 0
x_make_qstorno_1000_1200 5788 5788 0 0.00 1 36297 1154 0 0
x_make_qstorno_1200_2000 5735 5735 0 0.00 3 88537 2886 0 0
x_make_qstorno_2100_3300 485580 485580 0 0.00 0 64232 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-04-2022 01:41:03 to 12-04-2022 01:41:07.

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:08:18 11 8192000000 8810587728 8825704448 14875792 0.181 4 4 5 0 15103952 88896 139264
23:09:18 43614 8192000000 9913878032 10225168384 985470112 10.737 100 89 178 0 1016266336 6815552 23980672
23:11:09 34517 8192000000 9957211952 10284670976 1037214880 11.238 100 89 179 0 1069551984 9377056 22960048
23:12:48 6159 8192000000 9992130800 10329153536 1076992832 11.619 100 80 160 0 1107336144 10289008 20054304
23:13:57 8883 8192000000 10043650224 10395463680 1127862176 12.101 100 83 166 1 1159240224 11491072 19886976
23:15:12 6005 8192000000 10098561088 10458554368 1180885696 12.598 100 82 162 2 1212694752 13323104 18485952
23:16:20 3835 8192000000 10139678592 10510561280 1224174672 13.000 100 86 174 0 1257601520 14251376 19175472
23:17:26 8974 8192000000 10173290608 10557898752 1266638800 13.391 100 86 171 0 1297973168 12481296 18853072
23:18:36 1616 8192000000 10210721728 10596655104 1299556592 13.691 100 94 185 4 1333519408 12667984 21294832
23:19:39 4068 8192000000 10206879264 10608033792 1306810688 13.757 100 42 84 0 1325561920 8207008 10544224
23:20:44 6523 8192000000 10197172752 10616078336 1316810352 13.848 100 20 38 0 1327398688 6375792 4212544
23:21:52 9993 8192000000 10200660096 10623787008 1322067024 13.895 100 14 28 0 1331240560 5880512 3293024
23:23:03 40407 8192000000 10230536096 10644434944 1328367328 13.952 100 38 76 1 1343304496 6565248 8371920
23:24:46 8279 8192000000 10219764752 10643394560 1321901760 13.894 99 19 36 2 1331300672 5276048 4122864
23:25:56 7699 8192000000 10225303600 10648641536 1326200144 13.933 99 23 46 0 1337243024 5953552 5089328
23:27:04 12049 8192000000 10220673616 10637639680 1316355632 13.844 99 17 34 1 1325878016 5798784 3723600
23:28:17 30570 8192000000 10198443760 10615005184 1250304736 13.241 94 16 32 1 1259147424 5438336 3404352
23:29:49 4102 8192000000 10129010544 10524418048 1186982672 12.655 95 18 36 1 1196790480 5564704 4243104
23:30:54 8300 8192000000 10164030272 10568769536 1266269552 13.387 100 24 49 2 1277670720 5288240 6112928
23:32:06 54707 8192000000 10163651088 10560172032 1250779648 13.245 99 29 58 0 1263714704 5706528 7228528
23:34:12 3659 8192000000 10200368640 10595950592 1287057168 13.577 100 72 143 2 1312276224 7919760 17299296
23:35:17 10558 8192000000 10192729824 10603634688 1295880480 13.658 100 19 39 1 1305350528 5112848 4357200
23:36:30 11481 8192000000 10196865424 10615898112 1307459504 13.763 100 28 55 1 1320038752 5993856 6585392
23:37:41 72311 8192000000 10241177760 10650984448 1324533600 13.918 100 35 69 1 1338454512 6632720 7288192
23:39:56 18918 8192000000 10264798064 10675118080 1350712320 14.154 100 52 104 0 1374452080 11933488 11806272
23:41:29 10034 8192000000 10273270320 10691289088 1363566736 14.269 100 50 97 3 1386970320 11822208 11581376
23:42:51 4318 8192000000 10291417504 10712342528 1381876528 14.433 100 52 103 2 1405854256 11279472 12698256
23:44:03 5919 8192000000 10318288832 10737184768 1395070448 14.551 100 58 114 3 1426801824 18483872 13247504
23:45:21 13778 8192000000 10316026704 10742960128 1405003792 14.640 100 45 89 1 1431205216 14595248 11606176
23:46:46 16417 8192000000 10324569056 10747326464 1407359808 14.660 100 50 99 1 1436251792 16701568 12190416
23:48:06 9658 8192000000 10338667584 10760077312 1406282576 14.651 99 51 102 1 1439633504 20839328 12511600
23:49:23 2585 8192000000 10356702384 10774212608 1417655936 14.752 100 57 113 0 1462237056 29617808 14963312
23:50:30 1793 8192000000 10355896336 10760937472 1404014528 14.631 100 90 179 1 1460754976 34572832 22167616
23:51:36 7333 8192000000 10349395104 10760663040 1387642544 14.485 99 61 122 1 1444344240 40852784 15848912
23:52:47 6001 8192000000 10327040688 10733371392 1364493888 14.278 99 73 145 1 1421362944 38202608 18666448
23:53:56 7377 8192000000 10321363664 10737426432 1369308032 14.321 100 69 139 1 1428562240 41061776 18192432
23:55:12 15034 8192000000 10317999520 10746609664 1380763872 14.423 100 31 60 3 1427512800 38663776 8085152
23:56:35 7129 8192000000 10319822112 10729943040 1361147056 14.248 99 65 128 0 1415723952 38559472 16017424
23:57:48 11621 8192000000 10331342752 10751434752 1364466512 14.277 99 43 87 0 1421331024 44693536 12170976
23:59:09 21063 8192000000 10323388784 10754928640 1366700544 14.297 99 26 52 1 1423113840 48885776 7527520
00:00:40 9125 8192000000 10324403600 10761551872 1374280400 14.365 100 39 78 1 1439014432 53808928 10925104
00:02:00 18793 8192000000 10294612144 10729676800 1344609616 14.099 98 26 52 1 1387796496 36150224 7036656
00:03:23 48236 8192000000 10311701744 10749149184 1373314880 14.357 100 29 58 0 1413991328 33156096 7520352
00:05:22 54229 8192000000 10284728928 10722603008 1367506400 14.305 100 17 34 0 1389555232 17805408 4243424
00:07:57 110999 8192000000 10297350784 10737758208 1362542944 14.260 99 12 24 0 1382271296 16929904 2798448
00:10:49 67041 8192000000 10300622944 10754183168 1377273920 14.392 99 12 24 1 1396663856 16418864 2971072
00:13:11 85672 8192000000 10292747056 10749640704 1348400704 14.133 97 7 14 1 1367203728 17489136 1313888
00:15:39 30895 8192000000 10322449904 10761273344 1397247472 14.570 100 27 52 0 1410682752 7094704 6340576
00:17:18 98907 8192000000 10322581040 10773487616 1389783488 14.504 99 34 68 0 1413721200 15184704 8753008
00:20:01 44096 8192000000 10312379520 10760372224 1366135632 14.292 97 38 76 1 1383970624 9316784 8518208
00:21:56 44315 8192000000 10305102400 10760642560 1397246448 14.570 100 24 49 0 1410707040 7887168 5573424
00:23:43 31757 8192000000 10335123008 10777296896 1406113616 14.649 100 33 66 1 1425834864 11068384 8652864
00:25:19 36523 8192000000 10297208544 10769588224 1371310432 14.339 98 15 30 2 1387878544 13263536 3304576
00:26:58 53862 8192000000 10297371888 10753515520 1349748624 14.145 96 11 22 0 1364641760 12228896 2664240
00:29:20 42919 8192000000 10293993504 10756284416 1387861712 14.487 100 18 36 0 1409564320 16633696 5068912
00:31:39 4083 8192000000 10280516624 10743447552 1366197648 14.293 99 29 55 0 1384044048 11136256 6710144
00:32:44 50843 8192000000 10258941456 10725744640 1330765312 13.974 98 15 29 2 1357272816 22611360 3896144
00:34:47 66136 8192000000 10267209088 10712481792 1329497376 13.963 98 11 22 0 1349386000 17175888 2712736
00:36:57 26433 8192000000 10282567168 10729283584 1349996816 14.147 99 37 75 0 1369275152 11423056 7855280
00:38:24 27283 8192000000 10282807392 10720825344 1355911952 14.201 99 23 42 1 1366531376 6666544 3952880
00:39:53 48017 8192000000 10316291840 10756263936 1363720352 14.271 99 40 80 1 1391627744 18804176 9103216
00:42:42 14334 8192000000 10286463264 10728099840 1363636320 14.270 99 28 56 0 1378046208 8510160 5899728
00:44:01 47143 8192000000 10268271776 10706001920 1279952624 13.513 93 39 78 1 1297525312 9312192 8260496
00:45:49 67939 8192000000 10284120560 10745585664 1342895280 14.084 98 19 38 0 1365129152 18041360 4192512
00:48:23 99707 8192000000 10252094624 10703777792 1318893200 13.867 97 23 46 0 1333259568 8777264 5589104
00:51:03 10656 8192000000 10270859968 10705321984 1354323024 14.186 99 61 120 2 1375910848 7767264 13820560
00:52:16 78095 8192000000 10253220800 10710847488 1310383328 13.790 96 29 58 1 1331457904 14141296 6933280
00:54:34 53625 8192000000 10276447360 10730758144 1346115760 14.113 98 15 30 0 1365505520 15727040 3662720
00:56:28 54983 8192000000 10296571200 10746908672 1380873792 14.424 100 24 48 0 1410670656 22982848 6814016
00:58:30 72583 8192000000 10292596880 10756075520 1358561600 14.224 99 22 44 1 1390126128 26327952 5236576
01:00:49 62795 8192000000 10285560592 10748121088 1360756784 14.244 99 14 28 3 1381243104 16969632 3516688
01:02:53 19911 8192000000 10291185552 10735751168 1356027136 14.202 98 28 56 0 1369798032 7132080 6638816
01:04:16 56996 8192000000 10301789808 10740482048 1350311536 14.150 98 25 50 0 1365620576 10022112 5286928
01:06:15 33106 8192000000 10310641248 10759208960 1382371648 14.438 100 34 68 1 1403191008 13305744 7513616
01:07:51 44040 8192000000 10310282752 10757779456 1376245248 14.383 99 28 56 1 1401908144 19665840 5997056
01:09:41 85104 8192000000 10306186336 10765410304 1379372848 14.411 99 12 24 0 1395588368 13983488 2232032
01:12:06 109567 8192000000 10292854432 10755444736 1368370416 14.312 98 15 30 0 1383032480 11427136 3234928
01:15:00 81095 8192000000 10304522544 10766118912 1375096896 14.373 98 16 32 0 1389770768 11335408 3338464
01:17:22 23072 8192000000 10295957504 10768007168 1387467152 14.483 99 26 50 0 1403823184 10548160 5807872
01:18:47 66423 8192000000 10275726016 10740166656 1323007040 13.904 95 15 30 0 1333352928 7017312 3328576
01:20:55 71381 8192000000 10269058224 10742284288 1365881808 14.290 99 7 13 1 1378216720 11313840 1021072
01:23:06 63477 8192000000 10266836368 10739785728 1356094720 14.202 98 17 34 3 1367531136 7425232 4011184
01:25:30 52756 8192000000 10258081760 10718371840 1308617392 13.774 95 17 34 1 1319665552 7409680 3638480
01:27:27 66023 8192000000 10287686992 10753892352 1358112272 14.220 99 12 24 0 1381259200 19694096 3452832
01:29:42 118871 8192000000 10276411760 10731454464 1306668208 13.756 95 14 28 1 1321350208 11848784 2833216
01:32:44 76830 8192000000 10282558736 10729127936 1337459616 14.034 97 16 32 0 1353150320 11868080 3822624
01:35:01 90137 8192000000 10288857008 10739322880 1323106688 13.905 96 17 34 0 1342311744 15639264 3565792
01:37:32 73006 8192000000 9748472208 10128809984 846550336 9.365 62 14 24 2 858511248 9116592 2844320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-04-2022 01:41:09 to 12-04-2022 01:41:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-04-2022 01:41:25 to 12-04-2022 01:41:27.

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          248884
MON$OLDEST_ACTIVE               249106
MON$OLDEST_SNAPSHOT             249106
MON$NEXT_TRANSACTION            249106
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                       2054560
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:241: 
:242: mscs-test-161.budzdorov.ru    Tue Apr 12 01:38:51 2022
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
:245: 
:246: mscs-test-161.budzdorov.ru    Tue Apr 12 01:38:54 2022
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:248: 
:249: 
:250: mscs-test-161.budzdorov.ru    Tue Apr 12 01:39:07 2022
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:252: 
:253: 
:254: mscs-test-161.budzdorov.ru    Tue Apr 12 01:39:10 2022
:255:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:256: 
--- end of diff output ---

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

12.04.22 01:41:28. 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-04-12 01:41:30 No severe PSQL-related problems occured
12.04.22 01:41:31. End of report.