如何打印AXIS2的工作流程日志

为了理解Axis2的工作流程，需要打印出Axis2的工作流程，只需要配置相应的log就行了

 

log4j配置

 

log4j.rootLogger=DEBUG, Console 

 

#Console 

log4j.appender.Console=org.apache.log4j.ConsoleAppender 

log4j.appender.Console.layout=org.apache.log4j.PatternLayout 

log4j.appender.Console.layout.ConversionPattern=%d [%t] %-5p [%c] - %m%n 

 

log4j.logger.java.sql.ResultSet=INFO 

log4j.logger.org.apache=debug 

log4j.logger.java.sql.Connection=debug

log4j.logger.java.sql.Statement=debug

log4j.logger.java.sql.PreparedStatement=debug

log4j.logger.org.mybatis.caches.ehcache=debug

 

关键是打印org.apache包的log目录

 

 

打印Axis2的工作流程：

2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.builder.BuilderUtil] - Input contentType (text/xml;charset=UTF-8)

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.builder.BuilderUtil] - CharSetEncoding from content-type (UTF-8)

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.transport.TransportUtils] - createSOAPEnvelope using Builder (class org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.builder.BuilderUtil] - char set encoding set from default =UTF-8

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axiom.om.util.StAXUtils] - XMLStreamReader is org.apache.axiom.util.stax.dialect.WoodstoxStreamReaderWrapper

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Starting to process SOAP 1.1 message

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Build the OMElement Envelope by the StaxSOAPModelBuilder

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Build the OMElement Header by the StaxSOAPModelBuilder

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Build the OMElement Body by the StaxSOAPModelBuilder

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "Transport"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "Transport"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'RequestURIBasedDispatcher' in Phase 'Transport'

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.dispatchers.RequestURIBasedServiceDispatcher] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking for Service using target endpoint address : http://10.36.24.5:8999/VTMS3000/services/VTMS.VTMSHttpSoap11Endpoint/

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.util.Utils] - parseRequestURLForServiceAndOperation : [http://10.36.24.5:8999/VTMS3000/services/VTMS.VTMSHttpSoap11Endpoint/][/VTMS3000/services]

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.i18n.ProjectResourceBundle] - org.apache.axis2.i18n.resource::handleGetObject(servicefound)

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.AbstractDispatcher] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Found AxisService : VTMS

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'SOAPActionBasedDispatcher' in Phase 'Transport'

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.dispatchers.ActionBasedOperationDispatcher] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking for Operation using Action : urn:CallFunction

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.description.AxisService] - getOperationBySOAPAction: Operation (org.apache.axis2.description.InOutAxisOperation@a4f040,CallFunction) for soapAction: urn:CallFunction found in action map.

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.i18n.ProjectResourceBundle] - org.apache.axis2.i18n.resource::handleGetObject(operationfound)

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.AbstractDispatcher] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Found AxisOperation : CallFunction

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "Transport"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "Addressing"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "Addressing"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'AddressingInHandler' in Phase 'Addressing'

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.handlers.addressing.AddressingInHandler] - No headers present corresponding to http://www.w3.org/2005/08/addressing

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.handlers.addressing.AddressingInHandler] - No headers present corresponding to http://schemas.xmlsoap.org/ws/2004/08/addressing

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'AddressingBasedDispatcher' in Phase 'Addressing'

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "Addressing"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "Security"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "Security"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "Security"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "PreDispatch"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "PreDispatch"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "PreDispatch"

  2012-04-26 19:27:54,781 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "Dispatch"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "Dispatch"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'RequestURIBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'SOAPActionBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'RequestURIOperationDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'SOAPMessageBodyBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'AddressingValidationHandler' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.addressing.AddressingHelper] - getAddressingRequirementParemeterValue: value: 'null'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.addressing.AddressingHelper] - getInvocationPatternParameterValue: value: 'null'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'HTTPLocationBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'GenericProviderDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'MustUnderstandValidationDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.jaxws.dispatchers.MustUnderstandValidationDispatcher] - JAXWS MustUnderstandValidationDispatcher.invoke on AxisService VTMS; AxisOperation org.apache.axis2.description.InOutAxisOperation@a4f040

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "Dispatch"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.addressing.AddressingHelper] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] isReplyRedirected: ReplyTo is null. Returning false

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "RMPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "RMPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "RMPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "OperationInPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "OperationInPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'MustUnderstandChecker' in Phase 'OperationInPhase'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.jaxws.dispatchers.MustUnderstandUtils] - Building list of understood headers for all operations under VTMS

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.jaxws.dispatchers.MustUnderstandUtils] - Parameter not on VTMS; org.apache.axis2.jaxws.description.EndpointDescription.handlerParameterQNames

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.jaxws.dispatchers.MustUnderstandUtils] - Adding headers from SOAP handlers; headers = null

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "OperationInPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "soapmonitorPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "soapmonitorPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "soapmonitorPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking pre-condition for Phase "loggingPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking phase "loggingPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking Handler 'InFlowLogHandler' in Phase 'loggingPhase'

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Build the OMElement CallFunction by the StaxSOAPModelBuilder

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [com.dahuatech.handler.LogHandler] -  ip:10.36.24.5 request begin!

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Checking post-conditions for phase "loggingPhase"

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axis2.context.ConfigurationContext] - messageID is null.

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder] - Build the OMElement strXml by the StaxSOAPModelBuilder

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [com.dahuatech.service.VTMS] - 10_9_你好____( End_0,Start_0,)

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [java.sql.Connection] - ooo Connection Opened

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT count(*) FROM qowners AS a left JOIN qareas AS b ON a.iAreaCode = b.iAreaCode 

  2012-04-26 19:27:54,796 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT a.iOwnerId, a.sOwnerCode, a.iOwnerGender, a.sOwnerName, a.iFloor, a.iRoom, a.iOwnerType, a.iAreaCode, a.iRightGroupId, a.iOwnerVTH, a.sOwnerFamilyName, a.iDocumnetsType, b.sAreaAddr FROM qowners AS a left JOIN qareas AS b ON a.iAreaCode = b.iAreaCode 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1808(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1888(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1881(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1807(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 2699(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 4(Integer)

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,812 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1809(Integer)

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 6(Integer)

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 5(Integer)

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 1812(Integer)

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==>  Executing: SELECT iRightGroupId, sRightGroupName, sDesc, iAreaCode FROM qrightgroups where iRightGroupId = ? 

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [java.sql.PreparedStatement] - ==> Parameters: 7(Integer)

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "soapmonitorPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "soapmonitorPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "soapmonitorPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "OperationOutPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "OperationOutPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "OperationOutPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "RMPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "RMPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "RMPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "PolicyDetermination"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "PolicyDetermination"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "PolicyDetermination"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "MessageOut"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "MessageOut"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking Handler 'AddressingOutHandler' in Phase 'MessageOut'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "MessageOut"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "Security"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "Security"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "Security"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking pre-condition for Phase "loggingPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking phase "loggingPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking Handler 'OutFlowLogHandler' in Phase 'loggingPhase'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [com.dahuatech.handler.LogHandler] -  request end

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Checking post-conditions for phase "loggingPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.OMOutputFormat] - Start getContentType: OMOutputFormat [ mimeBoundary =null rootContentId=null doOptimize=false doingSWA=false isSOAP11=true charSetEncoding=UTF-8 xmlVersion=null contentType=null ignoreXmlDeclaration=false autoCloseWriter=true actionProperty=null optimizedThreshold=0]

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.OMOutputFormat] - getContentType= {text/xml}   OMOutputFormat [ mimeBoundary =null rootContentId=null doOptimize=false doingSWA=false isSOAP11=true charSetEncoding=UTF-8 xmlVersion=null contentType=text/xml ignoreXmlDeclaration=false autoCloseWriter=true actionProperty=null optimizedThreshold=0]

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] - contentType from the OMOutputFormat =text/xml

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] - contentType returned =text/xml; charset=UTF-8

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] - start writeTo()

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] -   preserve=false

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] -   isOptimized=false

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] -   isDoingSWA=false

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.util.Utils] - MTOM optimized Threshold value =0

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.impl.MTOMXMLStreamWriter] - Creating MTOMXMLStreamWriter

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.impl.MTOMXMLStreamWriter] - OutputStream =class java.io.BufferedOutputStream

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.impl.MTOMXMLStreamWriter] - OMFormat = OMOutputFormat [ mimeBoundary =null rootContentId=null doOptimize=false doingSWA=false isSOAP11=true charSetEncoding=UTF-8 xmlVersion=null contentType=text/xml ignoreXmlDeclaration=false autoCloseWriter=true actionProperty=null optimizedThreshold=0]

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.util.StAXUtils] - XMLStreamWriter is org.apache.axiom.util.stax.dialect.WoodstoxStreamWriterWrapper

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl] - Could not close builder or parser due to:

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl] - builder is null

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.impl.MTOMXMLStreamWriter] - Calling MTOMXMLStreamWriter.flush

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axiom.om.impl.MTOMXMLStreamWriter] - close

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.http.SOAPMessageFormatter] - end writeTo()

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.TransportUtils] - Found RequestResponseTransport setting response written

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "loggingPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() for Handler 'OutFlowLogHandler' in Phase 'loggingPhase'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "Security"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "MessageOut"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() for Handler 'AddressingOutHandler' in Phase 'MessageOut'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "PolicyDetermination"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "RMPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "OperationOutPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674865] Invoking flowComplete() in Phase "soapmonitorPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "loggingPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'InFlowLogHandler' in Phase 'loggingPhase'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "soapmonitorPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "OperationInPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'MustUnderstandChecker' in Phase 'OperationInPhase'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "RMPhase"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "Dispatch"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'MustUnderstandValidationDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'GenericProviderDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'HTTPLocationBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'AddressingValidationHandler' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'SOAPMessageBodyBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'RequestURIOperationDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'SOAPActionBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'RequestURIBasedDispatcher' in Phase 'Dispatch'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "PreDispatch"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "Security"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "Addressing"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'AddressingBasedDispatcher' in Phase 'Addressing'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'AddressingInHandler' in Phase 'Addressing'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() in Phase "Transport"

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'SOAPActionBasedDispatcher' in Phase 'Transport'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.engine.Phase] - [MessageContext: logID=urn:uuid:7F3B554DE52B92CF071335439674817] Invoking flowComplete() for Handler 'RequestURIBasedDispatcher' in Phase 'Transport'

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.TransportUtils] - Found RequestResponseTransport returning isResponseWritten()

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.TransportUtils] - Entering deleteAttachments()

  2012-04-26 19:27:54,828 [http-8999-1] DEBUG [org.apache.axis2.transport.TransportUtils] - Exiting deleteAttachments()