Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.11.2020 10:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
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 16688824 kB
MemAvailable 31502132 kB
Buffers 0 kB
Cached 14856596 kB
SwapCached 2688 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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 2020-11-29 10:26:53
DTS_END                         2020-11-29 10:26:53.2020
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(68:17)
IP                              ::1/38872
TRN_ID                          243517
ATT_ID                          689
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: 9035.50 21312 1084260
customer order: creation 178.60 354 21432
customer order: refuse 15.17 367 1820
order to supplier: creation 47.26 1411 5671
order to supplier: removal 3.43 103264 411
invoice (draft): creation 47.12 3549 5654
invoice (draft): removal 50.09 800 6011
invoice accept: apply 42.40 17259 5088
invoice accept: cancel 45.07 886 5408
customer reserve: creation 3875.25 171 465030
customer reserve: removal 4427.83 64 531340
realization accept: apply 87.41 165 10489
realization accept: cancel 81.43 108 9771
payment from customer: creation 61.29 113 7355
payment from customer: removal 5.82 60 698
payment to supplier: creation 51.97 117 6236
payment to supplier: removal 3.72 70 446
service: total inventory turnovers 4.10 3046 492
service: total monetary turnovers 6.60 500 792
service: aggregate perf. data 0.93 11117 112
service: refresh index statistics 0.03 282809 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-11-2020 10:27:30 to 29-11-2020 10:27:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2597
TEST_TIME 2 4435
TEST_TIME 3 4833
TEST_TIME 4 5108
TEST_TIME 5 5590
TEST_TIME 6 5438
TEST_TIME 7 5693
TEST_TIME 8 6236
TEST_TIME 9 6266
TEST_TIME 10 6358
TEST_TIME 11 6332
TEST_TIME 12 6417
TEST_TIME 13 6632
TEST_TIME 14 6861
TEST_TIME 15 7009
TEST_TIME 16 7272
TEST_TIME 17 7740
TEST_TIME 18 8023
TEST_TIME 19 8196
TEST_TIME 20 8323
TEST_TIME 21 8445
TEST_TIME 22 8691
TEST_TIME 23 9117
TEST_TIME 24 9283
TEST_TIME 25 9630
TEST_TIME 26 9803
TEST_TIME 27 10056
TEST_TIME 28 11087
TEST_TIME 29 10979
TEST_TIME 30 11016
TEST_TIME 31 10843
TEST_TIME 32 10772
TEST_TIME 33 10753
TEST_TIME 34 10616
TEST_TIME 35 10665
TEST_TIME 36 10458
TEST_TIME 37 10354
TEST_TIME 38 10115
TEST_TIME 39 10075
TEST_TIME 40 10133
TEST_TIME 41 10024
TEST_TIME 42 9957
TEST_TIME 43 9932
TEST_TIME 44 9944
TEST_TIME 45 9830
TEST_TIME 46 9764
TEST_TIME 47 9975
TEST_TIME 48 9817
TEST_TIME 49 9726
TEST_TIME 50 9897
TEST_TIME 51 9816
TEST_TIME 52 9826
TEST_TIME 53 9812
TEST_TIME 54 9870
TEST_TIME 55 9823
TEST_TIME 56 9865
TEST_TIME 57 9859
TEST_TIME 58 9876
TEST_TIME 59 9793
TEST_TIME 60 10038
TEST_TIME 61 10016
TEST_TIME 62 9938
TEST_TIME 63 9856
TEST_TIME 64 9748
TEST_TIME 65 9792
TEST_TIME 66 9681
TEST_TIME 67 9631
TEST_TIME 68 9547
TEST_TIME 69 9497
TEST_TIME 70 9390
TEST_TIME 71 9310
TEST_TIME 72 9337
TEST_TIME 73 9331
TEST_TIME 74 9263
TEST_TIME 75 9218
TEST_TIME 76 9199
TEST_TIME 77 9268
TEST_TIME 78 9245
TEST_TIME 79 9322
TEST_TIME 80 9284
TEST_TIME 81 9250
TEST_TIME 82 9270
TEST_TIME 83 9501
TEST_TIME 84 9485
TEST_TIME 85 9469
TEST_TIME 86 9365
TEST_TIME 87 9299
TEST_TIME 88 9273
TEST_TIME 89 9257
TEST_TIME 90 9225
TEST_TIME 91 9163
TEST_TIME 92 9154
TEST_TIME 93 9115
TEST_TIME 94 9093
TEST_TIME 95 9139
TEST_TIME 96 9193
TEST_TIME 97 9186
TEST_TIME 98 9144
TEST_TIME 99 9121
TEST_TIME 100 9167
TEST_TIME 101 9156
TEST_TIME 102 9230
TEST_TIME 103 9188
TEST_TIME 104 9226
TEST_TIME 105 9206
TEST_TIME 106 9149
TEST_TIME 107 9098
TEST_TIME 108 9055
TEST_TIME 109 9038
TEST_TIME 110 8979
TEST_TIME 111 9019
TEST_TIME 112 9007
TEST_TIME 113 9002
TEST_TIME 114 8980
TEST_TIME 115 9051
TEST_TIME 116 9038
TEST_TIME 117 9032
TEST_TIME 118 9056
TEST_TIME 119 9057
TEST_TIME 120 9040

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-11-2020 10:27:33 to 29-11-2020 10:27:37.

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 1516036 1516036 0 0.00 0 21206 7 0 0
doc_list_biud 1516027 1516027 0 0.00 0 35960 28 0 0
sp_add_invoice_to_stock 5187 5088 99 1.90 22 172955 17260 0 99
sp_cancel_adding_invoice 5408 5408 0 0.00 6 124296 886 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 9953 368 0 0
sp_cancel_customer_reserve 531340 531340 0 0.00 1 27882 65 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 1897 60 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 1865 70 0 0
sp_cancel_supplier_invoice 6016 6011 5 0.08 2 14267 801 0 5
sp_cancel_supplier_order 411 411 0 0.00 8 1122605 103264 0 0
sp_cancel_write_off 9771 9771 0 0.00 1 8470 109 0 0
sp_client_order 21432 21432 0 0.00 4 31362 355 0 0
sp_customer_reserve 465030 465030 0 0.00 0 104315 172 0 0
sp_fill_shopping_cart 32752 32752 0 0.00 1 11000 38 0 0
sp_fill_shopping_cart_clo_res 465020 465020 0 0.00 0 27286 16 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 55393 372 0 0
sp_kill_cost_storno 16323 16323 0 0.00 0 5749 51 0 0
sp_kill_qstorno_ret_qs2qd 1078873 1078873 0 0.00 0 22225 35 0 0
sp_kill_qty_storno 570319 570319 0 0.00 0 35959 74 0 0
sp_lock_selected_doc 21473 21473 0 0.00 0 9403 7 0 0
sp_make_cost_storno 29158 29158 0 0.00 0 21204 55 0 0
sp_multiply_rows_for_pdistr 29152 29152 0 0.00 0 6582 27 0 0
sp_multiply_rows_for_qdistr 21432 21432 0 0.00 0 31253 172 0 0
sp_pay_from_customer 7355 7355 0 0.00 3 21209 114 0 0
sp_pay_to_supplier 6236 6236 0 0.00 4 14726 117 0 0
sp_payment_common 13591 13591 0 0.00 1 8657 22 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 9947 344 0 0
sp_qd_handle_on_invoice_upd_sts 10479 10479 0 0.00 0 35958 351 0 0
sp_qd_handle_on_reserve_upd_sts 10487 10487 0 0.00 0 15244 43 0 0
sp_reserve_write_off 10489 10489 0 0.00 7 17074 165 0 0
sp_supplier_invoice 5654 5654 0 0.00 6 101734 3550 0 0
sp_supplier_order 5671 5671 0 0.00 5 40711 1411 0 0
srv_aggregate_perf_data 113 112 1 0.88 2848 76756 11118 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 2075 185365 45574 0 0
srv_find_qd_qs_mism 1035808 1035808 0 0.00 0 16555 6 0 0
srv_make_invnt_saldo 492 492 0 0.00 37 86220 3047 0 0
srv_make_money_saldo 792 792 0 0.00 1 14645 500 0 0
srv_recalc_idx_stat 4 4 0 0.00 135130 416607 282809 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 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 5088 5088 0 0.00 0 12816 165 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5493 94 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 2674 17 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 533 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1523 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 4649 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 614 12 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 19114 1425 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 3677 10 0 0
t$perf-norm:sp_client_order 21432 21432 0 0.00 0 12949 17 0 0
t$perf-norm:sp_customer_reserve 1085 1085 0 0.00 0 3619 7 0 0
t$perf-norm:sp_pay_from_customer 7355 7355 0 0.00 0 2227 7 0 0
t$perf-norm:sp_pay_to_supplier 6236 6236 0 0.00 0 3075 7 0 0
t$perf-norm:sp_reserve_write_off 10489 10489 0 0.00 0 8248 12 0 0
t$perf-norm:sp_supplier_invoice 5654 5654 0 0.00 0 7836 17 0 0
t$perf-norm:sp_supplier_order 5671 5671 0 0.00 0 6987 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 342 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 216 5 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 604 8 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 1907 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 6 0 0
v_add_invoice_to_stock 10340 10142 198 1.91 0 5755 17 0 198
v_all_customers 39064 39064 0 0.00 0 94 1 0 0
v_all_suppliers 31832 31832 0 0.00 0 127 1 0 0
v_all_wares 42864 42864 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1785 11 0 0
v_cancel_client_order 3640 3640 0 0.00 0 2886 6 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 592 4 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 4576 8 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 511 4 0 0
v_cancel_supplier_invoice 830 820 10 1.20 0 8617 24 0 10
v_cancel_supplier_order 822 822 0 0.00 0 489 4 0 0
v_cancel_write_off 2026 2026 0 0.00 0 6125 15 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 418 1 0 0
v_max_id_clo_res 1085 1085 0 0.00 0 2671 18 0 0
v_max_id_ord_sup 5649 5649 0 0.00 0 2156 3 0 0
v_max_non_paid_invoice 1640 1640 0 0.00 0 1710 3 0 0
v_max_non_paid_realizn 3845 3845 0 0.00 0 1328 4 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 4778 9 0 0
v_min_id_clo_res 1085 1085 0 0.00 0 1136 4 0 0
v_min_id_ord_sup 5649 5649 0 0.00 0 4001 5 0 0
v_min_non_paid_invoice 6236 6236 0 0.00 0 6212 13 0 0
v_min_non_paid_realizn 7355 7355 0 0.00 0 6921 14 0 0
v_our_firm 10820 10820 0 0.00 0 23 1 0 0
v_reserve_write_off 20974 20974 0 0.00 0 4675 5 0 0
x_lock_depdocs_on_canc_invoice 11009 11009 0 0.00 0 12631 101 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 15460 347 0 0
x_make_qstorno_1000_1200 5671 5671 0 0.00 2 40526 1334 0 0
x_make_qstorno_1200_2000 5654 5654 0 0.00 3 101693 3413 0 0
x_make_qstorno_2100_3300 465029 465029 0 0.00 0 104315 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2020 10:27:38 to 29-11-2020 10:27:42.

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
07:56:55 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
07:57:57 13439 8192000000 9703214720 9995821056 785597872 8.750 100 83 164 1 812408240 7065648 19744720
07:59:17 17786 8192000000 9750740272 10052141056 822386112 9.123 100 96 192 0 857440688 11856752 23197824
08:00:48 14279 8192000000 9780902640 10093002752 849852240 9.399 100 79 158 0 887487488 19383248 18252000
08:02:08 14768 8192000000 9845138080 10174668800 904479312 9.943 100 79 158 1 955221376 33313072 17428992
08:03:26 9053 8192000000 9910070640 10256936960 958767712 10.477 100 75 151 0 1021597536 47294048 15535776
08:04:37 5671 8192000000 9945183408 10295123968 989799344 10.780 100 85 169 1 1059757328 52848016 17109968
08:05:45 2282 8192000000 9983015312 10341003264 1016361696 11.037 100 86 172 1 1098374896 64680144 17333056
08:06:52 3724 8192000000 10016945712 10381361152 1033811392 11.205 100 84 168 1 1125122288 74831568 16479328
08:07:57 1436 8192000000 10017280112 10386714624 1060354944 11.460 100 93 186 0 1139560832 60304832 18901056
08:08:59 12037 8192000000 9973811776 10368614400 1058646976 11.444 100 36 72 1 1109055200 42730224 7678000
08:10:15 6780 8192000000 9984691104 10372833280 1068761760 11.540 100 38 76 0 1104096016 26013744 9320512
08:11:22 5014 8192000000 9984738736 10376253440 1071137744 11.563 100 38 76 2 1110375456 30631776 8605936
08:12:27 58983 8192000000 9985894752 10400796672 1077301584 11.622 100 41 82 2 1118659200 31882112 9475504
08:14:27 3036 8192000000 10012874208 10412986368 1082457264 11.671 100 43 86 0 1117671424 26361088 8853072
08:15:31 2840 8192000000 10013448160 10404687872 1094010496 11.781 100 43 84 1 1119535760 16145936 9379328
08:16:34 6553 8192000000 9978596160 10377715712 1063268416 11.488 98 32 62 1 1091206176 19968336 7969424
08:17:41 59527 8192000000 9974564224 10373586944 1013237312 11.007 94 21 41 1 1042887456 25207504 4442640
08:19:43 6831 8192000000 9943337616 10332573696 1004528032 10.922 97 59 118 2 1041249584 22865328 13856224
08:20:52 7025 8192000000 9935534400 10315354112 991245392 10.794 97 49 97 0 1023132512 21358864 10528256
08:22:02 63142 8192000000 9954435248 10360283136 1013644736 11.011 97 29 59 0 1038842128 18314496 6882896
08:24:10 7291 8192000000 9984223376 10380419072 1055296736 11.411 100 52 104 4 1080678304 13913872 11467696
08:25:19 6796 8192000000 9991828880 10388123648 1066276640 11.517 100 36 72 6 1103480480 29279376 7924464
08:26:26 5389 8192000000 10009559424 10407116800 1091685360 11.759 100 44 89 2 1119578224 17709616 10183248
08:27:34 28696 8192000000 10031974560 10427809792 1090004480 11.743 99 38 73 0 1127267920 30012304 7251136
08:29:09 19886 8192000000 10086388272 10493247488 1119828784 12.025 100 53 106 2 1193434992 62016400 11589808
08:30:35 7399 8192000000 10103353632 10512678912 1137260912 12.190 100 62 121 2 1214914240 64804576 12848752
08:31:48 2618 8192000000 10129187392 10535940096 1145673088 12.269 100 51 102 0 1232182704 75808144 10701472
08:32:55 9961 8192000000 10174865536 10583113728 1152614832 12.334 100 49 96 0 1275002288 112276848 10110608
08:34:08 4774 8192000000 10181673024 10593644544 1156516752 12.371 100 55 110 4 1298264096 128361952 13385392
08:35:18 13453 8192000000 10229185648 10643034112 1170903392 12.505 100 58 116 1 1323573184 140228416 12441376
08:36:36 11599 8192000000 10233540816 10664902656 1172215120 12.518 100 45 90 1 1340130928 157007152 10908656
08:38:00 3807 8192000000 10259450656 10677256192 1167035600 12.469 99 55 108 1 1362001824 182276992 12689232
08:39:11 4262 8192000000 10278470704 10690027520 1156251408 12.368 100 62 123 3 1374243328 203880304 14111616
08:40:23 3876 8192000000 10284596656 10693287936 1171956800 12.515 99 71 142 2 1385201488 197148240 16096448
08:41:32 6915 8192000000 10326984256 10738089984 1166542800 12.465 100 71 142 0 1434489760 251708160 16238800
08:42:47 3058 8192000000 10357418112 10767724544 1157885024 12.383 100 83 166 1 1460097312 283307520 18904768
08:43:57 3228 8192000000 10315099440 10719760384 1134759232 12.166 100 77 154 2 1414897728 261745648 18392848
08:45:04 2074 8192000000 10333851056 10744061952 1140939744 12.224 99 54 108 0 1429400800 275673408 12787648
08:46:16 2482 8192000000 10360392560 10772918272 1142533888 12.239 100 87 172 0 1469349552 305223728 21591936
08:47:23 10528 8192000000 10372319616 10786217984 1128024880 12.103 99 47 92 2 1470055152 330515600 11514672
08:48:37 12532 8192000000 10384079360 10838376448 1134701840 12.166 100 32 64 1 1511132784 367517184 8913760
08:49:55 15894 8192000000 10369010448 10793025536 1114597440 11.976 98 31 60 0 1440034784 317974928 7462416
08:51:17 27752 8192000000 10346647296 10813267968 1117301888 12.001 100 26 51 4 1411375472 286953328 7120256
08:52:58 26411 8192000000 10258354464 10721751040 1117080112 11.999 100 19 38 3 1370567056 248678240 4808704
08:54:59 92953 8192000000 10086987312 10535645184 1118807536 12.016 99 11 22 0 1161562576 40609312 2145728
08:57:32 14578 8192000000 10082637616 10501427200 1139501872 12.211 100 59 117 1 1173571392 20923600 13145920
08:58:48 87619 8192000000 10085897776 10550255616 1125636976 12.080 98 5 10 0 1167759088 41460720 661392
09:01:21 76202 8192000000 10080505968 10513489920 1122576816 12.051 97 15 30 0 1177006240 50928656 3500768
09:03:55 185364 8192000000 10051730992 10531373056 1096829936 11.808 95 38 74 3 1184377664 79006400 8541328
09:08:04 35698 8192000000 10113467680 10556354560 1152978448 12.337 100 40 78 1 1220757584 59617872 8161264
09:09:41 38240 8192000000 10145406720 10576732160 1144098400 12.254 98 34 65 1 1223041040 71474848 7467792
09:11:22 35095 8192000000 10150416576 10625839104 1156787472 12.373 100 17 35 2 1247955696 87317088 3851136
09:12:58 20740 8192000000 10166722656 10605699072 1156813184 12.373 100 30 50 3 1272611584 110806928 4991472
09:14:18 80108 8192000000 10117699440 10579169280 1144948544 12.262 99 15 28 1 1194270176 46171520 3150112
09:16:39 70657 8192000000 10078697888 10517233664 1128822720 12.110 98 14 25 1 1194983024 63311056 2849248
09:18:55 70736 8192000000 10084652112 10581262336 1119909280 12.026 97 17 32 2 1178403248 54960800 3533168
09:21:08 19312 8192000000 10118602704 10558840832 1144242160 12.255 99 25 51 2 1198271504 48189024 5840320
09:22:28 30604 8192000000 10098559088 10576363520 1149134816 12.301 100 9 18 0 1204612288 53556288 1921184
09:24:01 58576 8192000000 10096509216 10539274240 1092928736 11.770 95 20 40 0 1164510400 67397152 4184512
09:26:00 79132 8192000000 10087169248 10561830912 1079784816 11.645 95 7 14 1 1147542304 66530656 1226832
09:28:19 146993 8192000000 10082088768 10549297152 1087251440 11.717 96 17 34 2 1173510800 82833920 3425440
09:31:47 72295 8192000000 10000237008 10464538624 1035699840 11.223 94 6 12 0 1063725264 27036352 989072
09:33:59 52488 8192000000 10016403856 10480713728 1076400096 11.613 98 10 20 1 1106451248 27964160 2086992
09:35:53 81788 8192000000 10036617424 10480857088 1063018544 11.485 96 14 28 0 1112729472 46233904 3477024
09:39:01 74029 8192000000 10003637552 10444075008 1039575792 11.261 94 9 17 1 1080364032 38829968 1958272
09:41:15 27851 8192000000 10075766080 10510946304 1095325344 11.793 99 39 78 1 1155968416 52742752 7900320
09:42:44 64295 8192000000 10071965536 10541006848 1092176896 11.763 98 30 61 0 1202968000 104353696 6437408
09:44:48 24619 8192000000 10076231344 10533478400 1120997312 12.036 100 36 72 2 1174867424 46632432 7237680
09:46:14 17059 8192000000 10103325744 10532507648 1131480512 12.135 100 53 106 1 1193923024 51744832 10697680
09:47:35 50154 8192000000 10187386000 10649743360 1146213584 12.274 100 35 70 0 1276028336 122546592 7268160
09:49:25 46694 8192000000 10076038272 10628362240 1107390736 11.908 97 8 16 2 1219345520 110631312 1323472
09:51:12 105232 8192000000 10052781824 10518736896 1134677520 12.165 100 10 20 0 1170463600 33774464 2011616
09:53:58 94109 8192000000 10107055872 10603433984 1153811840 12.345 100 10 20 0 1221902352 65885488 2205024
09:57:20 73511 8192000000 10050177536 10519961600 1101592928 11.853 96 8 16 1 1146315104 43185216 1536960
09:59:38 45083 8192000000 10095668192 10553753600 1132454256 12.144 99 23 47 3 1199145984 61859216 4832512
10:01:24 40264 8192000000 10103253072 10575716352 1106412960 11.898 97 18 35 1 1179023024 68312592 4297472
10:03:05 64904 8192000000 10073500880 10504126464 1132902816 12.149 99 30 59 1 1152907936 13190672 6814448
10:05:12 14926 8192000000 10106674368 10527416320 1131523232 12.136 100 53 106 0 1197400096 55236688 10640176
10:06:28 50291 8192000000 10057565104 10499375104 1101163056 11.849 97 20 39 0 1139525152 33944464 4417632
10:08:22 49509 8192000000 10081920768 10585452544 1132391472 12.144 100 18 36 0 1222947136 86815504 3740160
10:10:12 88321 8192000000 10077827808 10510409728 1109952416 11.932 97 14 28 1 1138404544 25532816 2919312
10:12:40 116487 8192000000 10113071360 10588872704 1147923920 12.290 100 29 58 1 1195590960 41230640 6436400
10:15:46 72784 8192000000 10105721104 10593697792 1124709200 12.071 99 11 22 0 1191647392 64171072 2767120
10:17:59 72607 8192000000 10083722496 10528784384 1110120048 11.934 98 17 34 0 1176486576 62719760 3646768
10:20:23 39476 8192000000 10096919936 10539905024 1068870272 11.541 96 25 50 0 1142421344 68496448 5054624
10:22:12 52196 8192000000 10074486480 10503917568 1060593872 11.462 95 20 39 2 1099387840 34898336 3895632
10:24:07 60144 8192000000 10047776304 10514141184 1063996608 11.495 96 20 40 2 1143644592 75167648 4480336
10:26:09 47314 8192000000 9320876032 9636544512 441553680 5.114 40 13 26 0 460389344 16764144 2071520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2020 10:27:44 to 29-11-2020 10:27:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 99 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2020 10:28:00 to 29-11-2020 10:28:02.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242956
MON$OLDEST_ACTIVE               244243
MON$OLDEST_SNAPSHOT             244243
MON$NEXT_TRANSACTION            244243
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-30 07:30:08.6030 GMT
MON$PAGES                       2119488
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             822
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2280 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

29.11.20 10:28:03. There are 3 logs in /var/tmp/logs-oltp40 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
2020-11-29 10:28:06 No severe PSQL-related problems occured
29.11.20 10:28:07. End of report.