Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16076308 kB
MemAvailable 30752776 kB
Buffers 0 kB
Cached 15594072 kB
SwapCached 1628 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.8
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 2021-09-25 19:37:52
DTS_END                         2021-09-25 19:37:52.1740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57588
TRN_ID                          242777
ATT_ID                          702
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: 9254.53 25437 1110543
customer order: creation 176.05 363 21126
customer order: refuse 16.05 395 1926
order to supplier: creation 46.39 1380 5567
order to supplier: removal 3.50 103416 420
invoice (draft): creation 46.80 3225 5616
invoice (draft): removal 52.83 848 6339
invoice accept: apply 41.66 17134 4999
invoice accept: cancel 47.31 824 5677
customer reserve: creation 3865.32 167 463838
customer reserve: removal 4661.19 60 559343
realization accept: apply 85.36 201 10243
realization accept: cancel 79.93 113 9591
payment from customer: creation 60.52 128 7262
payment from customer: removal 5.73 107 687
payment to supplier: creation 49.87 137 5984
payment to supplier: removal 3.73 59 447
service: total inventory turnovers 5.03 1077 604
service: total monetary turnovers 6.42 25 770
service: aggregate perf. data 0.84 22291 101
service: refresh index statistics 0.03 356786 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2021 19:39:06 to 25-09-2021 19:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3263
TEST_TIME 2 3974
TEST_TIME 3 4667
TEST_TIME 4 5430
TEST_TIME 5 4864
TEST_TIME 6 4900
TEST_TIME 7 4548
TEST_TIME 8 4671
TEST_TIME 9 4746
TEST_TIME 10 4995
TEST_TIME 11 5174
TEST_TIME 12 5631
TEST_TIME 13 5716
TEST_TIME 14 5836
TEST_TIME 15 6036
TEST_TIME 16 6755
TEST_TIME 17 6866
TEST_TIME 18 7115
TEST_TIME 19 7385
TEST_TIME 20 7716
TEST_TIME 21 8572
TEST_TIME 22 8560
TEST_TIME 23 8902
TEST_TIME 24 9296
TEST_TIME 25 9401
TEST_TIME 26 9584
TEST_TIME 27 10298
TEST_TIME 28 10689
TEST_TIME 29 10700
TEST_TIME 30 10549
TEST_TIME 31 10550
TEST_TIME 32 10510
TEST_TIME 33 10329
TEST_TIME 34 10238
TEST_TIME 35 10204
TEST_TIME 36 10221
TEST_TIME 37 10326
TEST_TIME 38 10188
TEST_TIME 39 10049
TEST_TIME 40 9891
TEST_TIME 41 9871
TEST_TIME 42 9701
TEST_TIME 43 9585
TEST_TIME 44 9569
TEST_TIME 45 9628
TEST_TIME 46 9534
TEST_TIME 47 9581
TEST_TIME 48 9539
TEST_TIME 49 9623
TEST_TIME 50 9585
TEST_TIME 51 9536
TEST_TIME 52 9559
TEST_TIME 53 9661
TEST_TIME 54 9679
TEST_TIME 55 9630
TEST_TIME 56 9650
TEST_TIME 57 9722
TEST_TIME 58 9720
TEST_TIME 59 9775
TEST_TIME 60 9723
TEST_TIME 61 9575
TEST_TIME 62 9581
TEST_TIME 63 9546
TEST_TIME 64 9540
TEST_TIME 65 9499
TEST_TIME 66 9488
TEST_TIME 67 9458
TEST_TIME 68 9429
TEST_TIME 69 9447
TEST_TIME 70 9437
TEST_TIME 71 9438
TEST_TIME 72 9521
TEST_TIME 73 9629
TEST_TIME 74 9627
TEST_TIME 75 9584
TEST_TIME 76 9597
TEST_TIME 77 9642
TEST_TIME 78 9640
TEST_TIME 79 9590
TEST_TIME 80 9550
TEST_TIME 81 9573
TEST_TIME 82 9524
TEST_TIME 83 9512
TEST_TIME 84 9490
TEST_TIME 85 9470
TEST_TIME 86 9468
TEST_TIME 87 9471
TEST_TIME 88 9384
TEST_TIME 89 9383
TEST_TIME 90 9357
TEST_TIME 91 9325
TEST_TIME 92 9312
TEST_TIME 93 9293
TEST_TIME 94 9302
TEST_TIME 95 9281
TEST_TIME 96 9334
TEST_TIME 97 9392
TEST_TIME 98 9422
TEST_TIME 99 9408
TEST_TIME 100 9436
TEST_TIME 101 9469
TEST_TIME 102 9520
TEST_TIME 103 9522
TEST_TIME 104 9568
TEST_TIME 105 9562
TEST_TIME 106 9609
TEST_TIME 107 9557
TEST_TIME 108 9516
TEST_TIME 109 9456
TEST_TIME 110 9450
TEST_TIME 111 9456
TEST_TIME 112 9406
TEST_TIME 113 9377
TEST_TIME 114 9369
TEST_TIME 115 9309
TEST_TIME 116 9264
TEST_TIME 117 9284
TEST_TIME 118 9268
TEST_TIME 119 9280
TEST_TIME 120 9264

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 25-09-2021 19:39:09 to 25-09-2021 19:39:13.

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 1539754 1539754 0 0.00 0 16665 6 0 0
doc_list_biud 1539754 1539754 0 0.00 0 89614 28 0 0
sp_add_invoice_to_stock 5100 4999 101 1.98 38 180909 17135 0 101
sp_cancel_adding_invoice 5677 5677 0 0.00 4 62478 825 0 0
sp_cancel_client_order 1926 1926 0 0.00 5 20885 396 0 0
sp_cancel_customer_reserve 559343 559343 0 0.00 1 89615 61 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 3 6288 108 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 4092 59 0 0
sp_cancel_supplier_invoice 6346 6339 7 0.11 2 23529 848 0 7
sp_cancel_supplier_order 420 420 0 0.00 8 1012632 103417 0 0
sp_cancel_write_off 9591 9591 0 0.00 1 15286 114 0 0
sp_client_order 21126 21126 0 0.00 3 14583 363 0 0
sp_customer_reserve 463838 463838 0 0.00 0 37765 168 0 0
sp_fill_shopping_cart 32297 32297 0 0.00 1 12812 38 0 0
sp_fill_shopping_cart_clo_res 463817 463817 0 0.00 0 18535 14 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 23751 308 0 0
sp_kill_cost_storno 16402 16402 0 0.00 0 8126 58 0 0
sp_kill_qstorno_ret_qs2qd 1135036 1135036 0 0.00 0 89611 33 0 0
sp_kill_qty_storno 598514 598514 0 0.00 0 89614 72 0 0
sp_lock_selected_doc 21206 21206 0 0.00 0 8090 5 0 0
sp_make_cost_storno 28464 28464 0 0.00 0 13115 60 0 0
sp_multiply_rows_for_pdistr 28464 28464 0 0.00 0 7052 32 0 0
sp_multiply_rows_for_qdistr 21126 21126 0 0.00 0 13961 170 0 0
sp_pay_from_customer 7262 7262 0 0.00 4 18326 129 0 0
sp_pay_to_supplier 5984 5984 0 0.00 5 18950 138 0 0
sp_payment_common 13246 13246 0 0.00 1 15578 36 0 0
sp_qd_handle_on_cancel_clo 1926 1926 0 0.00 0 19567 347 0 0
sp_qd_handle_on_invoice_upd_sts 10652 10652 0 0.00 0 34317 356 0 0
sp_qd_handle_on_reserve_upd_sts 10243 10243 0 0.00 0 14556 69 0 0
sp_reserve_write_off 10243 10243 0 0.00 6 20890 202 0 0
sp_supplier_invoice 5616 5616 0 0.00 7 81111 3225 0 0
sp_supplier_order 5567 5567 0 0.00 5 44985 1381 0 0
srv_aggregate_perf_data 101 101 0 0.00 2509 377632 22292 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1647 164345 41164 0 0
srv_find_qd_qs_mism 1061953 1061953 0 0.00 0 16661 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 56 82920 1077 0 0
srv_make_money_saldo 770 770 0 0.00 1 1662 26 0 0
srv_recalc_idx_stat 3 3 0 0.00 301548 449738 356787 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4999 4999 0 0.00 0 27060 185 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 3948 79 0 0
t$perf-norm:sp_cancel_client_order 1926 1926 0 0.00 0 4121 16 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 802 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 4997 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 4195 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 1986 24 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 23358 1660 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1182 4 0 0
t$perf-norm:sp_client_order 21126 21126 0 0.00 0 26228 20 0 0
t$perf-norm:sp_customer_reserve 1051 1051 0 0.00 0 3474 13 0 0
t$perf-norm:sp_pay_from_customer 7262 7262 0 0.00 0 6634 10 0 0
t$perf-norm:sp_pay_to_supplier 5984 5984 0 0.00 0 4509 8 0 0
t$perf-norm:sp_reserve_write_off 10243 10243 0 0.00 0 6556 11 0 0
t$perf-norm:sp_supplier_invoice 5616 5616 0 0.00 0 15179 24 0 0
t$perf-norm:sp_supplier_order 5567 5567 0 0.00 0 3911 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 227 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 261 6 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 1062 21 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 1093 16 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10150 9948 202 1.99 0 11429 18 0 202
v_all_customers 38536 38536 0 0.00 0 23 1 0 0
v_all_suppliers 31200 31200 0 0.00 0 23 1 0 0
v_all_wares 42252 42252 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 2056 8 0 0
v_cancel_client_order 3852 3852 0 0.00 0 20239 13 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 1944 4 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 490 4 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 295 3 0 0
v_cancel_supplier_invoice 846 832 14 1.65 0 448 15 0 14
v_cancel_supplier_order 840 840 0 0.00 0 743 4 0 0
v_cancel_write_off 2062 2062 0 0.00 0 14561 19 0 0
v_max_id_clo_ord 5565 5565 0 0.00 0 1145 2 0 0
v_max_id_clo_res 1051 1051 0 0.00 0 1761 20 0 0
v_max_id_ord_sup 5606 5606 0 0.00 0 6689 6 0 0
v_max_non_paid_invoice 1555 1555 0 0.00 0 81 2 0 0
v_max_non_paid_realizn 3869 3869 0 0.00 0 5028 5 0 0
v_min_id_clo_ord 5565 5565 0 0.00 0 4843 8 0 0
v_min_id_clo_res 1051 1051 0 0.00 0 5573 15 0 0
v_min_id_ord_sup 5606 5606 0 0.00 0 6319 10 0 0
v_min_non_paid_invoice 5984 5984 0 0.00 0 6269 20 0 0
v_min_non_paid_realizn 7262 7262 0 0.00 0 10921 27 0 0
v_our_firm 10502 10502 0 0.00 0 79 1 0 0
v_reserve_write_off 20486 20486 0 0.00 0 20247 8 0 0
x_lock_depdocs_on_canc_invoice 11600 11600 0 0.00 0 12848 113 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 23489 433 0 0
x_make_qstorno_1000_1200 5567 5567 0 0.00 1 44958 1304 0 0
x_make_qstorno_1200_2000 5616 5616 0 0.00 4 81051 3088 0 0
x_make_qstorno_2100_3300 463838 463838 0 0.00 0 37763 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2021 19:39:14 to 25-09-2021 19:39:18.

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:07:56 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:57 23548 8192000000 10347109040 10663583744 1400750496 14.602 100 84 168 0 1433933776 5953264 27230016
17:10:27 17617 8192000000 10435800656 10765963264 1482823984 15.326 100 85 170 0 1517731760 7619744 27288032
17:11:51 19393 8192000000 10478626016 10819207168 1536843856 15.796 100 92 184 1 1575553344 9705680 29003808
17:13:16 12025 8192000000 10545741248 10901372928 1612334832 16.445 100 81 162 0 1647433744 10968064 24130848
17:14:33 11710 8192000000 10617195904 10985807872 1675920208 16.983 100 75 150 0 1709893136 12242736 21730192
17:15:47 5366 8192000000 10679968592 11052720128 1738778832 17.508 100 82 164 1 1775125952 13156384 23190736
17:16:54 3117 8192000000 10730529888 11108450304 1789985760 17.932 100 88 174 0 1828026832 14510768 23530304
17:17:59 2642 8192000000 10778027328 11165745152 1839949104 18.340 100 79 157 0 1874510272 12382160 22179008
17:19:03 2015 8192000000 10804525200 11191820288 1860360864 18.506 100 93 186 0 1900110576 13514752 26234960
17:20:08 8689 8192000000 10809073184 11204947968 1870604288 18.589 100 64 130 2 1898033872 9962784 17466800
17:21:19 9528 8192000000 10775213360 11206250496 1872460176 18.604 100 24 48 1 1886126064 6718224 6947664
17:22:31 8680 8192000000 10794349040 11224027136 1887744000 18.728 100 23 45 1 1900306976 6559472 6003504
17:23:41 14323 8192000000 10811994768 11240738816 1898305168 18.813 100 19 38 0 1913470000 9373808 5791024
17:24:59 6644 8192000000 10819712256 11253968896 1913669808 18.936 100 22 44 0 1926312560 6413440 6229312
17:26:07 35385 8192000000 10803085552 11243642880 1898641680 18.815 99 31 62 1 1913320304 4910240 9768384
17:27:44 5382 8192000000 10829278768 11259961344 1908381712 18.894 100 27 54 1 1922451984 6127024 7943248
17:28:51 8229 8192000000 10763629728 11189612544 1811155600 18.105 97 31 60 0 1825034288 4852912 9025776
17:30:02 12460 8192000000 10691373968 11105574912 1660656288 16.854 92 21 44 1 1672060736 5014576 6389872
17:31:17 25892 8192000000 10698539536 11123294208 1703019024 17.210 94 20 40 3 1714400496 5053600 6327872
17:32:47 3567 8192000000 10717322896 11124301824 1776896912 17.824 98 34 69 0 1792155616 4983696 10275008
17:33:52 5654 8192000000 10747847664 11172577280 1835836288 18.307 100 31 62 1 1851712528 7212096 8664144
17:34:58 56369 8192000000 10770128800 11196071936 1858249968 18.489 100 38 76 0 1875474672 5773472 11451232
17:36:55 4561 8192000000 10795686576 11223453696 1870696528 18.590 100 31 62 3 1894538528 15284256 8557744
17:38:04 9767 8192000000 10834928336 11254808576 1905325264 18.869 100 42 83 0 1924615584 7618336 11671984
17:39:17 15089 8192000000 10865917952 11284848640 1921550064 18.999 100 45 90 0 1943631536 8794944 13286528
17:40:35 6620 8192000000 10872793472 11296169984 1936333184 19.117 100 47 92 1 1965105888 15025184 13747520
17:41:46 9282 8192000000 10899289840 11331457024 1962595904 19.327 100 48 96 1 1992564288 15398512 14569872
17:43:32 20896 8192000000 10906110480 11348705280 1973535392 19.413 100 27 54 0 1997011312 15449296 8026624
17:45:03 18410 8192000000 10926684224 11364831232 1974553776 19.422 99 36 72 2 2007638816 22207184 10877856
17:46:34 3268 8192000000 10932967696 11358642176 1976114400 19.434 99 62 123 1 2019097056 23415696 19566960
17:47:46 1645 8192000000 10976025616 11394203648 2010205952 19.703 100 85 168 2 2062689200 27524960 24958288
17:48:51 5718 8192000000 10964261216 11387195392 1989429696 19.539 99 65 130 1 2041690064 32810656 19449712
17:50:09 4374 8192000000 10967356416 11386466304 1971820032 19.400 98 67 133 1 2027474160 35775200 19878928
17:51:18 8784 8192000000 10971346912 11402072064 1989123600 19.537 99 56 112 0 2043937328 36670256 18143472
17:52:33 12328 8192000000 10984419888 11411251200 1989322192 19.538 99 74 146 0 2060514752 47607552 23585008
17:53:52 2390 8192000000 10992877456 11417423872 1995158256 19.585 99 79 155 0 2061322896 42218064 23946576
17:55:02 2542 8192000000 11008558272 11424804864 2010615488 19.706 99 85 168 2 2078881840 42115024 26151328
17:56:10 10441 8192000000 10946750608 11387764736 1969346720 19.380 99 32 64 0 2021311232 40620640 11343872
17:57:26 9552 8192000000 10940577872 11377328128 1948259344 19.213 99 42 84 1 2008655088 45981632 14414112
17:58:39 25409 8192000000 10908847200 11352350720 1899022112 18.818 97 37 74 1 1951428656 39846912 12559632
18:00:34 12451 8192000000 10922322864 11357495296 1934432976 19.102 99 29 58 2 1986518160 42510240 9574944
18:01:50 30172 8192000000 10914609120 11365261312 1952051616 19.243 100 22 44 4 1998119008 38553408 7513984
18:04:24 32599 8192000000 10897287296 11361587200 1936468208 19.119 100 18 36 0 1986772704 43861456 6443040
18:06:10 40440 8192000000 10874835472 11310047232 1883834928 18.696 97 40 80 4 1914138016 17880864 12422224
18:07:51 76509 8192000000 10907493840 11348418560 1894068704 18.779 97 25 48 2 1910417392 9771120 6577568
18:10:09 87507 8192000000 10877015408 11338702848 1908690592 18.896 97 20 38 1 1925507760 11612400 5204768
18:12:44 2937 8192000000 10915753680 11352879104 1962463568 19.326 100 50 99 0 1993875888 16143088 15269232
18:13:49 21644 8192000000 10878833808 11339329536 1942505024 19.167 99 21 42 1 1958409472 9866928 6037520
18:15:22 29892 8192000000 10850713968 11322683392 1935117408 19.108 100 4 8 1 1945151520 9075168 958944
18:16:53 34856 8192000000 10878304448 11330400256 1949824720 19.225 100 12 24 0 1959287200 6458544 3003936
18:18:52 164345 8192000000 10893788832 11356024832 1946883376 19.202 99 17 34 0 1964070784 11799008 5388400
18:22:38 61154 8192000000 10904500000 11362676736 1968248944 19.372 100 14 28 1 1982842080 10041056 4552080
18:24:41 20559 8192000000 10934654848 11374559232 1966001504 19.354 99 34 69 3 1983702416 7136016 10564896
18:26:07 35888 8192000000 10902357536 11371520000 1980085184 19.465 100 16 32 0 1996652000 11672960 4893856
18:27:45 22704 8192000000 10915944752 11375964160 1977258448 19.443 99 34 68 2 1994177232 6888032 10030752
18:29:20 53392 8192000000 10925555680 11400609792 1988650720 19.533 99 17 34 1 2004442944 10977792 4814432
18:31:14 73925 8192000000 10927616304 11391377408 1966370960 19.357 98 15 30 1 1980375888 9857408 4147520
18:33:52 98029 8192000000 10912591632 11367796736 1894875520 18.785 95 15 30 0 1913065680 13681728 4508432
18:36:33 82257 8192000000 10905033584 11376390144 1945420336 19.190 98 16 32 0 1964639536 14799952 4419248
18:39:40 30088 8192000000 10894721872 11367309312 1953844880 19.257 98 22 44 1 1972384176 11969888 6569408
18:41:12 42395 8192000000 10887429456 11370201088 1974999344 19.425 100 12 24 2 1988321824 9936112 3386368
18:42:55 47599 8192000000 10855445856 11330215936 1909240560 18.901 98 9 18 0 1918904496 6872448 2791488
18:44:43 46341 8192000000 10849574768 11304886272 1845751088 18.388 95 29 57 1 1863542992 9210592 8581312
18:46:30 50639 8192000000 10887892672 11353100288 1936376704 19.118 99 25 49 0 1958956144 15128992 7450448
18:48:29 79180 8192000000 10889941424 11342815232 1907301600 18.885 98 26 52 0 1936432576 20855456 8275520
18:50:58 45768 8192000000 10875906960 11337826304 1930782192 19.073 99 26 52 0 1950709856 12650096 7277568
18:52:47 15256 8192000000 10870862224 11313221632 1885729024 18.711 97 52 100 3 1908822720 8579680 14514016
18:54:36 67922 8192000000 10874581712 11332620288 1885808032 18.712 97 24 48 1 1903960096 11681776 6470288
18:56:48 86356 8192000000 10891560176 11359367168 1946608208 19.199 100 13 26 0 1970531072 19653600 4269264
18:59:27 41646 8192000000 10888064656 11353489408 1957315200 19.285 100 30 60 1 1979119168 13065504 8738464
19:01:09 79978 8192000000 10886349776 11369324544 1969144448 19.379 100 20 40 0 1990922464 15936176 5841840
19:03:38 71328 8192000000 10881117552 11360251904 1950928176 19.234 99 26 53 0 1967779600 9617168 7234256
19:06:25 30104 8192000000 10883098448 11355062272 1946473840 19.198 99 21 43 1 1965523760 12950256 6099664
19:07:57 82495 8192000000 10864971264 11344924672 1899884912 18.825 97 10 21 0 1908828064 6539136 2404016
19:10:20 41806 8192000000 10892571920 11357487104 1949011216 19.219 99 21 41 0 1963072160 8890224 5170720
19:12:02 45478 8192000000 10948265168 11397312512 1990775760 19.550 100 26 51 1 2013216736 14938528 7502448
19:13:52 51643 8192000000 10915230992 11397906432 1988635712 19.533 100 12 23 0 2006601072 13906896 4058464
19:15:45 56837 8192000000 10906698896 11368861696 1915564208 18.951 96 29 58 1 1938368352 14715888 8088256
19:17:46 44248 8192000000 10928837600 11414355968 1980976128 19.472 100 14 28 1 1999748144 14788160 3983856
19:19:48 39501 8192000000 10935673408 11408764928 1978570672 19.453 99 18 36 0 1998444336 14416416 5457248
19:21:28 15391 8192000000 10937304144 11406966784 1998762496 19.613 100 43 86 1 2029191248 17619808 12808944
19:23:32 100375 8192000000 10887079392 11360595968 1885000496 18.705 95 23 46 0 1904746048 13145904 6599648
19:28:21 49341 8192000000 10884588128 11374379008 1949488192 19.222 99 10 20 0 1962858128 10533104 2836832
19:30:16 57271 8192000000 10862208656 11335823360 1883948928 18.697 96 19 38 1 1897532640 8129264 5454448
19:32:14 61593 8192000000 10861996608 11314823168 1864654240 18.541 96 14 28 0 1877786016 8946608 4185168
19:34:21 59672 8192000000 10861471712 11348983808 1927626464 19.048 99 7 14 0 1940018496 10290496 2101536
19:36:22 46417 8192000000 10861350256 11329273856 1872648704 18.606 96 16 32 0 1884884512 7847056 4388752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2021 19:39:20 to 25-09-2021 19:39:35.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-09-2021 19:39:36 to 25-09-2021 19:39:37.

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          241541
MON$OLDEST_ACTIVE               243275
MON$OLDEST_SNAPSHOT             243275
MON$NEXT_TRANSACTION            243275
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                       2018880
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Sat Sep 25 19:38:00 2021
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Sat Sep 25 19:38:00 2021
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
--- end of diff output ---

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

25.09.21 19:39:39. 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
2021-09-25 19:39:41 No severe PSQL-related problems occured
25.09.21 19:39:42. End of report.