11:34:50,885 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Proxy Service UserQueryProxyService received a new message from : 140.96.81.106 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Message To: /soap/UserQueryProxyService 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO SOAPAction: null 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO WSA-Action: null 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Using the anonymous in-sequence of the proxy service for mediation 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Sequence 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Sequence :: mediate() 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Property mediator 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting property : FORCE_HTTP_1.0 at scope : default to : true (i.e. constant : true) 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Property mediator 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Sequence key= 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Executing with key UserQuery_incoming_DBoperation 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Sequence 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Sequence :: mediate() 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Property mediator 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting property : Query_Message_ID at scope : default to : urn:uuid:4581B414A05B8C12B01216870478946 (i.e. result of expression : get-property('MessageID')) 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Property mediator 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : DBReport mediator 11:34:50,887 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Getting a connection from DataSource jdbc:mysql://localhost:3306/m1999agent_db?autoReconnect=true&useUnicode=true&characterEncoding=utf8 and preparing statement : insert into m1999agent_log(message_id, incoming_message, incoming_time, pleader_email, proxy_service_category) values (?, ?, now(), ?, ?) 11:34:50,888 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting as parameter : 1 value : urn:uuid:4581B414A05B8C12B01216870478946 as JDBC Type : 12(see java.sql.Types for valid types) 11:34:50,888 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting as parameter : 2 value : abc@mail.tcg.gov.tw 1234 as JDBC Type : 12(see java.sql.Types for valid types) 11:34:50,888 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting as parameter : 3 value : abc@mail.tcg.gov.tw as JDBC Type : 12(see java.sql.Types for valid types) 11:34:50,888 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Setting as parameter : 4 value : UserQuery as JDBC Type : 12(see java.sql.Types for valid types) 11:34:50,888 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Successfully prepared statement : insert into m1999agent_log(message_id, incoming_message, incoming_time, pleader_email, proxy_service_category) values (?, ?, now(), ?, ?) against DataSource : jdbc:mysql://localhost:3306/m1999agent_db?autoReconnect=true&useUnicode=true&characterEncoding=utf8 11:34:50,889 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Inserted 1 row/s using statement : insert into m1999agent_log(message_id, incoming_message, incoming_time, pleader_email, proxy_service_category) values (?, ?, now(), ?, ?) 11:34:50,889 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : DBReport mediator 11:34:50,889 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Sequence 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Sequence key= 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Sequence key= 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Executing with key queryRequestValidation 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Sequence 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Sequence :: mediate() 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Validate mediator 11:34:50,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Validation source : abc@mail.tcg.gov.tw 1234 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Validation of element returned by the XPath expression : s11:Body/child::*[position()=1] | s12:Body/child::*[position()=1] succeeded against the given schemas and the current message 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Validate mediator 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Sequence 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Sequence key= 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Class mediator 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO invoking : class org.icl.wso2.esb.mediators.LoginTimeCompareMediator_Query.mediate() 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : LoginTimeCompareMediator_Query mediator 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : PleaderEmailElement = abc@mail.tcg.gov.tw 11:34:50,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : pleader email = abc@mail.tcg.gov.tw 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : select * from m1999_accounting where pleader_email = 'abc@mail.tcg.gov.tw' 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : in while loop 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : TestPleaderName = ´ú¸Õ01 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : rsDate is 1216870448000 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : Date at row is 24 11 34 8 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : Have to be reserved 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO LoginTimeCompareMediator_Query : UPDATE m1999_accounting SET last_action_time = '2008-07-24 11:34:50.891' where sequence_id = '364' 11:34:50,896 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Class mediator 11:34:50,897 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Filter mediator 11:34:50,897 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO XPath expression : //soapenv:Fault evaluates to false and no else path - skipping child mediators 11:34:50,897 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : XSLT mediator 11:34:50,897 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Using byte array serialization for transformation 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Transformation completed - processing result 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO output method: xml; encoding: UTF-8 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Replace node with result 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : XSLT mediator 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO End : Sequence 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Forwarding message to the endpoint : UserCaseSearch 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Start : Address Endpoint 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO Sending message to endpoint : UserCaseSearch resolves to address = http://localhost:8080/examples/servlets/servlet/CaseServlet2 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO SOAPAction: null 11:34:50,898 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpServerWorker-18] INFO WSA-Action: null 11:35:09,807 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] WARN Fault handler - setting ERROR_MESSAGE : Connection timeout 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] WARN Fault handler - setting ERROR_DETAIL : java.lang.Exception: Connection timeout at org.apache.synapse.core.axis2.SynapseCallbackReceiver.handleMessage(SynapseCallbackReceiver.java:172) at org.apache.synapse.core.axis2.SynapseCallbackReceiver.receive(SynapseCallbackReceiver.java:131) at org.apache.synapse.transport.nhttp.ClientHandler$1.run(ClientHandler.java:298) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) at java.lang.Thread.run(Thread.java:619) 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] WARN Fault handler - setting ERROR_EXCEPTION : java.lang.Exception: Connection timeout 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] WARN FaultHandler executing impl: org.apache.synapse.mediators.MediatorFaultHandler 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] WARN Executing fault handler mediator : fault 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Sequence 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Sequence :: mediate() 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Sequence key= 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Executing with key err_msg 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Sequence 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Sequence :: mediate() 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Fault mediator 11:35:09,813 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Creating a SOAP 1.1 fault 11:35:09,814 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Fault mediator 11:35:09,814 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : XSLT mediator 11:35:09,814 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Using byte array serialization for transformation 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Transformation completed - processing result 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO output method: xml; encoding: UTF-8 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Replace node with result 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : XSLT mediator 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Property mediator 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Setting property : HTTP_SC at scope : axis2 to : 200 (i.e. constant : 200) 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Property mediator 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Log mediator 11:35:09,815 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Log message : To: ,MessageID: urn:uuid:4581B414A05B8C12B01216870450850,Direction: request,Envelope: Nsf11:ServerService Not Available 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Log mediator 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Send mediator 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Address Endpoint 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Sending message to endpoint : AnonymousEndpoint resolves to address = http://www.w3.org/2005/08/addressing/anonymous 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO SOAPAction: null 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO WSA-Action: null 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Send mediator 11:35:09,816 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO Start : Drop mediator 11:35:09,817 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Drop mediator 11:35:09,817 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Sequence 11:35:09,817 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Sequence key= 11:35:09,817 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-8] INFO End : Sequence 11:35:52,890 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] WARN Fault handler - setting ERROR_MESSAGE : Connection timeout 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] WARN Fault handler - setting ERROR_DETAIL : java.lang.Exception: Connection timeout at org.apache.synapse.core.axis2.SynapseCallbackReceiver.handleMessage(SynapseCallbackReceiver.java:172) at org.apache.synapse.core.axis2.SynapseCallbackReceiver.receive(SynapseCallbackReceiver.java:131) at org.apache.synapse.transport.nhttp.ClientHandler$1.run(ClientHandler.java:298) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) at java.lang.Thread.run(Thread.java:619) 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] WARN Fault handler - setting ERROR_EXCEPTION : java.lang.Exception: Connection timeout 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] WARN FaultHandler executing impl: org.apache.synapse.mediators.MediatorFaultHandler 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] WARN Executing fault handler mediator : fault 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Sequence 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Sequence :: mediate() 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Sequence key= 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Executing with key err_msg 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Sequence 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Sequence :: mediate() 11:35:52,891 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Fault mediator 11:35:52,892 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Creating a SOAP 1.1 fault 11:35:52,892 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Fault mediator 11:35:52,892 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : XSLT mediator 11:35:52,892 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Using byte array serialization for transformation 11:35:52,893 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Transformation completed - processing result 11:35:52,893 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO output method: xml; encoding: UTF-8 11:35:52,893 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Replace node with result 11:35:52,893 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : XSLT mediator 11:35:52,893 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Property mediator 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Setting property : HTTP_SC at scope : axis2 to : 200 (i.e. constant : 200) 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Property mediator 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Log mediator 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Log message : To: ,MessageID: urn:uuid:4581B414A05B8C12B01216870478946,Direction: request,Envelope: Nsf11:ServerService Not Available 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Log mediator 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Send mediator 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Address Endpoint 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Sending message to endpoint : AnonymousEndpoint resolves to address = http://www.w3.org/2005/08/addressing/anonymous 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO SOAPAction: null 11:35:52,894 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO WSA-Action: null 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Send mediator 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO Start : Drop mediator 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Drop mediator 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Sequence 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Sequence key= 11:35:52,895 [140.96.178.114-pc178114.ccl.itri.org.tw] [HttpClientWorker-11] INFO End : Sequence