Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.02.2023 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15502652 kB
MemAvailable 30439904 kB
Buffers 0 kB
Cached 16236564 kB
SwapCached 3140 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.11
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 2023-02-23 19:38:39
DTS_END                         2023-02-23 19:38:39.6330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38908
TRN_ID                          237730
ATT_ID                          647
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: 8919.38 22160 1070325
customer order: creation 166.68 374 20002
customer order: refuse 15.30 346 1836
order to supplier: creation 44.06 1291 5287
order to supplier: removal 3.23 104230 388
invoice (draft): creation 44.58 3260 5349
invoice (draft): removal 49.27 797 5912
invoice accept: apply 40.95 18896 4914
invoice accept: cancel 44.31 833 5317
customer reserve: creation 3875.19 182 465023
customer reserve: removal 4351.45 60 522174
realization accept: apply 80.55 176 9666
realization accept: cancel 80.64 115 9677
payment from customer: creation 57.13 124 6855
payment from customer: removal 5.73 76 687
payment to supplier: creation 45.90 146 5508
payment to supplier: removal 3.20 82 384
service: total inventory turnovers 4.48 1142 538
service: total monetary turnovers 5.96 52 715
service: aggregate perf. data 0.74 25975 89
service: refresh index statistics 0.03 285037 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-02-2023 19:40:09 to 23-02-2023 19:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3734
TEST_TIME 2 4275
TEST_TIME 3 4429
TEST_TIME 4 4588
TEST_TIME 5 4991
TEST_TIME 6 5186
TEST_TIME 7 6011
TEST_TIME 8 5911
TEST_TIME 9 6180
TEST_TIME 10 6031
TEST_TIME 11 5822
TEST_TIME 12 5757
TEST_TIME 13 5696
TEST_TIME 14 5911
TEST_TIME 15 5982
TEST_TIME 16 6173
TEST_TIME 17 6356
TEST_TIME 18 6567
TEST_TIME 19 6964
TEST_TIME 20 7019
TEST_TIME 21 7265
TEST_TIME 22 7837
TEST_TIME 23 7827
TEST_TIME 24 7829
TEST_TIME 25 7992
TEST_TIME 26 8196
TEST_TIME 27 8762
TEST_TIME 28 9192
TEST_TIME 29 9848
TEST_TIME 30 9898
TEST_TIME 31 9757
TEST_TIME 32 9533
TEST_TIME 33 9557
TEST_TIME 34 9410
TEST_TIME 35 9381
TEST_TIME 36 9196
TEST_TIME 37 9148
TEST_TIME 38 9261
TEST_TIME 39 9067
TEST_TIME 40 9006
TEST_TIME 41 9354
TEST_TIME 42 9194
TEST_TIME 43 9072
TEST_TIME 44 8970
TEST_TIME 45 9020
TEST_TIME 46 8955
TEST_TIME 47 8898
TEST_TIME 48 8866
TEST_TIME 49 8857
TEST_TIME 50 8875
TEST_TIME 51 8842
TEST_TIME 52 8793
TEST_TIME 53 8827
TEST_TIME 54 8863
TEST_TIME 55 8792
TEST_TIME 56 8816
TEST_TIME 57 8867
TEST_TIME 58 8938
TEST_TIME 59 8920
TEST_TIME 60 9051
TEST_TIME 61 9058
TEST_TIME 62 9029
TEST_TIME 63 9094
TEST_TIME 64 9113
TEST_TIME 65 9102
TEST_TIME 66 9167
TEST_TIME 67 9146
TEST_TIME 68 9047
TEST_TIME 69 9064
TEST_TIME 70 8981
TEST_TIME 71 8919
TEST_TIME 72 8911
TEST_TIME 73 8941
TEST_TIME 74 8904
TEST_TIME 75 8939
TEST_TIME 76 8863
TEST_TIME 77 8866
TEST_TIME 78 8904
TEST_TIME 79 8893
TEST_TIME 80 8956
TEST_TIME 81 8915
TEST_TIME 82 8972
TEST_TIME 83 9005
TEST_TIME 84 8995
TEST_TIME 85 8987
TEST_TIME 86 9057
TEST_TIME 87 9051
TEST_TIME 88 9106
TEST_TIME 89 9132
TEST_TIME 90 9175
TEST_TIME 91 9100
TEST_TIME 92 9124
TEST_TIME 93 9067
TEST_TIME 94 9019
TEST_TIME 95 8976
TEST_TIME 96 8984
TEST_TIME 97 8975
TEST_TIME 98 9015
TEST_TIME 99 9008
TEST_TIME 100 9009
TEST_TIME 101 8976
TEST_TIME 102 9032
TEST_TIME 103 9006
TEST_TIME 104 8980
TEST_TIME 105 9030
TEST_TIME 106 9073
TEST_TIME 107 9058
TEST_TIME 108 9114
TEST_TIME 109 9094
TEST_TIME 110 9078
TEST_TIME 111 9067
TEST_TIME 112 9032
TEST_TIME 113 8985
TEST_TIME 114 8995
TEST_TIME 115 8960
TEST_TIME 116 8962
TEST_TIME 117 8949
TEST_TIME 118 8944
TEST_TIME 119 8924
TEST_TIME 120 8919

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-02-2023 19:40:12 to 23-02-2023 19:40: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 1500060 1500060 0 0.00 0 28691 6 0 0
doc_list_biud 1500060 1500060 0 0.00 0 34133 27 0 0
sp_add_invoice_to_stock 5007 4914 93 1.85 43 194229 18896 0 93
sp_cancel_adding_invoice 5317 5317 0 0.00 5 45396 833 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 9651 346 0 0
sp_cancel_customer_reserve 522174 522174 0 0.00 0 18803 61 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 6100 77 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 2 4227 83 0 0
sp_cancel_supplier_invoice 5916 5912 4 0.06 1 15547 797 0 4
sp_cancel_supplier_order 388 388 0 0.00 7 1109504 104231 0 0
sp_cancel_write_off 9677 9677 0 0.00 1 15009 115 0 0
sp_client_order 20002 20002 0 0.00 3 32702 375 0 0
sp_customer_reserve 465023 465023 0 0.00 0 36295 183 0 0
sp_fill_shopping_cart 30624 30624 0 0.00 1 19578 39 0 0
sp_fill_shopping_cart_clo_res 465023 465023 0 0.00 0 16505 15 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 22175 340 0 0
sp_kill_cost_storno 16065 16065 0 0.00 0 11182 61 0 0
sp_kill_qstorno_ret_qs2qd 1060325 1060325 0 0.00 0 17559 33 0 0
sp_kill_qty_storno 559883 559883 0 0.00 0 34131 71 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 11592 4 0 0
sp_make_cost_storno 26942 26942 0 0.00 0 27200 51 0 0
sp_multiply_rows_for_pdistr 26942 26942 0 0.00 0 14378 29 0 0
sp_multiply_rows_for_qdistr 20002 20002 0 0.00 0 32678 165 0 0
sp_pay_from_customer 6855 6855 0 0.00 4 14393 124 0 0
sp_pay_to_supplier 5508 5508 0 0.00 4 28697 147 0 0
sp_payment_common 12363 12363 0 0.00 1 14367 31 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 9643 318 0 0
sp_qd_handle_on_invoice_upd_sts 10230 10230 0 0.00 0 34131 335 0 0
sp_qd_handle_on_reserve_upd_sts 9666 9666 0 0.00 0 20764 61 0 0
sp_reserve_write_off 9666 9666 0 0.00 6 20781 177 0 0
sp_supplier_invoice 5349 5349 0 0.00 7 93230 3261 0 0
sp_supplier_order 5287 5287 0 0.00 4 72536 1292 0 0
srv_aggregate_perf_data 89 89 0 0.00 2844 486103 25976 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 910 178155 47784 0 0
srv_find_qd_qs_mism 1023261 1023261 0 0.00 0 20691 5 0 0
srv_make_invnt_saldo 538 538 0 0.00 38 50892 1143 0 0
srv_make_money_saldo 715 715 0 0.00 0 10887 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 75181 526824 285038 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 0 16093 209 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5602 92 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 2042 12 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 10316 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 2048 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 943 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1596 19 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 21791 1547 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 4188 24 0 0
t$perf-norm:sp_client_order 20002 20002 0 0.00 0 8628 19 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 4668 16 0 0
t$perf-norm:sp_pay_from_customer 6855 6855 0 0.00 0 8000 11 0 0
t$perf-norm:sp_pay_to_supplier 5508 5508 0 0.00 0 11599 15 0 0
t$perf-norm:sp_reserve_write_off 9666 9666 0 0.00 0 11606 16 0 0
t$perf-norm:sp_supplier_invoice 5349 5349 0 0.00 0 7155 19 0 0
t$perf-norm:sp_supplier_order 5287 5287 0 0.00 0 6838 14 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 3943 46 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 166 4 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 3164 25 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 1855 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 10012 9826 186 1.85 0 16150 21 0 186
v_all_customers 36130 36130 0 0.00 0 20 1 0 0
v_all_suppliers 29294 29294 0 0.00 0 38 1 0 0
v_all_wares 40004 40004 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1809 8 0 0
v_cancel_client_order 3672 3672 0 0.00 0 3565 8 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 5942 10 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2165 4 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 3985 11 0 0
v_cancel_supplier_invoice 794 786 8 1.00 0 1492 16 0 8
v_cancel_supplier_order 776 776 0 0.00 0 258 3 0 0
v_cancel_write_off 1844 1844 0 0.00 0 2946 13 0 0
v_max_id_clo_ord 5287 5287 0 0.00 0 4629 3 0 0
v_max_id_clo_res 984 984 0 0.00 0 4144 21 0 0
v_max_id_ord_sup 5335 5335 0 0.00 0 4354 4 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 377 3 0 0
v_max_non_paid_realizn 3698 3698 0 0.00 0 5988 5 0 0
v_min_id_clo_ord 5287 5287 0 0.00 0 19283 11 0 0
v_min_id_clo_res 984 984 0 0.00 0 525 2 0 0
v_min_id_ord_sup 5335 5335 0 0.00 0 6193 5 0 0
v_min_non_paid_invoice 5508 5508 0 0.00 0 10918 23 0 0
v_min_non_paid_realizn 6855 6855 0 0.00 0 14362 26 0 0
v_our_firm 10188 10188 0 0.00 0 33 1 0 0
v_reserve_write_off 19332 19332 0 0.00 0 3860 5 0 0
x_lock_depdocs_on_canc_invoice 10836 10836 0 0.00 0 25778 129 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 6564 344 0 0
x_make_qstorno_1000_1200 5287 5287 0 0.00 2 72530 1214 0 0
x_make_qstorno_1200_2000 5349 5349 0 0.00 3 86708 3119 0 0
x_make_qstorno_2100_3300 465023 465023 0 0.00 0 36294 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-02-2023 19:40:17 to 23-02-2023 19:40:21.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:43 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:44 32430 8192000000 9922028224 10236473344 995595440 10.836 100 85 170 0 1023937232 6526496 21815296
17:11:20 21233 8192000000 9951386880 10275598336 1033399856 11.201 100 86 171 1 1064255136 8850592 22004688
17:12:43 10816 8192000000 9983105328 10317123584 1068916176 11.542 100 81 160 0 1097864768 9114400 19834192
17:13:58 3915 8192000000 10049106896 10399068160 1138679248 12.203 100 83 166 0 1169272688 10315888 20277552
17:15:06 4672 8192000000 10082830960 10441814016 1172180960 12.517 100 78 156 0 1202800672 12652352 17967360
17:16:16 3933 8192000000 10123599296 10495324160 1211090080 12.879 100 78 154 0 1241584976 12995648 17499248
17:17:23 2497 8192000000 10161701472 10534768640 1247159872 13.212 100 81 162 0 1279262080 13781552 18320656
17:18:28 1059 8192000000 10191541216 10572201984 1275316816 13.470 100 92 183 1 1310696256 13916912 21462528
17:19:30 5259 8192000000 10187040848 10587189248 1294814512 13.648 100 53 105 0 1317302864 11264640 11223712
17:20:41 4712 8192000000 10194354288 10598989824 1300727424 13.702 100 43 86 2 1319669632 8932832 10009376
17:21:46 41690 8192000000 10205012368 10616995840 1310673168 13.792 100 33 66 0 1324185744 6406352 7106224
17:23:29 5715 8192000000 10209956960 10626465792 1319667808 13.874 100 21 42 0 1330566256 6250800 4647648
17:24:35 13047 8192000000 10213590736 10630574080 1323038304 13.904 100 12 24 0 1332334336 6013584 3282448
17:25:49 47763 8192000000 10238391616 10649481216 1332994048 13.994 100 51 103 2 1352246432 6133856 13118528
17:27:51 6916 8192000000 10200724864 10624262144 1298610352 13.683 98 20 40 1 1307572656 4746720 4215584
17:29:00 4892 8192000000 10162186768 10569105408 1257402288 13.306 97 24 48 0 1269202112 6305680 5494144
17:30:05 54338 8192000000 10130694256 10522214400 1133174720 12.151 90 23 46 1 1143126080 4259376 5691984
17:32:05 7047 8192000000 10132645200 10536415232 1238812560 13.135 99 30 61 0 1251862016 5903088 7146368
17:33:12 4578 8192000000 10161495920 10568015872 1252328560 13.260 98 28 53 0 1265011792 6544848 6138384
17:34:18 17120 8192000000 10178949392 10588897280 1285973744 13.568 100 13 27 0 1294014400 5166640 2874016
17:35:40 34722 8192000000 10194482224 10607214592 1300725728 13.702 100 21 42 0 1310066624 4877424 4463472
17:37:15 4333 8192000000 10202780368 10618036224 1310008384 13.786 100 18 37 0 1319538560 5401008 4129168
17:38:24 9463 8192000000 10220282416 10643042304 1336210768 14.023 100 20 40 1 1345672528 5529168 3932592
17:39:39 3623 8192000000 10264855728 10675261440 1354213072 14.185 100 47 89 0 1371966848 8079520 9674256
17:40:46 81543 8192000000 10263738256 10692894720 1367591360 14.305 100 38 76 0 1384877952 8284560 9002032
17:43:11 29558 8192000000 10269134560 10696228864 1362893472 14.263 99 36 72 2 1382689168 11021136 8774560
17:45:02 2402 8192000000 10290961392 10712834048 1380862256 14.424 100 47 93 2 1403406224 12028320 10515648
17:46:11 4336 8192000000 10310406096 10733621248 1392369280 14.527 100 60 120 2 1422188928 16242352 13577296
17:47:18 50804 8192000000 10310730784 10748125184 1388833760 14.495 99 33 66 0 1412898240 16536208 7528272
17:49:12 5285 8192000000 10337236224 10766782464 1415991216 14.737 100 57 113 0 1448690992 18746656 13953120
17:50:25 2839 8192000000 10354262384 10780205056 1426049696 14.826 100 58 116 0 1462152256 21738288 14364272
17:51:32 6381 8192000000 10341256336 10767384576 1415032160 14.729 100 57 110 0 1455254608 26120288 14102160
17:52:43 16285 8192000000 10323949840 10743873536 1377129904 14.391 98 61 122 0 1416914032 25469280 14314848
17:54:15 10761 8192000000 10303413040 10727927808 1363877680 14.272 98 56 108 0 1407286944 29493136 13916128
17:55:30 2940 8192000000 10348200752 10768752640 1406697712 14.655 100 57 114 0 1454964640 33739728 14527200
17:56:40 1692 8192000000 10319728240 10719272960 1362086464 14.256 100 82 163 1 1420060464 36998112 20975888
17:57:48 6750 8192000000 10304471536 10710487040 1351110512 14.157 99 69 138 2 1403034112 34255344 17668256
17:59:02 1997 8192000000 10332954448 10731311104 1365855088 14.290 100 87 171 1 1431212032 43996784 21360160
18:00:10 5822 8192000000 10323857792 10743058432 1371673760 14.342 100 61 122 0 1434342896 46516496 16152640
18:01:17 6601 8192000000 10322935248 10743336960 1379063264 14.408 100 49 98 0 1429683648 37904480 12715904
18:02:32 910 8192000000 10331074912 10739445760 1375741648 14.378 100 60 115 2 1430074080 39609456 14722976
18:03:37 22397 8192000000 10325004720 10747170816 1353176528 14.176 98 42 83 1 1407165152 42491264 11497360
18:05:06 12458 8192000000 10318201856 10748710912 1370918080 14.335 100 29 57 0 1423791216 44848832 8024304
18:06:22 29648 8192000000 10288368720 10726944768 1342404560 14.079 99 19 36 0 1378268976 30633328 5231088
18:08:11 64127 8192000000 10266124560 10713124864 1340826272 14.065 98 25 50 0 1357847936 11382944 5638720
18:10:21 47310 8192000000 10299210336 10736836608 1378232272 14.401 100 17 34 0 1398581136 15858432 4490432
18:12:18 20321 8192000000 10288307120 10730512384 1357462320 14.215 98 31 60 0 1375800352 11757120 6580912
18:13:45 46356 8192000000 10280359920 10726375424 1356448016 14.205 98 15 30 0 1374106256 14188672 3469568
18:15:41 18977 8192000000 10286150160 10728599552 1363921184 14.273 99 30 61 0 1390638816 19628576 7089056
18:17:04 120754 8192000000 10291847584 10740916224 1376144304 14.382 100 22 44 0 1402050640 20942336 4964000
18:20:20 35381 8192000000 10278676400 10725646336 1376194720 14.383 100 12 24 0 1388764064 10218272 2351072
18:21:57 31278 8192000000 10297374400 10743750656 1390794960 14.513 100 37 74 0 1413136928 13650960 8691008
18:23:29 50736 8192000000 10301709088 10757816320 1397952416 14.577 100 19 38 0 1416603840 14329920 4321504
18:25:20 100852 8192000000 10294852672 10752569344 1400418160 14.599 100 12 24 0 1419373680 15725104 3230416
18:28:01 61011 8192000000 10303517536 10750210048 1381528432 14.430 99 33 63 0 1402087072 13608240 6950400
18:30:02 113384 8192000000 10306584048 10762768384 1402545312 14.618 100 11 20 0 1417650320 12866320 2238688
18:33:00 21910 8192000000 10295859632 10745344000 1393554096 14.538 100 44 88 0 1409912864 6081872 10276896
18:34:23 38905 8192000000 10310737712 10764791808 1405453216 14.644 100 25 50 0 1421785136 10527248 5804672
18:36:06 71169 8192000000 10304910608 10771349504 1388410496 14.492 99 17 34 0 1409745344 17452144 3882704
18:39:12 45469 8192000000 10300492944 10753835008 1373533728 14.359 98 27 54 0 1389428224 10259824 5634672
18:40:59 57822 8192000000 10294171616 10736140288 1323986624 13.913 95 34 68 0 1343162240 12007808 7167808
18:43:01 30651 8192000000 10294475584 10732363776 1357573424 14.216 98 33 66 0 1378829280 13780528 7475328
18:44:35 86007 8192000000 10257404464 10697269248 1314423088 13.826 96 17 34 0 1328385984 9969472 3993424
18:47:31 44349 8192000000 10264607296 10688724992 1295431328 13.654 95 37 71 0 1314484304 10472800 8580176
18:49:24 21669 8192000000 10274093568 10695528448 1326506704 13.936 98 62 120 1 1353412432 13236992 13668736
18:50:51 38669 8192000000 10237258384 10698428416 1342763840 14.082 100 5 10 0 1356306288 12329104 1213344
18:52:30 51636 8192000000 10232076512 10680983552 1327034592 13.940 99 16 32 0 1340206800 9391408 3780800
18:54:22 63070 8192000000 10235062304 10688118784 1308924928 13.776 97 20 40 1 1324981344 11684672 4371744
18:56:31 27025 8192000000 10247978528 10692980736 1337239568 14.033 99 21 42 0 1354615248 12808016 4567664
18:57:58 85759 8192000000 10243709024 10686140416 1291366304 13.617 96 10 20 0 1301387696 8006064 2015328
19:00:25 178154 8192000000 10240697792 10693410816 1262755280 13.355 93 13 24 0 1276100528 10656144 2689104
19:04:33 120079 8192000000 10262766480 10718261248 1320223200 13.879 98 9 18 0 1332309920 10321856 1764864
19:07:35 119856 8192000000 10290585904 10750554112 1359276304 14.231 99 13 26 0 1377249120 15205008 2767808
19:10:47 52577 8192000000 10281781376 10749431808 1384000064 14.452 100 18 36 0 1398972208 11716304 3255840
19:12:45 68988 8192000000 10295795008 10757505024 1397944704 14.577 100 16 32 0 1409849248 8448080 3456464
19:14:55 80015 8192000000 10297291824 10761949184 1386335616 14.473 99 13 26 0 1398556992 9459856 2761520
19:17:19 16374 8192000000 10307068512 10755129344 1391711696 14.521 100 40 80 0 1415950656 15723664 8515296
19:18:42 93036 8192000000 10306959440 10764079104 1387769664 14.486 99 31 61 0 1407062752 12015360 7277728
19:21:17 64753 8192000000 10310213328 10761326592 1387488176 14.483 99 20 40 0 1401455200 9343456 4623568
19:23:22 135848 8192000000 10297610272 10735493120 1298209584 13.679 93 12 24 0 1311112208 10323296 2579328
19:26:41 63658 8192000000 10299622416 10731499520 1330827104 13.975 96 20 40 0 1349449248 14100528 4521616
19:28:45 124049 8192000000 10278113040 10727923712 1344035248 14.094 97 22 44 1 1364138560 15244208 4859104
19:31:50 33249 8192000000 10263549040 10713341952 1342801408 14.083 98 12 24 1 1355881216 9781760 3298048
19:33:32 41276 8192000000 10264120816 10721828864 1367203904 14.302 100 6 12 0 1383292160 14635216 1453040
19:35:14 72709 8192000000 10233854368 10682687488 1306159760 13.751 96 15 31 0 1319122512 9642016 3320736
19:37:35 46047 8192000000 10250587184 10703671296 1328087840 13.950 98 15 30 1 1348160688 16242240 3830608

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-02-2023 19:40:23 to 23-02-2023 19:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-02-2023 19:40:39 to 23-02-2023 19:40: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          236442
MON$OLDEST_ACTIVE               237894
MON$OLDEST_SNAPSHOT             237894
MON$NEXT_TRANSACTION            237894
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                       2011072
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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.02.23 19:40: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
2023-02-23 19:40:45 No severe PSQL-related problems occured
23.02.23 19:40:46. End of report.