Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.2022 19: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 15295928 kB
MemAvailable 30567160 kB
Buffers 0 kB
Cached 16442232 kB
SwapCached 2184 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.11
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-10-08 19:38:30
DTS_END                         2022-10-08 19:38:30.1640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35112
TRN_ID                          237146
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: 9085.54 22507 1090265
customer order: creation 164.24 381 19709
customer order: refuse 14.63 387 1755
order to supplier: creation 44.76 1275 5371
order to supplier: removal 3.28 116990 393
invoice (draft): creation 43.73 3609 5247
invoice (draft): removal 52.04 850 6245
invoice accept: apply 40.44 20542 4853
invoice accept: cancel 46.83 877 5620
customer reserve: creation 3752.18 202 450261
customer reserve: removal 4641.18 64 556941
realization accept: apply 78.59 211 9431
realization accept: cancel 79.03 113 9484
payment from customer: creation 56.13 111 6735
payment from customer: removal 5.20 95 624
payment to supplier: creation 48.63 142 5836
payment to supplier: removal 3.21 100 385
service: total inventory turnovers 4.48 1349 538
service: total monetary turnovers 6.27 39 752
service: aggregate perf. data 0.68 31390 81
service: refresh index statistics 0.03 271408 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2022 19:39:58 to 08-10-2022 19:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9534
TEST_TIME 2 5567
TEST_TIME 3 5335
TEST_TIME 4 5260
TEST_TIME 5 5148
TEST_TIME 6 4638
TEST_TIME 7 4713
TEST_TIME 8 4855
TEST_TIME 9 4954
TEST_TIME 10 5410
TEST_TIME 11 5910
TEST_TIME 12 6334
TEST_TIME 13 6485
TEST_TIME 14 6698
TEST_TIME 15 6807
TEST_TIME 16 7074
TEST_TIME 17 7909
TEST_TIME 18 8071
TEST_TIME 19 8024
TEST_TIME 20 8150
TEST_TIME 21 8504
TEST_TIME 22 8482
TEST_TIME 23 8570
TEST_TIME 24 8968
TEST_TIME 25 9135
TEST_TIME 26 9171
TEST_TIME 27 9571
TEST_TIME 28 9815
TEST_TIME 29 10283
TEST_TIME 30 11257
TEST_TIME 31 10931
TEST_TIME 32 10810
TEST_TIME 33 10819
TEST_TIME 34 10697
TEST_TIME 35 10554
TEST_TIME 36 10362
TEST_TIME 37 10229
TEST_TIME 38 10201
TEST_TIME 39 10087
TEST_TIME 40 10031
TEST_TIME 41 10134
TEST_TIME 42 10083
TEST_TIME 43 10009
TEST_TIME 44 9895
TEST_TIME 45 9826
TEST_TIME 46 9680
TEST_TIME 47 9830
TEST_TIME 48 9711
TEST_TIME 49 9583
TEST_TIME 50 9557
TEST_TIME 51 9665
TEST_TIME 52 9571
TEST_TIME 53 9470
TEST_TIME 54 9352
TEST_TIME 55 9446
TEST_TIME 56 9377
TEST_TIME 57 9294
TEST_TIME 58 9373
TEST_TIME 59 9270
TEST_TIME 60 9367
TEST_TIME 61 9418
TEST_TIME 62 9435
TEST_TIME 63 9441
TEST_TIME 64 9492
TEST_TIME 65 9434
TEST_TIME 66 9478
TEST_TIME 67 9544
TEST_TIME 68 9464
TEST_TIME 69 9447
TEST_TIME 70 9618
TEST_TIME 71 9623
TEST_TIME 72 9601
TEST_TIME 73 9626
TEST_TIME 74 9624
TEST_TIME 75 9519
TEST_TIME 76 9498
TEST_TIME 77 9447
TEST_TIME 78 9417
TEST_TIME 79 9424
TEST_TIME 80 9420
TEST_TIME 81 9359
TEST_TIME 82 9359
TEST_TIME 83 9310
TEST_TIME 84 9285
TEST_TIME 85 9215
TEST_TIME 86 9182
TEST_TIME 87 9143
TEST_TIME 88 9183
TEST_TIME 89 9195
TEST_TIME 90 9157
TEST_TIME 91 9226
TEST_TIME 92 9245
TEST_TIME 93 9293
TEST_TIME 94 9277
TEST_TIME 95 9362
TEST_TIME 96 9371
TEST_TIME 97 9333
TEST_TIME 98 9371
TEST_TIME 99 9351
TEST_TIME 100 9411
TEST_TIME 101 9455
TEST_TIME 102 9471
TEST_TIME 103 9475
TEST_TIME 104 9391
TEST_TIME 105 9389
TEST_TIME 106 9382
TEST_TIME 107 9331
TEST_TIME 108 9339
TEST_TIME 109 9318
TEST_TIME 110 9268
TEST_TIME 111 9281
TEST_TIME 112 9260
TEST_TIME 113 9234
TEST_TIME 114 9172
TEST_TIME 115 9172
TEST_TIME 116 9149
TEST_TIME 117 9143
TEST_TIME 118 9129
TEST_TIME 119 9146
TEST_TIME 120 9094

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2022 19:40:02 to 08-10-2022 19:40:05.

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 1506782 1506782 0 0.00 0 25508 7 0 0
doc_list_biud 1506777 1506777 0 0.00 0 25219 30 0 0
sp_add_invoice_to_stock 4941 4853 88 1.78 44 203771 20542 0 88
sp_cancel_adding_invoice 5620 5620 0 0.00 3 64057 878 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 8727 387 0 0
sp_cancel_customer_reserve 556941 556941 0 0.00 1 25220 65 0 0
sp_cancel_pay_from_customer 624 624 0 0.00 3 9002 95 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 3 6092 100 0 0
sp_cancel_supplier_invoice 6251 6245 6 0.09 1 18942 851 0 6
sp_cancel_supplier_order 393 393 0 0.00 7 1047183 116991 0 0
sp_cancel_write_off 9484 9484 0 0.00 1 8339 114 0 0
sp_client_order 19709 19709 0 0.00 3 29654 381 0 0
sp_customer_reserve 450261 450261 0 0.00 0 74933 202 0 0
sp_fill_shopping_cart 30318 30318 0 0.00 1 21490 43 0 0
sp_fill_shopping_cart_clo_res 450250 450250 0 0.00 0 13017 15 0 0
sp_get_clo_for_invoice 4842 4842 0 0.00 0 23214 360 0 0
sp_kill_cost_storno 16113 16113 0 0.00 0 9265 60 0 0
sp_kill_qstorno_ret_qs2qd 1130004 1130004 0 0.00 0 25217 35 0 0
sp_kill_qty_storno 594708 594708 0 0.00 0 25219 76 0 0
sp_lock_selected_doc 19870 19870 0 0.00 0 11653 6 0 0
sp_make_cost_storno 26844 26844 0 0.00 0 25506 59 0 0
sp_multiply_rows_for_pdistr 26843 26843 0 0.00 0 14839 35 0 0
sp_multiply_rows_for_qdistr 19709 19709 0 0.00 0 17569 152 0 0
sp_pay_from_customer 6735 6735 0 0.00 4 17961 111 0 0
sp_pay_to_supplier 5836 5836 0 0.00 5 25512 143 0 0
sp_payment_common 12570 12570 0 0.00 1 14590 28 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 8720 353 0 0
sp_qd_handle_on_invoice_upd_sts 10459 10459 0 0.00 0 18940 381 0 0
sp_qd_handle_on_reserve_upd_sts 9431 9431 0 0.00 0 19081 73 0 0
sp_reserve_write_off 9431 9431 0 0.00 6 19219 212 0 0
sp_supplier_invoice 5247 5247 0 0.00 6 115878 3610 0 0
sp_supplier_order 5371 5371 0 0.00 5 30467 1275 0 0
srv_aggregate_perf_data 81 81 0 0.00 3183 584396 31391 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 948 132580 41007 0 0
srv_find_qd_qs_mism 1043962 1043962 0 0.00 0 9501 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 77 31748 1349 0 0
srv_make_money_saldo 752 752 0 0.00 1 5699 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 68323 423490 271408 0 0
t$perf-abend:sp_add_invoice_to_stock 88 88 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 4853 4853 0 0.00 0 19318 215 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 4310 122 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 6494 17 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 14882 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 624 624 0 0.00 0 3518 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 218 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1439 23 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 34224 1967 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 5432 13 0 0
t$perf-norm:sp_client_order 19709 19709 0 0.00 0 26201 24 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 5080 14 0 0
t$perf-norm:sp_pay_from_customer 6735 6735 0 0.00 0 8598 18 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 4062 11 0 0
t$perf-norm:sp_reserve_write_off 9431 9431 0 0.00 0 14336 22 0 0
t$perf-norm:sp_supplier_invoice 5247 5247 0 0.00 0 6619 19 0 0
t$perf-norm:sp_supplier_order 5371 5371 0 0.00 0 11800 21 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 748 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 582 10 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 1371 26 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1207 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 57 16 0 0
v_add_invoice_to_stock 9854 9678 176 1.78 0 8735 21 0 176
v_all_customers 35932 35932 0 0.00 0 31 1 0 0
v_all_suppliers 29910 29910 0 0.00 0 73 1 0 0
v_all_wares 39416 39416 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 1203 8 0 0
v_cancel_client_order 3510 3510 0 0.00 0 5170 8 0 0
v_cancel_customer_prepayment 1248 1248 0 0.00 0 1723 5 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 6208 15 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 6066 15 0 0
v_cancel_supplier_invoice 824 812 12 1.45 0 2358 20 0 12
v_cancel_supplier_order 786 786 0 0.00 0 1724 6 0 0
v_cancel_write_off 1926 1926 0 0.00 0 5055 17 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 360 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 2439 16 0 0
v_max_id_ord_sup 5240 5240 0 0.00 0 5979 6 0 0
v_max_non_paid_invoice 1490 1490 0 0.00 0 187 3 0 0
v_max_non_paid_realizn 3521 3521 0 0.00 0 2680 5 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 6436 7 0 0
v_min_id_clo_res 949 949 0 0.00 0 3582 8 0 0
v_min_id_ord_sup 5240 5240 0 0.00 0 5489 7 0 0
v_min_non_paid_invoice 5835 5835 0 0.00 0 14500 17 0 0
v_min_non_paid_realizn 6735 6735 0 0.00 0 14542 22 0 0
v_our_firm 9912 9912 0 0.00 0 33 1 0 0
v_reserve_write_off 18862 18862 0 0.00 0 7294 9 0 0
x_lock_depdocs_on_canc_invoice 11459 11459 0 0.00 0 23084 156 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 14236 499 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 1 30415 1190 0 0
x_make_qstorno_1200_2000 5247 5247 0 0.00 3 115807 3456 0 0
x_make_qstorno_2100_3300 450261 450261 0 0.00 0 74929 183 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2022 19:40:06 to 08-10-2022 19:40:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:34 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:35 31484 8192000000 9910116000 10222608384 994498016 10.825 100 85 170 0 1023544800 6957216 22089568
17:11:10 24580 8192000000 9966799232 10292097024 1046509392 11.327 100 95 190 0 1079963568 9561232 23892944
17:12:39 12165 8192000000 9993005968 10326941696 1076451200 11.614 100 82 164 0 1107241136 11045808 19744128
17:13:54 4638 8192000000 10024731232 10370318336 1113199088 11.963 100 84 165 0 1144532464 11628288 19705088
17:15:02 5844 8192000000 10068799504 10431037440 1159136464 12.395 100 74 148 0 1191576288 15606096 16833728
17:16:09 5088 8192000000 10109672096 10474536960 1195684560 12.736 100 75 148 1 1225022384 12723648 16614176
17:17:16 2255 8192000000 10146492432 10518794240 1237713088 13.125 100 80 158 0 1267018672 11855168 17450416
17:18:20 1117 8192000000 10191468112 10571894784 1274060096 13.459 100 83 167 1 1306892048 13672336 19159616
17:19:23 8351 8192000000 10201099888 10586763264 1290467920 13.608 100 74 139 2 1317612112 11201008 15943184
17:20:33 7156 8192000000 10184172880 10594029568 1296585824 13.664 100 23 46 0 1308260384 6677408 4997152
17:21:40 7041 8192000000 10195054080 10610216960 1311969504 13.804 100 14 27 1 1320845456 6139808 2736144
17:22:48 7129 8192000000 10202229584 10622763008 1322743360 13.902 100 15 32 0 1331772576 5529920 3499296
17:24:00 28287 8192000000 10214787280 10642276352 1332015968 13.985 100 28 56 0 1345267440 5875344 7376128
17:25:45 3024 8192000000 10240071888 10652356608 1334219616 14.005 100 49 98 0 1355200896 10613152 10368128
17:26:50 8211 8192000000 10228783488 10650517504 1335283184 14.015 100 21 43 0 1346448960 5919936 5245840
17:27:58 13480 8192000000 10212442208 10636587008 1315892240 13.839 99 14 28 0 1324465600 5550448 3022912
17:29:15 6381 8192000000 10180205232 10587590656 1264759408 13.374 98 33 62 3 1279156784 7336848 7060528
17:30:22 5558 8192000000 10143739360 10540523520 1228776608 13.043 98 25 50 0 1240453520 5871264 5805648
17:31:28 28996 8192000000 10130163872 10512666624 1190351200 12.687 96 57 113 0 1209503728 5536816 13615712
17:32:58 7591 8192000000 10144856352 10549760000 1256173888 13.295 100 27 55 0 1268926128 6237008 6515232
17:34:07 9776 8192000000 10168483232 10574774272 1261658336 13.345 99 35 70 0 1280938992 11719088 7561568
17:35:20 10932 8192000000 10185692576 10596290560 1291596832 13.619 100 22 44 0 1302265616 5701168 4967616
17:36:38 28972 8192000000 10191239328 10606796800 1297369984 13.671 100 20 40 0 1307736560 5394192 4972384
17:38:10 9636 8192000000 10211816144 10628210688 1320172272 13.878 100 24 48 0 1329941712 4044224 5725216
17:39:25 5279 8192000000 10244628512 10655272960 1336638128 14.027 100 54 109 0 1358526432 10199712 11688592
17:40:39 55818 8192000000 10269551344 10689241088 1358137584 14.221 100 54 108 0 1385606928 15271232 12198112
17:42:40 27710 8192000000 10278733120 10703417344 1368358720 14.312 100 40 80 0 1391632544 14258256 9015568
17:44:13 9652 8192000000 10308904496 10723254272 1387859984 14.487 100 60 119 1 1418732176 17639840 13232352
17:45:38 10851 8192000000 10309068784 10734149632 1394908416 14.550 100 44 86 0 1425057184 19791168 10357600
17:46:55 12611 8192000000 10332266304 10755670016 1406278640 14.651 100 54 109 0 1444340144 24104864 13956640
17:48:18 6953 8192000000 10343757936 10768343040 1411842000 14.700 100 51 102 1 1450626496 26086832 12697664
17:49:32 5477 8192000000 10348454608 10770231296 1409349888 14.678 100 67 134 1 1453618128 28195056 16073184
17:50:42 2356 8192000000 10348818112 10765676544 1400768192 14.602 99 74 148 1 1447360464 29700848 16891424
17:51:51 6168 8192000000 10370939152 10785415168 1420656432 14.779 100 77 154 1 1477344272 37990064 18697776
17:53:01 3208 8192000000 10364480416 10774372352 1391820144 14.522 99 77 152 0 1457489392 46541712 19127536
17:54:11 2285 8192000000 10346985824 10750554112 1376036512 14.381 100 79 154 2 1446427216 50615872 19774832
17:55:17 2078 8192000000 10347961184 10752438272 1371080576 14.337 100 69 137 0 1448133200 58292528 18760096
17:56:26 2298 8192000000 10328835600 10736533504 1377778656 14.397 100 76 144 0 1434215312 39974720 16461936
17:57:36 944 8192000000 10353142336 10759512064 1386943088 14.479 100 78 157 0 1453944176 46550400 20450688
17:58:44 7135 8192000000 10336283792 10754142208 1382139552 14.436 100 52 98 1 1444868224 49577648 13151024
18:00:05 1435 8192000000 10356860784 10762903552 1387254256 14.481 100 83 161 2 1455376192 48046176 20075760
18:01:10 2529 8192000000 10366088112 10791112704 1402220464 14.615 100 46 91 0 1475162656 60961968 11980224
18:02:33 11689 8192000000 10351069216 10780385280 1396101536 14.560 100 48 95 1 1462649440 54357280 12190624
18:03:49 10271 8192000000 10344036512 10772963328 1388661328 14.494 100 39 79 0 1452517328 53306848 10549152
18:05:22 14937 8192000000 10333600592 10767519744 1365840288 14.290 99 30 60 0 1430331888 55569088 8922512
18:06:42 38701 8192000000 10311178544 10750267392 1355556576 14.197 100 19 37 1 1413686384 52261040 5868768
18:08:24 32393 8192000000 10294986960 10729623552 1377298288 14.392 100 28 58 0 1394201296 10889008 6014000
18:09:57 6284 8192000000 10297171520 10736742400 1387733984 14.486 100 61 121 1 1409683440 8562880 13386576
18:11:14 20014 8192000000 10305766256 10749362176 1391426528 14.519 100 32 60 1 1404528576 7636320 5465728
18:12:37 41609 8192000000 10301707552 10759450624 1398608720 14.583 100 11 22 0 1411136128 10015504 2511904
18:14:19 54230 8192000000 10297891232 10743885824 1360355968 14.241 97 19 39 0 1379299088 14068288 4874832
18:16:45 31583 8192000000 10300669968 10747412480 1373512096 14.359 99 28 56 1 1400110976 20502352 6096528
18:18:23 52876 8192000000 10287997712 10740789248 1367226272 14.302 98 24 46 0 1390751488 18326272 5198944
18:20:35 31821 8192000000 10299668288 10749112320 1374231536 14.365 99 30 60 0 1396344448 14745968 7366944
18:22:12 97956 8192000000 10303281392 10762903552 1354780896 14.190 97 18 36 0 1383555664 24048848 4725920
18:24:59 90100 8192000000 10298461552 10740490240 1360366208 14.241 98 36 66 4 1378807136 10753216 7687712
18:27:32 18035 8192000000 10324411952 10755489792 1390173824 14.507 100 59 117 0 1416904352 13549472 13181056
18:28:56 92194 8192000000 10289177168 10764464128 1390385568 14.509 100 11 23 1 1415472880 22178400 2908912
18:31:32 78586 8192000000 10305978896 10763784192 1382404880 14.438 99 9 18 0 1406403520 21757856 2240784
18:33:52 39877 8192000000 10291167840 10750521344 1377992400 14.399 99 16 33 2 1395835456 14133664 3709392
18:35:41 60393 8192000000 10312736688 10769920000 1396128512 14.561 100 29 56 0 1426342000 23528496 6684992
18:37:42 70718 8192000000 10289854256 10760183808 1389748992 14.504 100 13 26 0 1412330464 19381824 3199648
18:40:12 94975 8192000000 10284055312 10741051392 1318394864 13.862 94 6 12 0 1329004176 9691408 917904
18:42:59 45210 8192000000 10248661072 10687885312 1288688304 13.592 94 10 20 0 1297757152 6962144 2106704
18:44:47 66935 8192000000 10252412800 10706710528 1306685232 13.756 96 14 28 0 1320054272 10524480 2844560
18:47:06 31949 8192000000 10273199808 10713153536 1331841712 13.984 98 43 85 0 1357989040 16904080 9243248
18:48:42 125689 8192000000 10255394704 10697588736 1265651280 13.382 93 8 16 0 1280704816 13385712 1667824
18:52:12 132580 8192000000 10238926368 10684297216 1297548832 13.673 96 21 42 0 1317240064 14563920 5127312
18:55:27 68967 8192000000 10254792960 10713415680 1352555584 14.170 100 12 24 0 1370152512 14913024 2683904
18:58:04 45251 8192000000 10244793088 10707316736 1329268464 13.961 98 2 4 0 1340657392 11122944 265984
18:59:50 62560 8192000000 10250349024 10708217856 1323571360 13.909 97 19 38 0 1338064480 9998432 4494688
19:01:52 32732 8192000000 10251844992 10705854464 1335641952 14.018 98 9 18 0 1348126128 10297696 2186480
19:03:27 11847 8192000000 10263197264 10707353600 1357785504 14.217 100 19 37 1 1367724608 5976144 3962960
19:04:39 71795 8192000000 10267126656 10732707840 1360700432 14.244 99 7 14 0 1375847552 13571984 1575136
19:07:58 32541 8192000000 10302302016 10749882368 1385557936 14.466 100 28 52 0 1401815904 10153136 6104832
19:09:39 81642 8192000000 10301844208 10759593984 1392824496 14.531 100 23 46 0 1413835872 15940704 5070672
19:12:03 26839 8192000000 10301007648 10747924480 1379619728 14.413 99 24 46 0 1392307216 7351600 5335888
19:13:33 42421 8192000000 10307993552 10774323200 1393153200 14.534 99 22 44 0 1410446832 13011888 4281744
19:15:40 14224 8192000000 10315028064 10761248768 1396890816 14.567 100 45 84 1 1418031984 11875856 9265312
19:16:57 11327 8192000000 10326916976 10760028160 1398828320 14.585 100 56 110 0 1422111232 10691072 12591840
19:18:11 14045 8192000000 10326860352 10761031680 1391767232 14.522 100 58 116 0 1421278512 16869408 12641872
19:19:33 86465 8192000000 10291752176 10746593280 1335637808 14.018 96 12 25 1 1351393264 12953712 2801744
19:22:05 97110 8192000000 10277057552 10738696192 1350621360 14.153 98 34 63 0 1370897984 12740624 7536000
19:24:42 49739 8192000000 10262897872 10708529152 1331356000 13.979 97 34 68 0 1353336656 14778032 7202624
19:26:36 93545 8192000000 10273075696 10742489088 1357453584 14.214 99 19 38 2 1377519184 15980416 4085184
19:29:17 108453 8192000000 10264256112 10724921344 1346386912 14.115 98 11 22 0 1359298112 10213200 2698000
19:32:06 72166 8192000000 10275224944 10727448576 1365578192 14.287 100 16 32 0 1380936320 11847680 3510448
19:34:22 78596 8192000000 10253608768 10714673152 1332408224 13.989 98 6 12 0 1344019072 10459408 1151440
19:36:47 35511 8192000000 10253834880 10701758464 1339850928 14.056 99 26 52 0 1356481952 10962048 5668976
19:38:25 12186 8192000000 9120921600 9371996160 278046080 3.282 21 5 10 0 283690144 4550288 1093776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-10-2022 19:40:12 to 08-10-2022 19:40:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 88 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 08-10-2022 19:40:29 to 08-10-2022 19:40:31.

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          237432
MON$OLDEST_ACTIVE               237433
MON$OLDEST_SNAPSHOT             237433
MON$NEXT_TRANSACTION            237433
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                       2060064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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 ---
:43: 
:44: mscs-test-161.budzdorov.ru    Sat Oct  8 19:38:39 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
--- end of diff output ---

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

08.10.22 19:40:32. 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-10-08 19:40:34 No severe PSQL-related problems occured
08.10.22 19:40:36. End of report.