Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2023 01: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 15448396 kB
MemAvailable 30380204 kB
Buffers 0 kB
Cached 16243560 kB
SwapCached 3580 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-05 01:38:35
DTS_END                         2023-02-05 01:38:35.7330
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/32848
TRN_ID                          240537
ATT_ID                          673
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: 9174.89 20350 1100987
customer order: creation 175.01 369 21001
customer order: refuse 14.73 301 1767
order to supplier: creation 45.49 1228 5459
order to supplier: removal 3.23 105216 388
invoice (draft): creation 44.62 3247 5354
invoice (draft): removal 51.08 774 6130
invoice accept: apply 41.34 17966 4961
invoice accept: cancel 47.13 805 5655
customer reserve: creation 3832.72 178 459926
customer reserve: removal 4621.85 57 554622
realization accept: apply 82.33 166 9880
realization accept: cancel 87.30 108 10476
payment from customer: creation 58.73 103 7047
payment from customer: removal 5.33 95 640
payment to supplier: creation 48.52 124 5822
payment to supplier: removal 3.53 82 424
service: total inventory turnovers 4.92 1076 590
service: total monetary turnovers 6.06 24 727
service: aggregate perf. data 0.95 16557 114
service: refresh index statistics 0.03 258521 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-02-2023 01:39:31 to 05-02-2023 01:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3982
TEST_TIME 2 4143
TEST_TIME 3 3837
TEST_TIME 4 4298
TEST_TIME 5 4143
TEST_TIME 6 4372
TEST_TIME 7 4691
TEST_TIME 8 5059
TEST_TIME 9 5366
TEST_TIME 10 5678
TEST_TIME 11 5574
TEST_TIME 12 5681
TEST_TIME 13 6277
TEST_TIME 14 6204
TEST_TIME 15 6629
TEST_TIME 16 6794
TEST_TIME 17 6922
TEST_TIME 18 6897
TEST_TIME 19 7870
TEST_TIME 20 8307
TEST_TIME 21 8437
TEST_TIME 22 8543
TEST_TIME 23 8593
TEST_TIME 24 8559
TEST_TIME 25 8769
TEST_TIME 26 9657
TEST_TIME 27 10192
TEST_TIME 28 10103
TEST_TIME 29 9850
TEST_TIME 30 9748
TEST_TIME 31 9991
TEST_TIME 32 9829
TEST_TIME 33 9909
TEST_TIME 34 9825
TEST_TIME 35 9771
TEST_TIME 36 9704
TEST_TIME 37 9611
TEST_TIME 38 9394
TEST_TIME 39 9339
TEST_TIME 40 9442
TEST_TIME 41 9360
TEST_TIME 42 9290
TEST_TIME 43 9170
TEST_TIME 44 9174
TEST_TIME 45 9047
TEST_TIME 46 8984
TEST_TIME 47 8919
TEST_TIME 48 8840
TEST_TIME 49 8833
TEST_TIME 50 8771
TEST_TIME 51 8723
TEST_TIME 52 8782
TEST_TIME 53 8811
TEST_TIME 54 8801
TEST_TIME 55 8820
TEST_TIME 56 8890
TEST_TIME 57 8967
TEST_TIME 58 8987
TEST_TIME 59 8991
TEST_TIME 60 9132
TEST_TIME 61 9150
TEST_TIME 62 9257
TEST_TIME 63 9195
TEST_TIME 64 9246
TEST_TIME 65 9191
TEST_TIME 66 9143
TEST_TIME 67 9092
TEST_TIME 68 9087
TEST_TIME 69 9053
TEST_TIME 70 8982
TEST_TIME 71 8884
TEST_TIME 72 8968
TEST_TIME 73 8972
TEST_TIME 74 8951
TEST_TIME 75 8942
TEST_TIME 76 8972
TEST_TIME 77 8970
TEST_TIME 78 8986
TEST_TIME 79 9005
TEST_TIME 80 9118
TEST_TIME 81 9127
TEST_TIME 82 9106
TEST_TIME 83 9207
TEST_TIME 84 9175
TEST_TIME 85 9097
TEST_TIME 86 9060
TEST_TIME 87 9095
TEST_TIME 88 9106
TEST_TIME 89 9059
TEST_TIME 90 9032
TEST_TIME 91 9025
TEST_TIME 92 9053
TEST_TIME 93 9051
TEST_TIME 94 9067
TEST_TIME 95 9113
TEST_TIME 96 9137
TEST_TIME 97 9134
TEST_TIME 98 9151
TEST_TIME 99 9253
TEST_TIME 100 9219
TEST_TIME 101 9285
TEST_TIME 102 9322
TEST_TIME 103 9290
TEST_TIME 104 9264
TEST_TIME 105 9268
TEST_TIME 106 9215
TEST_TIME 107 9169
TEST_TIME 108 9146
TEST_TIME 109 9127
TEST_TIME 110 9122
TEST_TIME 111 9128
TEST_TIME 112 9102
TEST_TIME 113 9155
TEST_TIME 114 9123
TEST_TIME 115 9139
TEST_TIME 116 9121
TEST_TIME 117 9165
TEST_TIME 118 9191
TEST_TIME 119 9201
TEST_TIME 120 9176

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-02-2023 01:39:35 to 05-02-2023 01:39:38.

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 1527656 1527656 0 0.00 0 31061 6 0 0
doc_list_biud 1527656 1527656 0 0.00 0 76863 27 0 0
sp_add_invoice_to_stock 5055 4961 94 1.85 31 219856 17966 0 94
sp_cancel_adding_invoice 5655 5655 0 0.00 2 86460 806 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 7962 302 0 0
sp_cancel_customer_reserve 554622 554622 0 0.00 0 28355 58 0 0
sp_cancel_pay_from_customer 640 640 0 0.00 2 4802 95 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 7347 82 0 0
sp_cancel_supplier_invoice 6138 6130 8 0.13 1 55368 775 0 8
sp_cancel_supplier_order 388 388 0 0.00 6 1001892 105217 0 0
sp_cancel_write_off 10476 10476 0 0.00 1 25412 109 0 0
sp_client_order 21001 21001 0 0.00 3 35806 369 0 0
sp_customer_reserve 459926 459926 0 0.00 0 53711 179 0 0
sp_fill_shopping_cart 31813 31813 0 0.00 1 11752 36 0 0
sp_fill_shopping_cart_clo_res 459923 459923 0 0.00 0 12439 16 0 0
sp_get_clo_for_invoice 4958 4958 0 0.00 0 26383 325 0 0
sp_kill_cost_storno 17195 17195 0 0.00 0 25408 49 0 0
sp_kill_qstorno_ret_qs2qd 1126238 1126238 0 0.00 0 55289 31 0 0
sp_kill_qty_storno 593876 593876 0 0.00 0 76859 68 0 0
sp_lock_selected_doc 20578 20578 0 0.00 0 6545 6 0 0
sp_make_cost_storno 27707 27707 0 0.00 0 9003 45 0 0
sp_multiply_rows_for_pdistr 27707 27707 0 0.00 0 7741 27 0 0
sp_multiply_rows_for_qdistr 21001 21001 0 0.00 0 13617 171 0 0
sp_pay_from_customer 7047 7047 0 0.00 4 9597 104 0 0
sp_pay_to_supplier 5822 5822 0 0.00 4 17449 124 0 0
sp_payment_common 12869 12869 0 0.00 1 17263 29 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 5361 273 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 58038 338 0 0
sp_qd_handle_on_reserve_upd_sts 9880 9880 0 0.00 0 15232 56 0 0
sp_reserve_write_off 9880 9880 0 0.00 6 15297 166 0 0
sp_supplier_invoice 5354 5354 0 0.00 5 86704 3247 0 0
sp_supplier_order 5459 5459 0 0.00 5 90426 1229 0 0
srv_aggregate_perf_data 114 114 0 0.00 2194 249673 16557 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2103 102587 44635 0 0
srv_find_qd_qs_mism 1053801 1053801 0 0.00 0 31061 6 0 0
srv_make_invnt_saldo 590 590 0 0.00 26 63558 1076 0 0
srv_make_money_saldo 727 727 0 0.00 1 457 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 129423 532823 258522 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4961 4961 0 0.00 0 25831 173 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 18822 200 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 12477 28 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 1901 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 640 640 0 0.00 0 395 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 858 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 382 382 0 0.00 0 946 17 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 26235 1577 0 0
t$perf-norm:sp_cancel_write_off 1022 1022 0 0.00 0 7093 19 0 0
t$perf-norm:sp_client_order 21001 21001 0 0.00 0 12688 14 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 3520 7 0 0
t$perf-norm:sp_pay_from_customer 7047 7047 0 0.00 0 2903 7 0 0
t$perf-norm:sp_pay_to_supplier 5822 5822 0 0.00 0 11518 12 0 0
t$perf-norm:sp_reserve_write_off 9880 9880 0 0.00 0 6133 12 0 0
t$perf-norm:sp_supplier_invoice 5354 5354 0 0.00 0 9332 21 0 0
t$perf-norm:sp_supplier_order 5459 5459 0 0.00 0 16322 16 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 271 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 581 12 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 1893 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10100 9912 188 1.86 0 7581 18 0 188
v_all_customers 38136 38136 0 0.00 0 22 0 0 0
v_all_suppliers 30010 30010 0 0.00 0 25 1 0 0
v_all_wares 42002 42002 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1878 11 0 0
v_cancel_client_order 3534 3534 0 0.00 0 7635 9 0 0
v_cancel_customer_prepayment 1280 1280 0 0.00 0 3496 15 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 3352 6 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 7332 18 0 0
v_cancel_supplier_invoice 780 764 16 2.05 0 2024 18 0 16
v_cancel_supplier_order 776 776 0 0.00 0 2803 17 0 0
v_cancel_write_off 2044 2044 0 0.00 0 2079 15 0 0
v_max_id_clo_ord 5459 5459 0 0.00 0 1951 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1968 14 0 0
v_max_id_ord_sup 5352 5352 0 0.00 0 5922 7 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 422 3 0 0
v_max_non_paid_realizn 3668 3668 0 0.00 0 3833 4 0 0
v_min_id_clo_ord 5459 5459 0 0.00 0 7403 6 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 7091 23 0 0
v_min_id_ord_sup 5352 5352 0 0.00 0 5028 9 0 0
v_min_non_paid_invoice 5822 5822 0 0.00 0 17258 21 0 0
v_min_non_paid_realizn 7047 7047 0 0.00 0 7719 20 0 0
v_our_firm 10624 10624 0 0.00 0 21 1 0 0
v_reserve_write_off 19760 19760 0 0.00 0 7738 8 0 0
x_lock_depdocs_on_canc_invoice 11403 11403 0 0.00 0 17623 104 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 5259 336 0 0
x_make_qstorno_1000_1200 5459 5459 0 0.00 1 90041 1167 0 0
x_make_qstorno_1200_2000 5354 5354 0 0.00 2 86687 3099 0 0
x_make_qstorno_2100_3300 459926 459926 0 0.00 0 53709 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2023 01:39:39 to 05-02-2023 01:39:43.

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:39 3 8192000000 8800002112 8811610112 6398640 0.078 2 1 2 0 6498272 56848 42784
23:09:40 21029 8192000000 9889841584 10197159936 974902928 10.635 100 86 172 0 1004706560 6302336 23501296
23:11:06 20591 8192000000 9935798560 10255069184 1014980240 11.024 100 90 180 0 1047093792 8509728 23603824
23:12:30 20453 8192000000 9964203088 10291986432 1045966928 11.322 100 85 170 0 1076946016 9330112 21648976
23:13:54 11894 8192000000 10017508080 10363420672 1102996480 11.866 100 76 152 0 1130366032 9680912 17688640
23:15:07 3681 8192000000 10066827792 10426687488 1161285792 12.415 100 80 160 0 1191745536 11884384 18575360
23:16:13 3159 8192000000 10113909680 10481766400 1208719024 12.857 100 74 148 0 1236838496 11671504 16447968
23:17:18 1829 8192000000 10156167168 10529406976 1244501104 13.188 100 89 176 0 1277840592 14153152 19186336
23:18:22 3455 8192000000 10173861616 10552614912 1262566016 13.354 100 79 156 0 1294104352 14575456 16962880
23:19:27 834 8192000000 10206132928 10584752128 1284806544 13.557 100 94 187 0 1319095664 14315664 19973456
23:20:29 30210 8192000000 10208919568 10602598400 1301504768 13.709 100 67 134 1 1326717568 9239520 15973280
23:22:00 5205 8192000000 10201814560 10610106368 1308893760 13.776 100 46 92 1 1327248064 8621696 9732608
23:23:08 13176 8192000000 10201947168 10619932672 1318087904 13.859 100 14 28 0 1326898176 5783664 3026608
23:24:24 32201 8192000000 10213206304 10636095488 1327539424 13.945 100 26 52 0 1339717552 5860720 6317408
23:26:10 9464 8192000000 10225330608 10644840448 1334333552 14.006 100 21 42 0 1345815616 6384272 5097792
23:27:19 8571 8192000000 10217968736 10633601024 1306029200 13.750 98 21 43 0 1316131280 5539904 4562176
23:28:29 8533 8192000000 10204018224 10619928576 1285546320 13.564 97 12 22 0 1293439184 5534960 2357904
23:29:41 44254 8192000000 10178169120 10575962112 1214805040 12.914 94 29 59 1 1226832128 5284800 6742288
23:31:40 5727 8192000000 10150279680 10555146240 1251346416 13.251 99 27 52 1 1263433392 5710080 6376896
23:32:49 8424 8192000000 10137556608 10535170048 1232897424 13.081 99 16 30 0 1241409456 5020096 3491936
23:33:58 9915 8192000000 10166389264 10574860288 1275860912 13.475 100 14 28 0 1289056576 9947088 3248576
23:35:08 50506 8192000000 10176692160 10591424512 1289781616 13.602 100 26 52 2 1301210192 5095840 6332736
23:37:00 14026 8192000000 10190763856 10609020928 1302654192 13.719 100 17 34 0 1311730672 4872864 4203616
23:38:14 11468 8192000000 10217039024 10633682944 1323168816 13.905 100 20 41 0 1336213456 9378064 3666576
23:39:32 29811 8192000000 10246894272 10660364288 1341328928 14.069 100 44 88 0 1359770496 8789168 9652400
23:41:17 42088 8192000000 10263900032 10684686336 1353073168 14.175 100 40 78 0 1382091872 20433904 8584800
23:43:04 20510 8192000000 10273025984 10693066752 1355847152 14.200 99 39 77 1 1378787536 14322624 8617760
23:44:32 15371 8192000000 10292553264 10713300992 1380079120 14.417 100 45 90 0 1409240880 18083408 11078352
23:45:53 3907 8192000000 10317574688 10733461504 1391927536 14.523 100 55 109 1 1424026768 19166944 12932288
23:47:00 10844 8192000000 10314005168 10738212864 1394233920 14.544 100 56 110 1 1429549296 22203280 13112096
23:48:17 26978 8192000000 10327430064 10757582848 1406610384 14.654 100 57 113 1 1446490096 26152432 13727280
23:49:48 16851 8192000000 10358548464 10782261248 1421104064 14.782 100 53 105 0 1463263808 28998416 13161328
23:51:09 3421 8192000000 10363068176 10781708288 1413177888 14.712 100 74 147 4 1469539888 37976016 18385984
23:52:21 2552 8192000000 10356551968 10772463616 1407662032 14.663 100 76 152 0 1465579488 38260112 19657344
23:53:38 3052 8192000000 10363222688 10783133696 1413481856 14.715 100 66 129 1 1469787568 40550448 15755264
23:54:47 9187 8192000000 10356561888 10777796608 1407605968 14.663 100 59 115 0 1470097728 47273072 15218688
23:56:02 5628 8192000000 10353409808 10770051072 1391076496 14.515 100 52 101 0 1453691360 49010240 13604624
23:57:13 2102 8192000000 10369009568 10775113728 1393801984 14.540 100 84 166 0 1471957776 57154624 21001168
23:58:18 27409 8192000000 10329848032 10767515648 1379327792 14.411 100 42 84 3 1443241424 51963520 11950112
23:59:52 13302 8192000000 10339874528 10768719872 1391360896 14.518 100 36 71 0 1441510464 40227520 9922048
00:01:07 19608 8192000000 10313916848 10737319936 1357684496 14.217 98 28 56 1 1406614240 41576336 7353408
00:02:28 26581 8192000000 10327071216 10757636096 1368611680 14.315 99 23 46 0 1421519184 46374768 6532736
00:04:06 27632 8192000000 10305127328 10739572736 1339705088 14.055 100 24 48 1 1408403456 61829888 6868480
00:05:36 20439 8192000000 10279063456 10722541568 1355960640 14.201 99 29 58 0 1386368016 22694240 7713136
00:07:00 19230 8192000000 10280756496 10700455936 1332894912 13.993 98 62 122 0 1360964144 13509536 14559696
00:08:20 73349 8192000000 10262204160 10704621568 1347443280 14.124 100 27 54 1 1372173568 17846752 6883536
00:11:20 26537 8192000000 10257973168 10719526912 1341316384 14.069 98 7 14 0 1357436720 14620384 1499952
00:12:51 81406 8192000000 10277710000 10724724736 1374319888 14.366 100 19 40 1 1386541616 8149376 4072352
00:15:15 54320 8192000000 10279849760 10734354432 1380615600 14.422 100 18 38 0 1394776976 10084336 4077040
00:17:10 53635 8192000000 10290433696 10743750656 1388741648 14.495 100 13 26 0 1407059152 15261120 3056384
00:19:05 57946 8192000000 10280297696 10741538816 1375290400 14.374 99 9 18 0 1390918608 13596880 2031328
00:21:37 55135 8192000000 10273604880 10730852352 1377348928 14.393 99 30 60 0 1393688448 9654592 6684928
00:23:41 40759 8192000000 10289106512 10744389632 1392703824 14.530 100 16 28 0 1404989360 8899904 3385632
00:25:28 77190 8192000000 10298932752 10749714432 1371313408 14.339 98 37 73 1 1386587632 6172688 9101536
00:28:00 45749 8192000000 10291352112 10750033920 1391881072 14.523 99 30 61 1 1410814192 12435008 6498112
00:30:13 59308 8192000000 10289501808 10760368128 1386204368 14.472 99 12 24 0 1403660576 14677360 2778848
00:32:19 38149 8192000000 10312448256 10766925824 1393336016 14.536 99 27 54 0 1410671488 11413888 5921584
00:34:00 91693 8192000000 10311327936 10774769664 1410364112 14.687 100 16 32 0 1434157104 20058992 3734000
00:36:40 16732 8192000000 10318056976 10766487552 1407699040 14.663 100 33 66 0 1422854960 8032224 7123696
00:37:57 43138 8192000000 10327070368 10793930752 1422313952 14.793 100 17 34 0 1440758976 14358944 4086080
00:39:46 96209 8192000000 10306343584 10752880640 1334181376 14.005 94 16 32 0 1348801600 11452960 3167264
00:42:23 56147 8192000000 10295176768 10733977600 1310490720 13.791 94 27 54 1 1327652000 11502288 5658992
00:44:23 84692 8192000000 10275152064 10728681472 1370339328 14.330 99 26 52 1 1384467280 8108528 6019424
00:47:02 37795 8192000000 10257299200 10716553216 1324875200 13.921 96 8 15 0 1333109136 6785696 1448240
00:48:40 50871 8192000000 10266616944 10721210368 1324130624 13.914 96 8 16 0 1340742592 14792912 1819056
00:50:34 65037 8192000000 10237042080 10688831488 1266974304 13.394 93 9 19 0 1280485376 11436384 2074688
00:52:48 35334 8192000000 10232416592 10675474432 1267410496 13.398 94 31 62 0 1282057408 7719424 6927488
00:54:26 52933 8192000000 10233189744 10675208192 1291030368 13.614 96 23 46 0 1309562032 13048000 5483664
00:56:23 49699 8192000000 10260743072 10707148800 1339979936 14.057 99 23 46 0 1359814768 14613600 5221232
00:58:14 44862 8192000000 10273041296 10717495296 1332841024 13.993 98 42 84 0 1358541296 16042096 9658176
01:00:00 65871 8192000000 10249525904 10695696384 1283983568 13.549 95 35 69 1 1306878736 15084576 7810592
01:02:15 21325 8192000000 10272345984 10712227840 1360084880 14.238 100 21 43 0 1376845280 11919968 4840432
01:03:42 95448 8192000000 10275952576 10729512960 1363553920 14.269 100 31 62 0 1390783872 19993744 7236208
01:06:35 56704 8192000000 10262038400 10726948864 1366455264 14.295 100 8 14 0 1377544576 8988272 2101040
01:08:33 31525 8192000000 10256991328 10707103744 1321678080 13.892 96 14 28 0 1330971808 5884880 3408848
01:10:07 76528 8192000000 10267133584 10720899072 1338743824 14.046 97 8 16 0 1346929376 6639024 1546528
01:13:32 102585 8192000000 10293869024 10754465792 1338997440 14.048 97 7 14 1 1359712112 19267856 1446816
01:16:15 101918 8192000000 10291652432 10762579968 1386733200 14.477 100 6 12 0 1407455584 19088016 1634368
01:19:00 83156 8192000000 10301949200 10769195008 1379774000 14.415 98 15 30 0 1394830032 11727808 3328224
01:21:41 42218 8192000000 10312281088 10761060352 1381369920 14.429 99 40 80 0 1398572000 8315712 8886368
01:23:24 102056 8192000000 10306638976 10759241728 1383132304 14.445 99 20 40 1 1395418272 7746400 4539568
01:26:07 58285 8192000000 10297202944 10770898944 1389229136 14.499 99 20 39 1 1406235232 12252368 4753728
01:28:10 51135 8192000000 10292795968 10767527936 1379894032 14.416 99 14 27 0 1400113712 17100336 3119344
01:30:34 38804 8192000000 10297529376 10755710976 1390660976 14.512 100 10 20 0 1403199120 9989968 2548176
01:32:20 50155 8192000000 10270389728 10731405312 1326114688 13.932 95 14 28 1 1338256880 9197296 2944896
01:34:13 58769 8192000000 10270627648 10725244928 1339442800 14.052 98 22 44 0 1355790592 11640432 4707360
01:36:45 40247 8192000000 10268987264 10711904256 1320558832 13.882 97 28 54 0 1338663280 12183840 5920608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-02-2023 01:39:45 to 05-02-2023 01:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2023 01:40:01 to 05-02-2023 01:40:03.

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          239268
MON$OLDEST_ACTIVE               240682
MON$OLDEST_SNAPSHOT             240682
MON$NEXT_TRANSACTION            240682
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                       2009024
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Feb  5 01:38:37 2023
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Feb  5 01:38:41 2023
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Feb  5 01:38:41 2023
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

05.02.23 01:40:04. 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-05 01:40:07 No severe PSQL-related problems occured
05.02.23 01:40:08. End of report.