Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.05.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 16409048 kB
MemAvailable 31433624 kB
Buffers 0 kB
Cached 15334628 kB
SwapCached 2588 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-05-24 01:38:21
DTS_END                         2022-05-24 01:38:21.6250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58918
TRN_ID                          238054
ATT_ID                          679
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: 9160.58 19562 1099269
customer order: creation 169.18 342 20302
customer order: refuse 14.65 347 1758
order to supplier: creation 44.75 1238 5370
order to supplier: removal 3.56 91555 427
invoice (draft): creation 45.51 3271 5461
invoice (draft): removal 52.97 783 6356
invoice accept: apply 41.63 17516 4995
invoice accept: cancel 47.68 816 5722
customer reserve: creation 3798.26 176 455791
customer reserve: removal 4647.35 54 557682
realization accept: apply 82.85 167 9942
realization accept: cancel 84.64 106 10157
payment from customer: creation 58.23 100 6987
payment from customer: removal 5.82 71 698
payment to supplier: creation 48.08 124 5770
payment to supplier: removal 3.58 90 430
service: total inventory turnovers 4.48 1028 538
service: total monetary turnovers 6.43 41 772
service: aggregate perf. data 0.89 21595 107
service: refresh index statistics 0.03 251822 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-05-2022 01:40:45 to 24-05-2022 01:40:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1291
TEST_TIME 2 2192
TEST_TIME 3 3877
TEST_TIME 4 4138
TEST_TIME 5 4579
TEST_TIME 6 5070
TEST_TIME 7 5338
TEST_TIME 8 5309
TEST_TIME 9 5328
TEST_TIME 10 5587
TEST_TIME 11 5598
TEST_TIME 12 5764
TEST_TIME 13 6137
TEST_TIME 14 6324
TEST_TIME 15 6524
TEST_TIME 16 6549
TEST_TIME 17 7155
TEST_TIME 18 7662
TEST_TIME 19 7739
TEST_TIME 20 8226
TEST_TIME 21 8356
TEST_TIME 22 8559
TEST_TIME 23 9193
TEST_TIME 24 9109
TEST_TIME 25 9814
TEST_TIME 26 10228
TEST_TIME 27 10138
TEST_TIME 28 10019
TEST_TIME 29 10180
TEST_TIME 30 10102
TEST_TIME 31 9937
TEST_TIME 32 9987
TEST_TIME 33 9862
TEST_TIME 34 9800
TEST_TIME 35 9668
TEST_TIME 36 9608
TEST_TIME 37 9646
TEST_TIME 38 9743
TEST_TIME 39 9723
TEST_TIME 40 9812
TEST_TIME 41 9696
TEST_TIME 42 9661
TEST_TIME 43 9698
TEST_TIME 44 9740
TEST_TIME 45 9686
TEST_TIME 46 9707
TEST_TIME 47 9709
TEST_TIME 48 9568
TEST_TIME 49 9444
TEST_TIME 50 9536
TEST_TIME 51 9395
TEST_TIME 52 9320
TEST_TIME 53 9334
TEST_TIME 54 9313
TEST_TIME 55 9424
TEST_TIME 56 9538
TEST_TIME 57 9459
TEST_TIME 58 9395
TEST_TIME 59 9383
TEST_TIME 60 9388
TEST_TIME 61 9442
TEST_TIME 62 9396
TEST_TIME 63 9352
TEST_TIME 64 9449
TEST_TIME 65 9416
TEST_TIME 66 9518
TEST_TIME 67 9442
TEST_TIME 68 9480
TEST_TIME 69 9518
TEST_TIME 70 9559
TEST_TIME 71 9585
TEST_TIME 72 9636
TEST_TIME 73 9665
TEST_TIME 74 9628
TEST_TIME 75 9540
TEST_TIME 76 9495
TEST_TIME 77 9478
TEST_TIME 78 9396
TEST_TIME 79 9456
TEST_TIME 80 9405
TEST_TIME 81 9448
TEST_TIME 82 9427
TEST_TIME 83 9428
TEST_TIME 84 9366
TEST_TIME 85 9301
TEST_TIME 86 9268
TEST_TIME 87 9311
TEST_TIME 88 9307
TEST_TIME 89 9313
TEST_TIME 90 9307
TEST_TIME 91 9308
TEST_TIME 92 9390
TEST_TIME 93 9379
TEST_TIME 94 9424
TEST_TIME 95 9452
TEST_TIME 96 9447
TEST_TIME 97 9464
TEST_TIME 98 9520
TEST_TIME 99 9529
TEST_TIME 100 9497
TEST_TIME 101 9554
TEST_TIME 102 9624
TEST_TIME 103 9552
TEST_TIME 104 9498
TEST_TIME 105 9453
TEST_TIME 106 9514
TEST_TIME 107 9466
TEST_TIME 108 9418
TEST_TIME 109 9386
TEST_TIME 110 9396
TEST_TIME 111 9420
TEST_TIME 112 9368
TEST_TIME 113 9342
TEST_TIME 114 9341
TEST_TIME 115 9330
TEST_TIME 116 9309
TEST_TIME 117 9288
TEST_TIME 118 9250
TEST_TIME 119 9190
TEST_TIME 120 9161

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-05-2022 01:40:49 to 24-05-2022 01:40:52.

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 1522181 1522181 0 0.00 0 24097 6 0 0
doc_list_biud 1522181 1522181 0 0.00 0 55331 26 0 0
sp_add_invoice_to_stock 5104 4995 109 2.13 32 158801 17517 0 109
sp_cancel_adding_invoice 5722 5722 0 0.00 4 63433 816 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 12560 347 0 0
sp_cancel_customer_reserve 557682 557682 0 0.00 1 55333 54 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 1755 72 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 4605 90 0 0
sp_cancel_supplier_invoice 6369 6356 13 0.20 2 17352 784 0 13
sp_cancel_supplier_order 427 427 0 0.00 6 826043 91555 0 0
sp_cancel_write_off 10157 10157 0 0.00 1 32298 106 0 0
sp_client_order 20302 20302 0 0.00 3 60448 342 0 0
sp_customer_reserve 455791 455791 0 0.00 0 55747 177 0 0
sp_fill_shopping_cart 31123 31123 0 0.00 1 12689 37 0 0
sp_fill_shopping_cart_clo_res 455790 455790 0 0.00 0 18557 13 0 0
sp_get_clo_for_invoice 4993 4993 0 0.00 0 42572 340 0 0
sp_kill_cost_storno 17007 17007 0 0.00 0 20848 55 0 0
sp_kill_qstorno_ret_qs2qd 1132304 1132304 0 0.00 0 55330 30 0 0
sp_kill_qty_storno 597034 597034 0 0.00 0 55331 65 0 0
sp_lock_selected_doc 20812 20812 0 0.00 0 6922 6 0 0
sp_make_cost_storno 27689 27689 0 0.00 0 14984 51 0 0
sp_multiply_rows_for_pdistr 27689 27689 0 0.00 0 8093 27 0 0
sp_multiply_rows_for_qdistr 20302 20302 0 0.00 0 60441 138 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 9257 100 0 0
sp_pay_to_supplier 5770 5770 0 0.00 5 16831 124 0 0
sp_payment_common 12757 12757 0 0.00 1 16809 25 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 12554 308 0 0
sp_qd_handle_on_invoice_upd_sts 10712 10712 0 0.00 0 27787 319 0 0
sp_qd_handle_on_reserve_upd_sts 9942 9942 0 0.00 0 16854 55 0 0
sp_reserve_write_off 9942 9942 0 0.00 6 17563 167 0 0
sp_supplier_invoice 5461 5461 0 0.00 6 106828 3271 0 0
sp_supplier_order 5370 5370 0 0.00 5 41123 1239 0 0
srv_aggregate_perf_data 107 107 0 0.00 2239 367454 21596 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1576 122898 46871 0 0
srv_find_qd_qs_mism 1052669 1052669 0 0.00 0 24096 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 36 24636 1028 0 0
srv_make_money_saldo 772 772 0 0.00 1 12788 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 208824 298991 251823 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 4995 4995 0 0.00 0 13236 150 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 4538 92 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 4051 14 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 2219 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 490 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 8278 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1985 19 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 18624 1498 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 4145 18 0 0
t$perf-norm:sp_client_order 20302 20302 0 0.00 0 11006 18 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 634 4 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 6474 12 0 0
t$perf-norm:sp_pay_to_supplier 5770 5770 0 0.00 0 6296 11 0 0
t$perf-norm:sp_reserve_write_off 9942 9942 0 0.00 0 14612 13 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 3408 13 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 5013 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 76 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 532 17 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 3924 27 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1090 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10198 9980 218 2.13 0 9381 19 0 218
v_all_customers 36924 36924 0 0.00 0 17 1 0 0
v_all_suppliers 29978 29978 0 0.00 0 16 1 0 0
v_all_wares 40602 40602 0 0.00 0 147 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 1413 8 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4982 12 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1161 6 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 3297 11 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 2627 10 0 0
v_cancel_supplier_invoice 880 854 26 2.95 0 4300 21 0 26
v_cancel_supplier_order 854 854 0 0.00 0 972 8 0 0
v_cancel_write_off 2016 2016 0 0.00 0 964 11 0 0
v_max_id_clo_ord 5370 5370 0 0.00 0 295 1 0 0
v_max_id_clo_res 985 985 0 0.00 0 2443 15 0 0
v_max_id_ord_sup 5451 5451 0 0.00 0 6210 7 0 0
v_max_non_paid_invoice 1602 1602 0 0.00 0 413 3 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 3007 4 0 0
v_min_id_clo_ord 5370 5370 0 0.00 0 3865 6 0 0
v_min_id_clo_res 985 985 0 0.00 0 8851 21 0 0
v_min_id_ord_sup 5451 5451 0 0.00 0 7246 14 0 0
v_min_non_paid_invoice 5770 5770 0 0.00 0 16805 24 0 0
v_min_non_paid_realizn 6987 6987 0 0.00 0 7734 15 0 0
v_our_firm 10096 10096 0 0.00 0 144 1 0 0
v_reserve_write_off 19884 19884 0 0.00 0 7027 9 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 20066 115 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 5665 338 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 2 41110 1167 0 0
x_make_qstorno_1200_2000 5461 5461 0 0.00 3 105764 3131 0 0
x_make_qstorno_2100_3300 455791 455791 0 0.00 0 41330 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-05-2022 01:40:53 to 24-05-2022 01:40:57.

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:24 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:25 33507 8192000000 9915965552 10225094656 987913056 10.761 100 91 182 1 1017178752 5640544 23625152
23:11:03 22690 8192000000 9952175664 10271322112 1028498816 11.154 100 87 174 0 1058710384 8283856 21927712
23:12:32 16059 8192000000 9991919648 10324746240 1076591584 11.615 100 91 182 0 1109495040 10409632 22493824
23:13:50 8183 8192000000 10043974304 10391642112 1128478480 12.107 100 78 156 0 1158053312 11044560 18530272
23:15:03 9622 8192000000 10087873488 10444435456 1169578416 12.493 100 79 158 1 1201063008 13202464 18282128
23:16:14 8961 8192000000 10121022304 10487926784 1212380208 12.891 100 83 166 0 1243860640 12778304 18702128
23:17:25 2968 8192000000 10154371984 10532806656 1250217392 13.240 100 74 146 1 1278762352 11815296 16729664
23:18:29 1630 8192000000 10198414752 10579628032 1285553920 13.564 100 90 179 3 1317777760 11476112 20747728
23:19:31 34356 8192000000 10191086528 10601132032 1302039136 13.714 100 37 74 0 1318871728 8189728 8642864
23:21:08 6889 8192000000 10195483984 10609901568 1312571280 13.809 100 27 54 0 1325820944 7401152 5848512
23:22:16 14583 8192000000 10201152320 10620432384 1316945952 13.849 100 13 26 0 1325369840 5627232 2796656
23:23:34 24076 8192000000 10217167072 10642939904 1332036560 13.986 100 36 73 0 1346739600 6406336 8296704
23:25:39 4566 8192000000 10229637392 10648453120 1335569680 14.017 100 34 68 1 1349054160 5571904 7912576
23:26:44 6247 8192000000 10222317472 10645086208 1324592960 13.918 99 24 47 2 1336059296 5764464 5701872
23:27:51 9749 8192000000 10203079920 10620735488 1291568048 13.619 97 15 30 1 1300503248 4927856 4007344
23:29:04 33089 8192000000 10166246560 10579230720 1201656656 12.792 92 35 68 0 1215152624 4752256 8743712
23:30:41 4634 8192000000 10152493552 10548617216 1208301440 12.853 95 29 58 0 1220853424 4885376 7666608
23:31:46 3915 8192000000 10155813888 10554400768 1256781760 13.300 100 34 68 4 1272597600 7886864 7928976
23:32:53 10886 8192000000 10167624896 10567819264 1267694224 13.401 100 31 62 0 1280777904 5049312 8034368
23:34:06 14627 8192000000 10147713376 10557800448 1240405712 13.150 98 24 49 0 1252084224 6084624 5593888
23:35:21 5408 8192000000 10181951824 10587729920 1284533664 13.554 100 31 62 0 1297791696 6282176 6975856
23:36:29 9012 8192000000 10190063760 10598686720 1294685072 13.647 100 25 48 3 1305516784 5119264 5712448
23:37:41 11434 8192000000 10186099936 10601086976 1296834672 13.666 100 10 20 0 1304487760 5489136 2163952
23:38:59 65844 8192000000 10228421312 10640052224 1323357632 13.907 100 41 80 4 1340038768 6667488 10013648
23:41:08 3650 8192000000 10249874224 10658521088 1341114704 14.067 100 62 124 2 1365621712 10703936 13803072
23:42:18 10731 8192000000 10271516048 10683723776 1355977680 14.201 100 60 120 1 1381117120 10948896 14190544
23:43:33 16014 8192000000 10270955312 10694627328 1367662544 14.306 100 40 78 1 1387933040 11228192 9042304
23:44:54 14832 8192000000 10299541392 10717704192 1386412080 14.474 100 57 113 1 1415031712 15450384 13169248
23:46:14 6831 8192000000 10310829152 10728112128 1382937520 14.443 99 51 100 2 1409095008 13910976 12246512
23:47:23 9877 8192000000 10318312752 10739798016 1392015392 14.524 99 52 104 0 1419963392 15691040 12256960
23:48:37 3984 8192000000 10329639424 10753343488 1405690304 14.646 100 52 104 1 1436573088 18773712 12109072
23:49:44 14354 8192000000 10323674816 10742837248 1386387456 14.474 99 58 117 1 1424989664 24273264 14328944
23:51:04 1574 8192000000 10351708144 10754142208 1402605616 14.618 100 85 163 1 1448546480 24589712 21351152
23:52:11 6614 8192000000 10311730592 10724388864 1372597648 14.350 99 57 113 1 1414357536 27449552 14310336
23:53:20 4053 8192000000 10289062064 10695348224 1347627088 14.126 100 61 122 0 1397251648 33318624 16305936
23:54:30 8373 8192000000 10316478064 10718232576 1346730528 14.118 98 61 122 0 1399960320 37568288 15661504
23:55:57 3001 8192000000 10326367600 10725552128 1367006240 14.300 100 79 155 2 1423190496 36588496 19595760
23:57:03 13089 8192000000 10327549552 10741321728 1368839120 14.317 99 50 100 0 1420065312 38172704 13053488
23:58:21 31825 8192000000 10301859904 10729938944 1346509344 14.116 98 37 73 1 1395270928 38843664 9917920
00:00:00 10157 8192000000 10306681440 10728579072 1361398128 14.250 100 35 70 0 1409723376 38568256 9756992
00:01:18 39236 8192000000 10281357760 10715795456 1347523840 14.125 100 30 58 2 1398042112 41756064 8762208
00:02:59 45417 8192000000 10277705232 10718556160 1317323664 13.852 98 24 47 1 1369892320 46024848 6543808
00:04:45 38863 8192000000 10254310048 10700898304 1340849152 14.065 100 16 32 1 1360737696 15855392 4033152
00:06:29 33265 8192000000 10259807280 10698465280 1341035680 14.067 99 22 44 0 1362565888 16246656 5283552
00:08:04 48430 8192000000 10252131360 10711486464 1335978400 14.021 99 8 15 1 1350272144 12915952 1377792
00:09:53 88413 8192000000 10271712112 10723266560 1357658832 14.216 99 6 12 0 1372141328 13657952 824544
00:12:22 28246 8192000000 10277116544 10722775040 1374055008 14.363 100 28 56 0 1390379824 10566448 5758368
00:14:51 58355 8192000000 10291469664 10738733056 1372960768 14.354 100 26 52 0 1395558496 15859360 6738368
00:16:56 15928 8192000000 10304906160 10732863488 1386134400 14.471 100 61 122 1 1409181920 8036656 15010864
00:18:13 97682 8192000000 10301618480 10741567488 1377276160 14.392 99 14 26 1 1390845120 10645312 2923648
00:20:55 122898 8192000000 10315215728 10775568384 1367162496 14.302 98 10 18 1 1388701856 19699344 1840016
00:23:58 104731 8192000000 10309581664 10769326080 1398022864 14.577 100 4 8 0 1411486864 12868864 595136
00:26:47 76655 8192000000 10325348000 10772783104 1408649072 14.672 100 29 54 3 1424042368 8965520 6427776
00:29:09 42629 8192000000 10335489936 10792878080 1404304432 14.633 99 21 40 1 1432957984 23486880 5166672
00:30:52 38525 8192000000 10351935520 10802384896 1428346896 14.847 100 55 110 0 1468220704 26758720 13115088
00:32:41 32931 8192000000 10329518080 10784301056 1415439584 14.732 100 21 42 0 1441256480 20811152 5005744
00:34:14 50528 8192000000 10299463504 10760265728 1391236464 14.517 99 19 38 0 1406535936 10631408 4668064
00:36:07 43975 8192000000 10286166080 10749755392 1383883200 14.451 99 6 12 0 1396007776 10691184 1433392
00:37:52 76001 8192000000 10294290144 10738728960 1325595456 13.927 95 22 45 0 1338476656 7931232 4949968
00:40:10 23910 8192000000 10282469936 10723086336 1347908832 14.129 98 49 91 1 1365587504 5717600 11961072
00:41:42 28881 8192000000 10294959184 10728308736 1343325680 14.087 98 40 80 2 1369057184 16362448 9369056
00:43:14 16553 8192000000 10269396128 10701291520 1347747472 14.127 100 33 66 1 1363116288 8191392 7177424
00:44:32 42921 8192000000 10270305808 10714800128 1353242592 14.177 99 16 32 0 1366688208 9605280 3840336
00:46:15 70860 8192000000 10263436128 10703978496 1270304448 13.424 93 15 30 1 1287279824 13278288 3697088
00:48:28 101613 8192000000 10249060336 10701889536 1301441072 13.708 96 10 20 0 1318513568 14808512 2263984
00:51:10 39181 8192000000 10274549040 10712018944 1331832576 13.984 98 29 58 0 1352392064 13968688 6590800
00:53:40 90548 8192000000 10285836880 10721468416 1331625392 13.982 97 28 56 1 1350725584 12232128 6868064
00:56:12 39946 8192000000 10292030304 10730274816 1371717696 14.342 100 48 95 2 1398042384 16170368 10154320
00:57:52 46937 8192000000 10244784048 10692022272 1314582704 13.828 96 15 30 0 1331690160 13626976 3480480
00:59:52 46343 8192000000 10266993232 10719236096 1354968416 14.192 99 14 28 0 1367907696 9739488 3199792
01:01:50 60480 8192000000 10260182320 10720247808 1347793664 14.128 98 8 16 0 1357269728 7587872 1888192
01:04:15 72166 8192000000 10268255936 10733961216 1360472928 14.242 99 5 10 0 1369881408 8370192 1038288
01:06:57 53975 8192000000 10278154096 10737676288 1364052752 14.274 99 18 37 0 1377792688 9805680 3934256
01:09:15 81578 8192000000 10294694032 10767171584 1389957456 14.505 100 8 16 0 1407457904 15659760 1840688
01:11:37 108321 8192000000 10322148048 10780790784 1394306544 14.544 99 14 29 1 1409694256 11728208 3659504
01:14:26 76669 8192000000 10352256064 10806702080 1417239200 14.748 100 21 42 0 1437240336 14986112 5015024
01:16:46 86880 8192000000 10302545056 10761031680 1348180352 14.131 96 10 20 0 1363488400 13540560 1767488
01:19:13 33701 8192000000 10327190208 10788888576 1404028128 14.631 100 13 26 0 1421239248 14087344 3123776
01:20:48 73956 8192000000 10317355440 10778034176 1393146208 14.534 99 15 30 2 1418247968 21016032 4085728
01:23:13 64064 8192000000 10317560032 10797662208 1411733168 14.699 100 35 70 0 1440204880 19875216 8596496
01:25:27 71963 8192000000 10297793344 10768351232 1387470224 14.483 99 6 11 0 1404509504 15904512 1134768
01:27:39 51171 8192000000 10281170160 10735452160 1318089952 13.859 95 15 30 1 1330125184 8854096 3181136
01:29:36 57177 8192000000 10244477712 10705645568 1310461792 13.790 95 7 14 0 1321987776 9981792 1544192
01:31:36 82879 8192000000 10262316800 10718965760 1306319872 13.753 95 32 64 0 1328199408 14627344 7252192
01:34:00 20031 8192000000 10250490176 10695680000 1319323632 13.871 97 23 45 2 1331164032 6549040 5291360
01:35:20 84546 8192000000 10262388240 10713612288 1310240992 13.788 96 19 38 0 1328405296 13518688 4645616
01:37:44 64929 8192000000 9428848992 9756082176 579262640 6.604 43 8 15 0 591335936 10397344 1675952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-05-2022 01:40:59 to 24-05-2022 01:41:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 24-05-2022 01:41:15 to 24-05-2022 01:41:17.

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          238820
MON$OLDEST_ACTIVE               239103
MON$OLDEST_SNAPSHOT             239103
MON$NEXT_TRANSACTION            239103
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                       2039008
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:289: 
:290: mscs-test-161.budzdorov.ru    Tue May 24 01:38:58 2022
:291:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:292: 
:293: 
:294: mscs-test-161.budzdorov.ru    Tue May 24 01:39:16 2022
:295:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:296: 
--- end of diff output ---

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

24.05.22 01:41:18. 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-05-24 01:41:20 No severe PSQL-related problems occured
24.05.22 01:41:22. End of report.