Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.05.2022 22: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 16236772 kB
MemAvailable 31428380 kB
Buffers 0 kB
Cached 15439548 kB
SwapCached 2664 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-13 22:38:14
DTS_END                         2022-05-13 22:38:14.1560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45022
TRN_ID                          247949
ATT_ID                          675
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: 9659.64 21357 1159157
customer order: creation 182.33 331 21880
customer order: refuse 14.85 333 1782
order to supplier: creation 48.28 1257 5793
order to supplier: removal 3.35 114071 402
invoice (draft): creation 47.92 3221 5750
invoice (draft): removal 53.63 900 6435
invoice accept: apply 44.09 16740 5291
invoice accept: cancel 47.91 787 5749
customer reserve: creation 4148.02 162 497763
customer reserve: removal 4768.52 62 572222
realization accept: apply 88.18 189 10581
realization accept: cancel 77.84 103 9341
payment from customer: creation 62.87 106 7544
payment from customer: removal 6.07 99 728
payment to supplier: creation 49.93 117 5991
payment to supplier: removal 3.58 67 429
service: total inventory turnovers 4.67 1098 560
service: total monetary turnovers 6.68 27 802
service: aggregate perf. data 0.92 20689 110
service: refresh index statistics 0.03 266784 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-05-2022 22:39:09 to 13-05-2022 22:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3925
TEST_TIME 2 4925
TEST_TIME 3 5543
TEST_TIME 4 5191
TEST_TIME 5 5874
TEST_TIME 6 5462
TEST_TIME 7 5665
TEST_TIME 8 5812
TEST_TIME 9 5734
TEST_TIME 10 5744
TEST_TIME 11 5801
TEST_TIME 12 6050
TEST_TIME 13 6848
TEST_TIME 14 6704
TEST_TIME 15 6885
TEST_TIME 16 7036
TEST_TIME 17 7101
TEST_TIME 18 7997
TEST_TIME 19 8017
TEST_TIME 20 7896
TEST_TIME 21 8209
TEST_TIME 22 8161
TEST_TIME 23 8911
TEST_TIME 24 9297
TEST_TIME 25 9515
TEST_TIME 26 9749
TEST_TIME 27 10181
TEST_TIME 28 10311
TEST_TIME 29 10572
TEST_TIME 30 10808
TEST_TIME 31 11002
TEST_TIME 32 10842
TEST_TIME 33 10830
TEST_TIME 34 10869
TEST_TIME 35 10699
TEST_TIME 36 10796
TEST_TIME 37 10703
TEST_TIME 38 10542
TEST_TIME 39 10614
TEST_TIME 40 10532
TEST_TIME 41 10450
TEST_TIME 42 10385
TEST_TIME 43 10276
TEST_TIME 44 10291
TEST_TIME 45 10139
TEST_TIME 46 10203
TEST_TIME 47 10200
TEST_TIME 48 10117
TEST_TIME 49 10075
TEST_TIME 50 10067
TEST_TIME 51 10050
TEST_TIME 52 10125
TEST_TIME 53 10117
TEST_TIME 54 10068
TEST_TIME 55 10065
TEST_TIME 56 9998
TEST_TIME 57 9956
TEST_TIME 58 9963
TEST_TIME 59 9893
TEST_TIME 60 9943
TEST_TIME 61 9978
TEST_TIME 62 10053
TEST_TIME 63 10102
TEST_TIME 64 10099
TEST_TIME 65 10163
TEST_TIME 66 10128
TEST_TIME 67 10094
TEST_TIME 68 10200
TEST_TIME 69 10149
TEST_TIME 70 10194
TEST_TIME 71 10157
TEST_TIME 72 10311
TEST_TIME 73 10205
TEST_TIME 74 10110
TEST_TIME 75 10083
TEST_TIME 76 10055
TEST_TIME 77 9996
TEST_TIME 78 10001
TEST_TIME 79 10004
TEST_TIME 80 9971
TEST_TIME 81 9942
TEST_TIME 82 9860
TEST_TIME 83 9838
TEST_TIME 84 9772
TEST_TIME 85 9896
TEST_TIME 86 9875
TEST_TIME 87 9870
TEST_TIME 88 9812
TEST_TIME 89 9836
TEST_TIME 90 9889
TEST_TIME 91 9851
TEST_TIME 92 9838
TEST_TIME 93 9964
TEST_TIME 94 9954
TEST_TIME 95 9997
TEST_TIME 96 10013
TEST_TIME 97 10009
TEST_TIME 98 9982
TEST_TIME 99 10046
TEST_TIME 100 9960
TEST_TIME 101 9926
TEST_TIME 102 9881
TEST_TIME 103 9869
TEST_TIME 104 9852
TEST_TIME 105 9822
TEST_TIME 106 9815
TEST_TIME 107 9781
TEST_TIME 108 9776
TEST_TIME 109 9738
TEST_TIME 110 9689
TEST_TIME 111 9694
TEST_TIME 112 9722
TEST_TIME 113 9704
TEST_TIME 114 9678
TEST_TIME 115 9711
TEST_TIME 116 9691
TEST_TIME 117 9703
TEST_TIME 118 9705
TEST_TIME 119 9726
TEST_TIME 120 9662

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-05-2022 22:39:13 to 13-05-2022 22:39:16.

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 1618945 1618945 0 0.00 0 19939 6 0 0
doc_list_biud 1618940 1618940 0 0.00 0 63637 28 0 0
sp_add_invoice_to_stock 5426 5291 135 2.48 31 164057 16741 0 135
sp_cancel_adding_invoice 5749 5749 0 0.00 5 53160 787 0 0
sp_cancel_client_order 1782 1782 0 0.00 4 11534 334 0 0
sp_cancel_customer_reserve 572222 572222 0 0.00 1 26135 62 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 3 7586 100 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 2755 67 0 0
sp_cancel_supplier_invoice 6440 6435 5 0.07 2 63665 901 0 5
sp_cancel_supplier_order 402 402 0 0.00 5 1291188 114071 0 0
sp_cancel_write_off 9341 9341 0 0.00 1 8153 103 0 0
sp_client_order 21880 21880 0 0.00 3 14941 332 0 0
sp_customer_reserve 497763 497763 0 0.00 0 109129 163 0 0
sp_fill_shopping_cart 33419 33419 0 0.00 1 10752 36 0 0
sp_fill_shopping_cart_clo_res 497754 497754 0 0.00 0 10944 11 0 0
sp_get_clo_for_invoice 5282 5282 0 0.00 0 30360 294 0 0
sp_kill_cost_storno 16247 16247 0 0.00 0 8033 56 0 0
sp_kill_qstorno_ret_qs2qd 1160622 1160622 0 0.00 0 63627 34 0 0
sp_kill_qty_storno 611789 611789 0 0.00 0 63634 73 0 0
sp_lock_selected_doc 21731 21731 0 0.00 0 11247 7 0 0
sp_make_cost_storno 29398 29398 0 0.00 0 19908 54 0 0
sp_multiply_rows_for_pdistr 29393 29393 0 0.00 0 15089 29 0 0
sp_multiply_rows_for_qdistr 21880 21880 0 0.00 0 10830 150 0 0
sp_pay_from_customer 7544 7544 0 0.00 4 15095 107 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 11964 117 0 0
sp_payment_common 13535 13535 0 0.00 1 8858 23 0 0
sp_qd_handle_on_cancel_clo 1782 1782 0 0.00 0 9236 290 0 0
sp_qd_handle_on_invoice_upd_sts 11027 11027 0 0.00 0 14802 335 0 0
sp_qd_handle_on_reserve_upd_sts 10580 10580 0 0.00 0 13456 62 0 0
sp_reserve_write_off 10581 10581 0 0.00 6 13570 189 0 0
sp_supplier_invoice 5750 5750 0 0.00 7 89725 3222 0 0
sp_supplier_order 5793 5793 0 0.00 5 21010 1258 0 0
srv_aggregate_perf_data 110 110 0 0.00 2553 426358 20690 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2244 133544 43185 0 0
srv_find_qd_qs_mism 1108022 1108022 0 0.00 0 14931 5 0 0
srv_make_invnt_saldo 560 560 0 0.00 31 48046 1098 0 0
srv_make_money_saldo 802 802 0 0.00 1 2380 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 126884 328760 266785 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5291 5291 0 0.00 0 13620 164 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3842 91 0 0
t$perf-norm:sp_cancel_client_order 1782 1782 0 0.00 0 4071 13 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2291 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 1224 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 5954 41 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1098 11 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 20925 1835 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 3887 10 0 0
t$perf-norm:sp_client_order 21880 21880 0 0.00 0 15631 22 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 3484 9 0 0
t$perf-norm:sp_pay_from_customer 7544 7544 0 0.00 0 10804 12 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 13627 18 0 0
t$perf-norm:sp_reserve_write_off 10581 10581 0 0.00 0 13599 12 0 0
t$perf-norm:sp_supplier_invoice 5750 5750 0 0.00 0 7536 16 0 0
t$perf-norm:sp_supplier_order 5793 5793 0 0.00 0 7525 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 668 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1312 21 0 0
t$perf-norm:srv_make_invnt_saldo 560 560 0 0.00 0 960 17 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 972 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10826 10556 270 2.49 0 8314 23 0 270
v_all_customers 39642 39642 0 0.00 0 20 0 0 0
v_all_suppliers 32074 32074 0 0.00 0 20 1 0 0
v_all_wares 43760 43760 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2241 9 0 0
v_cancel_client_order 3564 3564 0 0.00 0 8317 11 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 7573 17 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 5229 10 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 2679 9 0 0
v_cancel_supplier_invoice 850 840 10 1.17 0 661 14 0 10
v_cancel_supplier_order 804 804 0 0.00 0 2760 10 0 0
v_cancel_write_off 2076 2076 0 0.00 0 5204 17 0 0
v_max_id_clo_ord 5792 5792 0 0.00 0 2437 2 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 2059 13 0 0
v_max_id_ord_sup 5747 5747 0 0.00 0 5483 4 0 0
v_max_non_paid_invoice 1490 1490 0 0.00 0 405 3 0 0
v_max_non_paid_realizn 4197 4197 0 0.00 0 7883 6 0 0
v_min_id_clo_ord 5792 5792 0 0.00 0 1816 4 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 8905 17 0 0
v_min_id_ord_sup 5747 5747 0 0.00 0 6601 6 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 7614 19 0 0
v_min_non_paid_realizn 7544 7544 0 0.00 0 8852 15 0 0
v_our_firm 10812 10812 0 0.00 0 12 1 0 0
v_reserve_write_off 21158 21158 0 0.00 0 8318 10 0 0
x_lock_depdocs_on_canc_invoice 11764 11764 0 0.00 0 18974 125 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 7379 365 0 0
x_make_qstorno_1000_1200 5793 5793 0 0.00 2 20753 1181 0 0
x_make_qstorno_1200_2000 5750 5750 0 0.00 2 89626 3094 0 0
x_make_qstorno_2100_3300 497763 497763 0 0.00 0 109126 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 13-05-2022 22:39:17 to 13-05-2022 22:39:22.

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
20:08:17 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 12950 8192000000 9902680944 10209849344 989925888 10.781 100 94 188 1 1019290624 5880272 23484464
20:10:36 25605 8192000000 9936594160 10255310848 1018251424 11.055 100 89 178 1 1049796528 8659616 22885488
20:12:15 11088 8192000000 9979293312 10309951488 1059593312 11.453 100 85 170 0 1091109456 9802528 21713616
20:13:33 10095 8192000000 10029411536 10374606848 1110604848 11.938 100 73 146 0 1138683872 10750464 17328560
20:14:45 6092 8192000000 10086517696 10443767808 1169049888 12.488 100 89 178 0 1203507152 14175776 20281488
20:15:53 7281 8192000000 10118529968 10488279040 1208940080 12.859 100 81 160 0 1239147328 12276000 17931248
20:17:02 3485 8192000000 10171289744 10543898624 1250666928 13.244 100 86 168 1 1281736608 12945760 18123920
20:18:07 2340 8192000000 10200830848 10580467712 1280365856 13.516 100 96 193 0 1316314736 14530496 21418384
20:19:11 1792 8192000000 10219084064 10602909696 1300943376 13.704 100 89 176 3 1332388880 12320096 19125408
20:20:16 1595 8192000000 10235867888 10622418944 1314913344 13.831 100 95 190 1 1350436992 13655312 21868336
20:21:18 4501 8192000000 10219949776 10624913408 1317698768 13.856 100 42 84 1 1336029712 8769280 9561664
20:22:23 12012 8192000000 10211220624 10633760768 1325638384 13.928 100 21 42 0 1341411792 11119984 4653424
20:23:36 9359 8192000000 10221777568 10641682432 1335915040 14.021 100 19 36 1 1345706128 5828000 3963088
20:24:47 49145 8192000000 10248480944 10660347904 1341043904 14.067 100 42 83 1 1356422288 5882368 9496016
20:26:37 3685 8192000000 10233992240 10651987968 1332098672 13.986 99 30 54 3 1344402064 5066960 7236432
20:27:41 7842 8192000000 10221727600 10634412032 1288417280 13.590 97 21 43 2 1303554576 10312944 4824352
20:28:49 62674 8192000000 10201596352 10614169600 1245569584 13.197 94 27 54 1 1261503264 9780448 6153232
20:30:52 4117 8192000000 10196333888 10600996864 1294236240 13.643 100 44 88 1 1312423056 8773792 9413024
20:31:58 10406 8192000000 10129243792 10525310976 1189711888 12.681 95 19 38 2 1199023104 5064400 4246816
20:33:17 74553 8192000000 10153522880 10564894720 1248310736 13.223 98 21 42 0 1258432352 5304000 4817616
20:35:34 6093 8192000000 10184819728 10596892672 1293816048 13.639 100 29 58 0 1306920752 6645328 6459376
20:36:41 8814 8192000000 10199341568 10612215808 1307042304 13.759 100 23 46 0 1317654896 4991136 5621456
20:37:50 14636 8192000000 10206630640 10622124032 1315614608 13.837 100 15 30 0 1324620928 5425552 3580768
20:39:07 14757 8192000000 10260506048 10662031360 1339892288 14.056 100 71 143 0 1364355184 8388208 16074688
20:40:27 5662 8192000000 10267814960 10680967168 1355665520 14.198 100 61 122 2 1380135328 10868896 13600912
20:41:37 20050 8192000000 10265725536 10688102400 1364518976 14.278 100 46 92 1 1385276912 9874672 10883264
20:43:00 3736 8192000000 10279020304 10698891264 1358696416 14.226 99 53 103 1 1383549200 12999280 11853504
20:44:18 4753 8192000000 10297126512 10715803648 1377598416 14.395 100 48 94 0 1404092496 15278048 11216032
20:45:26 25939 8192000000 10312890336 10739703808 1397149824 14.570 100 50 99 1 1428572496 18959600 12463072
20:47:09 22647 8192000000 10331892608 10759278592 1410799376 14.691 100 44 88 0 1442022400 20447648 10775376
20:48:46 4778 8192000000 10366490352 10793971712 1433292800 14.890 100 54 108 3 1472117136 25635808 13188528
20:49:53 10781 8192000000 10359179104 10785685504 1424635808 14.814 100 56 112 0 1469597744 29999504 14962432
20:51:09 2913 8192000000 10380983952 10801836032 1431100928 14.871 100 76 152 0 1486736496 36566736 19068832
20:52:17 6417 8192000000 10354966336 10774192128 1406166672 14.650 98 73 145 3 1453895920 29239904 18489344
20:53:26 7204 8192000000 10383534976 10808020992 1439527312 14.945 100 56 112 0 1487899568 33572160 14800096
20:54:40 7806 8192000000 10391125056 10812096512 1426870352 14.834 99 75 150 0 1486022832 40245376 18907104
20:55:55 2242 8192000000 10375914624 10788724736 1409781856 14.682 100 76 151 2 1474129840 45217200 19130784
20:57:01 20403 8192000000 10320298608 10750357504 1356569680 14.207 97 32 64 0 1402913120 37807376 8536064
20:58:24 3366 8192000000 10356654528 10778882048 1400965856 14.604 100 56 112 1 1456904784 41571136 14367792
20:59:33 12209 8192000000 10342942352 10773884928 1383378336 14.447 99 37 74 0 1444697696 50568112 10751248
21:00:55 13954 8192000000 10333926160 10759942144 1351611408 14.162 98 44 88 1 1424429936 59852960 12965568
21:02:11 6199 8192000000 10309690272 10734673920 1347633376 14.126 99 42 84 1 1406523552 47773584 11116592
21:03:19 22912 8192000000 10281892560 10711691264 1331708032 13.983 99 24 48 0 1385892528 47030304 7154192
21:04:55 14243 8192000000 10299324224 10728304640 1350309280 14.150 100 26 52 0 1408678832 50206560 8162992
21:06:10 29764 8192000000 10308075664 10736517120 1358335312 14.222 100 45 90 0 1399052416 29269936 11447168
21:07:41 24460 8192000000 10272550880 10725580800 1344167328 14.095 100 20 38 1 1384939008 35676272 5095408
21:09:10 52692 8192000000 10265249280 10719842304 1340572816 14.063 99 8 16 0 1360451600 18246016 1632768
21:11:20 23141 8192000000 10283328016 10724683776 1369437808 14.322 100 30 59 2 1384217936 8389552 6390576
21:12:50 109802 8192000000 10291040432 10751270912 1380469408 14.421 100 16 33 3 1396537056 11846128 4221520
21:15:42 65859 8192000000 10305722048 10749464576 1381293360 14.428 100 28 56 0 1401155904 12464384 7398160
21:17:55 64416 8192000000 10282560496 10731032576 1363956240 14.273 99 24 46 1 1379094352 9996432 5141680
21:20:05 46491 8192000000 10291592496 10738143232 1362405920 14.259 99 29 58 1 1376733936 7633632 6694384
21:22:06 133544 8192000000 10301856848 10746032128 1379594160 14.413 100 24 47 1 1405087872 19709184 5784528
21:25:19 21964 8192000000 10290411072 10744070144 1379707008 14.414 100 25 50 0 1393228336 8301728 5219600
21:26:43 27844 8192000000 10306963792 10754220032 1385844000 14.469 100 32 64 1 1408462528 15190432 7428096
21:28:12 46033 8192000000 10311819264 10762371072 1385483856 14.466 99 23 43 1 1405482976 14567472 5431648
21:30:06 44668 8192000000 10308480800 10767761408 1393618208 14.538 100 31 62 1 1420141296 18584592 7938496
21:31:51 26542 8192000000 10302600416 10767822848 1388040464 14.488 99 9 16 0 1404333456 14279376 2013616
21:34:33 84045 8192000000 10304628080 10776547328 1396266448 14.562 100 20 40 0 1420185456 18903328 5015680
21:37:37 44486 8192000000 10311934960 10774450176 1395309184 14.553 99 33 66 0 1413471408 10594208 7568016
21:39:24 70762 8192000000 10285754544 10751217664 1365193056 14.284 98 11 22 0 1378508848 10908000 2407792
21:41:35 80381 8192000000 10295665792 10753826816 1320427120 13.881 95 9 18 0 1336261040 14053152 1780768
21:43:56 66013 8192000000 10281641104 10721234944 1301375616 13.708 95 16 32 0 1314140128 9194016 3570496
21:46:06 88568 8192000000 10270034336 10716712960 1280919600 13.521 94 14 28 0 1298260944 14410336 2931008
21:48:35 45669 8192000000 10263701536 10706862080 1323986032 13.913 98 24 47 0 1341879952 13166352 4727568
21:50:21 90386 8192000000 10264078848 10721755136 1353080080 14.175 100 11 22 0 1377912256 22508016 2324160
21:53:36 50168 8192000000 10249484624 10701021184 1314307456 13.825 97 45 90 0 1334741856 10360096 10074304
21:55:36 32870 8192000000 10264604704 10703736832 1336099824 14.022 99 28 56 1 1353608832 10369312 7139696
21:57:12 61803 8192000000 10274328880 10731057152 1347384672 14.124 99 14 28 3 1364217104 13058592 3773840
21:59:15 112800 8192000000 10282942752 10739273728 1373461968 14.358 100 15 30 0 1394738800 17479040 3797792
22:02:09 61933 8192000000 10256787136 10712936448 1332584176 13.990 98 22 43 0 1352703216 15032080 5086960
22:04:21 48294 8192000000 10278927088 10729164800 1363036976 14.265 100 24 48 1 1378406144 9595792 5773376
22:06:15 40399 8192000000 10290012816 10726088704 1359997888 14.237 100 38 76 1 1380778288 12128464 8651936
22:07:56 80523 8192000000 10299379840 10752225280 1374846944 14.370 99 14 28 1 1386575664 8988208 2740512
22:10:17 57074 8192000000 10289360448 10751197184 1362850880 14.263 98 21 42 0 1383623984 16073216 4699888
22:12:35 73219 8192000000 10300810656 10760597504 1367470064 14.304 98 10 20 3 1385780464 15909344 2401056
22:14:50 66569 8192000000 10303987392 10748661760 1351216784 14.158 97 14 28 0 1364914448 10517248 3180416
22:16:59 15934 8192000000 10307053600 10751094784 1375131424 14.373 99 35 71 1 1395883616 13179776 7572416
22:18:19 113736 8192000000 10290160656 10747801600 1366934128 14.300 98 25 50 1 1380908960 7975984 5998848
22:22:11 49518 8192000000 10288234320 10750197760 1368195152 14.311 98 19 38 1 1380507984 7153152 5159680
22:24:02 59413 8192000000 10277480768 10736984064 1348419216 14.133 97 9 18 0 1358569216 7742048 2407952
22:26:01 69655 8192000000 10268618944 10737000448 1327164064 13.942 96 8 16 1 1337598736 8726480 1708192
22:29:38 88054 8192000000 10271920656 10745421824 1358292800 14.222 99 17 34 0 1384136992 21646608 4197584
22:32:09 53037 8192000000 10251238192 10700689408 1267021424 13.394 93 22 44 0 1284594080 12822128 4750528
22:34:02 14049 8192000000 10249895568 10693877760 1329534560 13.963 98 37 74 1 1345434096 7770144 8129392
22:35:31 28873 8192000000 10265594384 10708099072 1334239952 14.005 98 37 74 1 1352184160 9780480 8163728
22:37:22 65398 8192000000 8977190176 9162981376 141986896 1.703 11 2 3 0 145695488 3369504 339088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-05-2022 22:39:23 to 13-05-2022 22:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-05-2022 22:39:39 to 13-05-2022 22:39:41.

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          248252
MON$OLDEST_ACTIVE               248253
MON$OLDEST_SNAPSHOT             248253
MON$NEXT_TRANSACTION            248253
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                       2061984
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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'

13.05.22 22:39:42. 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-13 22:39:45 No severe PSQL-related problems occured
13.05.22 22:39:46. End of report.