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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-16 16:42:38
DTS_END                         2022-08-16 16:42:38.2410
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(127:13) ==> X_MAKE_QSTORNO_2100_3300(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39446
TRN_ID                          236120
ATT_ID                          708
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: 9212.79 19041 1105535
customer order: creation 168.63 353 20236
customer order: refuse 14.69 362 1763
order to supplier: creation 44.84 1276 5381
order to supplier: removal 3.59 92141 431
invoice (draft): creation 45.01 3422 5401
invoice (draft): removal 52.76 819 6331
invoice accept: apply 41.14 16599 4937
invoice accept: cancel 47.83 746 5739
customer reserve: creation 3794.37 165 455324
customer reserve: removal 4713.52 54 565622
realization accept: apply 82.07 166 9848
realization accept: cancel 78.75 100 9450
payment from customer: creation 56.98 93 6837
payment from customer: removal 5.80 94 696
payment to supplier: creation 47.79 119 5735
payment to supplier: removal 3.36 68 403
service: total inventory turnovers 4.47 1052 536
service: total monetary turnovers 6.46 29 775
service: aggregate perf. data 0.72 26426 86
service: refresh index statistics 0.03 236742 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-08-2022 16:44:23 to 16-08-2022 16:44:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4089
TEST_TIME 2 4303
TEST_TIME 3 4032
TEST_TIME 4 4273
TEST_TIME 5 3920
TEST_TIME 6 3615
TEST_TIME 7 3937
TEST_TIME 8 4731
TEST_TIME 9 5005
TEST_TIME 10 5025
TEST_TIME 11 5352
TEST_TIME 12 5474
TEST_TIME 13 5396
TEST_TIME 14 5987
TEST_TIME 15 6385
TEST_TIME 16 6450
TEST_TIME 17 6887
TEST_TIME 18 6957
TEST_TIME 19 7171
TEST_TIME 20 7197
TEST_TIME 21 7724
TEST_TIME 22 7997
TEST_TIME 23 8026
TEST_TIME 24 8279
TEST_TIME 25 8681
TEST_TIME 26 9045
TEST_TIME 27 9665
TEST_TIME 28 10431
TEST_TIME 29 10376
TEST_TIME 30 10141
TEST_TIME 31 10051
TEST_TIME 32 10059
TEST_TIME 33 9944
TEST_TIME 34 9905
TEST_TIME 35 10071
TEST_TIME 36 9895
TEST_TIME 37 9862
TEST_TIME 38 9736
TEST_TIME 39 9727
TEST_TIME 40 9672
TEST_TIME 41 9720
TEST_TIME 42 9582
TEST_TIME 43 9584
TEST_TIME 44 9544
TEST_TIME 45 9491
TEST_TIME 46 9309
TEST_TIME 47 9239
TEST_TIME 48 9149
TEST_TIME 49 9146
TEST_TIME 50 9195
TEST_TIME 51 9181
TEST_TIME 52 9203
TEST_TIME 53 9114
TEST_TIME 54 9145
TEST_TIME 55 9222
TEST_TIME 56 9359
TEST_TIME 57 9312
TEST_TIME 58 9346
TEST_TIME 59 9456
TEST_TIME 60 9510
TEST_TIME 61 9516
TEST_TIME 62 9530
TEST_TIME 63 9634
TEST_TIME 64 9648
TEST_TIME 65 9555
TEST_TIME 66 9461
TEST_TIME 67 9436
TEST_TIME 68 9410
TEST_TIME 69 9414
TEST_TIME 70 9506
TEST_TIME 71 9448
TEST_TIME 72 9405
TEST_TIME 73 9309
TEST_TIME 74 9326
TEST_TIME 75 9266
TEST_TIME 76 9240
TEST_TIME 77 9221
TEST_TIME 78 9258
TEST_TIME 79 9234
TEST_TIME 80 9220
TEST_TIME 81 9276
TEST_TIME 82 9290
TEST_TIME 83 9296
TEST_TIME 84 9354
TEST_TIME 85 9434
TEST_TIME 86 9443
TEST_TIME 87 9513
TEST_TIME 88 9528
TEST_TIME 89 9567
TEST_TIME 90 9505
TEST_TIME 91 9452
TEST_TIME 92 9429
TEST_TIME 93 9375
TEST_TIME 94 9351
TEST_TIME 95 9286
TEST_TIME 96 9312
TEST_TIME 97 9321
TEST_TIME 98 9318
TEST_TIME 99 9309
TEST_TIME 100 9305
TEST_TIME 101 9287
TEST_TIME 102 9291
TEST_TIME 103 9326
TEST_TIME 104 9320
TEST_TIME 105 9351
TEST_TIME 106 9368
TEST_TIME 107 9398
TEST_TIME 108 9418
TEST_TIME 109 9390
TEST_TIME 110 9464
TEST_TIME 111 9481
TEST_TIME 112 9414
TEST_TIME 113 9367
TEST_TIME 114 9323
TEST_TIME 115 9310
TEST_TIME 116 9308
TEST_TIME 117 9289
TEST_TIME 118 9253
TEST_TIME 119 9269
TEST_TIME 120 9216

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2022 16:44:27 to 16-08-2022 16:44:30.

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 1528074 1528074 0 0.00 0 46205 5 0 0
doc_list_biud 1528074 1528074 0 0.00 0 30194 26 0 0
sp_add_invoice_to_stock 5041 4937 104 2.06 36 236160 16600 0 104
sp_cancel_adding_invoice 5739 5739 0 0.00 3 52404 747 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 10471 363 0 0
sp_cancel_customer_reserve 565622 565622 0 0.00 0 22602 55 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 8385 95 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 2938 69 0 0
sp_cancel_supplier_invoice 6341 6331 10 0.15 1 30202 819 0 10
sp_cancel_supplier_order 431 431 0 0.00 8 1003744 92142 0 0
sp_cancel_write_off 9450 9450 0 0.00 1 8522 100 0 0
sp_client_order 20236 20236 0 0.00 3 25218 354 0 0
sp_customer_reserve 455324 455324 0 0.00 0 93878 166 0 0
sp_fill_shopping_cart 31001 31001 0 0.00 1 15019 40 0 0
sp_fill_shopping_cart_clo_res 455320 455320 0 0.00 0 17119 14 0 0
sp_get_clo_for_invoice 4933 4933 0 0.00 0 25184 318 0 0
sp_kill_cost_storno 16288 16288 0 0.00 0 8377 49 0 0
sp_kill_qstorno_ret_qs2qd 1147456 1147456 0 0.00 0 30190 30 0 0
sp_kill_qty_storno 604117 604117 0 0.00 0 30192 65 0 0
sp_lock_selected_doc 20515 20515 0 0.00 0 5495 5 0 0
sp_make_cost_storno 27353 27353 0 0.00 0 46201 49 0 0
sp_multiply_rows_for_pdistr 27353 27353 0 0.00 0 13126 24 0 0
sp_multiply_rows_for_qdistr 20236 20236 0 0.00 0 17047 158 0 0
sp_pay_from_customer 6837 6837 0 0.00 4 20439 94 0 0
sp_pay_to_supplier 5735 5735 0 0.00 4 13468 119 0 0
sp_payment_common 12572 12572 0 0.00 1 20425 33 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 10464 332 0 0
sp_qd_handle_on_invoice_upd_sts 10672 10672 0 0.00 0 18600 300 0 0
sp_qd_handle_on_reserve_upd_sts 9848 9848 0 0.00 0 19416 54 0 0
sp_reserve_write_off 9848 9848 0 0.00 6 19468 166 0 0
sp_supplier_invoice 5401 5401 0 0.00 6 87132 3423 0 0
sp_supplier_order 5381 5381 0 0.00 5 52209 1276 0 0
srv_aggregate_perf_data 86 86 0 0.00 2607 551389 26426 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1547 137742 46103 0 0
srv_find_qd_qs_mism 1059268 1059268 0 0.00 0 21504 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 49 35262 1052 0 0
srv_make_money_saldo 775 775 0 0.00 1 6466 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 116016 355811 236742 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4938 4938 0 0.00 0 18200 158 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 8379 106 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 2482 10 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 2261 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 4107 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 332 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 616 11 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 24162 1437 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1268 5 0 0
t$perf-norm:sp_client_order 20236 20236 0 0.00 0 10371 16 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 12814 22 0 0
t$perf-norm:sp_pay_from_customer 6837 6837 0 0.00 0 3121 8 0 0
t$perf-norm:sp_pay_to_supplier 5735 5735 0 0.00 0 12540 13 0 0
t$perf-norm:sp_reserve_write_off 9848 9848 0 0.00 0 13398 11 0 0
t$perf-norm:sp_supplier_invoice 5401 5401 0 0.00 0 3950 16 0 0
t$perf-norm:sp_supplier_order 5381 5381 0 0.00 0 12194 19 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 89 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 65 3 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 2811 24 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 8883 36 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10068 9860 208 2.06 0 8948 17 0 208
v_all_customers 36222 36222 0 0.00 0 18 0 0 0
v_all_suppliers 30052 30052 0 0.00 0 17 1 0 0
v_all_wares 40470 40470 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 5147 13 0 0
v_cancel_client_order 3526 3526 0 0.00 0 5261 7 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 3021 5 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 583 3 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 2595 9 0 0
v_cancel_supplier_invoice 800 780 20 2.50 0 4002 19 0 20
v_cancel_supplier_order 862 862 0 0.00 0 2801 6 0 0
v_cancel_write_off 1930 1930 0 0.00 0 7161 20 0 0
v_max_id_clo_ord 5378 5378 0 0.00 0 1060 1 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 2925 15 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 5316 6 0 0
v_max_non_paid_invoice 1475 1475 0 0.00 0 325 2 0 0
v_max_non_paid_realizn 3850 3850 0 0.00 0 17842 10 0 0
v_min_id_clo_ord 5378 5378 0 0.00 0 6302 14 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 1191 7 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 4594 7 0 0
v_min_non_paid_invoice 5735 5735 0 0.00 0 12060 29 0 0
v_min_non_paid_realizn 6837 6837 0 0.00 0 20419 24 0 0
v_our_firm 10222 10222 0 0.00 0 21 1 0 0
v_reserve_write_off 19696 19696 0 0.00 0 7210 6 0 0
x_lock_depdocs_on_canc_invoice 11680 11680 0 0.00 0 18484 109 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 5953 288 0 0
x_make_qstorno_1000_1200 5381 5381 0 0.00 1 47187 1197 0 0
x_make_qstorno_1200_2000 5401 5401 0 0.00 3 87122 3271 0 0
x_make_qstorno_2100_3300 455324 455324 0 0.00 0 93836 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2022 16:44:31 to 16-08-2022 16:44:35.

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
14:12:41 4 8192000000 8803523040 8816525312 9046528 0.110 4 1 2 0 9153520 64208 42784
14:13:42 21571 8192000000 9901725872 10210889728 980015936 10.684 100 90 178 0 1008989184 6142256 22830992
14:15:08 21404 8192000000 9938534288 10260451328 1021320592 11.085 100 87 174 0 1051960928 8232736 22407600
14:16:40 14281 8192000000 9978927568 10309517312 1063234048 11.487 100 85 170 0 1094823280 10252736 21336496
14:17:58 8094 8192000000 10028053344 10374127616 1115474368 11.984 100 76 152 0 1143688960 9829792 18384800
14:19:11 8607 8192000000 10061211856 10420817920 1153202064 12.340 100 77 152 0 1183195360 12331088 17662208
14:20:24 3645 8192000000 10112392976 10481688576 1204981984 12.823 100 75 150 0 1234308992 12450528 16876480
14:21:29 3560 8192000000 10146130848 10524323840 1237077760 13.119 100 75 150 0 1265883136 11996752 16808624
14:22:35 896 8192000000 10171602496 10547752960 1265121808 13.377 100 80 160 0 1296473168 13441264 17910096
14:23:38 1391 8192000000 10198229328 10579124224 1284345680 13.553 100 92 181 0 1318093808 14075952 19672176
14:24:40 12787 8192000000 10196232496 10587721728 1288852864 13.594 100 63 125 0 1309673776 6496272 14324640
14:26:00 2488 8192000000 10190400752 10598952960 1301048624 13.705 100 43 84 2 1316863248 6572768 9241856
14:27:05 7566 8192000000 10199442480 10609971200 1310451456 13.790 100 25 51 0 1323050672 6951280 5647936
14:28:17 11063 8192000000 10210600432 10622652416 1319405648 13.871 100 22 42 1 1330318288 6212496 4700144
14:29:32 54367 8192000000 10226113008 10635919360 1326984976 13.940 100 43 84 2 1342286160 4883296 10417888
14:31:27 2127 8192000000 10238223824 10645221376 1327999728 13.949 100 49 96 1 1347062432 8428704 10634000
14:32:31 6418 8192000000 10192595200 10607644672 1288134304 13.587 98 17 34 0 1297429856 5606272 3689280
14:33:39 12527 8192000000 10203341168 10613485568 1304737728 13.738 100 11 22 0 1312067648 4892016 2437904
14:34:52 39062 8192000000 10156057104 10557288448 1173999328 12.534 90 20 40 0 1183327632 4647296 4681008
14:36:33 4357 8192000000 10120502400 10516537344 1225730464 13.015 99 33 66 1 1239737040 6361216 7645360
14:37:38 5186 8192000000 10145488784 10545639424 1223007088 12.989 97 17 34 0 1232730400 5924944 3798368
14:38:46 12249 8192000000 10163872016 10573373440 1268055072 13.404 99 20 39 0 1277963280 5181584 4726624
14:39:58 23422 8192000000 10173443968 10585587712 1284054864 13.550 100 26 52 0 1296248896 5710832 6483200
14:41:22 9849 8192000000 10193055360 10608640000 1301507392 13.709 100 23 46 0 1312780848 5952560 5320896
14:42:35 14183 8192000000 10223399568 10638503936 1326222592 13.933 100 25 51 0 1337092304 4980944 5888768
14:43:57 19862 8192000000 10249666528 10667405312 1346951440 14.120 100 45 92 0 1367013616 9036096 11026080
14:45:27 74108 8192000000 10267725328 10691846144 1365526240 14.287 100 37 74 0 1386077248 11668448 8882560
14:47:56 15930 8192000000 10284073152 10710847488 1379220688 14.410 100 30 59 0 1399942336 13733776 6987872
14:49:16 15337 8192000000 10299630096 10724081664 1385115568 14.462 100 43 86 1 1414002224 18522224 10364432
14:50:39 13429 8192000000 10315117024 10737102848 1381399072 14.429 99 39 78 0 1408830608 18140032 9291504
14:52:01 17129 8192000000 10309046064 10739904512 1395559888 14.555 100 47 95 0 1426593952 18725536 12308528
14:53:22 6031 8192000000 10333691504 10755817472 1404103632 14.632 100 44 86 0 1437357392 23673056 9580704
14:54:30 11972 8192000000 10343237712 10766462976 1399123968 14.587 99 52 102 0 1442675280 30757488 12793824
14:55:49 11302 8192000000 10354568272 10776707072 1413565184 14.716 100 59 118 0 1464142128 35341760 15235184
14:57:06 3057 8192000000 10349565056 10766016512 1394757248 14.548 99 85 168 0 1451007456 34731792 21518416
14:58:15 6802 8192000000 10358714512 10778902528 1417566384 14.751 100 64 127 1 1466182832 33395072 15221376
14:59:33 3698 8192000000 10351095664 10775678976 1396312752 14.562 99 60 115 0 1452621440 42426944 13881744
15:00:43 3097 8192000000 10350350320 10759004160 1395250304 14.553 100 81 159 0 1451525776 37209936 19065536
15:01:54 4872 8192000000 10350136048 10759397376 1391542736 14.520 100 75 151 0 1453835296 43341248 18951312
15:03:06 1547 8192000000 10348079072 10759958528 1379854592 14.415 100 71 142 1 1450417248 52686240 17876416
15:04:10 17568 8192000000 10325055280 10752888832 1367527872 14.305 98 25 50 0 1414547696 40253328 6766496
15:05:32 8115 8192000000 10336331872 10758987776 1370524288 14.332 99 42 81 0 1431390832 50398448 10468096
15:06:51 11927 8192000000 10336311520 10769338368 1382445760 14.438 100 27 54 0 1439503728 49041280 8016688
15:08:09 35082 8192000000 10301170608 10739638272 1354375248 14.187 99 26 51 0 1409682928 47378816 7928864
15:09:47 69991 8192000000 10259957680 10714763264 1320438528 13.881 97 12 23 0 1365999536 42346816 3214192
15:12:07 83267 8192000000 10247162192 10674130944 1308478336 13.772 97 33 63 1 1323749968 7873808 7397824
15:14:34 56161 8192000000 10282726864 10716733440 1326499216 13.936 97 30 57 1 1340007040 7059008 6448816
15:16:33 69203 8192000000 10260161616 10714980352 1335849952 14.020 98 18 36 0 1359062320 19118768 4093600
15:18:47 19254 8192000000 10270066528 10705252352 1346545824 14.116 99 32 64 1 1366200160 11927968 7726368
15:20:07 19791 8192000000 10295780176 10718769152 1373137856 14.355 100 49 92 1 1390201504 7308800 9754848
15:21:29 37805 8192000000 10279550176 10727759872 1373485360 14.358 100 48 99 0 1399782208 14596576 11700272
15:24:20 38185 8192000000 10284641616 10741592064 1385353456 14.464 100 8 16 0 1399715632 12330688 2031488
15:25:59 82207 8192000000 10287106416 10745921536 1381183456 14.427 99 22 44 0 1395365408 9565328 4616624
15:28:22 63479 8192000000 10307633808 10761695232 1402754960 14.620 100 41 74 1 1421608272 9504736 9348576
15:30:28 12123 8192000000 10289291760 10753171456 1386456080 14.474 99 12 24 0 1400799072 11644384 2698608
15:31:42 72055 8192000000 10294456112 10772758528 1401035840 14.604 100 12 24 0 1416502800 12602720 2864240
15:33:57 50485 8192000000 10304034272 10765234176 1399840464 14.594 100 17 34 0 1414256480 10120032 4295984
15:35:48 85985 8192000000 10315435648 10774405120 1389241920 14.499 98 21 40 0 1409689264 15776800 4670544
15:38:18 55914 8192000000 10305887776 10764967936 1392152128 14.525 99 11 20 0 1406155632 11798880 2204624
15:40:14 53186 8192000000 10320564160 10782752768 1414480192 14.724 100 24 48 1 1432801552 12834448 5486912
15:42:14 91495 8192000000 10302136736 10749218816 1349732048 14.145 96 16 32 0 1364778656 11595216 3451392
15:44:46 103450 8192000000 10288046080 10742181888 1328376688 13.952 95 5 10 0 1342781088 13361008 1043392
15:47:29 92934 8192000000 10277360272 10721218560 1334061472 14.004 96 36 73 0 1351148128 9018624 8068032
15:50:03 10364 8192000000 10297870656 10733572096 1365048512 14.283 99 47 92 0 1393848128 18230528 10569088
15:51:14 41699 8192000000 10274536816 10739998720 1365328576 14.285 99 7 14 0 1387222928 20338576 1555776
15:52:56 16360 8192000000 10265378048 10726494208 1364361648 14.276 100 6 12 0 1382584752 16962448 1260656
15:54:13 95645 8192000000 10246638704 10691190784 1307473408 13.763 96 19 38 0 1322877072 11253872 4149792
15:56:54 55095 8192000000 10237127184 10698510336 1326008720 13.931 98 6 12 0 1334468640 7509328 950592
15:58:56 38093 8192000000 10241196128 10686074880 1283790256 13.548 95 23 46 0 1295848896 7478704 4579936
16:00:47 25718 8192000000 10242539104 10686955520 1341091408 14.067 100 26 52 1 1359176832 12620160 5465264
16:02:21 45746 8192000000 10257540032 10696876032 1327684208 13.946 98 22 44 0 1345343424 12825360 4833856
16:04:08 113441 8192000000 10237756896 10687733760 1238755824 13.135 92 24 48 0 1255600400 11823632 5020944
16:07:04 57159 8192000000 10254259744 10712403968 1320352176 13.880 98 18 36 0 1339005520 14405712 4247632
16:09:02 50828 8192000000 10264592480 10719850496 1325200512 13.924 98 15 31 0 1348197312 19615184 3381616
16:10:53 137742 8192000000 10268864384 10723991552 1344950784 14.102 98 17 34 0 1364900624 16154000 3795840
16:14:55 40266 8192000000 10269252480 10714099712 1356312192 14.204 99 24 48 0 1370579120 9169744 5097184
16:16:37 70038 8192000000 10287031744 10739691520 1382992192 14.443 100 12 24 0 1398756304 13096112 2668000
16:18:47 61161 8192000000 10269725744 10732908544 1357079920 14.211 98 17 34 0 1374750976 13282144 4388912
16:20:52 57781 8192000000 10280830704 10738565120 1383507184 14.448 100 20 39 0 1394940512 7723824 3709504
16:22:50 42104 8192000000 10281947600 10733604864 1356735184 14.208 98 12 24 0 1371241680 11303888 3202608
16:24:33 35778 8192000000 10308140512 10759200768 1395547616 14.555 100 41 82 0 1415161552 10498368 9115568
16:26:09 71204 8192000000 10288282224 10745982976 1365385552 14.286 98 14 28 1 1380131808 11517552 3228704
16:28:28 90447 8192000000 10300759344 10765328384 1357845024 14.218 97 12 23 0 1374529360 14221968 2462368
16:31:03 101063 8192000000 10293762096 10748600320 1338028640 14.040 96 12 24 0 1363734160 22980736 2724784
16:33:44 108620 8192000000 10272137456 10717413376 1343605968 14.090 97 21 42 0 1356012944 7740208 4666768
16:36:42 15473 8192000000 10277893920 10724442112 1372307616 14.348 100 31 57 1 1384008992 5658784 6042592
16:37:58 70501 8192000000 10272082816 10735435776 1363839008 14.272 99 12 24 0 1376931424 10374048 2718368
16:40:10 64423 8192000000 10273736144 10728185856 1355513152 14.197 98 10 20 0 1370424592 12633616 2277824
16:42:15 27874 8192000000 9487930944 9824321536 634213424 7.185 47 10 20 0 648420288 12504512 1702352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-08-2022 16:44:37 to 16-08-2022 16:44:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2022 16:44:53 to 16-08-2022 16:44:55.

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          235803
MON$OLDEST_ACTIVE               236489
MON$OLDEST_SNAPSHOT             236489
MON$NEXT_TRANSACTION            236489
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                       2029152
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33617 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33617 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33617 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33617 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Aug 16 16:42:45 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Aug 16 16:42:45 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Aug 16 16:42:47 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Tue Aug 16 16:43:09 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

16.08.22 16:44:56. 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-08-16 16:44:58 No severe PSQL-related problems occured
16.08.22 16:45:00. End of report.