Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.09.2021 16: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 15885100 kB
MemAvailable 30779684 kB
Buffers 0 kB
Cached 15771840 kB
SwapCached 492 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-18 16:37:51
DTS_END                         2021-09-18 16:37:51.9150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58234
TRN_ID                          236520
ATT_ID                          711
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: 9333.64 23119 1120037
customer order: creation 165.69 380 19883
customer order: refuse 13.95 349 1674
order to supplier: creation 45.72 1278 5486
order to supplier: removal 3.21 110767 385
invoice (draft): creation 45.23 3747 5427
invoice (draft): removal 54.84 734 6581
invoice accept: apply 41.53 19158 4984
invoice accept: cancel 49.44 800 5933
customer reserve: creation 3824.55 189 458946
customer reserve: removal 4798.16 59 575779
realization accept: apply 81.18 183 9741
realization accept: cancel 82.96 117 9955
payment from customer: creation 57.62 123 6914
payment from customer: removal 5.93 58 712
payment to supplier: creation 48.35 122 5802
payment to supplier: removal 3.03 61 363
service: total inventory turnovers 4.97 1229 596
service: total monetary turnovers 6.46 41 775
service: aggregate perf. data 0.81 22327 97
service: refresh index statistics 0.03 300664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-09-2021 16:38:41 to 18-09-2021 16:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2389
TEST_TIME 2 3725
TEST_TIME 3 4672
TEST_TIME 4 5157
TEST_TIME 5 5518
TEST_TIME 6 5564
TEST_TIME 7 5420
TEST_TIME 8 5429
TEST_TIME 9 5439
TEST_TIME 10 5754
TEST_TIME 11 5833
TEST_TIME 12 6257
TEST_TIME 13 6576
TEST_TIME 14 6802
TEST_TIME 15 6970
TEST_TIME 16 7434
TEST_TIME 17 7633
TEST_TIME 18 7917
TEST_TIME 19 7975
TEST_TIME 20 8343
TEST_TIME 21 8538
TEST_TIME 22 8754
TEST_TIME 23 8949
TEST_TIME 24 8987
TEST_TIME 25 8970
TEST_TIME 26 9246
TEST_TIME 27 9697
TEST_TIME 28 9884
TEST_TIME 29 10344
TEST_TIME 30 10493
TEST_TIME 31 11069
TEST_TIME 32 10855
TEST_TIME 33 10941
TEST_TIME 34 10757
TEST_TIME 35 10470
TEST_TIME 36 10323
TEST_TIME 37 10232
TEST_TIME 38 10282
TEST_TIME 39 10192
TEST_TIME 40 10047
TEST_TIME 41 9901
TEST_TIME 42 9806
TEST_TIME 43 9735
TEST_TIME 44 9753
TEST_TIME 45 9828
TEST_TIME 46 9794
TEST_TIME 47 9723
TEST_TIME 48 9674
TEST_TIME 49 9620
TEST_TIME 50 9625
TEST_TIME 51 9540
TEST_TIME 52 9454
TEST_TIME 53 9391
TEST_TIME 54 9297
TEST_TIME 55 9198
TEST_TIME 56 9165
TEST_TIME 57 9096
TEST_TIME 58 9194
TEST_TIME 59 9177
TEST_TIME 60 9195
TEST_TIME 61 9201
TEST_TIME 62 9383
TEST_TIME 63 9412
TEST_TIME 64 9465
TEST_TIME 65 9459
TEST_TIME 66 9507
TEST_TIME 67 9573
TEST_TIME 68 9552
TEST_TIME 69 9608
TEST_TIME 70 9601
TEST_TIME 71 9574
TEST_TIME 72 9518
TEST_TIME 73 9432
TEST_TIME 74 9404
TEST_TIME 75 9312
TEST_TIME 76 9279
TEST_TIME 77 9427
TEST_TIME 78 9351
TEST_TIME 79 9333
TEST_TIME 80 9286
TEST_TIME 81 9253
TEST_TIME 82 9194
TEST_TIME 83 9202
TEST_TIME 84 9235
TEST_TIME 85 9197
TEST_TIME 86 9157
TEST_TIME 87 9230
TEST_TIME 88 9263
TEST_TIME 89 9253
TEST_TIME 90 9311
TEST_TIME 91 9383
TEST_TIME 92 9407
TEST_TIME 93 9449
TEST_TIME 94 9401
TEST_TIME 95 9465
TEST_TIME 96 9527
TEST_TIME 97 9503
TEST_TIME 98 9492
TEST_TIME 99 9438
TEST_TIME 100 9390
TEST_TIME 101 9347
TEST_TIME 102 9347
TEST_TIME 103 9391
TEST_TIME 104 9357
TEST_TIME 105 9353
TEST_TIME 106 9333
TEST_TIME 107 9295
TEST_TIME 108 9292
TEST_TIME 109 9242
TEST_TIME 110 9224
TEST_TIME 111 9233
TEST_TIME 112 9215
TEST_TIME 113 9186
TEST_TIME 114 9223
TEST_TIME 115 9253
TEST_TIME 116 9265
TEST_TIME 117 9294
TEST_TIME 118 9322
TEST_TIME 119 9343
TEST_TIME 120 9336

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-09-2021 16:38:44 to 18-09-2021 16:38: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 1544626 1544626 0 0.00 0 24302 6 0 0
doc_list_biud 1544626 1544626 0 0.00 0 30270 28 0 0
sp_add_invoice_to_stock 5047 4984 63 1.24 33 260570 19159 0 63
sp_cancel_adding_invoice 5933 5933 0 0.00 3 56968 801 0 0
sp_cancel_client_order 1674 1674 0 0.00 4 6507 349 0 0
sp_cancel_customer_reserve 575779 575779 0 0.00 1 30273 59 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 2021 58 0 0
sp_cancel_pay_to_supplier 363 363 0 0.00 3 1155 62 0 0
sp_cancel_supplier_invoice 6585 6581 4 0.06 2 15896 735 0 4
sp_cancel_supplier_order 385 385 0 0.00 6 1031305 110767 0 0
sp_cancel_write_off 9955 9955 0 0.00 1 11599 117 0 0
sp_client_order 19883 19883 0 0.00 4 19333 380 0 0
sp_customer_reserve 458946 458946 0 0.00 0 36714 189 0 0
sp_fill_shopping_cart 30779 30779 0 0.00 1 18479 43 0 0
sp_fill_shopping_cart_clo_res 458935 458935 0 0.00 0 15592 14 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 22473 313 0 0
sp_kill_cost_storno 16963 16963 0 0.00 0 9859 56 0 0
sp_kill_qstorno_ret_qs2qd 1168479 1168479 0 0.00 0 30229 32 0 0
sp_kill_qty_storno 615021 615021 0 0.00 0 30270 69 0 0
sp_lock_selected_doc 20292 20292 0 0.00 0 8217 6 0 0
sp_make_cost_storno 27430 27430 0 0.00 0 24296 59 0 0
sp_multiply_rows_for_pdistr 27430 27430 0 0.00 0 11553 26 0 0
sp_multiply_rows_for_qdistr 19883 19883 0 0.00 0 13760 157 0 0
sp_pay_from_customer 6914 6914 0 0.00 4 16158 124 0 0
sp_pay_to_supplier 5802 5802 0 0.00 5 9424 122 0 0
sp_payment_common 12716 12716 0 0.00 1 11288 34 0 0
sp_qd_handle_on_cancel_clo 1674 1674 0 0.00 0 6500 317 0 0
sp_qd_handle_on_invoice_upd_sts 10906 10906 0 0.00 0 24925 341 0 0
sp_qd_handle_on_reserve_upd_sts 9741 9741 0 0.00 0 14736 55 0 0
sp_reserve_write_off 9741 9741 0 0.00 6 24331 184 0 0
sp_supplier_invoice 5427 5427 0 0.00 6 98609 3748 0 0
sp_supplier_order 5486 5486 0 0.00 5 30114 1279 0 0
srv_aggregate_perf_data 98 97 1 1.02 2639 324377 22327 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1649 99803 44699 0 0
srv_find_qd_qs_mism 1072938 1072938 0 0.00 0 17603 5 0 0
srv_make_invnt_saldo 596 596 0 0.00 38 45996 1230 0 0
srv_make_money_saldo 775 775 0 0.00 0 3963 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 131662 385259 300664 0 0
t$perf-abend:sp_add_invoice_to_stock 63 63 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 11717 186 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 4563 136 0 0
t$perf-norm:sp_cancel_client_order 1674 1674 0 0.00 0 5847 21 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1568 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1424 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 363 363 0 0.00 0 6246 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1618 20 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 23724 1602 0 0
t$perf-norm:sp_cancel_write_off 970 970 0 0.00 0 1619 7 0 0
t$perf-norm:sp_client_order 19883 19883 0 0.00 0 6495 19 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 4996 10 0 0
t$perf-norm:sp_pay_from_customer 6914 6914 0 0.00 0 6009 11 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 8222 11 0 0
t$perf-norm:sp_reserve_write_off 9741 9741 0 0.00 0 4718 10 0 0
t$perf-norm:sp_supplier_invoice 5427 5427 0 0.00 0 10450 23 0 0
t$perf-norm:sp_supplier_order 5486 5486 0 0.00 0 8038 17 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 456 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 85 3 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2528 26 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 2598 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10070 9944 126 1.25 0 8295 19 0 126
v_all_customers 35922 35922 0 0.00 0 19 1 0 0
v_all_suppliers 29910 29910 0 0.00 0 21 1 0 0
v_all_wares 39764 39764 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2919 14 0 0
v_cancel_client_order 3348 3348 0 0.00 0 3120 8 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1282 4 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 4121 12 0 0
v_cancel_payment_to_supplier 726 726 0 0.00 0 631 4 0 0
v_cancel_supplier_invoice 812 804 8 0.98 0 2105 19 0 8
v_cancel_supplier_order 770 770 0 0.00 0 1614 9 0 0
v_cancel_write_off 1940 1940 0 0.00 0 4441 21 0 0
v_max_id_clo_ord 5485 5485 0 0.00 0 256 1 0 0
v_max_id_clo_res 933 933 0 0.00 0 1157 11 0 0
v_max_id_ord_sup 5412 5412 0 0.00 0 2721 3 0 0
v_max_non_paid_invoice 1744 1744 0 0.00 0 247 2 0 0
v_max_non_paid_realizn 3737 3737 0 0.00 0 2700 4 0 0
v_min_id_clo_ord 5485 5485 0 0.00 0 2097 5 0 0
v_min_id_clo_res 933 933 0 0.00 0 6441 29 0 0
v_min_id_ord_sup 5412 5412 0 0.00 0 8780 9 0 0
v_min_non_paid_invoice 5802 5802 0 0.00 0 5121 18 0 0
v_min_non_paid_realizn 6914 6914 0 0.00 0 7534 25 0 0
v_our_firm 10196 10196 0 0.00 0 22 1 0 0
v_reserve_write_off 19478 19478 0 0.00 0 5765 8 0 0
x_lock_depdocs_on_canc_invoice 12112 12112 0 0.00 0 21716 117 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 10472 369 0 0
x_make_qstorno_1000_1200 5486 5486 0 0.00 2 30070 1193 0 0
x_make_qstorno_1200_2000 5427 5427 0 0.00 3 96023 3589 0 0
x_make_qstorno_2100_3300 458946 458946 0 0.00 0 32328 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-09-2021 16:38:49 to 18-09-2021 16:38: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
14:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:56 19480 8192000000 10362697312 10681864192 1424970560 14.817 100 90 180 0 1459457808 5537968 28949280
14:10:21 24836 8192000000 10435015520 10765553664 1494633120 15.429 100 94 188 1 1533168688 8198320 30337248
14:11:49 13941 8192000000 10479356208 10818392064 1534429904 15.775 100 84 166 1 1569974672 9125696 26419072
14:13:11 12507 8192000000 10555019200 10913861632 1615444400 16.471 100 74 147 0 1649010720 10422080 23144240
14:14:27 9063 8192000000 10620466128 10991992832 1691616720 17.115 100 70 140 0 1723231600 11141168 20473712
14:15:37 4961 8192000000 10698972496 11077754880 1761682112 17.698 100 79 156 2 1797519024 13972944 21863968
14:16:45 3983 8192000000 10750681696 11133456384 1806142736 18.064 100 83 163 1 1842670240 13367072 23160432
14:17:51 2088 8192000000 10792247168 11175550976 1847003440 18.398 100 85 170 1 1884996560 13574912 24418208
14:18:54 2042 8192000000 10813058656 11209359360 1875072640 18.625 100 70 143 2 1905713376 11055248 19585488
14:19:56 13072 8192000000 10786545872 11214364672 1879032112 18.657 100 37 74 1 1897736880 7436096 11268672
14:21:13 7510 8192000000 10800893440 11227643904 1891680752 18.759 100 24 48 2 1904979456 6508560 6790144
14:22:24 8234 8192000000 10819560000 11243520000 1901780816 18.841 100 26 52 2 1913895584 4855184 7259584
14:23:34 60402 8192000000 10869368384 11269652480 1917531008 18.967 100 57 116 0 1941313712 5627792 18154912
14:25:35 11794 8192000000 10827808528 11264765952 1916208512 18.956 100 28 56 0 1929918384 5523760 8186112
14:26:48 6494 8192000000 10835322336 11264131072 1911510544 18.919 100 26 50 0 1928754256 10242064 7001648
14:27:57 67850 8192000000 10746127392 11181486080 1747015696 17.577 92 30 60 2 1761727680 5171536 9540448
14:30:08 8093 8192000000 10714807920 11134709760 1770836128 17.774 98 28 54 1 1784366208 5519568 8010512
14:31:17 10633 8192000000 10715793424 11128070144 1745033552 17.560 96 25 50 1 1758338496 5974800 7330144
14:32:28 16621 8192000000 10732605600 11153633280 1798822960 18.004 99 29 58 0 1812645728 6115360 7707408
14:33:46 10680 8192000000 10741754448 11162038272 1800377408 18.017 98 42 83 3 1819488320 7336640 11774272
14:34:59 4814 8192000000 10775693328 11200368640 1854982880 18.463 100 24 48 1 1868179376 6139760 7056736
14:36:05 8403 8192000000 10798429168 11221876736 1874691392 18.622 100 27 54 1 1888653392 5744304 8217696
14:37:14 53664 8192000000 10831581824 11250855936 1906046096 18.875 100 19 38 1 1916001584 4875968 5079520
14:39:15 27411 8192000000 10865634784 11293351936 1932369936 19.086 100 43 86 2 1951887408 7531728 11985744
14:40:46 16135 8192000000 10884822672 11317473280 1956831072 19.281 100 54 108 0 1986687712 14462048 15394592
14:42:05 7222 8192000000 10907207648 11339141120 1972897856 19.408 100 50 100 2 2001133344 14042720 14192768
14:43:23 15367 8192000000 10931081216 11362607104 1984486272 19.500 100 59 116 3 2016028000 15425104 16116624
14:44:44 15406 8192000000 10939144608 11371442176 1993065264 19.568 100 52 104 0 2028090368 20000304 15024800
14:46:03 12050 8192000000 10937813008 11372003328 1995426272 19.587 100 50 100 1 2029712784 19249680 15036832
14:47:20 5948 8192000000 10951751344 11388276736 2002705728 19.644 100 50 97 0 2039793520 22535504 14552288
14:48:31 1647 8192000000 10963276720 11374338048 1988481168 19.532 99 87 172 2 2037785600 23815264 25489168
14:49:37 5437 8192000000 10949231168 11362127872 1943930496 19.178 97 85 170 1 1997781536 29535088 24315952
14:50:45 9735 8192000000 10929442496 11362443264 1964817344 19.344 99 51 102 1 2011884272 30718528 16348400
14:52:04 1969 8192000000 10941751552 11356446720 1970799360 19.392 100 83 163 2 2026881792 31255888 24826544
14:53:09 4991 8192000000 10958408464 11374153728 1977263552 19.443 100 70 141 0 2036597696 37370624 21963520
14:54:20 4154 8192000000 10938735632 11357945856 1941281840 19.157 99 67 134 2 2000084080 38181520 20620720
14:55:28 9004 8192000000 10935351744 11353280512 1957762928 19.288 100 70 141 0 2017591504 39030224 20798352
14:56:41 3472 8192000000 10938703200 11344830464 1949316112 19.221 100 80 160 4 2015084352 41584544 24183696
14:57:50 2296 8192000000 10947548672 11360133120 1957638400 19.287 100 77 152 2 2020397200 38451792 24307008
14:58:57 8844 8192000000 10925011008 11350028288 1948873584 19.218 100 55 110 1 2009653920 42452784 18327552
15:00:10 14457 8192000000 10916813424 11357691904 1956878512 19.281 100 35 70 1 2007441040 38971392 11591136
15:01:28 14812 8192000000 10937607472 11375624192 1966035984 19.354 100 34 68 0 2013632608 36427376 11169248
15:02:55 6568 8192000000 10938615600 11368894464 1931591040 19.080 98 37 74 2 1991050144 46417680 13041424
15:04:03 23150 8192000000 10921347152 11381800960 1956905888 19.281 100 26 52 2 2009871184 44211344 8753952
15:05:43 21570 8192000000 10915064096 11377004544 1947388848 19.206 100 20 40 1 1999881968 45451888 7041232
15:07:07 44957 8192000000 10905328928 11358760960 1896314784 18.797 97 14 28 0 1941040496 39363088 5362624
15:08:53 63251 8192000000 10911027504 11348643840 1941308368 19.157 99 23 46 1 1956453712 8122112 7023232
15:10:59 91329 8192000000 10895491808 11338575872 1930793632 19.073 98 16 32 2 1948057536 12691920 4571984
15:13:41 41785 8192000000 10902987104 11358924800 1972502384 19.405 100 31 62 1 1996794256 14679344 9612528
15:15:28 21639 8192000000 10905386448 11373408256 1979071376 19.457 100 24 48 1 1997799760 12056416 6671968
15:16:53 82499 8192000000 10920068336 11385679872 1972472384 19.405 99 15 30 1 1989044752 12496928 4075440
15:19:19 61656 8192000000 10932706032 11396915200 1997082432 19.600 100 36 70 3 2024221040 16680752 10457856
15:21:21 77950 8192000000 10909038080 11379687424 1910484448 18.911 96 18 36 1 1937814768 21939328 5390992
15:23:47 71305 8192000000 10916603904 11386912768 1961340464 19.317 99 12 24 2 1979426448 15188560 2897424
15:25:59 57754 8192000000 10918005696 11387359232 1989114576 19.537 100 22 44 0 2010529056 14835728 6578752
15:27:58 70844 8192000000 10900553744 11364225024 1934127360 19.100 97 31 62 1 1956486160 13997264 8361536
15:30:09 54620 8192000000 10912319712 11392462848 1990980480 19.552 100 16 32 0 2007264992 11355984 4928528
15:32:04 75352 8192000000 10902185488 11375947776 1935597520 19.112 97 12 24 0 1955482240 16400960 3483760
15:34:32 49312 8192000000 10918117968 11396460544 1980211728 19.466 100 21 42 2 2011129280 24771072 6146480
15:36:22 86294 8192000000 10910419328 11388112896 1933354000 19.094 97 12 24 0 1951653216 15254272 3044944
15:38:49 79271 8192000000 10914833808 11401289728 1965439504 19.349 99 10 20 0 1981806016 13843664 2522848
15:41:26 65620 8192000000 10893890160 11362488320 1835582656 18.305 92 14 28 1 1848102560 8989424 3530480
15:43:32 34226 8192000000 10896318416 11365642240 1898500816 18.814 97 16 32 0 1926351696 23521952 4328928
15:45:07 49459 8192000000 10859011968 11309658112 1867447936 18.564 96 28 55 0 1885339072 10402080 7489056
15:47:00 57084 8192000000 10886324896 11344257024 1921381408 18.998 99 28 56 1 1942882496 13384144 8116944
15:49:00 13362 8192000000 10878622096 11319918592 1901558992 18.839 98 36 72 2 1921259328 9603568 10096768
15:50:13 98530 8192000000 10882129712 11340529664 1929070016 19.059 99 37 74 1 1949387824 10341680 9976128
15:52:54 61240 8192000000 10883118256 11351175168 1938930176 19.138 99 26 52 1 1966384288 20400128 7053984
15:55:00 59151 8192000000 10852792928 11330080768 1919235184 18.981 99 7 14 0 1937666528 16146848 2284496
15:57:30 81475 8192000000 10845492048 11327422464 1906857840 18.881 98 18 36 2 1923765792 11285392 5622560
16:00:06 99803 8192000000 10857280368 11347177472 1907850304 18.889 98 10 20 0 1930002528 19207792 2944432
16:02:46 43032 8192000000 10886469392 11354075136 1953393440 19.253 100 19 38 0 1969861248 11034400 5433408
16:04:44 69151 8192000000 10885988288 11359535104 1900133280 18.827 97 13 26 0 1920699088 16448032 4117776
16:06:53 52722 8192000000 10851172832 11317202944 1876992752 18.641 96 32 64 1 1900548112 14409744 9145616
16:08:53 74041 8192000000 10879403696 11356475392 1937051568 19.123 99 14 27 1 1954518752 13819184 3648000
16:11:14 68173 8192000000 10916577728 11386290176 1957152624 19.283 99 20 40 1 1974001616 11558960 5290032
16:13:35 61349 8192000000 10930359888 11389685760 1969822256 19.384 99 22 44 0 1988516688 13029648 5664784
16:16:06 77242 8192000000 10900220576 11374419968 1927973488 19.051 97 19 38 1 1939870336 6381728 5515120
16:18:26 27081 8192000000 10889427264 11356274688 1923966624 19.019 97 21 42 0 1945356608 15182560 6207424
16:19:54 40335 8192000000 10903359888 11387416576 1957677024 19.288 99 17 34 1 1974706688 11974896 5054768
16:21:42 57021 8192000000 10896767056 11390431232 1983368416 19.491 100 6 12 0 1995229152 10283904 1576832
16:23:39 62173 8192000000 10892417680 11376267264 1971035104 19.394 99 15 30 1 1981475904 6762304 3678496
16:25:42 61131 8192000000 10892519952 11383336960 1967535360 19.366 99 11 22 1 1979175264 8849536 2790368
16:27:51 63436 8192000000 10908604752 11392139264 1960374896 19.309 99 11 22 0 1972048816 8725120 2948800
16:29:56 49274 8192000000 10906932224 11372785664 1906767344 18.881 96 17 34 0 1923414992 11801024 4846624
16:31:52 68449 8192000000 10874097968 11357986816 1884172528 18.699 96 16 32 0 1905938768 17291504 4474736
16:34:40 74312 8192000000 10880173152 11364286464 1843871968 18.372 94 22 44 0 1863048736 13563312 5613456
16:36:55 66031 8192000000 9508900784 9825787904 592348880 6.743 31 4 6 0 605484736 11856160 1279696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-09-2021 16:38:55 to 18-09-2021 16:39:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 63 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-09-2021 16:39:11 to 18-09-2021 16:39: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          236519
MON$OLDEST_ACTIVE               237190
MON$OLDEST_SNAPSHOT             237190
MON$NEXT_TRANSACTION            237190
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                       2053920
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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 ---
:46: 
:47: mscs-test-161.budzdorov.ru    Sat Sep 18 16:38:04 2021
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:49: 
--- end of diff output ---

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

18.09.21 16:39: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
2021-09-18 16:39:16 No severe PSQL-related problems occured
18.09.21 16:39:17. End of report.