Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.01.2022 22: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 16661172 kB
MemAvailable 31287144 kB
Buffers 0 kB
Cached 15035152 kB
SwapCached 2292 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.9
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-01-11 22:37:57
DTS_END                         2022-01-11 22:37:57.8200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36242
TRN_ID                          236114
ATT_ID                          662
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: 8936.98 23695 1072437
customer order: creation 166.73 397 20007
customer order: refuse 14.82 342 1778
order to supplier: creation 43.92 1291 5270
order to supplier: removal 3.50 94661 420
invoice (draft): creation 44.23 3158 5307
invoice (draft): removal 51.00 796 6120
invoice accept: apply 40.47 17765 4856
invoice accept: cancel 45.70 784 5484
customer reserve: creation 3725.52 176 447062
customer reserve: removal 4516.23 57 541948
realization accept: apply 80.76 189 9691
realization accept: cancel 78.47 115 9417
payment from customer: creation 57.45 124 6894
payment from customer: removal 5.28 122 633
payment to supplier: creation 47.77 121 5732
payment to supplier: removal 3.34 87 401
service: total inventory turnovers 4.73 1216 568
service: total monetary turnovers 6.20 47 744
service: aggregate perf. data 0.84 19595 101
service: refresh index statistics 0.03 332865 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2022 22:39:29 to 11-01-2022 22:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3166
TEST_TIME 2 3626
TEST_TIME 3 4648
TEST_TIME 4 4298
TEST_TIME 5 4182
TEST_TIME 6 4135
TEST_TIME 7 4120
TEST_TIME 8 4555
TEST_TIME 9 5084
TEST_TIME 10 5500
TEST_TIME 11 5589
TEST_TIME 12 5788
TEST_TIME 13 6016
TEST_TIME 14 6089
TEST_TIME 15 6576
TEST_TIME 16 7197
TEST_TIME 17 7117
TEST_TIME 18 7398
TEST_TIME 19 7858
TEST_TIME 20 7819
TEST_TIME 21 8015
TEST_TIME 22 8570
TEST_TIME 23 8663
TEST_TIME 24 8965
TEST_TIME 25 9447
TEST_TIME 26 9746
TEST_TIME 27 10456
TEST_TIME 28 10395
TEST_TIME 29 10369
TEST_TIME 30 10057
TEST_TIME 31 10115
TEST_TIME 32 9841
TEST_TIME 33 9736
TEST_TIME 34 9712
TEST_TIME 35 9525
TEST_TIME 36 9524
TEST_TIME 37 9652
TEST_TIME 38 9445
TEST_TIME 39 9348
TEST_TIME 40 9387
TEST_TIME 41 9266
TEST_TIME 42 9167
TEST_TIME 43 9118
TEST_TIME 44 8998
TEST_TIME 45 8862
TEST_TIME 46 8836
TEST_TIME 47 8874
TEST_TIME 48 8865
TEST_TIME 49 8800
TEST_TIME 50 8762
TEST_TIME 51 8854
TEST_TIME 52 8846
TEST_TIME 53 8833
TEST_TIME 54 8846
TEST_TIME 55 8856
TEST_TIME 56 8847
TEST_TIME 57 8853
TEST_TIME 58 8910
TEST_TIME 59 8996
TEST_TIME 60 9011
TEST_TIME 61 9100
TEST_TIME 62 9205
TEST_TIME 63 9162
TEST_TIME 64 9202
TEST_TIME 65 9153
TEST_TIME 66 9066
TEST_TIME 67 9011
TEST_TIME 68 8957
TEST_TIME 69 8941
TEST_TIME 70 8909
TEST_TIME 71 8892
TEST_TIME 72 8876
TEST_TIME 73 8841
TEST_TIME 74 8784
TEST_TIME 75 8770
TEST_TIME 76 8718
TEST_TIME 77 8736
TEST_TIME 78 8767
TEST_TIME 79 8871
TEST_TIME 80 8861
TEST_TIME 81 9052
TEST_TIME 82 9001
TEST_TIME 83 9044
TEST_TIME 84 9096
TEST_TIME 85 9137
TEST_TIME 86 9167
TEST_TIME 87 9249
TEST_TIME 88 9173
TEST_TIME 89 9108
TEST_TIME 90 9068
TEST_TIME 91 9060
TEST_TIME 92 9048
TEST_TIME 93 9060
TEST_TIME 94 8993
TEST_TIME 95 8944
TEST_TIME 96 8950
TEST_TIME 97 8987
TEST_TIME 98 8960
TEST_TIME 99 8946
TEST_TIME 100 8994
TEST_TIME 101 9026
TEST_TIME 102 9057
TEST_TIME 103 9107
TEST_TIME 104 9093
TEST_TIME 105 9093
TEST_TIME 106 9113
TEST_TIME 107 9161
TEST_TIME 108 9152
TEST_TIME 109 9200
TEST_TIME 110 9158
TEST_TIME 111 9098
TEST_TIME 112 9087
TEST_TIME 113 9056
TEST_TIME 114 9055
TEST_TIME 115 9030
TEST_TIME 116 9002
TEST_TIME 117 8989
TEST_TIME 118 8985
TEST_TIME 119 8966
TEST_TIME 120 8943

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2022 22:39:33 to 11-01-2022 22:39:36.

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 1486956 1486956 0 0.00 0 19829 6 0 0
doc_list_biud 1486956 1486956 0 0.00 0 24660 27 0 0
sp_add_invoice_to_stock 4964 4856 108 2.17 57 189321 17765 0 108
sp_cancel_adding_invoice 5484 5484 0 0.00 5 71435 785 0 0
sp_cancel_client_order 1778 1778 0 0.00 4 10137 342 0 0
sp_cancel_customer_reserve 541948 541948 0 0.00 1 14833 58 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 2 9648 122 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 10547 88 0 0
sp_cancel_supplier_invoice 6127 6120 7 0.11 2 10961 797 0 7
sp_cancel_supplier_order 420 420 0 0.00 8 1035551 94662 0 0
sp_cancel_write_off 9417 9417 0 0.00 1 15320 116 0 0
sp_client_order 20007 20007 0 0.00 3 32682 397 0 0
sp_customer_reserve 447062 447062 0 0.00 0 38020 177 0 0
sp_fill_shopping_cart 30581 30581 0 0.00 1 21420 40 0 0
sp_fill_shopping_cart_clo_res 447057 447057 0 0.00 0 12251 15 0 0
sp_get_clo_for_invoice 4846 4846 0 0.00 0 21763 343 0 0
sp_kill_cost_storno 15935 15935 0 0.00 0 8868 60 0 0
sp_kill_qstorno_ret_qs2qd 1099853 1099853 0 0.00 0 15266 32 0 0
sp_kill_qty_storno 579704 579704 0 0.00 0 24658 68 0 0
sp_lock_selected_doc 20183 20183 0 0.00 0 7010 6 0 0
sp_make_cost_storno 27163 27163 0 0.00 0 14025 52 0 0
sp_multiply_rows_for_pdistr 27163 27163 0 0.00 0 19827 34 0 0
sp_multiply_rows_for_qdistr 20007 20007 0 0.00 0 13363 171 0 0
sp_pay_from_customer 6894 6894 0 0.00 4 19835 125 0 0
sp_pay_to_supplier 5732 5732 0 0.00 4 14031 122 0 0
sp_payment_common 12626 12626 0 0.00 1 11371 33 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 10002 306 0 0
sp_qd_handle_on_invoice_upd_sts 10330 10330 0 0.00 0 24654 304 0 0
sp_qd_handle_on_reserve_upd_sts 9691 9691 0 0.00 0 10051 58 0 0
sp_reserve_write_off 9691 9691 0 0.00 6 12124 189 0 0
sp_supplier_invoice 5307 5307 0 0.00 6 61305 3158 0 0
sp_supplier_order 5270 5270 0 0.00 5 23372 1292 0 0
srv_aggregate_perf_data 101 101 0 0.00 2620 117749 19595 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2065 141468 47114 0 0
srv_find_qd_qs_mism 1026504 1026504 0 0.00 0 18956 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 36 93331 1217 0 0
srv_make_money_saldo 744 744 0 0.00 0 16063 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 186167 520967 332865 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4856 4856 0 0.00 0 9516 180 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 0 8675 124 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 12043 19 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 5402 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 1373 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 187 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1045 18 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 25358 1465 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 1411 9 0 0
t$perf-norm:sp_client_order 20007 20007 0 0.00 0 9035 17 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 6969 14 0 0
t$perf-norm:sp_pay_from_customer 6894 6894 0 0.00 0 5114 11 0 0
t$perf-norm:sp_pay_to_supplier 5732 5732 0 0.00 0 5626 13 0 0
t$perf-norm:sp_reserve_write_off 9691 9691 0 0.00 0 8829 13 0 0
t$perf-norm:sp_supplier_invoice 5307 5307 0 0.00 0 5207 21 0 0
t$perf-norm:sp_supplier_order 5270 5270 0 0.00 0 5941 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 376 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 757 18 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 3266 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 9908 9692 216 2.18 0 8970 21 0 216
v_all_customers 36656 36656 0 0.00 0 84 0 0 0
v_all_suppliers 29638 29638 0 0.00 0 18 1 0 0
v_all_wares 40014 40014 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 521 7 0 0
v_cancel_client_order 3556 3556 0 0.00 0 5314 8 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 2075 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 2242 7 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 574 3 0 0
v_cancel_supplier_invoice 804 790 14 1.74 0 2311 23 0 14
v_cancel_supplier_order 840 840 0 0.00 0 1011 8 0 0
v_cancel_write_off 2004 2004 0 0.00 0 4581 22 0 0
v_max_id_clo_ord 5269 5269 0 0.00 0 1571 2 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 7780 19 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 2337 3 0 0
v_max_non_paid_invoice 1487 1487 0 0.00 0 1908 5 0 0
v_max_non_paid_realizn 3599 3599 0 0.00 0 2569 4 0 0
v_min_id_clo_ord 5269 5269 0 0.00 0 6128 12 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 2239 6 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 5550 5 0 0
v_min_non_paid_invoice 5732 5732 0 0.00 0 11367 21 0 0
v_min_non_paid_realizn 6894 6894 0 0.00 0 10144 29 0 0
v_our_firm 9948 9948 0 0.00 0 20 1 0 0
v_reserve_write_off 19382 19382 0 0.00 0 6317 8 0 0
x_lock_depdocs_on_canc_invoice 11209 11209 0 0.00 0 11705 110 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 5329 301 0 0
x_make_qstorno_1000_1200 5270 5270 0 0.00 1 23352 1212 0 0
x_make_qstorno_1200_2000 5307 5307 0 0.00 3 58544 3015 0 0
x_make_qstorno_2100_3300 447062 447062 0 0.00 0 38017 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-01-2022 22:39:37 to 11-01-2022 22:39:41.

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:01 4 8192000000 8804004928 8817053696 9581392 0.116 3 2 3 0 9723856 67504 74960
20:09:03 32135 8192000000 9911758096 10223992832 988865040 10.770 100 92 183 1 1018985744 6143120 23977584
20:10:40 27494 8192000000 9942916784 10266243072 1023756704 11.108 100 86 172 0 1054103968 8582368 21764896
20:12:10 18725 8192000000 9995732704 10332192768 1078401136 11.632 100 79 158 1 1107244464 9801840 19041488
20:13:36 13268 8192000000 10058215616 10411077632 1142210960 12.236 100 86 170 1 1174675792 12263424 20201408
20:14:53 5526 8192000000 10100016784 10462932992 1186601392 12.652 100 83 166 0 1218469712 13136224 18732096
20:16:00 3942 8192000000 10140449968 10508972032 1220341456 12.965 100 87 174 1 1253444464 13717392 19385616
20:17:06 6328 8192000000 10159374032 10538078208 1250550864 13.243 100 82 164 2 1282049536 13971168 17527504
20:18:15 1237 8192000000 10198497248 10583015424 1284824336 13.557 100 88 176 0 1317110656 11886608 20399712
20:19:18 19767 8192000000 10215691152 10606292992 1300619072 13.701 100 80 160 2 1331571952 12096592 18856288
20:20:38 3957 8192000000 10223538880 10628362240 1324274912 13.915 100 45 91 1 1340986656 6186032 10525712
20:21:45 57456 8192000000 10231614256 10645499904 1331195504 13.978 100 19 38 0 1340771136 5156320 4419312
20:23:43 7200 8192000000 10228555136 10651996160 1336981792 14.030 100 24 48 1 1352152768 9963968 5207008
20:24:53 17753 8192000000 10229877264 10656665600 1343294432 14.087 100 21 42 0 1354772208 6643760 4834016
20:26:12 69205 8192000000 10232989120 10657103872 1334692608 14.010 99 29 58 3 1347674080 5272368 7709104
20:28:23 11496 8192000000 10212269536 10634829824 1325492048 13.926 100 26 51 3 1337265088 5225216 6547824
20:29:36 9553 8192000000 10160619728 10562715648 1243898672 13.182 97 24 49 0 1255105808 5435104 5772032
20:30:47 63077 8192000000 10098909024 10488135680 1146465792 12.276 92 33 66 1 1158656688 4692288 7498608
20:32:58 3245 8192000000 10172631840 10571063296 1269516224 13.417 100 41 82 1 1286679760 7503200 9660336
20:34:06 9969 8192000000 10188840032 10594590720 1289457712 13.599 100 20 40 0 1299891328 5104512 5329104
20:35:18 23042 8192000000 10197814352 10606264320 1303060864 13.723 100 14 28 1 1311341728 5222832 3058032
20:36:41 27602 8192000000 10195112432 10612658176 1307387056 13.762 100 20 40 1 1317734272 5646016 4701200
20:38:10 3038 8192000000 10242016496 10645377024 1333858288 14.002 100 47 93 0 1351170880 6403136 10909456
20:39:19 22027 8192000000 10270408608 10683146240 1355269408 14.195 100 50 100 0 1378020208 11823360 10927440
20:40:59 12187 8192000000 10284940240 10704236544 1374304800 14.366 100 43 86 1 1396983440 13060704 9617936
20:42:15 30204 8192000000 10316096416 10733367296 1390515152 14.510 100 46 92 1 1425489120 24400816 10573152
20:43:55 29395 8192000000 10310273936 10732978176 1392364704 14.527 100 49 98 0 1424056656 19552816 12139136
20:45:36 18186 8192000000 10319215472 10739752960 1383984752 14.452 99 49 98 1 1417015600 21520736 11510112
20:47:07 8772 8192000000 10336932560 10764095488 1410903504 14.692 100 53 104 1 1448401536 24411200 13086832
20:48:25 2460 8192000000 10361594800 10780975104 1422908544 14.798 100 76 149 2 1468737616 28372400 17456672
20:49:33 6574 8192000000 10352662192 10776834048 1418336592 14.758 100 62 124 0 1467880112 34088288 15455232
20:50:44 6926 8192000000 10359683904 10782240768 1406158336 14.650 99 65 131 1 1461089872 38072288 16859248
20:51:54 4720 8192000000 10374769312 10790801408 1417441360 14.750 100 76 152 0 1482800976 45567136 19792480
20:53:03 2064 8192000000 10377974064 10787098624 1409162704 14.677 100 90 179 1 1475850832 44154688 22533440
20:54:12 3745 8192000000 10354289248 10772197376 1404312752 14.633 100 61 122 0 1459196064 38984112 15899200
20:55:24 3706 8192000000 10366403936 10779078656 1406680800 14.654 100 71 139 1 1467896768 42723984 18491984
20:56:32 22104 8192000000 10331157200 10757410816 1382230432 14.436 99 31 62 3 1431935264 41025264 8679568
20:57:59 6925 8192000000 10326644176 10748530688 1373810272 14.361 100 45 90 2 1431722464 45750016 12162176
20:59:13 10474 8192000000 10301441408 10738176000 1364442896 14.277 100 32 62 4 1423915664 50275968 9196800
21:00:33 25900 8192000000 10310116992 10741542912 1360884640 14.245 100 32 64 3 1414112784 44302304 8925840
21:02:17 21016 8192000000 10294016272 10723721216 1346296384 14.114 100 30 60 0 1398409248 43277376 8835488
21:03:40 61798 8192000000 10287787904 10732666880 1353299344 14.177 99 16 32 0 1396790128 39162432 4328352
21:05:45 17924 8192000000 10268114384 10703888384 1355359056 14.196 100 40 82 3 1378630912 14135792 9136064
21:07:58 40456 8192000000 10260064048 10718871552 1356077472 14.202 100 18 36 0 1377682608 17566448 4038688
21:09:40 58950 8192000000 10286973760 10720718848 1355283616 14.195 99 37 72 2 1376679648 13285728 8110304
21:12:02 49771 8192000000 10272014704 10715586560 1355001248 14.192 99 11 22 0 1372813248 15144960 2667040
21:14:10 103906 8192000000 10278059712 10718007296 1349896176 14.147 98 13 26 2 1370023376 17185152 2942048
21:16:54 97989 8192000000 10274918208 10721443840 1330753456 13.974 96 20 40 1 1343443040 7835072 4854512
21:19:33 74575 8192000000 10273311600 10726522880 1357158704 14.212 98 20 39 0 1373287680 12083760 4045216
21:21:49 55055 8192000000 10284297984 10735489024 1355321408 14.195 97 14 28 1 1375304752 16798576 3184768
21:24:15 61397 8192000000 10283779648 10742239232 1374225152 14.365 99 21 42 0 1397172464 17941360 5005952
21:26:18 80921 8192000000 10288631728 10759725056 1394283312 14.544 100 18 36 1 1410681728 12232976 4165440
21:28:54 64080 8192000000 10307530336 10765660160 1386117104 14.471 99 11 23 1 1399922128 11047712 2757312
21:30:59 76318 8192000000 10299121504 10755063808 1386018928 14.470 99 24 48 0 1399325232 7873856 5432448
21:33:21 20031 8192000000 10319865904 10768171008 1394295936 14.544 99 41 82 0 1413719328 10553952 8869440
21:34:45 42043 8192000000 10320202064 10762887168 1385607456 14.467 98 33 66 1 1405614736 12489392 7517888
21:36:28 79085 8192000000 10294125712 10758729728 1322202416 13.897 94 19 38 0 1340689680 14169568 4317696
21:38:59 141468 8192000000 10288523776 10748563456 1284866592 13.557 92 13 26 0 1301634736 14141952 2626192
21:42:22 102631 8192000000 10254728032 10701381632 1300405840 13.699 95 28 55 0 1318026048 10516736 7103472
21:45:17 14338 8192000000 10283369232 10724429824 1368242464 14.311 100 42 84 1 1388754144 11343136 9168544
21:46:31 25274 8192000000 10259156096 10706247680 1332226512 13.987 97 37 74 0 1348682768 7066560 9389696
21:48:04 78277 8192000000 10234519792 10687729664 1289405504 13.599 95 8 16 0 1300360688 8926464 2028720
21:50:22 58017 8192000000 10219782208 10671595520 1321568368 13.891 98 6 12 0 1330596048 7711520 1316160
21:52:20 86727 8192000000 10262690656 10712322048 1343759856 14.091 99 15 31 0 1354716000 7491664 3464480
21:54:48 28818 8192000000 10266506992 10709094400 1337615008 14.036 98 44 89 0 1361491088 13519120 10356960
21:56:18 47965 8192000000 10287500224 10729717760 1354601968 14.189 99 17 34 0 1376881872 18060688 4219216
21:58:37 84725 8192000000 10286601600 10727407616 1337360432 14.034 97 8 16 0 1356694304 17327824 2006048
22:01:06 66540 8192000000 10277558160 10733416448 1345525728 14.107 97 8 16 1 1362182032 14652592 2003712
22:03:13 40491 8192000000 10287475872 10735046656 1349198288 14.140 98 38 76 0 1370484032 12694560 8591184
22:06:10 81919 8192000000 10290371408 10733535232 1371716224 14.342 99 32 62 1 1386289136 7575824 6997088
22:08:34 31174 8192000000 10273383424 10724478976 1370925840 14.335 100 16 32 0 1384616272 10288608 3401824
22:10:23 61748 8192000000 10270195696 10739470336 1366062448 14.292 99 4 8 0 1375991296 9422048 506800
22:12:40 59421 8192000000 10286255760 10751528960 1378476656 14.403 99 5 10 0 1386636048 7304928 854464
22:14:43 64916 8192000000 10295481024 10746990592 1368655552 14.315 98 13 27 2 1383583536 12065040 2862944
22:16:48 73381 8192000000 10308493872 10778124288 1390945648 14.514 99 17 34 1 1410929792 16109072 3875072
22:19:13 53888 8192000000 10299371328 10752999424 1365335872 14.285 97 35 69 1 1380922560 7448384 8138304
22:21:10 74258 8192000000 10319275472 10767126528 1384654288 14.458 99 26 52 0 1405872608 14781712 6436608
22:23:29 63380 8192000000 10302916640 10775498752 1366712240 14.298 98 18 36 0 1396298416 25381104 4205072
22:25:35 49610 8192000000 10306608192 10751545344 1366623680 14.297 98 35 70 2 1387784032 12652016 8508336
22:27:25 49054 8192000000 10275939408 10726813696 1368520096 14.314 99 25 50 1 1382563920 8575472 5468352
22:29:14 69048 8192000000 10274185920 10731155456 1338855536 14.047 97 24 48 1 1353640096 9501744 5282816
22:31:26 53693 8192000000 10262764608 10725355520 1360977168 14.246 99 11 22 1 1372712320 9408800 2326352
22:33:20 97096 8192000000 10262434480 10720321536 1325185472 13.924 96 8 16 1 1338918880 11880672 1852736
22:37:37 38390 8192000000 9057232000 9279905792 229294928 2.722 17 6 12 0 235768880 5356384 1117568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-01-2022 22:39:43 to 11-01-2022 22:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2022 22:39:58 to 11-01-2022 22:40:00.

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          236394
MON$OLDEST_ACTIVE               236395
MON$OLDEST_SNAPSHOT             236395
MON$NEXT_TRANSACTION            236395
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                       2007552
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:828: 
:829: mscs-test-161.budzdorov.ru    Tue Jan 11 22:38:27 2022
:830:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:831: 
--- end of diff output ---

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

11.01.22 22:40:01. 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-01-11 22:40:04 No severe PSQL-related problems occured
11.01.22 22:40:05. End of report.