Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 19:40
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 16380036 kB
MemAvailable 31258920 kB
Buffers 0 kB
Cached 15316356 kB
SwapCached 2452 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-14 19:39:23
DTS_END                         2022-06-14 19:38:20.0990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52388
TRN_ID                          240012
ATT_ID                          274
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: 9219.83 20451 1106380
customer order: creation 173.52 362 20822
customer order: refuse 14.67 377 1760
order to supplier: creation 45.78 1253 5494
order to supplier: removal 3.36 104176 403
invoice (draft): creation 46.82 3446 5618
invoice (draft): removal 50.18 858 6022
invoice accept: apply 42.08 17531 5050
invoice accept: cancel 45.51 806 5461
customer reserve: creation 3952.18 171 474262
customer reserve: removal 4548.85 59 545862
realization accept: apply 84.92 177 10190
realization accept: cancel 82.12 105 9854
payment from customer: creation 60.10 104 7212
payment from customer: removal 5.78 86 694
payment to supplier: creation 48.83 127 5860
payment to supplier: removal 3.23 65 387
service: total inventory turnovers 4.55 1147 546
service: total monetary turnovers 6.47 31 776
service: aggregate perf. data 0.86 20813 103
service: refresh index statistics 0.03 257322 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2022 19:40:41 to 14-06-2022 19:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5959
TEST_TIME 2 5289
TEST_TIME 3 5112
TEST_TIME 4 4786
TEST_TIME 5 4720
TEST_TIME 6 5183
TEST_TIME 7 4942
TEST_TIME 8 5215
TEST_TIME 9 5120
TEST_TIME 10 5590
TEST_TIME 11 5789
TEST_TIME 12 5759
TEST_TIME 13 7016
TEST_TIME 14 7419
TEST_TIME 15 7545
TEST_TIME 16 7898
TEST_TIME 17 7949
TEST_TIME 18 8141
TEST_TIME 19 8546
TEST_TIME 20 8574
TEST_TIME 21 9081
TEST_TIME 22 9170
TEST_TIME 23 9073
TEST_TIME 24 9317
TEST_TIME 25 9573
TEST_TIME 26 9620
TEST_TIME 27 10771
TEST_TIME 28 11020
TEST_TIME 29 11123
TEST_TIME 30 10835
TEST_TIME 31 10844
TEST_TIME 32 10744
TEST_TIME 33 10749
TEST_TIME 34 10554
TEST_TIME 35 10382
TEST_TIME 36 10332
TEST_TIME 37 10162
TEST_TIME 38 10135
TEST_TIME 39 9996
TEST_TIME 40 9914
TEST_TIME 41 9693
TEST_TIME 42 9803
TEST_TIME 43 9776
TEST_TIME 44 9749
TEST_TIME 45 9648
TEST_TIME 46 9561
TEST_TIME 47 9625
TEST_TIME 48 9551
TEST_TIME 49 9453
TEST_TIME 50 9411
TEST_TIME 51 9384
TEST_TIME 52 9351
TEST_TIME 53 9317
TEST_TIME 54 9312
TEST_TIME 55 9234
TEST_TIME 56 9503
TEST_TIME 57 9501
TEST_TIME 58 9494
TEST_TIME 59 9599
TEST_TIME 60 9618
TEST_TIME 61 9538
TEST_TIME 62 9594
TEST_TIME 63 9587
TEST_TIME 64 9652
TEST_TIME 65 9670
TEST_TIME 66 9724
TEST_TIME 67 9607
TEST_TIME 68 9628
TEST_TIME 69 9576
TEST_TIME 70 9509
TEST_TIME 71 9449
TEST_TIME 72 9373
TEST_TIME 73 9376
TEST_TIME 74 9373
TEST_TIME 75 9397
TEST_TIME 76 9459
TEST_TIME 77 9423
TEST_TIME 78 9410
TEST_TIME 79 9439
TEST_TIME 80 9455
TEST_TIME 81 9472
TEST_TIME 82 9490
TEST_TIME 83 9487
TEST_TIME 84 9466
TEST_TIME 85 9507
TEST_TIME 86 9540
TEST_TIME 87 9571
TEST_TIME 88 9586
TEST_TIME 89 9583
TEST_TIME 90 9521
TEST_TIME 91 9446
TEST_TIME 92 9475
TEST_TIME 93 9450
TEST_TIME 94 9412
TEST_TIME 95 9406
TEST_TIME 96 9350
TEST_TIME 97 9357
TEST_TIME 98 9347
TEST_TIME 99 9324
TEST_TIME 100 9345
TEST_TIME 101 9403
TEST_TIME 102 9386
TEST_TIME 103 9413
TEST_TIME 104 9402
TEST_TIME 105 9402
TEST_TIME 106 9428
TEST_TIME 107 9423
TEST_TIME 108 9472
TEST_TIME 109 9480
TEST_TIME 110 9456
TEST_TIME 111 9538
TEST_TIME 112 9467
TEST_TIME 113 9455
TEST_TIME 114 9406
TEST_TIME 115 9376
TEST_TIME 116 9361
TEST_TIME 117 9306
TEST_TIME 118 9314
TEST_TIME 119 9280
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-06-2022 19:40:44 to 14-06-2022 19:40:48.

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 1545684 1545684 0 0.00 0 23623 6 0 0
doc_list_biud 1545684 1545684 0 0.00 0 25664 27 0 0
sp_add_invoice_to_stock 5164 5050 114 2.20 22 233725 17532 0 114
sp_cancel_adding_invoice 5461 5461 0 0.00 4 54753 807 0 0
sp_cancel_client_order 1760 1760 0 0.00 4 10623 378 0 0
sp_cancel_customer_reserve 545862 545862 0 0.00 1 18294 60 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 12151 86 0 0
sp_cancel_pay_to_supplier 387 387 0 0.00 3 3445 65 0 0
sp_cancel_supplier_invoice 6031 6022 9 0.14 2 18715 858 0 9
sp_cancel_supplier_order 403 403 0 0.00 8 920102 104176 0 0
sp_cancel_write_off 9854 9854 0 0.00 1 11235 106 0 0
sp_client_order 20822 20822 0 0.00 4 25993 363 0 0
sp_customer_reserve 474262 474262 0 0.00 0 176984 171 0 0
sp_fill_shopping_cart 31932 31932 0 0.00 1 25340 43 0 0
sp_fill_shopping_cart_clo_res 474254 474254 0 0.00 0 13157 14 0 0
sp_get_clo_for_invoice 5042 5042 0 0.00 0 36713 317 0 0
sp_kill_cost_storno 16396 16396 0 0.00 0 12145 58 0 0
sp_kill_qstorno_ret_qs2qd 1108003 1108003 0 0.00 0 18703 33 0 0
sp_kill_qty_storno 584592 584592 0 0.00 0 25663 70 0 0
sp_lock_selected_doc 21015 21015 0 0.00 0 8964 6 0 0
sp_make_cost_storno 28302 28302 0 0.00 0 16290 59 0 0
sp_multiply_rows_for_pdistr 28302 28302 0 0.00 0 8578 30 0 0
sp_multiply_rows_for_qdistr 20822 20822 0 0.00 0 17826 169 0 0
sp_pay_from_customer 7212 7212 0 0.00 4 11607 105 0 0
sp_pay_to_supplier 5860 5860 0 0.00 4 16004 127 0 0
sp_payment_common 13072 13072 0 0.00 1 11596 25 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 10581 344 0 0
sp_qd_handle_on_invoice_upd_sts 10501 10501 0 0.00 0 25661 332 0 0
sp_qd_handle_on_reserve_upd_sts 10190 10190 0 0.00 0 14730 56 0 0
sp_reserve_write_off 10190 10190 0 0.00 6 14761 178 0 0
sp_supplier_invoice 5618 5618 0 0.00 8 105216 3446 0 0
sp_supplier_order 5494 5494 0 0.00 5 35051 1254 0 0
srv_aggregate_perf_data 103 103 0 0.00 2338 309430 20814 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2271 219589 48805 0 0
srv_find_qd_qs_mism 1058065 1058065 0 0.00 0 23621 6 0 0
srv_make_invnt_saldo 546 546 0 0.00 31 26999 1148 0 0
srv_make_money_saldo 776 776 0 0.00 1 3367 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 135887 372315 257322 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5050 5050 0 0.00 0 13571 171 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 3301 63 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 9852 16 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 10140 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 1708 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 387 387 0 0.00 0 1357 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 2511 21 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 26089 1723 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 4210 8 0 0
t$perf-norm:sp_client_order 20822 20822 0 0.00 0 11978 16 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 5264 24 0 0
t$perf-norm:sp_pay_from_customer 7212 7212 0 0.00 0 10900 12 0 0
t$perf-norm:sp_pay_to_supplier 5860 5860 0 0.00 0 8725 15 0 0
t$perf-norm:sp_reserve_write_off 10190 10190 0 0.00 0 4410 10 0 0
t$perf-norm:sp_supplier_invoice 5618 5618 0 0.00 0 7288 20 0 0
t$perf-norm:sp_supplier_order 5494 5494 0 0.00 0 9837 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 67 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 80 3 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 2040 18 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 7614 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 85 23 0 0
v_add_invoice_to_stock 10308 10080 228 2.21 0 7141 18 0 228
v_all_customers 37732 37732 0 0.00 0 26 1 0 0
v_all_suppliers 30566 30566 0 0.00 0 19 1 0 0
v_all_wares 41640 41640 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2457 10 0 0
v_cancel_client_order 3520 3520 0 0.00 0 3885 9 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 1693 7 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 3745 10 0 0
v_cancel_payment_to_supplier 774 774 0 0.00 0 600 4 0 0
v_cancel_supplier_invoice 850 832 18 2.11 0 514 12 0 18
v_cancel_supplier_order 806 806 0 0.00 0 3399 9 0 0
v_cancel_write_off 2004 2004 0 0.00 0 2263 14 0 0
v_max_id_clo_ord 5494 5494 0 0.00 0 301 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 2910 15 0 0
v_max_id_ord_sup 5618 5618 0 0.00 0 3621 4 0 0
v_max_non_paid_invoice 1689 1689 0 0.00 0 5410 7 0 0
v_max_non_paid_realizn 3884 3884 0 0.00 0 2582 5 0 0
v_min_id_clo_ord 5494 5494 0 0.00 0 5235 7 0 0
v_min_id_clo_res 981 981 0 0.00 0 5820 10 0 0
v_min_id_ord_sup 5618 5618 0 0.00 0 4722 8 0 0
v_min_non_paid_invoice 5860 5860 0 0.00 0 9792 16 0 0
v_min_non_paid_realizn 7212 7212 0 0.00 0 11589 18 0 0
v_our_firm 10564 10564 0 0.00 0 17 1 0 0
v_reserve_write_off 20380 20380 0 0.00 0 3754 7 0 0
x_lock_depdocs_on_canc_invoice 11067 11067 0 0.00 0 14262 113 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 18098 460 0 0
x_make_qstorno_1000_1200 5494 5494 0 0.00 1 35010 1179 0 0
x_make_qstorno_1200_2000 5618 5618 0 0.00 3 105203 3281 0 0
x_make_qstorno_2100_3300 474262 474262 0 0.00 0 176981 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2022 19:40:49 to 14-06-2022 19:40:53.

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:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:25 22553 8192000000 9883317264 10190426112 966311904 10.551 100 82 164 1 994197424 5811472 22074048
17:10:56 14000 8192000000 9938537568 10256252928 1019641360 11.069 100 93 186 0 1051661024 8145200 23874464
17:12:20 19168 8192000000 9969571056 10302271488 1050689824 11.367 100 78 156 1 1079575136 9672896 19212416
17:13:43 9449 8192000000 10023918688 10367336448 1111635888 11.948 100 80 160 0 1141363952 11210464 18517600
17:14:55 5574 8192000000 10075282288 10433581056 1164916480 12.449 100 74 149 0 1193990448 12358688 16715280
17:16:04 4878 8192000000 10125428928 10495303680 1208484528 12.855 100 75 149 0 1239021984 12728384 17809072
17:17:11 5210 8192000000 10150922400 10525974528 1241835456 13.163 100 80 161 0 1273901440 14970560 17095424
17:18:18 2145 8192000000 10201351168 10579312640 1279530144 13.509 100 92 184 0 1314738656 15064528 20143984
17:19:22 1387 8192000000 10211359744 10593927168 1293286048 13.634 100 93 182 1 1328242992 14715408 20241536
17:20:24 8448 8192000000 10204820432 10604179456 1305752624 13.748 100 56 111 4 1327014384 8751664 12510096
17:21:35 4123 8192000000 10202368352 10611269632 1312323680 13.807 100 26 52 0 1325260736 7417696 5519360
17:22:40 5076 8192000000 10200202400 10616586240 1319533952 13.872 100 30 58 1 1332546080 6113472 6898656
17:23:48 67214 8192000000 10253760544 10648760320 1329188128 13.960 100 49 98 1 1346551936 5564912 11798896
17:25:55 8132 8192000000 10229849568 10648485888 1334369504 14.007 100 28 57 0 1347246352 6080320 6796528
17:27:05 7646 8192000000 10223573360 10640994304 1320755584 13.884 99 31 62 0 1333343264 5686944 6900736
17:28:15 8626 8192000000 10199990640 10620796928 1303378176 13.726 99 22 44 0 1314801584 6549488 4873920
17:29:25 6656 8192000000 10213627184 10627100672 1316152448 13.842 100 22 44 1 1326864592 5601168 5110976
17:30:32 8590 8192000000 10148695696 10551709696 1224886672 13.007 97 16 31 1 1234166928 5980320 3299936
17:31:41 68290 8192000000 10139658704 10544615424 1171780432 12.513 93 35 70 2 1189815792 9043344 8992016
17:33:50 3315 8192000000 10170116144 10574024704 1276109648 13.477 100 45 90 2 1291403920 4738944 10555328
17:34:55 5402 8192000000 10182220224 10591936512 1291638384 13.619 100 31 62 1 1304377376 5764704 6974288
17:36:02 5260 8192000000 10193448480 10606665728 1301193024 13.706 100 23 46 0 1312084208 5896144 4995040
17:37:10 37519 8192000000 10201622656 10614493184 1309229616 13.779 100 31 61 0 1323105296 6794384 7081296
17:38:51 14360 8192000000 10235463760 10651856896 1334206240 14.005 100 46 91 2 1350946096 6568176 10171680
17:40:13 33652 8192000000 10256404448 10679443456 1357098176 14.211 100 47 94 1 1379427712 10934240 11395296
17:41:50 16050 8192000000 10284009120 10701721600 1372975648 14.354 100 49 96 1 1400150240 16213824 10960768
17:43:10 10559 8192000000 10297607920 10722209792 1384243024 14.454 100 36 72 1 1409694896 16627168 8824704
17:44:26 17281 8192000000 10309472704 10734501888 1387873728 14.487 100 44 86 0 1422991904 24603808 10514368
17:45:46 6069 8192000000 10312677296 10738081792 1393384320 14.536 100 44 88 4 1424323632 19597824 11341488
17:46:59 17712 8192000000 10330277424 10756837376 1407683968 14.663 100 42 82 0 1436868096 18530768 10653360
17:48:22 11471 8192000000 10321445184 10752159744 1403417520 14.625 100 48 96 1 1439237760 23131552 12688688
17:49:44 10791 8192000000 10337685952 10758418432 1401324624 14.607 100 67 135 0 1445581344 27886480 16370240
17:51:00 4973 8192000000 10360099712 10782998528 1403157472 14.623 99 62 123 0 1458190496 38910288 16122736
17:52:10 2267 8192000000 10363778848 10783002624 1419629280 14.769 100 77 152 4 1466976656 28781392 18565984
17:53:16 3776 8192000000 10358133936 10786230272 1408395648 14.670 99 63 126 1 1456127792 31716656 16015488
17:54:26 7929 8192000000 10342045952 10769477632 1404747600 14.637 100 51 101 1 1449275280 31460048 13067632
17:55:39 8122 8192000000 10340794928 10756526080 1384503856 14.457 99 66 132 0 1431453744 30756384 16193504
17:56:55 11780 8192000000 10332356096 10752925696 1386365872 14.473 100 58 113 1 1440941008 40119856 14455280
17:58:11 5812 8192000000 10332780144 10746634240 1365484704 14.287 99 70 140 1 1419613328 35753088 18375536
17:59:21 9547 8192000000 10329384864 10759421952 1384077456 14.453 100 45 90 2 1440160144 44289168 11793520
18:00:50 14430 8192000000 10322179728 10751180800 1380777792 14.424 100 28 56 1 1427914528 39327488 7809248
18:02:07 17473 8192000000 10331271360 10761183232 1376263664 14.383 100 32 63 0 1436067584 50971296 8832624
18:03:31 23392 8192000000 10299796080 10731294720 1336776416 14.028 98 25 49 0 1390523024 46965440 6781168
18:04:56 28631 8192000000 10296902336 10738393088 1339818400 14.056 99 26 52 1 1406215760 58621344 7776016
18:06:27 60020 8192000000 10264113040 10712145920 1320941408 13.885 98 25 50 1 1356476768 28712752 6822608
18:08:28 44506 8192000000 10275493840 10704117760 1351302224 14.159 100 44 90 0 1375800608 14092528 10405856
18:10:16 19230 8192000000 10292759024 10712084480 1365722256 14.289 100 63 123 3 1390153808 10466320 13965232
18:11:36 219585 8192000000 10261128544 10710786048 1308228656 13.770 95 10 20 0 1319321200 8876736 2215808
18:16:17 42633 8192000000 10283562816 10733637632 1373178688 14.356 100 19 38 0 1386982800 9816048 3988064
18:18:00 98306 8192000000 10287529088 10734735360 1366436160 14.295 99 21 42 1 1383870496 12295600 5138736
18:21:03 32697 8192000000 10273403712 10725285888 1362399232 14.259 99 35 71 0 1378509152 7615856 8494064
18:23:09 59270 8192000000 10270193376 10731466752 1354203424 14.185 98 6 12 0 1367279936 11706736 1369776
18:25:11 47463 8192000000 10267475200 10729967616 1366963360 14.300 99 14 28 1 1383144256 13143360 3037536
18:27:00 57119 8192000000 10263905216 10717364224 1367396800 14.304 99 16 30 2 1378549632 7288592 3864240
18:29:15 56197 8192000000 10287230288 10743988224 1374940368 14.371 99 8 16 1 1384235648 7873904 1421376
18:31:13 36517 8192000000 10282532464 10735841280 1377673728 14.396 99 35 70 0 1393240224 7237840 8328656
18:32:52 26174 8192000000 10317013888 10770935808 1404679760 14.637 100 16 32 1 1425941264 17337968 3923536
18:34:20 54014 8192000000 10287628112 10759647232 1377131952 14.391 99 14 28 1 1402828240 22334768 3361520
18:36:15 99027 8192000000 10293206256 10756452352 1347398960 14.124 97 11 22 0 1367510912 17453744 2658208
18:39:06 36707 8192000000 10283644800 10725883904 1328024368 13.949 96 29 58 1 1349500192 15338544 6137280
18:41:18 77028 8192000000 10256957120 10705825792 1261606048 13.345 92 21 42 0 1275725920 9165248 4954624
18:43:35 94955 8192000000 10257618032 10717945856 1302226656 13.715 96 19 38 0 1328391088 21989648 4174784
18:47:11 34213 8192000000 10255494352 10710745088 1346954784 14.120 100 20 40 0 1367191984 16063616 4173584
18:48:47 68728 8192000000 10241776352 10687299584 1314767936 13.829 98 16 32 1 1333196352 15035312 3393104
18:51:03 59347 8192000000 10258065968 10703704064 1349824592 14.146 100 12 24 0 1373972032 21163376 2984064
18:53:08 108139 8192000000 10248433872 10709196800 1330534128 13.972 98 13 26 1 1352620592 18514112 3572352
18:55:57 34938 8192000000 10263048736 10709622784 1330500720 13.972 97 26 52 0 1347093248 10858208 5734320
18:57:56 25314 8192000000 10279376736 10711130112 1356121184 14.203 100 27 54 0 1371009408 8950592 5937632
18:59:23 55375 8192000000 10268948512 10724384768 1350662848 14.153 99 13 26 1 1366957136 13371872 2922416
19:01:19 126022 8192000000 10291894960 10730577920 1330381168 13.971 97 30 60 2 1349911984 12397008 7133808
19:04:31 138469 8192000000 10279609360 10732347392 1294395264 13.644 94 19 36 2 1314357040 16307952 3653824
19:07:51 108884 8192000000 10286177312 10726076416 1349372288 14.142 98 28 56 0 1369526864 13918672 6235904
19:10:41 58307 8192000000 10286415568 10732908544 1384857632 14.460 100 23 44 0 1397035232 7508032 4669568
19:12:39 58715 8192000000 10285961472 10746654720 1382730368 14.441 99 9 18 1 1401545824 16955216 1860240
19:14:38 66345 8192000000 10293077472 10747580416 1368002016 14.309 98 11 22 0 1381616224 10934368 2679840
19:16:50 68460 8192000000 10312684656 10758144000 1382262384 14.437 98 21 42 0 1397494768 10204112 5028272
19:19:28 57914 8192000000 10286656240 10741116928 1362856064 14.263 98 23 46 1 1381825184 13801712 5167408
19:21:31 68058 8192000000 10283856624 10739994624 1340960720 14.066 96 25 50 2 1359517424 12599472 5957232
19:23:40 40658 8192000000 10290264656 10742902784 1360759200 14.244 98 24 48 1 1377123696 10880912 5483584
19:25:21 46161 8192000000 10281493744 10730295296 1344154752 14.095 97 25 50 0 1362269376 12511504 5603120
19:27:20 70613 8192000000 10293537584 10729136128 1324427728 13.917 96 20 40 0 1339028784 10600544 4000512
19:29:31 140509 8192000000 10255050880 10704560128 1294132512 13.642 94 26 52 1 1315120000 14669200 6318288
19:33:00 32770 8192000000 10264635824 10715979776 1358364848 14.223 100 25 48 0 1376356272 12455728 5535696
19:34:48 55899 8192000000 10267065616 10725617664 1332904272 13.993 97 6 12 0 1352558304 18148144 1505888
19:36:45 64260 8192000000 10261595104 10711982080 1327366032 13.943 97 19 39 1 1345733808 14333280 4034496

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-06-2022 19:40:55 to 14-06-2022 19:41:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 19:41:10 to 14-06-2022 19:41:12.

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          244924
MON$OLDEST_ACTIVE               246493
MON$OLDEST_SNAPSHOT             246493
MON$NEXT_TRANSACTION            246493
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                       2020096
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:587: 
:588: mscs-test-161.budzdorov.ru    Tue Jun 14 19:39:25 2022
:589:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:590: 
--- end of diff output ---

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

14.06.22 19:41:13. 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-06-14 19:41:15 No severe PSQL-related problems occured
14.06.22 19:41:17. End of report.