Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 01:38
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 16096392 kB
MemAvailable 31200564 kB
Buffers 0 kB
Cached 15584592 kB
SwapCached 2476 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-06-23 01:38:15
DTS_END                         2022-06-23 01:38:15.8270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39974
TRN_ID                          239079
ATT_ID                          646
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: 9681.24 21315 1161749
customer order: creation 169.49 363 20339
customer order: refuse 14.42 355 1730
order to supplier: creation 44.40 1338 5328
order to supplier: removal 3.75 99342 450
invoice (draft): creation 44.79 3380 5375
invoice (draft): removal 57.27 724 6872
invoice accept: apply 41.63 18273 4995
invoice accept: cancel 51.68 750 6201
customer reserve: creation 3890.56 178 466867
customer reserve: removal 5058.45 57 607014
realization accept: apply 81.67 171 9800
realization accept: cancel 95.31 115 11437
payment from customer: creation 58.11 137 6973
payment from customer: removal 5.73 107 687
payment to supplier: creation 48.23 139 5787
payment to supplier: removal 3.35 52 402
service: total inventory turnovers 4.73 1235 568
service: total monetary turnovers 6.80 49 816
service: aggregate perf. data 0.87 17723 104
service: refresh index statistics 0.03 281818 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-06-2022 01:38:57 to 23-06-2022 01:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3831
TEST_TIME 2 3635
TEST_TIME 3 3294
TEST_TIME 4 4102
TEST_TIME 5 4715
TEST_TIME 6 4725
TEST_TIME 7 5045
TEST_TIME 8 5862
TEST_TIME 9 6036
TEST_TIME 10 6731
TEST_TIME 11 6653
TEST_TIME 12 6826
TEST_TIME 13 6830
TEST_TIME 14 6953
TEST_TIME 15 6968
TEST_TIME 16 7353
TEST_TIME 17 7417
TEST_TIME 18 7570
TEST_TIME 19 7785
TEST_TIME 20 8257
TEST_TIME 21 8197
TEST_TIME 22 8855
TEST_TIME 23 9387
TEST_TIME 24 9657
TEST_TIME 25 9637
TEST_TIME 26 9592
TEST_TIME 27 10047
TEST_TIME 28 10828
TEST_TIME 29 10828
TEST_TIME 30 11109
TEST_TIME 31 10839
TEST_TIME 32 10779
TEST_TIME 33 10570
TEST_TIME 34 10780
TEST_TIME 35 10874
TEST_TIME 36 11003
TEST_TIME 37 10876
TEST_TIME 38 10640
TEST_TIME 39 10522
TEST_TIME 40 10489
TEST_TIME 41 10295
TEST_TIME 42 10267
TEST_TIME 43 10134
TEST_TIME 44 10160
TEST_TIME 45 10160
TEST_TIME 46 10151
TEST_TIME 47 10069
TEST_TIME 48 10065
TEST_TIME 49 9924
TEST_TIME 50 9949
TEST_TIME 51 9792
TEST_TIME 52 9991
TEST_TIME 53 9922
TEST_TIME 54 9906
TEST_TIME 55 9880
TEST_TIME 56 9786
TEST_TIME 57 9670
TEST_TIME 58 9657
TEST_TIME 59 9571
TEST_TIME 60 9630
TEST_TIME 61 9621
TEST_TIME 62 9590
TEST_TIME 63 9554
TEST_TIME 64 9561
TEST_TIME 65 9791
TEST_TIME 66 9837
TEST_TIME 67 9774
TEST_TIME 68 9837
TEST_TIME 69 9810
TEST_TIME 70 9865
TEST_TIME 71 9901
TEST_TIME 72 9892
TEST_TIME 73 9911
TEST_TIME 74 9796
TEST_TIME 75 9740
TEST_TIME 76 9722
TEST_TIME 77 9660
TEST_TIME 78 9702
TEST_TIME 79 9660
TEST_TIME 80 9593
TEST_TIME 81 9527
TEST_TIME 82 9512
TEST_TIME 83 9543
TEST_TIME 84 9572
TEST_TIME 85 9555
TEST_TIME 86 9515
TEST_TIME 87 9580
TEST_TIME 88 9605
TEST_TIME 89 9611
TEST_TIME 90 9610
TEST_TIME 91 9646
TEST_TIME 92 9698
TEST_TIME 93 9687
TEST_TIME 94 9745
TEST_TIME 95 9732
TEST_TIME 96 9812
TEST_TIME 97 9807
TEST_TIME 98 9734
TEST_TIME 99 9688
TEST_TIME 100 9671
TEST_TIME 101 9649
TEST_TIME 102 9651
TEST_TIME 103 9638
TEST_TIME 104 9611
TEST_TIME 105 9567
TEST_TIME 106 9558
TEST_TIME 107 9514
TEST_TIME 108 9506
TEST_TIME 109 9529
TEST_TIME 110 9553
TEST_TIME 111 9552
TEST_TIME 112 9562
TEST_TIME 113 9564
TEST_TIME 114 9569
TEST_TIME 115 9647
TEST_TIME 116 9650
TEST_TIME 117 9710
TEST_TIME 118 9717
TEST_TIME 119 9725
TEST_TIME 120 9682

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 01:39:01 to 23-06-2022 01:39:04.

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 1594050 1594050 0 0.00 0 25237 6 0 0
doc_list_biud 1594050 1594050 0 0.00 0 61090 28 0 0
sp_add_invoice_to_stock 5095 4995 100 1.96 33 219714 18273 0 100
sp_cancel_adding_invoice 6201 6201 0 0.00 4 63434 751 0 0
sp_cancel_client_order 1730 1730 0 0.00 4 11805 355 0 0
sp_cancel_customer_reserve 607014 607014 0 0.00 1 61093 58 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 11347 108 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 2 2349 53 0 0
sp_cancel_supplier_invoice 6881 6872 9 0.13 2 18256 725 0 9
sp_cancel_supplier_order 450 450 0 0.00 6 1125469 99342 0 0
sp_cancel_write_off 11437 11437 0 0.00 1 14328 115 0 0
sp_client_order 20339 20339 0 0.00 3 38580 363 0 0
sp_customer_reserve 466867 466867 0 0.00 0 81106 179 0 0
sp_fill_shopping_cart 31038 31038 0 0.00 1 16988 36 0 0
sp_fill_shopping_cart_clo_res 466866 466866 0 0.00 0 15781 13 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 31022 296 0 0
sp_kill_cost_storno 18727 18727 0 0.00 0 11084 59 0 0
sp_kill_qstorno_ret_qs2qd 1232787 1232787 0 0.00 0 61062 31 0 0
sp_kill_qty_storno 648498 648498 0 0.00 0 61090 67 0 0
sp_lock_selected_doc 20474 20474 0 0.00 0 7638 5 0 0
sp_make_cost_storno 27554 27554 0 0.00 0 22715 60 0 0
sp_multiply_rows_for_pdistr 27554 27554 0 0.00 0 25195 35 0 0
sp_multiply_rows_for_qdistr 20339 20339 0 0.00 0 38568 159 0 0
sp_pay_from_customer 6973 6973 0 0.00 4 31801 138 0 0
sp_pay_to_supplier 5787 5787 0 0.00 4 16908 140 0 0
sp_payment_common 12760 12760 0 0.00 1 31796 30 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 11799 327 0 0
sp_qd_handle_on_invoice_upd_sts 11195 11195 0 0.00 0 17879 334 0 0
sp_qd_handle_on_reserve_upd_sts 9800 9800 0 0.00 0 17864 56 0 0
sp_reserve_write_off 9800 9800 0 0.00 6 17939 171 0 0
sp_supplier_invoice 5375 5375 0 0.00 7 77535 3381 0 0
sp_supplier_order 5328 5328 0 0.00 5 69058 1338 0 0
srv_aggregate_perf_data 104 104 0 0.00 2413 115024 17723 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1727 120145 44254 0 0
srv_find_qd_qs_mism 1114352 1114352 0 0.00 0 9881 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 38 92234 1235 0 0
srv_make_money_saldo 816 816 0 0.00 1 17091 49 0 0
srv_recalc_idx_stat 4 4 0 0.00 126600 484914 281818 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 9507 182 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 6099 92 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 6943 18 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2851 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 1286 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 6470 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 372 10 0 0
t$perf-norm:sp_cancel_supplier_order 450 450 0 0.00 0 26777 1595 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 5399 19 0 0
t$perf-norm:sp_client_order 20339 20339 0 0.00 0 10238 20 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 5616 13 0 0
t$perf-norm:sp_pay_from_customer 6973 6973 0 0.00 0 9588 12 0 0
t$perf-norm:sp_pay_to_supplier 5787 5787 0 0.00 0 9204 13 0 0
t$perf-norm:sp_reserve_write_off 9800 9800 0 0.00 0 6437 10 0 0
t$perf-norm:sp_supplier_invoice 5375 5375 0 0.00 0 13363 21 0 0
t$perf-norm:sp_supplier_order 5328 5328 0 0.00 0 8161 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 550 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4539 116 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 5849 36 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 708 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10188 9988 200 1.96 0 10367 17 0 200
v_all_customers 37478 37478 0 0.00 0 16 1 0 0
v_all_suppliers 30156 30156 0 0.00 0 19 1 0 0
v_all_wares 40678 40678 0 0.00 0 109 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 797 7 0 0
v_cancel_client_order 3460 3460 0 0.00 0 2516 6 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 480 4 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 474 4 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 2325 6 0 0
v_cancel_supplier_invoice 748 730 18 2.40 0 1779 17 0 18
v_cancel_supplier_order 900 900 0 0.00 0 2193 10 0 0
v_cancel_write_off 1960 1960 0 0.00 0 3184 14 0 0
v_max_id_clo_ord 5326 5326 0 0.00 0 760 1 0 0
v_max_id_clo_res 936 936 0 0.00 0 7628 28 0 0
v_max_id_ord_sup 5373 5373 0 0.00 0 1667 3 0 0
v_max_non_paid_invoice 1408 1408 0 0.00 0 325 3 0 0
v_max_non_paid_realizn 3532 3532 0 0.00 0 963 3 0 0
v_min_id_clo_ord 5326 5326 0 0.00 0 16983 12 0 0
v_min_id_clo_res 936 936 0 0.00 0 9067 27 0 0
v_min_id_ord_sup 5373 5373 0 0.00 0 6225 8 0 0
v_min_non_paid_invoice 5787 5787 0 0.00 0 11886 18 0 0
v_min_non_paid_realizn 6973 6973 0 0.00 0 31790 25 0 0
v_our_firm 10082 10082 0 0.00 0 17 1 0 0
v_reserve_write_off 19600 19600 0 0.00 0 4093 5 0 0
x_lock_depdocs_on_canc_invoice 12708 12708 0 0.00 0 13649 127 0 0
x_lock_depdocs_on_canc_sup_ord 450 450 0 0.00 0 5492 324 0 0
x_make_qstorno_1000_1200 5328 5328 0 0.00 2 69033 1256 0 0
x_make_qstorno_1200_2000 5375 5375 0 0.00 3 77527 3257 0 0
x_make_qstorno_2100_3300 466867 466867 0 0.00 0 81101 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 01:39:05 to 23-06-2022 01:39:09.

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:19 2 8192000000 8798719456 8810037248 4622400 0.056 2 1 2 0 4721888 56704 42784
23:09:19 25519 8192000000 9908422768 10217082880 978816320 10.673 100 87 174 0 1007558928 5788704 22953904
23:10:52 20997 8192000000 9949328720 10275688448 1028336272 11.152 100 87 174 1 1059645456 8764432 22544752
23:12:20 14217 8192000000 9989125408 10322272256 1062473824 11.480 100 81 162 0 1092807408 9496304 20837280
23:13:45 11756 8192000000 10046361040 10393026560 1119316960 12.021 100 88 176 1 1151892544 11172304 21403280
23:15:00 9108 8192000000 10094303776 10454441984 1163104224 12.432 100 86 170 2 1195792160 13477280 19210656
23:16:10 5085 8192000000 10136370832 10504220672 1207218368 12.843 100 83 167 0 1238382928 12575936 18588624
23:17:17 5956 8192000000 10171094672 10551930880 1248886608 13.228 100 81 159 1 1278822000 12468112 17467280
23:18:25 1377 8192000000 10207802288 10589986816 1278750688 13.502 100 88 175 3 1311763792 13137360 19875744
23:19:27 36611 8192000000 10199661568 10602348544 1293310192 13.634 100 41 82 0 1308683888 5807664 9566032
23:21:04 4923 8192000000 10191517024 10604257280 1293759008 13.638 100 19 38 2 1304105008 6620400 3725600
23:22:10 5274 8192000000 10203780528 10617212928 1303307168 13.725 100 33 67 0 1317503760 6499504 7697088
23:23:20 8468 8192000000 10205499008 10625204224 1310289184 13.789 100 25 50 0 1321787520 5478464 6019872
23:24:30 74106 8192000000 10228043424 10643619840 1317602544 13.855 100 41 82 0 1332647856 5076544 9968768
23:26:48 7875 8192000000 10229649248 10646593536 1322691632 13.901 100 21 42 1 1333637360 5654448 5291280
23:27:58 6099 8192000000 10216142032 10634657792 1313720944 13.820 100 24 48 2 1325371152 6418288 5231920
23:29:09 15623 8192000000 10186339488 10584264704 1235042832 13.101 95 31 62 3 1249026016 4911200 9071984
23:30:26 10514 8192000000 10157963440 10563411968 1205936736 12.831 94 12 24 0 1213704528 5318384 2449408
23:31:38 5368 8192000000 10125636256 10521980928 1212911008 12.896 99 24 48 3 1223976960 5228128 5837824
23:32:46 6056 8192000000 10156678384 10556710912 1254465840 13.279 99 26 51 2 1267202688 5492736 7244112
23:33:59 44329 8192000000 10183506928 10585640960 1284225152 13.552 100 33 66 0 1298379648 6210944 7943552
23:36:00 7294 8192000000 10201560128 10606751744 1301421904 13.708 100 40 80 0 1317449056 7552128 8475024
23:37:08 8230 8192000000 10205072464 10622361600 1315348992 13.835 100 28 56 2 1327110160 4744032 7017136
23:38:17 52787 8192000000 10242179408 10654777344 1336121072 14.022 100 35 67 3 1349585136 5669696 7794368
23:40:14 78806 8192000000 10265332704 10687860736 1349399552 14.142 99 47 93 2 1374014784 12729488 11885744
23:42:47 52934 8192000000 10281230096 10709913600 1371081184 14.337 100 31 62 1 1395653744 15850224 8722336
23:44:44 13296 8192000000 10302311200 10728943616 1390752368 14.513 100 45 90 0 1416642848 15676512 10213968
23:46:00 3442 8192000000 10332697680 10745241600 1400312560 14.598 100 78 155 0 1439141344 21031056 17797728
23:47:07 9612 8192000000 10342197776 10760310784 1410031008 14.684 100 75 148 0 1449431248 21540704 17859536
23:48:23 2173 8192000000 10348407808 10769256448 1415277616 14.731 100 75 144 2 1455665792 23110656 17277520
23:49:29 8421 8192000000 10329915712 10757713920 1397806608 14.575 99 51 101 2 1430034128 20189968 12037552
23:50:41 3002 8192000000 10346457536 10762653696 1412507296 14.706 100 71 142 1 1452230848 22916704 16806848
23:51:48 6734 8192000000 10338743648 10761592832 1410316592 14.687 100 61 122 0 1451057504 25660528 15080384
23:53:00 1716 8192000000 10364056976 10773938176 1416236096 14.739 100 89 178 1 1468173200 29477392 22459712
23:54:05 7622 8192000000 10330660688 10743398400 1384370032 14.456 99 66 132 2 1434943248 33583024 16990192
23:55:22 6226 8192000000 10349867536 10759061504 1397274288 14.571 100 71 142 1 1451651728 36560400 17817040
23:56:33 2788 8192000000 10377501648 10790252544 1417175936 14.748 100 83 166 0 1482132480 44187968 20768576
23:57:42 1937 8192000000 10372048432 10776162304 1399097936 14.587 99 85 170 1 1465867008 45000048 21769024
23:58:47 8954 8192000000 10354622352 10772910080 1384538992 14.457 98 61 121 0 1446870960 46502272 15829696
00:00:02 16253 8192000000 10363393760 10786127872 1387156048 14.480 99 46 92 0 1456517840 56258000 13103792
00:01:32 11936 8192000000 10330767008 10755432448 1361181504 14.248 99 55 110 2 1429535696 53029648 15324544
00:02:45 26468 8192000000 10312395424 10738671616 1366609184 14.297 100 33 66 3 1413303184 38136848 8557152
00:04:16 29606 8192000000 10306384144 10744123392 1356945360 14.210 99 25 50 2 1409800928 45892016 6963552
00:06:11 29104 8192000000 10302029712 10767183872 1352087408 14.166 99 14 28 1 1400470896 44590992 3792496
00:07:59 68512 8192000000 10287017120 10735235072 1346789632 14.119 98 11 22 0 1366921712 17770688 2361392
00:10:11 82083 8192000000 10294666976 10741444608 1362262672 14.258 98 24 47 1 1381054832 13519120 5273040
00:12:34 82895 8192000000 10311586688 10770120704 1359456592 14.232 98 7 14 0 1380351184 19792416 1102176
00:14:58 105971 8192000000 10306496400 10734665728 1342115168 14.076 97 14 28 0 1364289008 19151072 3022768
00:17:48 61211 8192000000 10303415168 10748276736 1388119600 14.489 100 31 62 0 1410435936 14363536 7952800
00:19:51 20906 8192000000 10309080400 10762059776 1399918608 14.594 100 31 62 0 1417682848 10583968 7180272
00:21:14 44801 8192000000 10304916592 10751594496 1386285456 14.473 99 26 52 1 1402835616 9861616 6688544
00:23:09 46141 8192000000 10298506720 10753150976 1378040096 14.399 99 26 52 1 1402082304 17456000 6586208
00:24:56 42225 8192000000 10306298176 10759532544 1386693072 14.476 99 13 26 2 1404836416 14703904 3439440
00:27:06 31514 8192000000 10315407216 10784882688 1406112496 14.649 100 14 28 0 1429672416 19939216 3620704
00:28:48 97874 8192000000 10323106976 10789863424 1408333728 14.669 100 20 40 0 1435263440 22410416 4519296
00:31:32 47562 8192000000 10300520768 10773483520 1406414352 14.652 100 17 34 0 1426076608 16192064 3470192
00:33:20 76573 8192000000 10289968976 10761482240 1372497168 14.349 98 11 22 0 1384075200 9169648 2408384
00:36:26 66683 8192000000 10280134544 10749267968 1374066064 14.363 99 4 8 0 1387150464 12157152 927248
00:38:43 49098 8192000000 10277834976 10737283072 1335883376 14.020 96 5 10 0 1347334736 10737760 713600
00:40:32 26304 8192000000 10285737136 10738790400 1364383728 14.277 99 22 44 0 1378997216 9541424 5072064
00:42:00 59407 8192000000 10287599392 10748223488 1344256800 14.096 97 19 38 1 1368618272 19736480 4624992
00:44:00 31143 8192000000 10278252048 10733359104 1364153920 14.275 99 13 26 1 1379704864 12361088 3189856
00:45:39 20806 8192000000 10295088720 10737795072 1356614448 14.207 98 57 113 2 1382395344 12580544 13200352
00:47:01 43404 8192000000 10289212432 10730647552 1349433792 14.142 98 23 46 1 1362570448 8330976 4805680
00:48:46 45697 8192000000 10269703280 10724728832 1306282592 13.752 95 29 58 0 1327272560 14645456 6344512
00:50:35 94786 8192000000 10251405680 10688557056 1280252112 13.515 94 12 22 1 1294067792 11343840 2471840
00:53:11 95172 8192000000 10271625296 10723409920 1336292992 14.024 98 20 40 0 1349015952 7682064 5040896
00:55:52 37307 8192000000 10281505968 10729865216 1366693024 14.297 100 41 82 0 1387572256 11562768 9316464
00:57:36 44662 8192000000 10274375248 10727575552 1361177008 14.248 99 5 10 0 1372058032 9782768 1098256
00:59:21 50305 8192000000 10280285472 10732847104 1359065808 14.229 98 25 50 0 1376633424 11707856 5859760
01:01:13 55958 8192000000 10254569072 10731134976 1345758544 14.109 98 6 12 0 1361555712 14418064 1379104
01:03:10 71318 8192000000 10269273152 10725302272 1350872080 14.155 98 17 34 1 1366170128 10993936 4304112
01:05:35 41149 8192000000 10271776192 10719662080 1340002432 14.057 98 18 36 1 1358978048 14835584 4140032
01:07:18 58279 8192000000 10255405936 10708541440 1331100672 13.977 98 15 30 0 1345731648 11171504 3459472
01:09:22 49612 8192000000 10269425888 10721787904 1332261760 13.988 97 29 58 0 1356337472 17874240 6201472
01:11:14 70307 8192000000 10292298992 10746343424 1370361808 14.330 99 28 56 0 1392436928 15776096 6299024
01:13:29 22299 8192000000 10309199760 10742636544 1375699312 14.378 99 27 54 1 1391711920 9306656 6705952
01:14:54 120145 8192000000 10303380704 10767368192 1394320496 14.544 100 9 18 0 1411585744 15176928 2088320
01:18:02 76530 8192000000 10307344784 10758340608 1365821168 14.290 97 29 58 0 1386058064 13532784 6704112
01:20:39 30864 8192000000 10273589040 10729697280 1361963376 14.255 98 27 51 3 1373107424 5363168 5780880
01:22:28 32287 8192000000 10285574832 10742411264 1376946720 14.389 99 13 26 0 1386567552 6816480 2804352
01:24:02 69734 8192000000 10276966368 10742558720 1365392272 14.286 98 22 44 2 1380917872 10277792 5247808
01:27:22 70258 8192000000 10262296912 10721271808 1294707664 13.647 94 6 12 1 1314022992 17954560 1360768
01:29:39 64041 8192000000 10261853200 10718085120 1293021600 13.632 94 20 39 1 1308375328 11106944 4246784
01:31:44 51519 8192000000 10290366336 10745622528 1352476752 14.170 97 26 52 1 1374260192 15332912 6450528
01:33:57 45387 8192000000 10298480352 10754134016 1376964608 14.389 100 14 28 0 1400332464 20335552 3032304
01:35:44 65112 8192000000 10286826624 10739445760 1345921696 14.111 97 12 24 1 1360957280 12253568 2782016
01:37:50 34725 8192000000 8946799232 9128144896 115122864 1.385 9 2 4 0 116335360 1127424 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-06-2022 01:39:11 to 23-06-2022 01:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-06-2022 01:39:27 to 23-06-2022 01:39:29.

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          239387
MON$OLDEST_ACTIVE               239388
MON$OLDEST_SNAPSHOT             239388
MON$NEXT_TRANSACTION            239388
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                       2043040
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
--- end of diff output ---

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

23.06.22 01:39:30. 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-06-23 01:39:32 No severe PSQL-related problems occured
23.06.22 01:39:34. End of report.