Message Flow Logging

The TIBCO ActiveMatrix platform supports logging the flow of messages between service consumers, services, bindings, components, and references.

In TIBCO Business Studio, you configure message flow logging for a local node by setting the Log Level to DEBUG in a run or debug configuration. In Administrator, the message flow logger com.tibco.amx.messageflow controls message flow logging for remote nodes. This logger is based on the node logging hierarchy and thus can be only configured at the node level. When you set a message flow logger level to DEBUG, message flow logging is enabled for all applications deployed on the node.

The following log fragment shows the DEBUG level message log entries for the HelloWorld application depicted in noPageCitation.
HelloWorld Message Flow
11 Jun 2012 16:57:09,499 [hello1Connector_30] [DEBUG] [jv.helloworld1.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999100: Request Message from External Consumer to Promoted Service. 
PromotedServiceName=HelloWorldPT/HelloWorldPT, BindingName=HelloWorld1SOAP, BindingType=SOAP/HTTP, URL=http://sbnb-t61p.na.tibco.com:9092/helloWorldPT/, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld1SOAP, 
OperationName=sayHello, CorrelationId=f4455bdc-3adc-4438-9c68-1128600245b4, ContextID=f4455bdc-3adc-4438-9c68-1128600245b4, ParentContextID=null, Message=<?xml version="1.0" encoding="UTF-8"?>
<soapenv:Envelope xmlns:q0="http://ns.tibco.com/Hello/" xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
  <soapenv:Body>
    <q0:HelloRequest>Jim</q0:HelloRequest>
  </soapenv:Body>
</soapenv:Envelope>
11 Jun 2012 16:57:09,499 [hello1Connector_30] [DEBUG] [jv.helloworld1.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999101: Request Message from TIBCO-BT-SOAP Service Binding to TIBCO-IT-JAVA Component Service . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld1SOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620, OperationName={http://ns.tibco.com/Hello/}sayHello, 
CorrelationID=f4455bdc-3adc-4438-9c68-1128600245b4, ContextID=f4455bdc-3adc-4438-9c68-1128600245b4, ParentContextID=null 
11 Jun 2012 16:57:09,515 [hello1Connector_30] [DEBUG] [jv.helloworld1.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999201: Response (Reply) Message from TIBCO-IT-JAVA Component Service to TIBCO-BT-SOAP Service Binding . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld1SOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620, 
OperationName={http://ns.tibco.com/Hello/}sayHello, CorrelationID=f4455bdc-3adc-4438-9c68-1128600245b4, ContextID=6e4780cb-49cc-43f2-b476-ddd1efbe2257, 
ParentContextID=f4455bdc-3adc-4438-9c68-1128600245b4 
11 Jun 2012 16:57:09,515 [hello1Connector_30] [DEBUG] [jv.helloworld1.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999200: Response (Reply) Message from Promoted Service to External Consumer. 
PromotedServiceName=HelloWorldPT/HelloWorldPT, BindingName=HelloWorld1SOAP, BindingType=SOAP/HTTP, URL=N/A, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld1.soa/JavaHelloComponent_1.0.0.v2012-06-11-1620_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld1SOAP, 
OperationName={http://ns.tibco.com/Hello/}sayHello, CorrelationId=f4455bdc-3adc-4438-9c68-1128600245b4, ContextID=6e4780cb-49cc-43f2-b476-ddd1efbe2257, 
ParentContextID=f4455bdc-3adc-4438-9c68-1128600245b4, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><HelloResponse xmlns="http://ns.tibco.com/Hello/">Hi Jim! This is the Java component.
</HelloResponse></SOAP-ENV:Body></SOAP-ENV:Envelope>
The following log fragment shows the DEBUG level message log entries for the Hello World and Date Manager applications depicted in Hello World and Date Manager Message Flow interspersed with INFO level application logging to stdout.
Hello World and Date Manager Message Flow


11 Jun 2012 14:53:12,187 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999100: Request Message from External Consumer to Promoted Service. 
PromotedServiceName=HelloWorldPT/HelloWorldPT, BindingName=HelloWorld2SOAP, BindingType=SOAP/HTTP, 
URL=http://sbnb-t61p.na.tibco.com:9095/helloWorldPT/, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld2SOAP, 
OperationName=sayHello, CorrelationId=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, ContextID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ParentContextID=null, Message=<?xml version="1.0" encoding="UTF-8"?>
<soapenv:Envelope xmlns:q0="http://ns.tibco.com/Hello/" xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/" 
xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
  <soapenv:Body>
    <q0:HelloRequest>Jim</q0:HelloRequest>
  </soapenv:Body>
</soapenv:Envelope>
11 Jun 2012 14:53:12,187 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999101: Request Message from TIBCO-BT-SOAP Service Binding to TIBCO-IT-JAVA Component Service . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld2SOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400, 
OperationName={http://ns.tibco.com/Hello/}sayHello, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, ParentContextID=null 
11 Jun 2012 14:53:12,187 [hello2Connector_30] [INFO ] [jv.helloworld2.soa] 
stdout - --> Generating Java Hello Component Response...
11 Jun 2012 14:53:12,187 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999101: Request Message from TIBCO-IT-JAVA Component Reference to TIBCO-IT-JAVA Component Service . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400, 
OperationName={http://ns.tibco.com/Date/}getCurrentTime, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID=198f1b70-d92a-4351-bb7c-84c86418377e, 
ParentContextID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239
11 Jun 2012 14:53:12,187 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999101: Request Message from TIBCO-IT-JAVA Component Reference to TIBCO-BT-SOAP Reference Binding. 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400_outbound_reference_DateManagerPT_DateManagerSOAP, 
OperationName={http://ns.tibco.com/Date/_gen}getCurrentTime, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID=85ee8fe9-3c7f-4731-89df-c1f3ccd00649, 
ParentContextID=198f1b70-d92a-4351-bb7c-84c86418377e
11 Jun 2012 14:53:12,203 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999105: Request Message from Promoted Reference to External Service. 
PromotedReferenceName=DateManagerPT, BindingName=DateManagerSOAP, BindingType=SOAP/HTTP, URL=http://localhost:9097/dateManagerPT/, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400_outbound_reference_DateManagerPT_DateManagerSOAP, 
OperationName={http://ns.tibco.com/Date/_gen}getCurrentTime, CorrelationId=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID=85ee8fe9-3c7f-4731-89df-c1f3ccd00649, 
ParentContextID=198f1b70-d92a-4351-bb7c-84c86418377e, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/>
<SOAP-ENV:Body><TimeRequest xmlns="http://ns.tibco.com/Date/">America/Los_Angeles</TimeRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>
11 Jun 2012 14:53:12,203 [datemgrConnector_26] [DEBUG] [jv.datemanager.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999100: Request Message from External Consumer to Promoted Service. 
PromotedServiceName=DateManagerPT/DateManagerPT, BindingName=DateManagerSOAP, BindingType=SOAP/HTTP, URL=http://localhost:9097/dateManagerPT/, 
ComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111_inbound_service_DateManagerPT/DateManagerPT_DateManagerSOAP, 
OperationName=getCurrentTime, CorrelationId=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, ContextID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, 
ParentContextID=null, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/>
<SOAP-ENV:Body><TimeRequest xmlns="http://ns.tibco.com/Date/">America/Los_Angeles</TimeRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>
11 Jun 2012 14:53:12,203 [datemgrConnector_26] [DEBUG] [jv.datemanager.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999101: Request Message from TIBCO-BT-SOAP Service Binding to TIBCO-IT-JAVA Component Service . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111_inbound_service_DateManagerPT/DateManagerPT_DateManagerSOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111, 
OperationName={http://ns.tibco.com/Date/}getCurrentTime, CorrelationID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, ContextID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, 
ParentContextID=null 
11 Jun 2012 14:53:12,218 [datemgrConnector_26] [INFO ] [jv.datemanager.soa] stdout - --> Generating Date Manager Component Response...
11 Jun 2012 14:53:12,218 [datemgrConnector_26] [INFO ] [jv.datemanager.soa] stdout - --> Date Manager Component Response: 2012-06-11 14:53:12.218
11 Jun 2012 14:53:12,218 [datemgrConnector_26] [DEBUG] [jv.datemanager.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999201: Response (Reply) Message from TIBCO-IT-JAVA Component Service to TIBCO-BT-SOAP Service Binding . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111_inbound_service_DateManagerPT/DateManagerPT_DateManagerSOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111, 
OperationName={http://ns.tibco.com/Date/}getCurrentTime, CorrelationID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, 
ContextID=680d5ee6-d431-4a82-9f46-b17b7f2a9817, 
ParentContextID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f 
11 Jun 2012 14:53:12,218 [datemgrConnector_26] [DEBUG] [jv.datemanager.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999200: Response (Reply) Message from Promoted Service to External Consumer. 
PromotedServiceName=DateManagerPT/DateManagerPT, BindingName=DateManagerSOAP, BindingType=SOAP/HTTP, URL=N/A, 
ComponentURI=urn:amx:DevEnvironment/
jv.datemanager.soa/JavaDateManagerComponent_1.0.0.201005041111_inbound_service_DateManagerPT/DateManagerPT_DateManagerSOAP, 
OperationName={http://ns.tibco.com/Date/}getCurrentTime, CorrelationId=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, ContextID=680d5ee6-d431-4a82-9f46-b17b7f2a9817, 
ParentContextID=37c1a0b9-4610-4afc-bbf4-f8966f95a36f, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/>
<SOAP-ENV:Body><TimeResponse xmlns="http://ns.tibco.com/Date/">2012-06-11 14:53:12.218</TimeResponse></SOAP-ENV:Body></SOAP-ENV:Envelope>
11 Jun 2012 14:53:12,218 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999205: Response (Reply) Message from External Service to Promoted Reference. 
PromotedReferenceName=DateManagerPT, BindingName=DateManagerSOAP, BindingType=SOAP/HTTP, URL=N/A, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400_outbound_reference_DateManagerPT_DateManagerSOAP, 
OperationName=getCurrentTime, CorrelationId=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, ContextID=0bd769d1-26cc-430a-bdf2-52849cad9a27, 
ParentContextID=85ee8fe9-3c7f-4731-89df-c1f3ccd00649, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/>
<SOAP-ENV:Body><TimeResponse xmlns="http://ns.tibco.com/Date/">2012-06-11 14:53:12.218</TimeResponse></SOAP-ENV:Body></SOAP-ENV:Envelope>
11 Jun 2012 14:53:12,234 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999201: Response (Reply) Message from TIBCO-BT-SOAP Reference Binding to TIBCO-IT-JAVA Component Reference. 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400_outbound_reference_DateManagerPT_DateManagerSOAP, 
OperationName={http://ns.tibco.com/Date/_gen}getCurrentTime, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID=0bd769d1-26cc-430a-bdf2-52849cad9a27, ParentContextID=85ee8fe9-3c7f-4731-89df-c1f3ccd00649 
11 Jun 2012 14:53:12,234 [hello2Connector_30] [INFO ] [jv.helloworld2.soa] stdout - --> Date Manager Component Response: 2012-06-11 14:53:12.218
11 Jun 2012 14:53:12,234 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999201: Response (Reply) Message from TIBCO-IT-JAVA Component Service to TIBCO-IT-JAVA Component Reference. 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaDateManagerComponent_1.0.0.201011151400, 
OperationName={http://ns.tibco.com/Date/}getCurrentTime, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, 
ContextID= 7cc827f9-6ab6-4048-9023-f86f2ed089c5, ParentContextID=198f1b70-d92a-4351-bb7c-84c86418377e 
11 Jun 2012 14:53:12,234 [hello2Connector_30] [INFO ] [jv.helloworld2.soa] stdout - --> Java Hello Component Response: 
		Hi Jim! This is the Java component.
The current time is 2012-06-11 14:53:12.218.
11 Jun 2012 14:53:12,234 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-CF-999201: Response (Reply) Message from TIBCO-IT-JAVA Component Service to TIBCO-BT-SOAP Service Binding . 
SourceComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld2SOAP, 
DestinationComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400, 
OperationName={http://ns.tibco.com/Hello/}sayHello, CorrelationID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, ContextID=8cbca586-fce6-43d3-ac36-02479eea07d9, 
ParentContextID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239 
11 Jun 2012 14:53:12,234 [hello2Connector_30] [DEBUG] [jv.helloworld2.soa] 
com.tibco.amx.messageflow - TIBCO-AMX-BT-SOAP-999200: Response (Reply) Message from Promoted Service to External Consumer. 
PromotedServiceName=HelloWorldPT/HelloWorldPT, BindingName=HelloWorld2SOAP, BindingType=SOAP/HTTP, URL=N/A, 
ComponentURI=urn:amx:DevEnvironment/
jv.helloworld2.soa/JavaHelloWorldComponent_1.0.0.201011151400_inbound_service_HelloWorldPT/HelloWorldPT_HelloWorld2SOAP, 
OperationName={http://ns.tibco.com/Hello/}sayHello, CorrelationId=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, ContextID=8cbca586-fce6-43d3-ac36-02479eea07d9, 
ParentContextID=1ab56e2b-cc6c-4e3a-82ba-22c510ca1239, Message=<?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/>
<SOAP-ENV:Body><HelloResponse xmlns="http://ns.tibco.com/Hello/">Hi Jim! This is the Java component.
The current time is 2012-06-11 14:53:12.218.</HelloResponse></SOAP-ENV:Body></SOAP-ENV:Envelope>