Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 01: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 16013000 kB
MemAvailable 30804664 kB
Buffers 0 kB
Cached 15656848 kB
SwapCached 416 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-17 01:37:46
DTS_END                         2021-09-17 01:37:46.7810
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/60650
TRN_ID                          236936
ATT_ID                          670
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: 9214.03 19257 1105684
customer order: creation 169.53 353 20343
customer order: refuse 13.92 373 1670
order to supplier: creation 45.41 1287 5449
order to supplier: removal 3.44 108689 413
invoice (draft): creation 45.56 3579 5467
invoice (draft): removal 53.30 844 6396
invoice accept: apply 40.92 18187 4911
invoice accept: cancel 48.33 827 5799
customer reserve: creation 3782.89 179 453947
customer reserve: removal 4713.67 62 565640
realization accept: apply 81.72 198 9807
realization accept: cancel 88.14 113 10577
payment from customer: creation 58.46 135 7015
payment from customer: removal 5.76 91 691
payment to supplier: creation 47.71 148 5725
payment to supplier: removal 3.63 113 436
service: total inventory turnovers 4.38 1234 526
service: total monetary turnovers 6.38 49 766
service: aggregate perf. data 0.85 21368 102
service: refresh index statistics 0.03 227317 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-09-2021 01:38:44 to 17-09-2021 01:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3880
TEST_TIME 2 3023
TEST_TIME 3 3790
TEST_TIME 4 4259
TEST_TIME 5 4067
TEST_TIME 6 4899
TEST_TIME 7 4911
TEST_TIME 8 5223
TEST_TIME 9 5580
TEST_TIME 10 6209
TEST_TIME 11 6659
TEST_TIME 12 6695
TEST_TIME 13 6851
TEST_TIME 14 6942
TEST_TIME 15 7403
TEST_TIME 16 7469
TEST_TIME 17 7599
TEST_TIME 18 7702
TEST_TIME 19 8184
TEST_TIME 20 8173
TEST_TIME 21 8320
TEST_TIME 22 8263
TEST_TIME 23 8405
TEST_TIME 24 8395
TEST_TIME 25 8443
TEST_TIME 26 8671
TEST_TIME 27 8941
TEST_TIME 28 9071
TEST_TIME 29 9278
TEST_TIME 30 9545
TEST_TIME 31 10650
TEST_TIME 32 10662
TEST_TIME 33 10556
TEST_TIME 34 10352
TEST_TIME 35 10168
TEST_TIME 36 10133
TEST_TIME 37 10043
TEST_TIME 38 9951
TEST_TIME 39 9774
TEST_TIME 40 9786
TEST_TIME 41 9658
TEST_TIME 42 9588
TEST_TIME 43 9441
TEST_TIME 44 9490
TEST_TIME 45 9405
TEST_TIME 46 9361
TEST_TIME 47 9430
TEST_TIME 48 9366
TEST_TIME 49 9313
TEST_TIME 50 9328
TEST_TIME 51 9236
TEST_TIME 52 9190
TEST_TIME 53 9164
TEST_TIME 54 9249
TEST_TIME 55 9172
TEST_TIME 56 9033
TEST_TIME 57 9123
TEST_TIME 58 9069
TEST_TIME 59 9035
TEST_TIME 60 9176
TEST_TIME 61 9133
TEST_TIME 62 9183
TEST_TIME 63 9269
TEST_TIME 64 9288
TEST_TIME 65 9259
TEST_TIME 66 9205
TEST_TIME 67 9374
TEST_TIME 68 9410
TEST_TIME 69 9350
TEST_TIME 70 9433
TEST_TIME 71 9494
TEST_TIME 72 9577
TEST_TIME 73 9500
TEST_TIME 74 9425
TEST_TIME 75 9472
TEST_TIME 76 9402
TEST_TIME 77 9343
TEST_TIME 78 9397
TEST_TIME 79 9339
TEST_TIME 80 9325
TEST_TIME 81 9275
TEST_TIME 82 9235
TEST_TIME 83 9241
TEST_TIME 84 9279
TEST_TIME 85 9266
TEST_TIME 86 9215
TEST_TIME 87 9147
TEST_TIME 88 9134
TEST_TIME 89 9120
TEST_TIME 90 9086
TEST_TIME 91 9061
TEST_TIME 92 9126
TEST_TIME 93 9239
TEST_TIME 94 9210
TEST_TIME 95 9266
TEST_TIME 96 9377
TEST_TIME 97 9404
TEST_TIME 98 9365
TEST_TIME 99 9398
TEST_TIME 100 9413
TEST_TIME 101 9460
TEST_TIME 102 9496
TEST_TIME 103 9429
TEST_TIME 104 9420
TEST_TIME 105 9349
TEST_TIME 106 9318
TEST_TIME 107 9336
TEST_TIME 108 9301
TEST_TIME 109 9277
TEST_TIME 110 9270
TEST_TIME 111 9244
TEST_TIME 112 9203
TEST_TIME 113 9173
TEST_TIME 114 9165
TEST_TIME 115 9202
TEST_TIME 116 9186
TEST_TIME 117 9161
TEST_TIME 118 9168
TEST_TIME 119 9224
TEST_TIME 120 9220

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-09-2021 01:38:48 to 17-09-2021 01:38:51.

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 1528187 1528187 0 0.00 0 21949 6 0 0
doc_list_biud 1528187 1528187 0 0.00 0 39776 29 0 0
sp_add_invoice_to_stock 5043 4911 132 2.61 31 173356 18187 0 132
sp_cancel_adding_invoice 5799 5799 0 0.00 4 65365 827 0 0
sp_cancel_client_order 1670 1670 0 0.00 4 11617 373 0 0
sp_cancel_customer_reserve 565640 565640 0 0.00 1 23031 63 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 11512 92 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 8085 114 0 0
sp_cancel_supplier_invoice 6412 6396 16 0.24 2 39786 844 0 16
sp_cancel_supplier_order 413 413 0 0.00 7 881385 108689 0 0
sp_cancel_write_off 10577 10577 0 0.00 1 12020 113 0 0
sp_client_order 20343 20343 0 0.00 3 27386 353 0 0
sp_customer_reserve 453947 453947 0 0.00 0 111205 180 0 0
sp_fill_shopping_cart 31251 31251 0 0.00 1 32358 42 0 0
sp_fill_shopping_cart_clo_res 453935 453935 0 0.00 0 22346 13 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 17791 289 0 0
sp_kill_cost_storno 17503 17503 0 0.00 0 21944 64 0 0
sp_kill_qstorno_ret_qs2qd 1148666 1148666 0 0.00 0 39769 34 0 0
sp_kill_qty_storno 605201 605201 0 0.00 0 39772 73 0 0
sp_lock_selected_doc 20410 20410 0 0.00 0 9683 6 0 0
sp_make_cost_storno 27446 27446 0 0.00 0 15620 63 0 0
sp_multiply_rows_for_pdistr 27446 27446 0 0.00 0 8919 31 0 0
sp_multiply_rows_for_qdistr 20343 20343 0 0.00 0 15120 152 0 0
sp_pay_from_customer 7015 7015 0 0.00 4 14666 136 0 0
sp_pay_to_supplier 5725 5725 0 0.00 5 11997 148 0 0
sp_payment_common 12740 12740 0 0.00 1 14050 36 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 0 11607 348 0 0
sp_qd_handle_on_invoice_upd_sts 10698 10698 0 0.00 0 14990 321 0 0
sp_qd_handle_on_reserve_upd_sts 9807 9807 0 0.00 0 18018 59 0 0
sp_reserve_write_off 9807 9807 0 0.00 6 18048 199 0 0
sp_supplier_invoice 5467 5467 0 0.00 7 79977 3580 0 0
sp_supplier_order 5449 5449 0 0.00 5 33537 1288 0 0
srv_aggregate_perf_data 103 102 1 0.97 2785 172633 21369 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1714 132250 45260 0 0
srv_find_qd_qs_mism 1059769 1059769 0 0.00 0 19057 5 0 0
srv_make_invnt_saldo 526 526 0 0.00 23 72227 1234 0 0
srv_make_money_saldo 766 766 0 0.00 0 16107 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 114921 348969 227318 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 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 4911 4911 0 0.00 0 18631 199 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 3584 98 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 7683 21 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 3389 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 678 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 101 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1187 12 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 28654 1914 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 3929 8 0 0
t$perf-norm:sp_client_order 20343 20343 0 0.00 0 8990 23 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 5187 11 0 0
t$perf-norm:sp_pay_from_customer 7015 7015 0 0.00 0 6495 13 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 5927 12 0 0
t$perf-norm:sp_reserve_write_off 9807 9807 0 0.00 0 6156 12 0 0
t$perf-norm:sp_supplier_invoice 5467 5467 0 0.00 0 14089 24 0 0
t$perf-norm:sp_supplier_order 5449 5449 0 0.00 0 7007 21 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 790 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 499 9 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 2151 28 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 905 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 5 0 0
v_add_invoice_to_stock 10060 9796 264 2.62 0 9799 24 0 264
v_all_customers 37166 37166 0 0.00 0 20 1 0 0
v_all_suppliers 30190 30190 0 0.00 0 19 1 0 0
v_all_wares 40686 40686 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 10424 34 0 0
v_cancel_client_order 3340 3340 0 0.00 0 3638 5 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 1982 5 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 3704 6 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 2207 7 0 0
v_cancel_supplier_invoice 846 814 32 3.78 0 2519 21 0 32
v_cancel_supplier_order 826 826 0 0.00 0 2837 7 0 0
v_cancel_write_off 2010 2010 0 0.00 0 3743 14 0 0
v_max_id_clo_ord 5449 5449 0 0.00 0 701 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 10200 18 0 0
v_max_id_ord_sup 5459 5459 0 0.00 0 4550 3 0 0
v_max_non_paid_invoice 1538 1538 0 0.00 0 4499 5 0 0
v_max_non_paid_realizn 3649 3649 0 0.00 0 4871 7 0 0
v_min_id_clo_ord 5449 5449 0 0.00 0 2332 7 0 0
v_min_id_clo_res 965 965 0 0.00 0 2506 12 0 0
v_min_id_ord_sup 5459 5459 0 0.00 0 4598 5 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 5563 19 0 0
v_min_non_paid_realizn 7015 7015 0 0.00 0 9575 28 0 0
v_our_firm 10252 10252 0 0.00 0 22 1 0 0
v_reserve_write_off 19614 19614 0 0.00 0 10028 7 0 0
x_lock_depdocs_on_canc_invoice 11788 11788 0 0.00 0 19639 126 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 16397 444 0 0
x_make_qstorno_1000_1200 5449 5449 0 0.00 2 33528 1195 0 0
x_make_qstorno_1200_2000 5467 5467 0 0.00 3 74040 3431 0 0
x_make_qstorno_2100_3300 453947 453947 0 0.00 0 111203 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 01:38:53 to 17-09-2021 01:38:57.

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
23:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:51 24312 8192000000 10360690576 10678415360 1413728208 14.717 100 91 182 0 1449830240 6445152 29656880
23:10:29 20878 8192000000 10435611984 10769551360 1491113472 15.399 100 85 171 0 1526597264 8379696 27104096
23:12:00 12107 8192000000 10472745888 10819301376 1539740432 15.821 100 83 166 0 1574494992 9550928 25203632
23:13:18 7206 8192000000 10547737104 10901344256 1606717344 16.397 100 86 172 0 1643276032 11059200 25499488
23:14:28 7638 8192000000 10618878448 10984898560 1672426960 16.954 100 78 155 1 1706886592 12023632 22436000
23:15:38 7007 8192000000 10673316320 11047620608 1730851568 17.443 100 69 139 0 1762718464 12382160 19484736
23:16:46 10223 8192000000 10733376336 11117658112 1799803040 18.012 100 74 148 0 1832561344 12319056 20439248
23:17:58 2109 8192000000 10792863488 11183812608 1855294224 18.465 100 88 176 1 1893677328 13498208 24884896
23:19:01 1285 8192000000 10823004064 11211788288 1877018336 18.641 100 89 176 1 1915500704 13811360 24671008
23:20:04 11518 8192000000 10810231520 11213758464 1881086688 18.674 100 70 139 2 1910876144 10056352 19733104
23:21:16 5457 8192000000 10795396000 11219693568 1885042384 18.706 100 36 71 3 1903029328 7510736 10476208
23:22:24 10621 8192000000 10790628752 11225653248 1889445808 18.741 100 17 34 0 1900365552 6427760 4491984
23:23:36 14453 8192000000 10811251232 11246731264 1907761184 18.889 100 17 34 0 1918066032 5739856 4564992
23:24:53 18244 8192000000 10874142048 11274358784 1917131904 18.964 100 65 130 4 1941723728 5397264 19194560
23:26:12 4957 8192000000 10845853696 11271675904 1905206992 18.868 99 31 62 3 1921750224 7897920 8645312
23:27:19 6773 8192000000 10833673424 11262459904 1913693840 18.936 100 23 45 1 1925596736 5735200 6167696
23:28:31 46649 8192000000 10730454560 11159633920 1795006512 17.973 95 29 56 2 1808415504 5247264 8161728
23:30:19 5618 8192000000 10754266256 11175546880 1822461216 18.198 99 47 93 1 1843651152 6556320 14633616
23:31:26 10883 8192000000 10709992480 11126517760 1737992336 17.502 96 13 26 1 1747440208 5148432 4299440
23:32:40 64901 8192000000 10738723504 11165257728 1798191328 17.999 98 27 54 1 1811224768 5042944 7990496
23:34:45 9043 8192000000 10751523664 11180294144 1838612912 18.330 100 33 66 1 1854706688 5515824 10577952
23:35:54 6030 8192000000 10780223136 11206266880 1863531344 18.532 100 18 35 3 1874226544 5537696 5157504
23:37:01 7749 8192000000 10804767744 11225661440 1880610016 18.670 100 21 42 1 1891923728 5079472 6234240
23:38:13 38819 8192000000 10824325968 11255627776 1904617088 18.863 100 33 66 0 1918716176 4845296 9253792
23:39:55 16925 8192000000 10866548704 11290435584 1930165792 19.068 100 37 71 2 1947978960 7168656 10644512
23:41:25 25561 8192000000 10860672720 11292348416 1925830032 19.034 99 44 88 2 1949021104 9984384 13206688
23:42:53 11820 8192000000 10897899568 11320258560 1955105552 19.267 100 55 110 1 1983830064 12732128 15992384
23:44:11 5784 8192000000 10905768112 11334815744 1967050720 19.362 100 47 94 2 1992688176 11492352 14145104
23:45:20 22889 8192000000 10905958944 11350007808 1975271872 19.427 100 41 82 4 2007776016 19283552 13220592
23:46:53 4201 8192000000 10952724096 11373883392 1996684064 19.597 100 74 145 2 2035665216 17351968 21629184
23:48:00 8003 8192000000 10941747968 11374104576 1989078544 19.537 100 52 104 0 2022527456 17724048 15724864
23:49:13 4769 8192000000 10942281856 11379343360 1993433184 19.571 100 53 104 0 2030701216 21748816 15519216
23:50:20 4981 8192000000 10939557200 11368648704 1984029280 19.497 100 54 107 1 2019193792 19021344 16143168
23:51:30 8229 8192000000 10957538400 11379007488 1993901248 19.575 100 77 153 0 2038778800 22714032 22163520
23:52:48 7569 8192000000 10940672000 11369586688 1980995328 19.473 100 62 124 3 2024400112 24478016 18926768
23:54:00 1713 8192000000 10962580064 11384037376 1992871824 19.566 100 90 178 0 2051650096 30769104 28009168
23:55:03 5245 8192000000 10950960992 11371798528 1972901472 19.408 100 80 161 1 2034740576 36859648 24979456
23:56:17 2088 8192000000 10949585792 11371171840 1970425392 19.389 100 76 150 1 2034103248 39697600 23980256
23:57:27 11051 8192000000 10944497984 11364487168 1963404848 19.333 100 62 126 5 2019866704 36569280 19892576
23:58:49 2733 8192000000 10952992816 11363700736 1960251088 19.308 100 85 169 1 2029977328 42187536 27538704
00:00:00 3521 8192000000 10961769168 11387465728 1979668032 19.462 100 64 128 0 2045236880 44775152 20793696
00:01:08 2253 8192000000 10963084704 11391533056 1984377488 19.499 100 54 109 2 2042890672 41514928 16998256
00:02:15 21961 8192000000 10946218800 11382304768 1963590384 19.335 99 30 60 1 2023869856 49990352 10289120
00:03:40 16464 8192000000 10958960096 11408912384 1978928192 19.456 100 35 71 1 2041000544 49918256 12154096
00:05:02 15306 8192000000 10924705600 11368284160 1924946368 19.026 98 25 50 4 1980874224 46140256 9787600
00:06:25 43140 8192000000 10921331648 11384061952 1945861120 19.194 100 16 32 0 2001730800 49482096 6387584
00:08:18 18437 8192000000 10897854288 11372548096 1951512864 19.239 100 25 47 3 1993126160 33903312 7709984
00:09:52 56568 8192000000 10895374880 11337252864 1927451680 19.046 98 25 47 1 1941809632 7728976 6628976
00:11:52 132250 8192000000 10874361824 11349221376 1917172288 18.964 97 35 71 1 1939901648 12062400 10666960
00:16:01 45699 8192000000 10899403168 11363950592 1963648992 19.335 99 23 46 1 1979218688 8717824 6851872
00:17:58 61920 8192000000 10913449056 11369648128 1965846000 19.352 99 35 69 2 1982385056 7234224 9304832
00:20:06 123716 8192000000 10920418192 11381796864 1957789952 19.288 98 14 28 1 1972907984 11440320 3677712
00:23:11 99122 8192000000 10911424304 11396431872 1983982640 19.496 99 24 48 2 2003518992 12737488 6798864
00:26:01 57820 8192000000 10921716912 11396243456 1982737008 19.486 99 30 60 1 2003085264 11865456 8482800
00:28:02 5517 8192000000 10947031808 11407581184 2003953968 19.654 100 38 76 1 2027312208 12530976 10827264
00:29:09 45021 8192000000 10923746032 11396362240 1987003040 19.520 99 25 49 1 2011575552 17327808 7244704
00:31:08 42991 8192000000 10917580224 11413032960 1999131136 19.616 100 12 24 1 2021855456 19480608 3243712
00:33:06 93503 8192000000 10920487040 11413954560 1993497536 19.571 99 18 36 0 2011489232 12443072 5548624
00:35:42 74900 8192000000 10934501024 11417640960 1989508768 19.540 99 12 24 1 2011084032 17888304 3686960
00:37:58 20942 8192000000 10932041584 11384897536 1992693536 19.565 100 37 71 0 2012047632 8974560 10379536
00:39:23 82495 8192000000 10911052464 11389259776 1936344048 19.118 97 15 30 0 1954047584 13641552 4061984
00:41:57 41643 8192000000 10900250720 11367989248 1958978080 19.298 99 7 14 0 1970535648 9540688 2016880
00:43:47 64955 8192000000 10899694528 11376418816 1937149184 19.124 98 22 43 0 1962484032 18937040 6397808
00:46:19 70698 8192000000 10881089088 11345965056 1921257104 18.997 97 26 52 1 1943005552 14280768 7467680
00:48:36 111056 8192000000 10870599328 11344015360 1837731296 18.322 94 9 18 0 1865428448 25058528 2638624
00:51:29 96077 8192000000 10870481536 11338850304 1909673392 18.904 98 18 36 0 1926615536 12006736 4935408
00:54:29 34224 8192000000 10881955984 11333332992 1911536576 18.919 98 32 64 0 1933208304 12997760 8673968
00:56:07 52761 8192000000 10879175488 11340939264 1946471008 19.198 100 20 41 0 1960262976 8384912 5407056
00:58:00 82364 8192000000 10869889840 11344478208 1931034752 19.075 99 12 24 1 1948436800 13424704 3977344
01:00:30 94193 8192000000 10853829056 11328487424 1887202288 18.723 97 10 20 1 1904906352 14821440 2882624
01:03:05 73400 8192000000 10843428256 11317534720 1868456176 18.572 96 18 36 0 1880138752 6716576 4966000
01:05:48 57648 8192000000 10820831072 11296690176 1868949104 18.576 97 8 16 0 1878766544 7192752 2624688
01:07:46 17478 8192000000 10864729184 11325812736 1910250208 18.909 98 23 46 0 1928515088 12075536 6189344
01:09:10 86512 8192000000 10872433360 11344719872 1910202160 18.908 98 16 32 1 1940822160 26201568 4418432
01:11:38 62625 8192000000 10890292096 11369594880 1906064352 18.875 97 11 22 0 1928286944 18903408 3319184
01:13:41 41143 8192000000 10878029248 11371634688 1948493728 19.214 100 6 12 0 1968535568 18373504 1668336
01:15:22 17617 8192000000 10916751456 11366211584 1966642592 19.359 100 49 98 2 1995206496 15168096 13395808
01:16:45 37804 8192000000 10920722864 11379208192 1971219456 19.395 100 35 70 1 1993843376 12770352 9853568
01:18:23 93234 8192000000 10900301696 11372879872 1939409520 19.142 98 33 66 2 1967814144 19116912 9287712
01:21:01 122605 8192000000 10919126464 11371384832 1946799776 19.201 98 39 76 3 1964448544 7915136 9733632
01:24:04 39466 8192000000 10885825344 11365183488 1936605936 19.120 98 19 38 1 1952419744 10610096 5203712
01:25:44 48143 8192000000 10899284816 11370463232 1957843184 19.289 99 16 32 0 1968334688 6243104 4248400
01:27:37 53792 8192000000 10884191360 11364831232 1927827952 19.050 97 13 26 0 1937875280 6439296 3608032
01:29:43 70624 8192000000 10910918976 11399938048 1986285824 19.514 100 19 38 0 2005052608 13349984 5416800
01:31:55 101168 8192000000 10878037408 11371065344 1867649216 18.565 94 7 14 1 1885198384 15604208 1944960
01:34:37 31920 8192000000 10896747472 11364429824 1964701088 19.343 100 28 56 2 1983064320 10430880 7932352
01:36:09 47430 8192000000 10870116304 11338629120 1866779040 18.558 95 16 32 2 1884512048 13163824 4569184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 01:38:58 to 17-09-2021 01:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 132 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-09-2021 01:39:14 to 17-09-2021 01:39:16.

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          235707
MON$OLDEST_ACTIVE               237268
MON$OLDEST_SNAPSHOT             237268
MON$NEXT_TRANSACTION            237268
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                       2038496
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.33497 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33497 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33497 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33497 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Fri Sep 17 01:37:55 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

17.09.21 01:39:17. 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-17 01:39:20 No severe PSQL-related problems occured
17.09.21 01:39:21. End of report.