Example of user trace entries for message flow accounting and statistics

This example shows a user trace that contains message flow accounting and statistics data.

The following example shows what is generated for a snapshot report. The messages that are written to the trace show that the message flow is called myExampleFlow, and that it is running in an integration server named default on integration node TESTNODE_user_name . The message flow contains the following message flow nodes:

An MQInput node called inNode
A Compute node called First1
An MQOutput node called outNode

The nodes are connected together (Out terminal to In terminal for each connection).

During the interval for which statistics have been collected, this message flow processed 150 input messages.

The records show that two threads are assigned to this message flow. One thread is assigned when the message flow is deployed (the default number); an additional thread (thread 0) listens on the input queue. The listening thread starts additional threads to process input messages that are dependent on the number of instances that you have configured for the message flow, and on the rate of arrival of the input messages on the input queue.

The following command has been issued to achieve these results:

mqsichangeflowstats TESTNODE_user_name -s -c active -e default -f myExampleFlow -n advanced -t basic -b basic

The integration node takes information about statistics and accounting from the operating system. On some operating systems, such as Windows, UNIX, and Linux®, rounding can occur because the system calls that are used to determine the processor times are not sufficiently granular. This rounding might affect the accuracy of the data.

BIP2380I: IIB message flow statistics. ProcessID='328467', Key='6', Type='SnapShot', Reason='Snapshot', 
IntegrationNodeLabel='TESTNODE_user_name', BrokerUUID='18792e66-e100-0000-0080-f197e5ed81bd', 
IntegrationServerName='default', IntegrationServerUUID='15d4314a-3607-11d4-8000-09140f7b0000', 
MessageFlowName='myExampleFlow', 
StartDate='2003-05-20', StartTime='13:44:31.885862', 
EndDate='2003-05-20', EndTime='13:44:51.310080', 
TotalElapsedTime='9414843', MaximumElapsedTime='1143442', MinimumElapsedTime='35154', 
TotalCPUTime='760147', MaximumCPUTime='70729', MinimumCPUTime='3124', 
CPUTimeWaitingForInputMessage='45501', ElapsedTimeWaitingForInputMessage='11106438', 
TotalInputMessages='150', TotalSizeOfInputMessages='437250', 
MaximumSizeOfInputMessages='2915', MinimumSizeOfInputMessages='2915', 
NumberOfThreadsInPool='1', TimesMaximumNumberOfThreadsReached='150', 
TotalNumberOfMQErrors='0', TotalNumberOfMessagesWithErrors='0', 
TotalNumberOfErrorsProcessingMessages='0', TotalNumberOfTimeOuts='0', 
TotalNumberOfCommits='150', TotalNumberOfBackouts='0', AccountingOrigin="DEPT2". 
Statistical information for message flow 'myExampleFlow' in integration node 'MQ01BRK'. 
This is an information message produced by IIB statistics. 

BIP2381I: IIB thread statistics. ProcessID='328467', Key='6', Number='0', 
TotalNumberOfInputMessages='0', 
TotalElapsedTime='0', TotalCPUTime='0', CPUTimeWaitingForInputMessage='110', 
ElapsedTimeWaitingForInputMessage='5000529', TotalSizeOfInputMessages='0', 
MaximumSizeOfInputMessages='0', MinimumSizeOfInputMessages='0'. 
Statistical information for thread '0'. 
This is an information message produced by IIB statistics. 

BIP2381I: IIB thread statistics. ProcessID='328467', Key='6', Number='18', 
TotalNumberOfInputMessages='150', 
TotalElapsedTime='9414843', TotalCPUTime='760147', CPUTimeWaitingForInputMessage='45391', 
ElapsedTimeWaitingForInputMessage='6105909', TotalSizeOfInputMessages='437250', 
MaximumSizeOfInputMessages='2915', MinimumSizeOfInputMessages='2915'. 
Statistical information for thread '18'. 
This is an information message produced by IIB statistics.

BIP2382I: IIB node statistics. ProcessID='328467', Key='6', 
Label='First1', Type='ComputeNode', 
TotalElapsedTime='6428815', MaximumElapsedTime='138261', MinimumElapsedTime='28367', 
TotalCPUTime='604060', MaximumCPUTime='69645', MinimumCPUTime='2115', 
CountOfInvocations='150', NumberOfInputTerminals='1', NumberOfOutputTerminals='2'. 
Statistical information for node 'First1'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='failure', Type='Output', CountOfInvocations='0', 
Statistical information for terminal 'failure'. 
This is an information message produced by IIB statistics.  

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='in', Type='Input', CountOfInvocations='150', 
Statistical information for terminal 'in'. 
This is an information message produced by IIB statistics.

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='out', Type='Output', CountOfInvocations='150', 
Statistical information for terminal 'out'. 
This is an information message produced by IIB statistics.

BIP2382I: IIB node statistics. ProcessID='328467', Key='6', 
Label='inNode', Type='MQInputNode', 
TotalElapsedTime='1813446', MaximumElapsedTime='1040209', MinimumElapsedTime='1767', 
TotalCPUTime='70565', MaximumCPUTime='686', MinimumCPUTime='451', 
CountOfInvocations='150', NumberOfInputTerminals='0', NumberOfOutputTerminals='3'. 
Statistical information for node 'inNode'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='catch', Type='Output', CountOfInvocations='0', 
Statistical information for terminal 'catch'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='failure', Type='Output', CountOfInvocations='0', 
Statistical information for terminal 'failure'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='out', Type='Output', CountOfInvocations='150', 
Statistical information for terminal 'out'. 
This is an information message produced by IIB statistics. 

BIP2382I: IIB node statistics. ProcessID='328467', Key='6', 
Label='outNode', Type='MQOutputNode', 
TotalElapsedTime='1172582', MaximumElapsedTime='177516', MinimumElapsedTime='3339', 
TotalCPUTime='85522', MaximumCPUTime='762', MinimumCPUTime='536', 
CountOfInvocations='150', NumberOfInputTerminals='1', NumberOfOutputTerminals='2'. 
Statistical information for node 'outNode'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='failure', Type='Output', CountOfInvocations='0', 
Statistical information for terminal 'failure'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='in', Type='Input', CountOfInvocations='150', 
Statistical information for terminal 'in'. 
This is an information message produced by IIB statistics. 

BIP2383I: IIB terminal statistics. ProcessID='328467', Key='6', 
Label='out', Type='Output', CountOfInvocations='0', 
Statistical information for terminal 'out'. 
This is an information message produced by IIB statistics.