How to read the output of the debug flag "-dbg STAT_HISTO"?

If the debug flag "-dbg STAT_HISTO" is activated detailed information for the processed value changes and changes on alert instances is reported.
The information is written to the log file for the Event Manager.
The interval can be defined with the config entry MessageDiagSecs in the [general] section. The default value is 30 seconds.

The output may slightly differ from WinCC OA version to WinCC OA version.
When the output does not contain all lines described below the information is still valid for the other lines.
If the output contains more columns then described below the additional columns must not be taken into account, they are not used.

For every debug three lines of information are written.
-- 1st line contains information for the value changes in the system.
-- 2nd line contains information for the processed changes of alert instances.
The information is written in form of a histogram. Each slot of the histogram has a special meaning.
-- 3rd line contains information for the size of values changes for a DP element of type string or blob

 

+++ Value changes +++

The first column contains the total number of value changes for the last period.

In the following columns detailed information for the latency is reported. The latency is the difference between the value change timestamp and the processing in the Event Manager.
If the value is changed due to a dpSet/dpSetWait message, the difference is 0 in any case as the time stamp is given by the Event Manager.
If the value is changed in a PLC and the driver provides the timestamp, some latency might be detected. Due to time synchronization there might be negative
latency intervals as well, which are counted in a separate slot.

The histogram contains information for following latency intervals:

// slots:
// total number  [ 0]
//   0 ..  90 ms [ 1] .. [10]
// 100 .. 900 ms [11] .. [19]
//   1 ..   9  s [21] .. [29]
//  10 ..  90  s [31] .. [39]
// <0.0          [102]
// >= 100s       [101]

The example for the log message shows value changes triggered by a Ctrl script running on the same server like the Event Manager, therefore no latency is detected.
There are 68 changes in total, and all are detected with less than 10 ms latency.

WCCILevent   (0), <TIMESTAMP>, STAT_HISTO, "STAT_HISTO, "DPEChanges [10 ms] - decades: total 68 | 68, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | >= 100000 0, < 0 0"

 

+++ Changes of alert instances +++

The first column contains the total number of changes of alert instances for the last period.

In the following columns detailed information is listed according to the alert type (continuous alarms, multi instance alarms, suppressed alarms) and the alert state.


// ALERTEVENT_CAME (0)
// ALERTEVENT_CAME_IMPULSE (1)
// ALERTEVENT_GONE (2)
// ALERTEVENT_MULTIPLE_ACK (3)
// ALERTEVENT_SINGLE_ACK (4)
// ALERTEVENT_GONE_INACTIVE (5)
// ALERTEVENT_GONE_IMPULSE (6)
// ALERTEVENT_GONE_OBSOLETE (7)
// NOEVENT (8)

The histogram contains the following slots:

// slots:
// total number                              [ 0]
// continuous alarms                        [ 1] .. [10]  sorted by AlertEvent constant
// multi instance alarms                     [11] .. [20]  sorted by AlertEvent constant
// suppressed alarms due to minPrio setting [32]
// _alert_hdl config was set active/inactive [33]

The following sample shows changes of continuous alarms and multi instance alarms.
1036 alarm changes in total
29   continuous alarms (ALERTEVENT_CAME)
7    continuous alarms (ALERTEVENT_GONE)
1000 multi instance alarms (ALERTEVENT_CAME)

WCCILevent   (0), <TIMESTAMP>, STAT_HISTO, "AlarmChanges [#]: total 1036 | 29, 0, 7, 0, 0, 0, 0, 0, 0, 0 | 1000, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0, 0 | > 10 0, < 0 0"

 

+++ Size of value changes (strings, blobs) +++

The first column contains the total number of messages which were counted in the last period.

In the following columns the number of messages is listed according to the size of the message in decades.
The histogram contains information for following message sizes:

// slots:
// total number of messages  [ 0]
// 0-1 .. 9-9.99 kB [ 1] .. [10]
// 10-19.99 .. 90-99.99 kB [11] .. [19]
// 100-199.99 .. 900-999.99 kB [20] .. [29]
// 1000-1999.99 .. 9000-9999.99 kB [30] .. [39]
// 10000-19999.99 [40]
// >20000 [41]

WCCILevent   (0), <TIMESTAMP>, STAT_HISTO, "Msg Size [kB] - decades: total 56 | 11, 10, 10, 10, 10, 5, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | 0, 0, 0, 0, 0, 0, 0, 0, 0 | >= 100000 0, < 0 0"

Date added:
Last revised:
Hits:
13.902
Rating:
Rating: 4.3. 35 vote(s). No rating done at all.
Your vote was '' (0 of 5) You are an anonymous user.
You may log on to do personalized votings
Click the rating bar to rate this item Please log on to do ratings
  • Notification

    FE user cannot be identified! (1403201096)