Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.04.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 16627500 kB
MemAvailable 31562232 kB
Buffers 0 kB
Cached 15073372 kB
SwapCached 2732 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-25 19:38:20
DTS_END                         2022-04-25 19:38:20.2440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53296
TRN_ID                          244813
ATT_ID                          738
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: 9098.04 23712 1091765
customer order: creation 173.73 363 20848
customer order: refuse 14.83 372 1780
order to supplier: creation 46.57 1316 5588
order to supplier: removal 3.43 90887 412
invoice (draft): creation 46.35 4008 5562
invoice (draft): removal 51.04 774 6125
invoice accept: apply 42.27 17563 5072
invoice accept: cancel 47.26 855 5671
customer reserve: creation 3829.78 176 459573
customer reserve: removal 4550.87 54 546104
realization accept: apply 83.68 181 10042
realization accept: cancel 78.45 111 9414
payment from customer: creation 60.19 115 7223
payment from customer: removal 5.40 116 648
payment to supplier: creation 48.61 137 5833
payment to supplier: removal 3.63 62 435
service: total inventory turnovers 4.84 950 581
service: total monetary turnovers 6.28 28 753
service: aggregate perf. data 0.81 18867 97
service: refresh index statistics 0.03 337306 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-04-2022 19:39:37 to 25-04-2022 19:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6250
TEST_TIME 2 4988
TEST_TIME 3 4540
TEST_TIME 4 4395
TEST_TIME 5 4471
TEST_TIME 6 4628
TEST_TIME 7 5179
TEST_TIME 8 5618
TEST_TIME 9 5632
TEST_TIME 10 6215
TEST_TIME 11 6220
TEST_TIME 12 6587
TEST_TIME 13 6582
TEST_TIME 14 6767
TEST_TIME 15 6851
TEST_TIME 16 7335
TEST_TIME 17 7697
TEST_TIME 18 8047
TEST_TIME 19 8572
TEST_TIME 20 8417
TEST_TIME 21 8579
TEST_TIME 22 8637
TEST_TIME 23 8926
TEST_TIME 24 8941
TEST_TIME 25 9080
TEST_TIME 26 9279
TEST_TIME 27 9375
TEST_TIME 28 9297
TEST_TIME 29 9551
TEST_TIME 30 9781
TEST_TIME 31 9678
TEST_TIME 32 9905
TEST_TIME 33 9805
TEST_TIME 34 9727
TEST_TIME 35 9913
TEST_TIME 36 9782
TEST_TIME 37 9685
TEST_TIME 38 9472
TEST_TIME 39 9380
TEST_TIME 40 9485
TEST_TIME 41 9549
TEST_TIME 42 9551
TEST_TIME 43 9501
TEST_TIME 44 9508
TEST_TIME 45 9341
TEST_TIME 46 9287
TEST_TIME 47 9247
TEST_TIME 48 9398
TEST_TIME 49 9588
TEST_TIME 50 9604
TEST_TIME 51 9588
TEST_TIME 52 9524
TEST_TIME 53 9576
TEST_TIME 54 9677
TEST_TIME 55 9627
TEST_TIME 56 9702
TEST_TIME 57 9717
TEST_TIME 58 9660
TEST_TIME 59 9747
TEST_TIME 60 9718
TEST_TIME 61 9592
TEST_TIME 62 9514
TEST_TIME 63 9441
TEST_TIME 64 9455
TEST_TIME 65 9385
TEST_TIME 66 9377
TEST_TIME 67 9330
TEST_TIME 68 9297
TEST_TIME 69 9304
TEST_TIME 70 9307
TEST_TIME 71 9326
TEST_TIME 72 9286
TEST_TIME 73 9283
TEST_TIME 74 9277
TEST_TIME 75 9398
TEST_TIME 76 9404
TEST_TIME 77 9378
TEST_TIME 78 9361
TEST_TIME 79 9326
TEST_TIME 80 9438
TEST_TIME 81 9458
TEST_TIME 82 9433
TEST_TIME 83 9485
TEST_TIME 84 9405
TEST_TIME 85 9377
TEST_TIME 86 9288
TEST_TIME 87 9310
TEST_TIME 88 9264
TEST_TIME 89 9238
TEST_TIME 90 9216
TEST_TIME 91 9169
TEST_TIME 92 9207
TEST_TIME 93 9188
TEST_TIME 94 9140
TEST_TIME 95 9149
TEST_TIME 96 9201
TEST_TIME 97 9230
TEST_TIME 98 9261
TEST_TIME 99 9266
TEST_TIME 100 9300
TEST_TIME 101 9346
TEST_TIME 102 9328
TEST_TIME 103 9297
TEST_TIME 104 9340
TEST_TIME 105 9370
TEST_TIME 106 9406
TEST_TIME 107 9397
TEST_TIME 108 9326
TEST_TIME 109 9264
TEST_TIME 110 9265
TEST_TIME 111 9300
TEST_TIME 112 9275
TEST_TIME 113 9255
TEST_TIME 114 9233
TEST_TIME 115 9186
TEST_TIME 116 9143
TEST_TIME 117 9158
TEST_TIME 118 9160
TEST_TIME 119 9162
TEST_TIME 120 9102

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-04-2022 19:39:41 to 25-04-2022 19:39:44.

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 1518731 1518731 0 0.00 0 37824 7 0 0
doc_list_biud 1518731 1518731 0 0.00 0 53988 25 0 0
sp_add_invoice_to_stock 5149 5072 77 1.49 18 189425 17563 0 77
sp_cancel_adding_invoice 5671 5671 0 0.00 4 55114 855 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 14913 373 0 0
sp_cancel_customer_reserve 546104 546104 0 0.00 1 44993 54 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 2 22716 116 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 2268 63 0 0
sp_cancel_supplier_invoice 6132 6125 7 0.11 1 54067 775 0 7
sp_cancel_supplier_order 412 412 0 0.00 18 725786 90888 0 0
sp_cancel_write_off 9414 9414 0 0.00 1 9601 112 0 0
sp_client_order 20848 20848 0 0.00 3 32773 364 0 0
sp_customer_reserve 459573 459573 0 0.00 0 68612 177 0 0
sp_fill_shopping_cart 31995 31995 0 0.00 1 32015 48 0 0
sp_fill_shopping_cart_clo_res 459567 459567 0 0.00 0 23990 14 0 0
sp_get_clo_for_invoice 5066 5066 0 0.00 0 25997 308 0 0
sp_kill_cost_storno 16168 16168 0 0.00 0 22704 57 0 0
sp_kill_qstorno_ret_qs2qd 1108159 1108159 0 0.00 0 53987 30 0 0
sp_kill_qty_storno 584614 584614 0 0.00 0 53988 65 0 0
sp_lock_selected_doc 20946 20946 0 0.00 0 9038 6 0 0
sp_make_cost_storno 28164 28164 0 0.00 0 37765 73 0 0
sp_multiply_rows_for_pdistr 28164 28164 0 0.00 0 6092 25 0 0
sp_multiply_rows_for_qdistr 20848 20848 0 0.00 0 32745 152 0 0
sp_pay_from_customer 7223 7223 0 0.00 4 26240 116 0 0
sp_pay_to_supplier 5833 5833 0 0.00 5 26275 137 0 0
sp_payment_common 13056 13056 0 0.00 2 26218 25 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 14897 334 0 0
sp_qd_handle_on_invoice_upd_sts 10737 10737 0 0.00 0 31556 338 0 0
sp_qd_handle_on_reserve_upd_sts 10042 10042 0 0.00 0 13645 56 0 0
sp_reserve_write_off 10042 10042 0 0.00 6 33687 182 0 0
sp_supplier_invoice 5562 5562 0 0.00 5 117707 4009 0 0
sp_supplier_order 5588 5588 0 0.00 5 75509 1317 0 0
srv_aggregate_perf_data 97 97 0 0.00 3513 222585 18867 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2261 170209 46297 0 0
srv_find_qd_qs_mism 1044605 1044605 0 0.00 0 27205 5 0 0
srv_make_invnt_saldo 581 581 0 0.00 42 21062 950 0 0
srv_make_money_saldo 753 753 0 0.00 1 4387 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 229334 482555 337306 0 0
t$perf-abend:sp_add_invoice_to_stock 77 77 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5072 5072 0 0.00 0 14538 176 0 0
t$perf-norm:sp_cancel_adding_invoice 474 474 0 0.00 0 18608 161 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 5173 18 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 926 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 1159 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 357 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 687 11 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 30243 1676 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 9351 18 0 0
t$perf-norm:sp_client_order 20848 20848 0 0.00 0 14964 21 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 7094 11 0 0
t$perf-norm:sp_pay_from_customer 7223 7223 0 0.00 0 13199 16 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 7389 15 0 0
t$perf-norm:sp_reserve_write_off 10042 10042 0 0.00 0 5798 9 0 0
t$perf-norm:sp_supplier_invoice 5562 5562 0 0.00 0 11231 19 0 0
t$perf-norm:sp_supplier_order 5588 5588 0 0.00 0 7932 21 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 183 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 344 14 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 1164 22 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 2801 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10286 10132 154 1.49 0 6276 15 0 154
v_all_customers 37976 37976 0 0.00 0 19 0 0 0
v_all_suppliers 30514 30514 0 0.00 0 19 1 0 0
v_all_wares 41696 41696 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 948 948 0 0.00 0 518 7 0 0
v_cancel_client_order 3560 3560 0 0.00 0 1578 3 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 6677 11 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 436 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 481 4 0 0
v_cancel_supplier_invoice 760 746 14 1.84 0 204 10 0 14
v_cancel_supplier_order 824 824 0 0.00 0 3946 9 0 0
v_cancel_write_off 2030 2030 0 0.00 0 4768 17 0 0
v_max_id_clo_ord 5588 5588 0 0.00 0 1510 1 0 0
v_max_id_clo_res 976 976 0 0.00 0 3452 12 0 0
v_max_id_ord_sup 5559 5559 0 0.00 0 6633 5 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 2667 6 0 0
v_max_non_paid_realizn 3914 3914 0 0.00 0 478 3 0 0
v_min_id_clo_ord 5588 5588 0 0.00 0 1898 6 0 0
v_min_id_clo_res 976 976 0 0.00 0 3052 9 0 0
v_min_id_ord_sup 5559 5559 0 0.00 0 29585 36 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 8636 13 0 0
v_min_non_paid_realizn 7223 7223 0 0.00 0 10400 18 0 0
v_our_firm 10338 10338 0 0.00 0 16 1 0 0
v_reserve_write_off 20084 20084 0 0.00 0 4867 5 0 0
x_lock_depdocs_on_canc_invoice 11423 11423 0 0.00 0 22438 115 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 14928 389 0 0
x_make_qstorno_1000_1200 5588 5588 0 0.00 2 75496 1237 0 0
x_make_qstorno_1200_2000 5562 5562 0 0.00 2 116736 3819 0 0
x_make_qstorno_2100_3300 459573 459573 0 0.00 0 68611 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-04-2022 19:39:45 to 25-04-2022 19:39:49.

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:23 4 8192000000 8805744752 8819281920 11079712 0.135 3 3 4 0 11263632 76800 107120
17:09:25 24395 8192000000 9912915664 10222116864 991340896 10.794 100 87 174 1 1020160160 5808160 23011104
17:10:52 26482 8192000000 9951909472 10273832960 1034305168 11.210 100 84 168 1 1063861184 7569600 21986416
17:12:22 21926 8192000000 9992531968 10327281664 1076251072 11.612 100 79 158 0 1104608256 8399088 19958096
17:13:50 11190 8192000000 10050669776 10402385920 1137604576 12.193 100 80 160 1 1167601776 10447520 19549680
17:15:07 8818 8192000000 10099399904 10462191616 1189147248 12.675 100 87 171 1 1220660784 11649968 19863568
17:16:20 3803 8192000000 10138517056 10509942784 1229818240 13.052 100 85 170 0 1262283936 13435920 19029776
17:17:25 3145 8192000000 10183578528 10557038592 1259713056 13.327 100 89 176 0 1293243504 14509664 19020784
17:18:30 1933 8192000000 10202054016 10582036480 1285881568 13.567 100 95 191 1 1321284064 13956000 21446496
17:19:34 45459 8192000000 10204441696 10601771008 1299728512 13.693 100 58 116 0 1322557296 9207376 13621408
17:21:20 8720 8192000000 10196127232 10611793920 1308857376 13.776 100 22 42 0 1319960352 6525296 4577680
17:22:29 5181 8192000000 10206851728 10620162048 1317572992 13.855 100 27 52 2 1330393520 6296176 6524352
17:23:38 8541 8192000000 10215385856 10624241664 1323010112 13.904 100 22 43 3 1333847200 5736464 5100624
17:24:50 48828 8192000000 10213148208 10639560704 1328558224 13.954 100 22 44 0 1338906352 5446848 4901280
17:26:40 5344 8192000000 10234860320 10643976192 1321649856 13.892 99 33 65 2 1343541088 14635552 7255680
17:27:49 9838 8192000000 10194179152 10606538752 1273628464 13.455 97 24 47 3 1285452432 6240688 5583280
17:29:04 56819 8192000000 10164923824 10568798208 1235640240 13.106 96 37 74 1 1249984144 5415808 8928096
17:31:03 6589 8192000000 10152238736 10557603840 1244726256 13.190 98 30 60 1 1257008480 5038064 7244160
17:32:12 2473 8192000000 10153365248 10546393088 1245714192 13.199 99 28 56 1 1258284368 5498432 7071744
17:33:16 6814 8192000000 10175425920 10573398016 1273651504 13.455 100 29 58 2 1285451408 5030368 6769536
17:34:23 39282 8192000000 10172841888 10583912448 1280849232 13.521 100 31 62 0 1294187488 6583680 6754576
17:36:03 8198 8192000000 10187700032 10598612992 1294201600 13.642 100 24 48 1 1305280144 5426016 5652528
17:37:12 7000 8192000000 10187936192 10608459776 1303814768 13.730 100 24 48 2 1315298288 5760752 5722768
17:38:23 7381 8192000000 10233905696 10635939840 1327848096 13.948 100 53 105 2 1345900640 6488928 11563616
17:39:34 110260 8192000000 10253938480 10674659328 1342965952 14.084 100 49 96 3 1368589856 13921136 11702768
17:42:31 21319 8192000000 10268338224 10694209536 1368097520 14.310 100 32 64 1 1387749264 11653760 7997984
17:43:58 9331 8192000000 10281706928 10697912320 1352085648 14.166 98 48 95 0 1378676576 15503760 11087168
17:45:12 21105 8192000000 10283746464 10701619200 1362313520 14.258 99 37 74 0 1389621872 18098752 9209600
17:46:38 8258 8192000000 10304441584 10727391232 1390178496 14.507 100 48 95 0 1417264224 14744000 12341728
17:47:52 7844 8192000000 10313405728 10741911552 1395458816 14.555 100 59 117 1 1429264208 18512864 15292528
17:49:05 8818 8192000000 10316944448 10736742400 1394195648 14.543 100 59 116 3 1423668128 15337456 14135024
17:50:17 10959 8192000000 10298201184 10725511168 1371192768 14.338 98 53 105 0 1399957264 16234320 12530176
17:51:33 5325 8192000000 10314641936 10741915648 1394120608 14.543 100 56 112 1 1424624448 17120240 13383600
17:52:42 4505 8192000000 10301343968 10708373504 1361872272 14.254 100 86 170 3 1402718544 20573904 20272368
17:53:52 2260 8192000000 10314118624 10723495936 1353083152 14.175 98 79 157 2 1402670992 29819280 19768560
17:55:01 3055 8192000000 10313949248 10718310400 1353714080 14.181 99 94 187 3 1410681360 33832880 23134400
17:56:08 7558 8192000000 10278227408 10685333504 1330853136 13.975 99 61 120 3 1378225232 30937728 16434368
17:57:20 6853 8192000000 10283773728 10699079680 1350745216 14.154 100 60 120 0 1396842672 30285568 15811888
17:58:45 18634 8192000000 10283616192 10697814016 1348635744 14.135 100 39 78 1 1383323088 24376992 10310352
18:00:12 12622 8192000000 10252302384 10678935552 1333253776 13.997 99 20 40 0 1364583440 25982432 5347232
18:01:30 2951 8192000000 10289934192 10695245824 1342740144 14.082 100 66 131 0 1391484224 32802688 15941392
18:02:38 9378 8192000000 10263757280 10690695168 1329679424 13.964 99 28 56 0 1365171776 28561600 6930752
18:03:51 9841 8192000000 10271740368 10697306112 1345707472 14.109 100 27 55 1 1379496464 27175376 6613616
18:05:03 6406 8192000000 10275890992 10702282752 1352279328 14.168 100 42 83 0 1382674112 20438000 9956784
18:06:18 38493 8192000000 10279882704 10715840512 1332561392 13.990 98 15 30 3 1367988864 31517568 3909904
18:08:00 50491 8192000000 10276046592 10704617472 1335040800 14.013 98 17 35 1 1350898224 12339920 3517504
18:09:59 27100 8192000000 10271666048 10708520960 1367268992 14.303 100 11 22 0 1378236944 9019936 1948016
18:11:37 30785 8192000000 10281967616 10710880256 1359168976 14.230 99 26 51 1 1373572912 8818512 5585424
18:13:14 92164 8192000000 10293881392 10735030272 1379454352 14.412 100 10 20 0 1394142576 12199776 2488448
18:15:59 170209 8192000000 10317458736 10760749056 1392555408 14.529 100 42 84 1 1418404144 16228112 9620624
18:19:50 10068 8192000000 10316941744 10760372224 1401170496 14.605 100 44 85 4 1423146464 11162816 10813152
18:21:01 31632 8192000000 10321146000 10766155776 1405373472 14.643 100 29 58 1 1430979216 18560896 7044848
18:22:34 28235 8192000000 10297076128 10751569920 1386133456 14.471 99 21 42 0 1416801088 24868016 5799616
18:24:13 28897 8192000000 10303525536 10755129344 1378889088 14.407 99 18 36 1 1407042000 23232208 4920704
18:25:42 35691 8192000000 10322087488 10786222080 1403624080 14.627 100 17 34 1 1430859488 23446656 3788752
18:27:24 59466 8192000000 10295891344 10753327104 1385943920 14.470 99 17 34 1 1406019056 15897472 4177664
18:30:05 85632 8192000000 10290744064 10738077696 1323450144 13.908 95 17 34 0 1341380512 14036848 3893520
18:32:38 70890 8192000000 10296791888 10752684032 1369288592 14.321 99 10 20 1 1386684496 15144512 2251392
18:34:50 91758 8192000000 10258550352 10705625088 1244122544 13.184 91 17 34 0 1271688368 23350048 4215776
18:37:23 41469 8192000000 10276850832 10731159552 1357625744 14.216 100 25 50 1 1384638192 21409280 5603168
18:39:08 89285 8192000000 10224914368 10655092736 1241378912 13.159 91 21 42 1 1255149344 8944656 4825776
18:41:42 10570 8192000000 10249408960 10691600384 1337348240 14.033 100 29 58 1 1359981456 16040336 6592880
18:42:53 79997 8192000000 10254128784 10705813504 1323696352 13.910 97 21 43 0 1339658032 11266624 4695056
18:45:17 67571 8192000000 10263133728 10719481856 1348685008 14.136 99 16 32 2 1363456992 11312304 3459680
18:47:38 32480 8192000000 10267642768 10715009024 1364781472 14.280 100 22 43 3 1380358816 10351536 5225808
18:49:17 91159 8192000000 10283649088 10725834752 1323335904 13.907 96 17 34 0 1339967488 12939520 3692064
18:51:51 27846 8192000000 10278378944 10722783232 1365545648 14.287 100 39 77 2 1387051888 11716192 9790048
18:53:23 85199 8192000000 10266769456 10715578368 1273717392 13.456 93 9 18 0 1288157888 12612048 1828448
18:55:48 12813 8192000000 10298512928 10725437440 1367801680 14.307 100 48 96 2 1388255040 8686128 11767232
18:57:09 21560 8192000000 10300270192 10729725952 1367159280 14.302 100 61 121 2 1392008992 11550832 13298880
18:58:34 39359 8192000000 10287856416 10751500288 1371235664 14.338 100 12 24 0 1395611296 21658624 2717008
19:00:14 105467 8192000000 10287231008 10746236928 1374480512 14.367 99 16 32 0 1388962672 11093712 3388448
19:04:09 89594 8192000000 10287738176 10754674688 1377641792 14.395 99 15 30 0 1396935392 15769232 3524368
19:06:42 70140 8192000000 10307201888 10766921728 1405960320 14.648 100 12 24 0 1419758864 11073120 2725424
19:08:53 41660 8192000000 10313962720 10778841088 1413928000 14.719 100 11 22 0 1430404144 14236320 2239824
19:11:18 78066 8192000000 10311059392 10776461312 1372025168 14.345 97 15 30 0 1385410864 10065984 3319712
19:13:52 53007 8192000000 10304040544 10758537216 1373233712 14.356 97 15 30 0 1394712448 18190176 3288560
19:15:45 74326 8192000000 10312897632 10777337856 1401580496 14.609 100 16 32 0 1422724768 17808272 3336000
19:17:59 36079 8192000000 10306029200 10757177344 1378300176 14.401 98 30 58 2 1399222224 14069728 6852320
19:19:42 81665 8192000000 10280450928 10732965888 1309294000 13.780 94 8 16 0 1323622192 12643696 1684496
19:22:04 71022 8192000000 10268494960 10729873408 1333876848 14.002 97 29 58 0 1354731264 14415200 6439216
19:24:22 105370 8192000000 10273056848 10724663296 1341700160 14.073 98 15 30 0 1357088192 11895888 3492144
19:27:13 105704 8192000000 10300273968 10760462336 1383769456 14.450 100 26 52 1 1411757952 21560704 6427792
19:30:02 71032 8192000000 10251430560 10713595904 1307686384 13.765 95 7 14 0 1320474608 11207904 1580320
19:32:13 72114 8192000000 10238506320 10694864896 1278988240 13.504 94 11 22 0 1289530576 7863984 2678352
19:34:28 62020 8192000000 10225993968 10681749504 1300514256 13.700 96 22 44 0 1315958176 10279344 5164576
19:36:37 100249 8192000000 10242297488 10693398528 1278899968 13.503 95 10 20 0 1292162368 11075120 2187280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-04-2022 19:39:51 to 25-04-2022 19:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 77 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-04-2022 19:40:07 to 25-04-2022 19:40:09.

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          243679
MON$OLDEST_ACTIVE               244929
MON$OLDEST_SNAPSHOT             244929
MON$NEXT_TRANSACTION            244929
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                       2031904
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 ---
:1653: 
:1654: mscs-test-161.budzdorov.ru    Mon Apr 25 19:38:21 2022
:1655:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1656: 
:1657: 
:1658: mscs-test-161.budzdorov.ru    Mon Apr 25 19:38:26 2022
:1659:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1660: 
:1661: 
:1662: mscs-test-161.budzdorov.ru    Mon Apr 25 19:38:26 2022
:1663:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1664: 
--- end of diff output ---

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

25.04.22 19:40:10. 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-25 19:40:12 No severe PSQL-related problems occured
25.04.22 19:40:13. End of report.