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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16028136 kB
MemAvailable 31231716 kB
Buffers 0 kB
Cached 15604188 kB
SwapCached 2312 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-14 19:38:04
DTS_END                         2022-01-14 19:38:04.8890
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/51884
TRN_ID                          251475
ATT_ID                          656
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: 9536.34 24605 1144361
customer order: creation 180.03 346 21604
customer order: refuse 15.99 326 1919
order to supplier: creation 50.28 1226 6033
order to supplier: removal 3.70 101260 444
invoice (draft): creation 48.48 3535 5817
invoice (draft): removal 54.54 826 6545
invoice accept: apply 43.95 17373 5274
invoice accept: cancel 49.14 852 5897
customer reserve: creation 4026.08 171 483130
customer reserve: removal 4746.40 61 569568
realization accept: apply 89.34 186 10721
realization accept: cancel 89.60 115 10752
payment from customer: creation 62.86 111 7543
payment from customer: removal 5.84 63 701
payment to supplier: creation 53.28 114 6393
payment to supplier: removal 3.71 97 445
service: total inventory turnovers 5.14 1076 617
service: total monetary turnovers 6.88 29 826
service: aggregate perf. data 1.07 16070 128
service: refresh index statistics 0.03 348271 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2022 19:39:00 to 14-01-2022 19:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4553
TEST_TIME 2 4798
TEST_TIME 3 6270
TEST_TIME 4 6403
TEST_TIME 5 6241
TEST_TIME 6 5779
TEST_TIME 7 6268
TEST_TIME 8 6534
TEST_TIME 9 6693
TEST_TIME 10 7788
TEST_TIME 11 7624
TEST_TIME 12 7614
TEST_TIME 13 7907
TEST_TIME 14 7893
TEST_TIME 15 8310
TEST_TIME 16 8318
TEST_TIME 17 8827
TEST_TIME 18 8653
TEST_TIME 19 8599
TEST_TIME 20 8952
TEST_TIME 21 8852
TEST_TIME 22 8711
TEST_TIME 23 8763
TEST_TIME 24 9425
TEST_TIME 25 9818
TEST_TIME 26 9993
TEST_TIME 27 10352
TEST_TIME 28 10457
TEST_TIME 29 11059
TEST_TIME 30 11656
TEST_TIME 31 11566
TEST_TIME 32 11395
TEST_TIME 33 11204
TEST_TIME 34 11266
TEST_TIME 35 11178
TEST_TIME 36 11253
TEST_TIME 37 11146
TEST_TIME 38 10982
TEST_TIME 39 11014
TEST_TIME 40 10813
TEST_TIME 41 10784
TEST_TIME 42 10573
TEST_TIME 43 10499
TEST_TIME 44 10655
TEST_TIME 45 10454
TEST_TIME 46 10351
TEST_TIME 47 10256
TEST_TIME 48 10292
TEST_TIME 49 10369
TEST_TIME 50 10386
TEST_TIME 51 10309
TEST_TIME 52 10218
TEST_TIME 53 10238
TEST_TIME 54 10136
TEST_TIME 55 10013
TEST_TIME 56 10057
TEST_TIME 57 9988
TEST_TIME 58 9909
TEST_TIME 59 9822
TEST_TIME 60 9861
TEST_TIME 61 9852
TEST_TIME 62 9795
TEST_TIME 63 9865
TEST_TIME 64 9963
TEST_TIME 65 10038
TEST_TIME 66 10038
TEST_TIME 67 9980
TEST_TIME 68 10016
TEST_TIME 69 10071
TEST_TIME 70 10093
TEST_TIME 71 10124
TEST_TIME 72 10164
TEST_TIME 73 10149
TEST_TIME 74 10179
TEST_TIME 75 10153
TEST_TIME 76 10029
TEST_TIME 77 10076
TEST_TIME 78 9976
TEST_TIME 79 9918
TEST_TIME 80 9939
TEST_TIME 81 9947
TEST_TIME 82 9922
TEST_TIME 83 9890
TEST_TIME 84 9861
TEST_TIME 85 9846
TEST_TIME 86 9811
TEST_TIME 87 9837
TEST_TIME 88 9812
TEST_TIME 89 9833
TEST_TIME 90 9856
TEST_TIME 91 9831
TEST_TIME 92 9829
TEST_TIME 93 9817
TEST_TIME 94 9885
TEST_TIME 95 9938
TEST_TIME 96 10024
TEST_TIME 97 10016
TEST_TIME 98 10089
TEST_TIME 99 9990
TEST_TIME 100 9935
TEST_TIME 101 9967
TEST_TIME 102 9925
TEST_TIME 103 9881
TEST_TIME 104 9832
TEST_TIME 105 9806
TEST_TIME 106 9779
TEST_TIME 107 9752
TEST_TIME 108 9715
TEST_TIME 109 9745
TEST_TIME 110 9685
TEST_TIME 111 9673
TEST_TIME 112 9644
TEST_TIME 113 9618
TEST_TIME 114 9586
TEST_TIME 115 9570
TEST_TIME 116 9608
TEST_TIME 117 9582
TEST_TIME 118 9601
TEST_TIME 119 9607
TEST_TIME 120 9539

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2022 19:39:04 to 14-01-2022 19:39:07.

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 1591900 1591900 0 0.00 0 16123 6 0 0
doc_list_biud 1591900 1591900 0 0.00 0 55237 28 0 0
sp_add_invoice_to_stock 5388 5274 114 2.11 44 198028 17374 0 114
sp_cancel_adding_invoice 5897 5897 0 0.00 4 62739 853 0 0
sp_cancel_client_order 1919 1919 0 0.00 4 15381 326 0 0
sp_cancel_customer_reserve 569568 569568 0 0.00 1 32334 62 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 1749 64 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 3 11254 97 0 0
sp_cancel_supplier_invoice 6556 6545 11 0.16 1 22173 826 0 11
sp_cancel_supplier_order 444 444 0 0.00 6 1161952 101261 0 0
sp_cancel_write_off 10752 10752 0 0.00 1 16991 116 0 0
sp_client_order 21604 21604 0 0.00 3 35993 347 0 0
sp_customer_reserve 483130 483130 0 0.00 0 31915 172 0 0
sp_fill_shopping_cart 33439 33439 0 0.00 1 16863 40 0 0
sp_fill_shopping_cart_clo_res 483128 483128 0 0.00 0 11566 12 0 0
sp_get_clo_for_invoice 5264 5264 0 0.00 0 27667 306 0 0
sp_kill_cost_storno 17795 17795 0 0.00 0 8558 58 0 0
sp_kill_qstorno_ret_qs2qd 1156877 1156877 0 0.00 0 55236 34 0 0
sp_kill_qty_storno 611105 611105 0 0.00 0 55237 73 0 0
sp_lock_selected_doc 22136 22136 0 0.00 0 10080 6 0 0
sp_make_cost_storno 29916 29916 0 0.00 0 11422 53 0 0
sp_multiply_rows_for_pdistr 29916 29916 0 0.00 0 6325 28 0 0
sp_multiply_rows_for_qdistr 21604 21604 0 0.00 0 14179 144 0 0
sp_pay_from_customer 7543 7543 0 0.00 4 11452 112 0 0
sp_pay_to_supplier 6393 6393 0 0.00 4 8500 115 0 0
sp_payment_common 13936 13936 0 0.00 1 10120 27 0 0
sp_qd_handle_on_cancel_clo 1919 1919 0 0.00 0 15370 298 0 0
sp_qd_handle_on_invoice_upd_sts 11156 11156 0 0.00 0 39279 359 0 0
sp_qd_handle_on_reserve_upd_sts 10721 10721 0 0.00 0 20069 60 0 0
sp_reserve_write_off 10721 10721 0 0.00 6 20095 187 0 0
sp_supplier_invoice 5817 5817 0 0.00 5 89690 3536 0 0
sp_supplier_order 6033 6033 0 0.00 5 47380 1226 0 0
srv_aggregate_perf_data 128 128 0 0.00 2750 158030 16070 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1566 135625 43054 0 0
srv_find_qd_qs_mism 1093970 1093970 0 0.00 0 16103 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 19 36474 1077 0 0
srv_make_money_saldo 826 826 0 0.00 1 1362 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 188481 436932 348272 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5274 5274 0 0.00 0 12183 158 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 3455 84 0 0
t$perf-norm:sp_cancel_client_order 1919 1919 0 0.00 0 3737 14 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 758 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 4282 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 784 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1314 25 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 22843 1662 0 0
t$perf-norm:sp_cancel_write_off 1104 1104 0 0.00 0 3712 14 0 0
t$perf-norm:sp_client_order 21604 21604 0 0.00 0 7644 18 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 5589 15 0 0
t$perf-norm:sp_pay_from_customer 7543 7543 0 0.00 0 8836 10 0 0
t$perf-norm:sp_pay_to_supplier 6393 6393 0 0.00 0 11254 15 0 0
t$perf-norm:sp_reserve_write_off 10721 10721 0 0.00 0 5645 11 0 0
t$perf-norm:sp_supplier_invoice 5817 5817 0 0.00 0 10860 20 0 0
t$perf-norm:sp_supplier_order 6033 6033 0 0.00 0 3401 14 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 332 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 138 3 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 1029 22 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 2903 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10746 10518 228 2.12 0 7175 18 0 228
v_all_customers 39622 39622 0 0.00 0 240 1 0 0
v_all_suppliers 33190 33190 0 0.00 0 229 1 0 0
v_all_wares 43208 43208 0 0.00 0 238 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 6911 28 0 0
v_cancel_client_order 3838 3838 0 0.00 0 6302 6 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1651 6 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 4405 6 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 2505 6 0 0
v_cancel_supplier_invoice 854 832 22 2.57 0 5896 22 0 22
v_cancel_supplier_order 888 888 0 0.00 0 200 3 0 0
v_cancel_write_off 2208 2208 0 0.00 0 6318 16 0 0
v_max_id_clo_ord 6033 6033 0 0.00 0 4123 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 3447 15 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 6312 6 0 0
v_max_non_paid_invoice 1632 1632 0 0.00 0 2651 5 0 0
v_max_non_paid_realizn 4009 4009 0 0.00 0 2451 5 0 0
v_min_id_clo_ord 6033 6033 0 0.00 0 4206 8 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 8311 14 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 6725 7 0 0
v_min_non_paid_invoice 6393 6393 0 0.00 0 6141 17 0 0
v_min_non_paid_realizn 7543 7543 0 0.00 0 10117 22 0 0
v_our_firm 10654 10654 0 0.00 0 238 1 0 0
v_reserve_write_off 21442 21442 0 0.00 0 7173 7 0 0
x_lock_depdocs_on_canc_invoice 12026 12026 0 0.00 0 29420 116 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 8012 364 0 0
x_make_qstorno_1000_1200 6033 6033 0 0.00 2 47372 1140 0 0
x_make_qstorno_1200_2000 5817 5817 0 0.00 2 89460 3396 0 0
x_make_qstorno_2100_3300 483130 483130 0 0.00 0 31913 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-01-2022 19:39:08 to 14-01-2022 19:39:12.

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:08 22 8192000000 8811863248 8827478016 16055408 0.195 4 4 5 0 16282128 87456 139264
17:09:10 18995 8192000000 9908568320 10223439872 1001972096 10.898 100 88 176 0 1030476592 5763440 22741056
17:10:38 32211 8192000000 9960155920 10286972928 1041174416 11.276 100 88 176 1 1073117664 9073984 22869264
17:12:15 11486 8192000000 9986645520 10328137728 1076982272 11.619 100 82 164 0 1107775904 10589056 20204576
17:13:34 14215 8192000000 10049979440 10400591872 1129185440 12.114 100 78 152 2 1157803568 10489936 18128192
17:14:51 6530 8192000000 10102548944 10468638720 1187859472 12.663 100 73 144 1 1216506640 12153648 16493520
17:16:04 4696 8192000000 10152785008 10526347264 1235514880 13.105 100 87 174 1 1267173072 12532880 19125312
17:17:11 9436 8192000000 10171886832 10557890560 1268342336 13.406 100 78 156 0 1299286176 13928624 17015216
17:18:22 1580 8192000000 10212279936 10598678528 1295121456 13.651 100 96 191 3 1330024704 13065296 21837952
17:19:24 5959 8192000000 10219056656 10608275456 1302233648 13.716 100 82 166 1 1329447456 8159392 19054416
17:20:30 5074 8192000000 10193172992 10609074176 1306334352 13.753 100 25 50 0 1319349376 7616592 5398432
17:21:35 5762 8192000000 10205718320 10619850752 1315656896 13.837 100 19 38 2 1326427024 5484640 5285488
17:22:44 10737 8192000000 10206190544 10631512064 1324439456 13.917 100 16 32 1 1333391120 5657440 3294224
17:23:55 34476 8192000000 10254869616 10652815360 1336026272 14.022 100 80 161 2 1361382736 5970240 19386224
17:25:29 6826 8192000000 10221140368 10645012480 1334344208 14.006 100 18 35 1 1344913840 6086512 4483120
17:26:42 5106 8192000000 10227091392 10649497600 1337642960 14.036 100 23 46 0 1349444240 6997344 4803936
17:27:47 50906 8192000000 10207609680 10625748992 1308792912 13.775 99 28 58 1 1320915056 5747792 6374352
17:29:43 8574 8192000000 10159179728 10564366336 1246611008 13.207 97 27 54 0 1259081984 6199968 6271008
17:30:53 7720 8192000000 10115845424 10510655488 1215579536 12.921 98 15 30 0 1224398288 5606464 3212288
17:32:02 8208 8192000000 10139174416 10534277120 1226942784 13.026 98 36 71 1 1241399648 5633872 8822992
17:33:13 18570 8192000000 10150767920 10558685184 1250859232 13.246 99 17 34 2 1259952512 5513088 3580192
17:34:34 6232 8192000000 10176961424 10583523328 1280268944 13.515 100 28 56 1 1297172448 11123648 5779856
17:35:41 9692 8192000000 10182377312 10594414592 1294935776 13.649 100 14 28 0 1303320160 4849952 3534432
17:36:54 17300 8192000000 10190797696 10607288320 1302852512 13.721 100 27 54 2 1315017600 5294624 6870464
17:38:14 68243 8192000000 10223109312 10638557184 1324436944 13.917 100 30 60 0 1337505760 6382768 6686048
17:40:25 16816 8192000000 10258826944 10670977024 1352500208 14.170 100 43 85 1 1374516560 11999856 10016496
17:41:43 10655 8192000000 10276804240 10691325952 1362196480 14.257 100 55 110 0 1390795680 15657168 12942032
17:42:57 3652 8192000000 10286757904 10703134720 1374455936 14.367 100 57 114 2 1400948944 13358336 13134672
17:44:05 22953 8192000000 10300599904 10715279360 1381812048 14.433 100 66 132 1 1413683840 15624464 16247328
17:45:34 9371 8192000000 10305007488 10724020224 1382015328 14.435 100 56 111 1 1414559216 19368288 13175600
17:46:53 11182 8192000000 10302867792 10719416320 1377947504 14.398 99 54 108 1 1411328656 20332944 13048208
17:48:10 5296 8192000000 10338540048 10752557056 1389962640 14.506 100 73 146 1 1439407072 31652304 17792128
17:49:19 10018 8192000000 10319701936 10746028032 1399453312 14.590 100 61 122 2 1435899744 21950480 14495952
17:50:48 2256 8192000000 10344222064 10754260992 1400177328 14.597 100 86 171 2 1446627792 25496000 20954464
17:51:54 5280 8192000000 10315181136 10737782784 1372857472 14.353 98 45 90 1 1407588528 22903792 11827264
17:53:05 1565 8192000000 10317418464 10723840000 1363477328 14.269 98 82 165 2 1416657168 32139792 21040048
17:54:11 5273 8192000000 10314857072 10723610624 1369231216 14.320 99 74 148 0 1422398128 34471248 18695664
17:55:21 3008 8192000000 10359161792 10768924672 1402343264 14.616 100 84 166 0 1462656432 39708048 20605120
17:56:28 4834 8192000000 10349285536 10757042176 1395396032 14.554 100 76 150 0 1451151568 36288000 19467536
17:57:42 3756 8192000000 10364595200 10773696512 1403083696 14.622 100 82 164 1 1467776608 43837008 20855904
17:58:48 12477 8192000000 10337076368 10764427264 1384182896 14.454 99 38 77 0 1439739888 44927392 10629600
18:00:05 6210 8192000000 10361278464 10785226752 1407673584 14.663 100 47 94 2 1466134416 45395312 13065520
18:01:15 2006 8192000000 10384386880 10787569664 1390074320 14.507 99 88 177 2 1468550816 55890272 22586224
18:02:19 8448 8192000000 10376386272 10794057728 1405011552 14.640 100 58 116 0 1476523792 55596128 15916112
18:03:33 13611 8192000000 10340535792 10769891328 1394780320 14.548 100 31 60 3 1448204528 45140192 8284016
18:04:48 9822 8192000000 10336609040 10773741568 1382906256 14.443 100 33 66 1 1443927264 51607936 9413072
18:06:14 33405 8192000000 10315618640 10752032768 1374993120 14.372 100 37 75 4 1430459632 45192240 10274272
18:07:48 30858 8192000000 10305198288 10767519744 1379816480 14.415 100 16 32 0 1419350144 34730000 4803664
18:10:16 91942 8192000000 10296249504 10729844736 1345290064 14.105 97 23 47 5 1360295280 9826752 5178464
18:12:52 75927 8192000000 10290949760 10735259648 1340834544 14.065 96 13 26 1 1354898848 11241152 2823152
18:15:10 30895 8192000000 10306043088 10755485696 1388006272 14.488 100 11 22 1 1402156080 11419456 2730352
18:16:48 104386 8192000000 10325232928 10770845696 1375722608 14.378 98 14 28 0 1393230096 14581424 2926064
18:19:41 100342 8192000000 10324285264 10773352448 1407420208 14.661 100 31 62 1 1425454368 10179888 7854272
18:22:23 18670 8192000000 10318182240 10766536704 1363403712 14.268 97 43 86 0 1387840064 14465344 9971008
18:23:52 27294 8192000000 10316777440 10780119040 1407757920 14.664 100 11 22 0 1420600544 9870736 2971888
18:25:21 27525 8192000000 10314111408 10768261120 1398846032 14.585 99 22 44 0 1414123136 9647632 5629472
18:26:48 41679 8192000000 10318640736 10771238912 1399194064 14.588 99 44 86 0 1419253680 11119984 8939632
18:28:48 47778 8192000000 10265883424 10723565568 1342031008 14.076 96 12 24 0 1352384064 7645248 2707808
18:30:38 59791 8192000000 10304289456 10766553088 1399696720 14.592 100 11 22 0 1416008336 13631184 2680432
18:32:38 58289 8192000000 10297468976 10760900608 1378571344 14.404 99 10 20 1 1392903584 11991360 2340880
18:35:02 54090 8192000000 10267323024 10725552128 1334202400 14.005 96 25 50 0 1346070176 5477296 6390480
18:37:00 49292 8192000000 10279213808 10745303040 1372318912 14.348 99 21 42 0 1386718208 9475440 4923856
18:39:33 102617 8192000000 10283719632 10734342144 1289221328 13.597 93 8 16 0 1304705536 14069424 1414784
18:42:58 49467 8192000000 10269855552 10727591936 1330187920 13.969 97 16 32 0 1345923200 11716368 4018912
18:44:48 31711 8192000000 10251995488 10687741952 1301882272 13.712 96 20 40 0 1314800544 8713280 4204992
18:46:24 75682 8192000000 10257032592 10714185728 1308837600 13.776 96 18 36 1 1326846048 14058976 3949472
18:48:46 40262 8192000000 10275574944 10728726528 1361953152 14.255 100 11 22 0 1376136800 11054720 3128928
18:50:38 44310 8192000000 10283738432 10731872256 1367153856 14.302 100 19 38 0 1382169728 10447120 4568752
18:52:22 29246 8192000000 10307412112 10732609536 1345850832 14.110 98 36 72 3 1362103664 7355616 8897216
18:53:54 112874 8192000000 10285992048 10742718464 1362945920 14.264 99 34 66 3 1393839760 23447120 7446720
18:56:48 25519 8192000000 10285517376 10730409984 1366100064 14.292 99 32 64 1 1383997120 10933776 6963280
18:58:13 81072 8192000000 10260541648 10725818368 1349584160 14.144 98 26 53 1 1366143280 10356176 6202944
19:00:42 63177 8192000000 10271053760 10734776320 1366902944 14.299 99 10 20 0 1378210880 8594048 2713888
19:02:45 97401 8192000000 10289175232 10761375744 1382961264 14.443 100 10 20 0 1395707856 10690976 2055616
19:05:26 37525 8192000000 10286902128 10736431104 1355936848 14.201 98 15 30 0 1368697520 9730256 3030416
19:07:07 75769 8192000000 10300551792 10748018688 1367603184 14.306 99 18 36 0 1383996512 11809504 4583824
19:10:06 24552 8192000000 10308821216 10757623808 1379931888 14.416 99 20 40 1 1396026000 11655056 4439056
19:11:31 67126 8192000000 10298957040 10760429568 1365039824 14.283 98 14 28 1 1385305584 17121328 3144432
19:13:38 86109 8192000000 10270822320 10717335552 1290715792 13.611 93 7 14 0 1309115472 16943968 1455712
19:16:06 93702 8192000000 10308921552 10766577664 1360726192 14.244 98 12 24 0 1376134624 12786080 2622352
19:18:58 54874 8192000000 10267348176 10722275328 1338793280 14.047 96 14 27 1 1354271040 11954592 3523168
19:20:53 69930 8192000000 10268446944 10725490688 1340060960 14.058 97 10 20 0 1352202080 10053728 2087392
19:23:07 55786 8192000000 10265356064 10724216832 1364384816 14.277 100 8 16 1 1374207152 7878288 1944048
19:25:05 104638 8192000000 10264554240 10723459072 1321673136 13.892 96 16 32 1 1338663552 12867888 4122528
19:28:31 17006 8192000000 10279274272 10736947200 1378179680 14.400 100 29 58 0 1393850480 8883904 6786896
19:29:51 53821 8192000000 10276077936 10744438784 1355638480 14.198 98 17 35 0 1372861824 13418704 3804640
19:32:33 34741 8192000000 10267995824 10722480128 1334579520 14.009 97 29 58 0 1349459344 8125168 6754656
19:34:09 135624 8192000000 10257471136 10706231296 1220318896 12.965 89 15 30 0 1233826016 9949536 3557584
19:37:25 45010 8192000000 9147847968 9397129216 308620400 3.630 23 13 26 0 316670144 5417024 2632720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-01-2022 19:39:14 to 14-01-2022 19:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2022 19:39:30 to 14-01-2022 19:39:32.

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          251783
MON$OLDEST_ACTIVE               251784
MON$OLDEST_SNAPSHOT             251784
MON$NEXT_TRANSACTION            251784
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                       2069216
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 ---
--- end of diff output ---

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

14.01.22 19:39:33. 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-14 19:39:35 No severe PSQL-related problems occured
14.01.22 19:39:36. End of report.