Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 16: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 16788496 kB
MemAvailable 31629156 kB
Buffers 0 kB
Cached 14958536 kB
SwapCached 2204 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-01 16:38:12
DTS_END                         2022-04-01 16:38:12.0330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36272
TRN_ID                          247306
ATT_ID                          633
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: 9345.86 20388 1121503
customer order: creation 182.85 344 21942
customer order: refuse 15.48 328 1857
order to supplier: creation 48.13 1257 5775
order to supplier: removal 3.41 99820 409
invoice (draft): creation 47.56 3184 5707
invoice (draft): removal 51.68 818 6201
invoice accept: apply 43.83 17272 5260
invoice accept: cancel 46.83 815 5620
customer reserve: creation 3976.25 173 477150
customer reserve: removal 4616.75 58 554010
realization accept: apply 89.61 175 10753
realization accept: cancel 86.28 107 10354
payment from customer: creation 62.82 113 7538
payment from customer: removal 6.27 73 752
payment to supplier: creation 51.65 134 6198
payment to supplier: removal 3.53 95 423
service: total inventory turnovers 5.14 933 617
service: total monetary turnovers 6.92 28 830
service: aggregate perf. data 0.86 21420 103
service: refresh index statistics 0.03 260603 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-04-2022 16:39:39 to 01-04-2022 16:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7223
TEST_TIME 2 6636
TEST_TIME 3 6186
TEST_TIME 4 6038
TEST_TIME 5 5769
TEST_TIME 6 5363
TEST_TIME 7 5532
TEST_TIME 8 5383
TEST_TIME 9 5395
TEST_TIME 10 5591
TEST_TIME 11 6122
TEST_TIME 12 6287
TEST_TIME 13 6583
TEST_TIME 14 6826
TEST_TIME 15 6852
TEST_TIME 16 6981
TEST_TIME 17 7026
TEST_TIME 18 7957
TEST_TIME 19 8680
TEST_TIME 20 8838
TEST_TIME 21 9144
TEST_TIME 22 9475
TEST_TIME 23 10048
TEST_TIME 24 10216
TEST_TIME 25 10621
TEST_TIME 26 11056
TEST_TIME 27 10962
TEST_TIME 28 11020
TEST_TIME 29 10993
TEST_TIME 30 10832
TEST_TIME 31 10798
TEST_TIME 32 10749
TEST_TIME 33 10628
TEST_TIME 34 10455
TEST_TIME 35 10281
TEST_TIME 36 10316
TEST_TIME 37 10100
TEST_TIME 38 10244
TEST_TIME 39 10157
TEST_TIME 40 10292
TEST_TIME 41 10165
TEST_TIME 42 10106
TEST_TIME 43 9990
TEST_TIME 44 9799
TEST_TIME 45 9860
TEST_TIME 46 9924
TEST_TIME 47 9816
TEST_TIME 48 9764
TEST_TIME 49 9601
TEST_TIME 50 9780
TEST_TIME 51 9754
TEST_TIME 52 9666
TEST_TIME 53 9759
TEST_TIME 54 9741
TEST_TIME 55 9845
TEST_TIME 56 9770
TEST_TIME 57 9855
TEST_TIME 58 9860
TEST_TIME 59 9820
TEST_TIME 60 9976
TEST_TIME 61 9965
TEST_TIME 62 10005
TEST_TIME 63 9901
TEST_TIME 64 9863
TEST_TIME 65 9797
TEST_TIME 66 9746
TEST_TIME 67 9740
TEST_TIME 68 9687
TEST_TIME 69 9633
TEST_TIME 70 9558
TEST_TIME 71 9588
TEST_TIME 72 9642
TEST_TIME 73 9600
TEST_TIME 74 9572
TEST_TIME 75 9605
TEST_TIME 76 9655
TEST_TIME 77 9632
TEST_TIME 78 9815
TEST_TIME 79 9810
TEST_TIME 80 9748
TEST_TIME 81 9784
TEST_TIME 82 9808
TEST_TIME 83 9841
TEST_TIME 84 9851
TEST_TIME 85 9766
TEST_TIME 86 9802
TEST_TIME 87 9724
TEST_TIME 88 9708
TEST_TIME 89 9672
TEST_TIME 90 9600
TEST_TIME 91 9585
TEST_TIME 92 9564
TEST_TIME 93 9566
TEST_TIME 94 9600
TEST_TIME 95 9606
TEST_TIME 96 9614
TEST_TIME 97 9678
TEST_TIME 98 9648
TEST_TIME 99 9691
TEST_TIME 100 9670
TEST_TIME 101 9653
TEST_TIME 102 9693
TEST_TIME 103 9667
TEST_TIME 104 9700
TEST_TIME 105 9736
TEST_TIME 106 9753
TEST_TIME 107 9723
TEST_TIME 108 9665
TEST_TIME 109 9616
TEST_TIME 110 9612
TEST_TIME 111 9598
TEST_TIME 112 9583
TEST_TIME 113 9537
TEST_TIME 114 9515
TEST_TIME 115 9487
TEST_TIME 116 9478
TEST_TIME 117 9451
TEST_TIME 118 9425
TEST_TIME 119 9417
TEST_TIME 120 9350

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-04-2022 16:39:42 to 01-04-2022 16:39:46.

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 1564438 1564438 0 0.00 0 17676 7 0 0
doc_list_biud 1564435 1564435 0 0.00 0 31799 26 0 0
sp_add_invoice_to_stock 5379 5260 119 2.21 26 148752 17272 0 119
sp_cancel_adding_invoice 5620 5620 0 0.00 4 68342 816 0 0
sp_cancel_client_order 1857 1857 0 0.00 4 12875 329 0 0
sp_cancel_customer_reserve 554010 554010 0 0.00 1 21148 58 0 0
sp_cancel_pay_from_customer 752 752 0 0.00 2 5232 73 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 13583 95 0 0
sp_cancel_supplier_invoice 6217 6201 16 0.25 2 31827 818 0 16
sp_cancel_supplier_order 409 409 0 0.00 5 1110264 99820 0 0
sp_cancel_write_off 10354 10354 0 0.00 1 8081 108 0 0
sp_client_order 21942 21942 0 0.00 3 22131 345 0 0
sp_customer_reserve 477150 477150 0 0.00 0 39361 174 0 0
sp_fill_shopping_cart 33416 33416 0 0.00 1 18690 39 0 0
sp_fill_shopping_cart_clo_res 477140 477140 0 0.00 0 12150 13 0 0
sp_get_clo_for_invoice 5249 5249 0 0.00 0 21049 278 0 0
sp_kill_cost_storno 17149 17149 0 0.00 0 13577 54 0 0
sp_kill_qstorno_ret_qs2qd 1124984 1124984 0 0.00 0 31791 32 0 0
sp_kill_qty_storno 594452 594452 0 0.00 0 31797 68 0 0
sp_lock_selected_doc 22144 22144 0 0.00 0 8868 7 0 0
sp_make_cost_storno 29737 29737 0 0.00 0 15825 56 0 0
sp_multiply_rows_for_pdistr 29736 29736 0 0.00 0 14696 30 0 0
sp_multiply_rows_for_qdistr 21942 21942 0 0.00 0 22122 157 0 0
sp_pay_from_customer 7538 7538 0 0.00 4 16657 113 0 0
sp_pay_to_supplier 6198 6198 0 0.00 4 14560 134 0 0
sp_payment_common 13735 13735 0 0.00 2 14209 32 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 12868 305 0 0
sp_qd_handle_on_invoice_upd_sts 10869 10869 0 0.00 0 23654 326 0 0
sp_qd_handle_on_reserve_upd_sts 10752 10752 0 0.00 0 18804 49 0 0
sp_reserve_write_off 10753 10753 0 0.00 6 18832 175 0 0
sp_supplier_invoice 5707 5707 0 0.00 6 70069 3184 0 0
sp_supplier_order 5775 5775 0 0.00 6 49459 1257 0 0
srv_aggregate_perf_data 103 103 0 0.00 2189 426303 21421 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2404 129944 46546 0 0
srv_find_qd_qs_mism 1071996 1071996 0 0.00 0 12086 6 0 0
srv_make_invnt_saldo 617 617 0 0.00 35 87128 933 0 0
srv_make_money_saldo 830 830 0 0.00 0 4160 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 118187 374036 260603 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5260 5260 0 0.00 0 14488 198 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 5343 88 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 3533 10 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1948 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 752 752 0 0.00 0 2849 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 341 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 773 12 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 28660 1652 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 2809 9 0 0
t$perf-norm:sp_client_order 21942 21942 0 0.00 0 13542 21 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 892 6 0 0
t$perf-norm:sp_pay_from_customer 7538 7538 0 0.00 0 10427 14 0 0
t$perf-norm:sp_pay_to_supplier 6198 6198 0 0.00 0 9952 14 0 0
t$perf-norm:sp_reserve_write_off 10753 10753 0 0.00 0 14590 18 0 0
t$perf-norm:sp_supplier_invoice 5707 5707 0 0.00 0 13611 23 0 0
t$perf-norm:sp_supplier_order 5775 5775 0 0.00 0 7329 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 468 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 301 5 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 4468 28 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 3238 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10736 10498 238 2.21 0 12364 20 0 238
v_all_customers 39990 39990 0 0.00 0 19 1 0 0
v_all_suppliers 31810 31810 0 0.00 0 142 1 0 0
v_all_wares 43882 43882 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 897 6 0 0
v_cancel_client_order 3714 3714 0 0.00 0 1114 3 0 0
v_cancel_customer_prepayment 1504 1504 0 0.00 0 1625 4 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 3658 8 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 2365 7 0 0
v_cancel_supplier_invoice 916 884 32 3.49 0 418 14 0 32
v_cancel_supplier_order 818 818 0 0.00 0 2461 6 0 0
v_cancel_write_off 2150 2150 0 0.00 0 660 10 0 0
v_max_id_clo_ord 5772 5772 0 0.00 0 2959 2 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 1629 12 0 0
v_max_id_ord_sup 5703 5703 0 0.00 0 7925 9 0 0
v_max_non_paid_invoice 1767 1767 0 0.00 0 1518 3 0 0
v_max_non_paid_realizn 3986 3986 0 0.00 0 3918 5 0 0
v_min_id_clo_ord 5772 5772 0 0.00 0 9097 12 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 1223 3 0 0
v_min_id_ord_sup 5703 5703 0 0.00 0 9796 10 0 0
v_min_non_paid_invoice 6197 6197 0 0.00 0 13023 23 0 0
v_min_non_paid_realizn 7538 7538 0 0.00 0 14201 26 0 0
v_our_firm 10996 10996 0 0.00 0 17 1 0 0
v_reserve_write_off 21504 21504 0 0.00 0 3591 6 0 0
x_lock_depdocs_on_canc_invoice 11379 11379 0 0.00 0 15736 112 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 17086 381 0 0
x_make_qstorno_1000_1200 5775 5775 0 0.00 1 49411 1177 0 0
x_make_qstorno_1200_2000 5707 5707 0 0.00 3 63579 3040 0 0
x_make_qstorno_2100_3300 477150 477150 0 0.00 0 34330 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2022 16:39:47 to 01-04-2022 16:39:51.

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
14:08:15 3 8192000000 8800354832 8812068864 6630496 0.080 2 2 2 0 6731920 58640 42784
14:09:16 21535 8192000000 9899497136 10207334400 976909152 10.654 100 91 182 1 1007166912 5857536 24400224
14:10:49 23425 8192000000 9958007984 10281213952 1038052048 11.246 100 91 182 0 1070206832 8714432 23440352
14:12:21 11795 8192000000 9992285664 10329145344 1076949712 11.618 100 78 156 0 1106057424 10404336 18703376
14:13:39 12430 8192000000 10047506288 10393067520 1125950208 12.083 100 82 165 0 1157156624 11879520 19326896
14:14:56 7268 8192000000 10074046336 10431561728 1163836176 12.439 100 75 150 2 1194613504 13479808 17297520
14:16:08 4641 8192000000 10123060608 10493083648 1212330208 12.891 100 72 144 1 1240646192 11941952 16374032
14:17:16 4534 8192000000 10163026656 10542985216 1253674656 13.272 100 72 142 1 1282926976 13894928 15357392
14:18:24 2265 8192000000 10204081696 10583199744 1290025920 13.604 100 92 183 1 1323284480 13632176 19626384
14:19:27 11078 8192000000 10211304688 10599497728 1302428304 13.717 100 69 135 1 1326794304 8901296 15464704
14:20:39 3705 8192000000 10199932304 10605281280 1310810912 13.793 100 39 78 1 1332614928 13595296 8208720
14:21:43 22955 8192000000 10198523776 10622447616 1316452160 13.845 100 23 46 4 1328222480 6533456 5236864
14:23:43 6459 8192000000 10227910336 10641010688 1325383392 13.925 100 32 64 1 1343703552 11105872 7214288
14:24:51 4125 8192000000 10237500432 10654011392 1337558944 14.035 100 39 78 2 1353784768 7212144 9013680
14:25:56 14317 8192000000 10229268000 10649321472 1336975536 14.030 100 15 28 2 1345905872 5809648 3120688
14:27:10 35713 8192000000 10220770304 10647502848 1326847920 13.939 99 32 64 1 1340060944 5478176 7734848
14:28:48 5697 8192000000 10215331984 10632531968 1312757216 13.811 99 34 68 2 1326863120 6396672 7709232
14:29:56 12143 8192000000 10156902848 10565312512 1217624272 12.940 95 14 28 0 1225741984 4863504 3254208
14:31:09 63992 8192000000 10116349824 10502840320 1192888512 12.710 95 27 54 0 1204299376 5102032 6308832
14:33:17 8022 8192000000 10166255680 10571587584 1264772176 13.374 99 40 78 2 1279374960 5671936 8930848
14:34:25 8536 8192000000 10173236224 10580742144 1280967872 13.522 100 22 44 1 1291185584 5206112 5011600
14:35:38 12081 8192000000 10188960864 10605563904 1300132032 13.696 100 19 38 1 1309761088 5782512 3846544
14:36:54 22706 8192000000 10221631648 10623209472 1306617968 13.755 100 78 161 1 1331588160 5276048 19694144
14:38:17 5327 8192000000 10237691920 10646884352 1330502016 13.972 100 59 120 0 1354885184 9968688 14414480
14:39:37 14571 8192000000 10263327088 10676129792 1350995168 14.156 100 56 112 1 1375566992 11910272 12661552
14:41:02 54814 8192000000 10275371552 10706280448 1375499040 14.376 100 38 76 2 1400872752 15368736 10004976
14:43:39 12107 8192000000 10299591552 10728873984 1385897664 14.469 100 30 60 2 1412019920 18912832 7209424
14:45:06 27807 8192000000 10322035808 10750558208 1400002528 14.595 100 46 92 0 1432349456 21532240 10814688
14:46:53 11527 8192000000 10337455408 10760634368 1407442672 14.661 100 51 100 2 1447525280 28400224 11682384
14:48:08 2996 8192000000 10363880624 10778181632 1405707024 14.646 99 73 146 2 1459089536 35545440 17837072
14:49:17 3214 8192000000 10378243088 10792341504 1414859392 14.727 99 84 163 2 1472477808 38273520 19344896
14:50:24 3274 8192000000 10377840160 10801106944 1430191136 14.863 100 58 117 1 1485806704 41138320 14477248
14:51:33 7673 8192000000 10379309792 10801876992 1423768768 14.806 100 64 128 0 1487019376 46980320 16270288
14:52:45 2404 8192000000 10374403072 10788511744 1418124800 14.756 100 85 170 1 1483688000 44454848 21108352
14:53:55 9476 8192000000 10365361936 10774593536 1401384976 14.607 100 73 146 2 1470146816 49670464 19091376
14:55:10 3523 8192000000 10349453120 10761797632 1380335664 14.420 99 66 132 0 1455295888 57101312 17858912
14:56:18 2504 8192000000 10376074944 10790158336 1393950288 14.541 100 88 177 1 1480566896 63119728 23496880
14:57:23 14961 8192000000 10343429088 10776948736 1397655888 14.574 100 41 82 1 1453227920 43759008 11813024
14:58:42 10108 8192000000 10309648768 10738917376 1332140624 13.986 97 45 90 2 1388212912 43708640 12363648
14:59:57 15939 8192000000 10306598880 10740908032 1352598544 14.171 99 27 53 0 1405665744 45443280 7623920
15:01:16 10420 8192000000 10315164624 10742964224 1356378672 14.205 99 41 80 1 1409620816 42463088 10779056
15:02:31 26632 8192000000 10281019632 10719723520 1312137232 13.805 97 17 34 0 1355508896 37991584 5380080
15:03:58 44119 8192000000 10265693184 10707779584 1308022304 13.768 98 12 23 0 1350087280 39111072 2953904
15:05:45 42343 8192000000 10255021328 10693345280 1342584080 14.081 100 32 64 0 1361809376 11847696 7377600
15:07:28 90812 8192000000 10263611760 10698338304 1312403536 13.808 97 32 65 1 1334768560 14633232 7731792
15:10:01 80683 8192000000 10274680688 10700943360 1344698768 14.100 99 37 73 0 1363429536 9315488 9415280
15:12:34 55640 8192000000 10268299232 10715598848 1362695280 14.262 99 11 22 0 1377287616 11746976 2845360
15:14:31 58105 8192000000 10280796864 10724974592 1357189680 14.212 98 13 25 1 1370004704 9141888 3673136
15:16:39 49260 8192000000 10281879984 10725277696 1361557104 14.251 99 21 42 0 1379931184 12717712 5656368
15:18:33 31727 8192000000 10276059968 10729078784 1371454224 14.340 100 14 27 1 1384754320 10052912 3247184
15:21:42 97107 8192000000 10275281248 10738774016 1358059056 14.220 98 16 32 0 1376469328 14766880 3643392
15:24:28 38457 8192000000 10264446384 10719412224 1359765568 14.235 99 12 24 0 1368864256 6502944 2595744
15:26:07 51400 8192000000 10286110896 10749517824 1389281680 14.499 100 19 36 3 1405410352 11390640 4738032
15:28:04 64978 8192000000 10290076592 10774118400 1391010112 14.515 100 7 14 1 1408140128 15294256 1835760
15:30:25 28303 8192000000 10302644688 10754150400 1394386224 14.545 100 33 67 0 1412495072 10471136 7637712
15:31:59 34491 8192000000 10297131200 10744270848 1366976928 14.300 98 19 38 2 1382137376 10815424 4345024
15:33:35 73297 8192000000 10296516960 10745794560 1354015920 14.184 97 12 24 0 1368234944 11498528 2720496
15:35:52 63210 8192000000 10288668704 10735407104 1309238592 13.779 94 22 41 2 1324056192 10135072 4682528
15:37:55 58492 8192000000 10274649040 10730221568 1316677744 13.847 95 18 36 1 1339095072 18444960 3972368
15:39:54 129944 8192000000 10248021424 10689695744 1264895632 13.375 92 17 34 0 1277392944 9103712 3393600
15:43:16 51482 8192000000 10273596368 10719178752 1330507984 13.972 97 40 80 1 1351519904 11841872 9170048
15:45:22 27279 8192000000 10272744944 10719608832 1366778016 14.298 100 28 56 0 1382065680 8897920 6389744
15:47:51 67332 8192000000 10240015648 10692358144 1301114752 13.705 96 11 22 0 1315463248 11673728 2674768
15:49:58 81058 8192000000 10243488800 10690826240 1299861776 13.694 97 8 16 0 1316971968 15219872 1890320
15:52:21 68219 8192000000 10245125760 10691727360 1304830288 13.739 97 17 34 0 1324400912 15826160 3744464
15:54:30 33495 8192000000 10263545936 10699522048 1330157744 13.969 98 17 34 0 1347366352 13336688 3871920
15:56:05 93169 8192000000 10269423120 10723905536 1347781616 14.128 99 9 18 0 1368436496 18910784 1744096
15:58:42 16267 8192000000 10279419200 10713743360 1356466992 14.206 100 45 89 2 1378937328 12469520 10000816
16:00:00 44583 8192000000 10274463488 10724196352 1346793760 14.119 99 12 24 1 1366137536 16440144 2903632
16:01:45 70886 8192000000 10276718176 10723074048 1349937232 14.147 99 20 40 1 1369580352 14794464 4848656
16:03:57 112887 8192000000 10267610736 10731126784 1329634144 13.964 97 13 26 0 1345963952 13648096 2681712
16:06:51 59806 8192000000 10268657504 10742571008 1374081200 14.364 100 9 18 0 1389719968 13110464 2528304
16:08:52 84166 8192000000 10287302112 10754129920 1374432464 14.367 99 14 28 0 1395934096 18468816 3032816
16:11:36 69019 8192000000 10295164624 10755575808 1380898448 14.425 99 10 20 0 1396500080 13426000 2175632
16:13:46 52244 8192000000 10311792416 10769666048 1377504944 14.394 98 13 26 0 1393163536 12632288 3026304
16:15:38 44897 8192000000 10296356720 10734706688 1340539008 14.062 96 14 27 1 1357230944 13875584 2816352
16:17:23 42636 8192000000 10295381760 10754875392 1368505216 14.314 98 21 42 0 1382609344 9481760 4622368
16:19:10 26831 8192000000 10309641216 10760192000 1387412048 14.483 99 37 74 0 1406464752 10780752 8271952
16:20:40 34383 8192000000 10291846912 10738356224 1364392752 14.277 98 27 54 0 1379402224 8595248 6414224
16:22:26 91316 8192000000 10279042720 10737528832 1295368224 13.653 94 19 38 1 1320101456 20318704 4414528
16:24:58 108967 8192000000 10266836720 10708807680 1307735344 13.766 95 32 64 0 1326665504 11510096 7420064
16:27:58 66047 8192000000 10254443984 10710716416 1318654240 13.865 97 21 41 0 1338608208 14686432 5267536
16:30:08 70079 8192000000 10248886208 10710376448 1341515984 14.071 98 7 14 0 1349066960 5793920 1757056
16:32:19 70412 8192000000 10248429072 10710343680 1317715216 13.856 96 9 18 0 1326846656 6940608 2190832
16:34:37 52340 8192000000 10259973680 10720698368 1361310288 14.249 100 12 24 0 1372679968 8708208 2661472
16:36:31 60883 8192000000 10268734864 10720493568 1351420464 14.160 99 10 20 0 1365228032 11790000 2017568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 01-04-2022 16:39:53 to 01-04-2022 16:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 16:40:08 to 01-04-2022 16:40:10.

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          245745
MON$OLDEST_ACTIVE               247454
MON$OLDEST_SNAPSHOT             247454
MON$NEXT_TRANSACTION            247454
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                       2022848
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:806: 
:807: mscs-test-161.budzdorov.ru    Fri Apr  1 16:38:16 2022
:808:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:809: 
--- end of diff output ---

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

01.04.22 16:40:11. 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-01 16:40:14 No severe PSQL-related problems occured
01.04.22 16:40:15. End of report.