2016-05-14 14:14:06,174 [DefaultSipTimerThread] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processTransactionTerminated]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@849425e terminated => INVITE sip:9988002@192.168.4.160:11180 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:06,175 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 2 serverTransactionTable 2 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:06,175 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@849425e terminated => INVITE sip:9988002@192.168.4.160:11180 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:06,176 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanup : z9hg4bk7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 2016-05-14 14:14:06,176 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.proxy.ProxyBranchImpl.removeTransaction]-[DEBUG] Removing transaction z9hG4bK7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 from proxy branch FAILED. Not found. 2016-05-14 14:14:06,177 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.proxy.ProxyImpl.removeTransaction]-[DEBUG] Transaction z9hG4bK7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 removed from proxy. 2016-05-14 14:14:06,177 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanupOnTimer: z9hg4bk7599adf4_1180947b_2efc3a3b-1d87-47ef-a7ce-81fa37cc7f66 2016-05-14 14:14:06,178 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@849425e has been removed from sip session's ongoingTransactions ? true 2016-05-14 14:14:06,178 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipSessionImpl.updateReadyToInvalidate]-[DEBUG] ongoingTransactions false for sipsession (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:06,179 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 transactionId = null transactionType false 2016-05-14 14:14:06,179 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@420c2f4a 2016-05-14 14:14:06,179 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaned up tx app data hops 2016-05-14 14:14:06,180 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation null 2016-05-14 14:14:06,180 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation terminate false 2016-05-14 14:14:06,181 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.getSipApplicationSession]-[DEBUG] trying to find sip application session with key 7599adf4;MySipServlet 2016-05-14 14:14:06,181 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.setToTag]-[DEBUG] Totag HFcU3mmj6pHQe currentKeyToTag HFcU3mmj6pHQe 2016-05-14 14:14:06,181 [MSS-Executor-Thread-5] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] don't Invalidate Proxy session 2016-05-14 14:14:06,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] LingerTimer : z9hg4bk7cy3ae6rfn43c 2016-05-14 14:14:10,991 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] executing TransactionTimerJ() : z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0:cancel 2016-05-14 14:14:10,991 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] fireTimeoutTimer gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 2016-05-14 14:14:10,991 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] sipDialogs = [] default dialog null retval null 2016-05-14 14:14:10,992 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Transaction:setState 5 gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 branchID = z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 isClient = true 2016-05-14 14:14:10,993 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:564][SIPClientTransactionImpl.java:1414][SIPClientTransactionImpl.java:1197][SIPClientTransactionImpl.java:691][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:10,993 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] removing = gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 isReliable false 2016-05-14 14:14:10,993 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Removing Transaction = z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0:cancel transaction = gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 2016-05-14 14:14:10,994 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] REMOVED client tx gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 KEY = z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0:cancel 2016-05-14 14:14:10,994 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] handleEvent javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]currentTransaction = gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481this.sipListener = org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36eesipEvent.source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:10,994 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SipProviderImpl.java:175][SIPTransactionStack.java:1900][SIPClientTransactionImpl.java:1893][SIPClientTransactionImpl.java:692][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:10,995 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] sipEvent = javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:10,995 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] About to deliver transactionTerminatedEvent 2016-05-14 14:14:10,995 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 2016-05-14 14:14:10,995 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = null 2016-05-14 14:14:10,996 [DefaultSipTimerThread] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processTransactionTerminated]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 terminated => CANCEL sip:9988003@192.168.4.30:5080 SIP/2.0 Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 To: CSeq: 91266547 CANCEL From: "Extension 9988001" ;tag=KtgZ29USetpNj Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Max-Forwards: 68 User-Agent: Mobicents Sip Servlets 3.0.0-SNAPSHOT Content-Length: 0 2016-05-14 14:14:10,997 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 terminated => CANCEL sip:9988003@192.168.4.30:5080 SIP/2.0 Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 To: CSeq: 91266547 CANCEL From: "Extension 9988001" ;tag=KtgZ29USetpNj Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Max-Forwards: 68 User-Agent: Mobicents Sip Servlets 3.0.0-SNAPSHOT Content-Length: 0 2016-05-14 14:14:10,998 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 has been removed from sip session's ongoingTransactions ? true 2016-05-14 14:14:10,999 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.updateReadyToInvalidate]-[DEBUG] ongoingTransactions false for sipsession (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:10,999 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 transactionId = null transactionType false 2016-05-14 14:14:11,000 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] Session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet): cleaning up INVITE sip:9988003@192.168.4.30:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 since transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@c2947481 with branch id z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 is the same as sessionCreatingRequestTransaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 with branch id z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 and method INVITE 2016-05-14 14:14:11,001 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@18580a0e 2016-05-14 14:14:11,001 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaned up tx app data hops 2016-05-14 14:14:11,001 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.message.SipServletMessageImpl.setTransaction]-[DEBUG] Setting transaction null on message INVITE sip:9988003@192.168.4.30:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:11,002 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] nullifying sessionCreatingTransactionRequestINVITE sip:9988003@192.168.4.30:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 from Session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:11,002 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@3bfc299e 2016-05-14 14:14:11,003 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation null 2016-05-14 14:14:11,003 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation terminate false 2016-05-14 14:14:11,003 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.getSipApplicationSession]-[DEBUG] trying to find sip application session with key 7599adf4;MySipServlet 2016-05-14 14:14:11,003 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.setToTag]-[DEBUG] Totag HFcU3mmj6pHQe currentKeyToTag HFcU3mmj6pHQe 2016-05-14 14:14:11,003 [MSS-Executor-Thread-6] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] don't Invalidate Proxy session 2016-05-14 14:14:11,003 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 1 serverTransactionTable 2 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:11,004 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanup : z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0:cancel 2016-05-14 14:14:11,004 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanupOnTimer: z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0:cancel 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] removinggov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup : z9hg4bk7cy3ae6rfn43c 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Removing Transaction = z9hg4bk7cy3ae6rfn43c transaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionStack.java:1842][SIPServerTransactionImpl.java:1971][SIPTransactionImpl.java:289][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removePendingTx: z9hg4bk7cy3ae6rfn43c 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] Removing tx from merge table 2016-05-14 14:14:14,309 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] handleEvent javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]currentTransaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197this.sipListener = org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36eesipEvent.source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SipProviderImpl.java:175][SIPTransactionStack.java:1863][SIPServerTransactionImpl.java:1971][SIPTransactionImpl.java:289][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] sipEvent = javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] About to deliver transactionTerminatedEvent 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = null 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 2016-05-14 14:14:14,310 [DefaultSipTimerThread] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processTransactionTerminated]-[INFO] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 terminated => INVITE sip:9988002@192.168.4.89 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 69 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:14,311 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 1 serverTransactionTable 1 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[INFO] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 terminated => INVITE sip:9988002@192.168.4.89 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 69 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:14,311 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup on timer : z9hg4bk7cy3ae6rfn43c 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.proxy.ProxyBranchImpl.removeTransaction]-[DEBUG] Removed transaction z9hG4bK7cy3ae6rFN43c from proxy branch ? true 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.proxy.ProxyImpl.removeTransaction]-[DEBUG] Transaction z9hG4bK7cy3ae6rFN43c removed from proxy. 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.proxy.ProxyImpl.checkAndCleanProxy]-[DEBUG] Cleaning Proxy to optimize perf org.mobicents.servlet.sip.proxy.ProxyImpl@7d8b570d 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@470056df 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.message.SipServletMessageImpl.setTransaction]-[DEBUG] Setting transaction null on message ACK sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK8NQvc9pvcytpr;received=192.168.4.204 Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 ACK Contact: Content-Length: 0 2016-05-14 14:14:14,311 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.message.SipServletRequestImpl.cleanUpLastResponses]-[DEBUG] cleaning up last responses on ACK sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK8NQvc9pvcytpr;received=192.168.4.204 Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 ACK Contact: Content-Length: 0 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.proxy.ProxyImpl.checkAndCleanProxy]-[DEBUG] Cleaning proxy finalBranchForSubsequentRequests to optimize perf org.mobicents.servlet.sip.proxy.ProxyImpl@7d8b570d 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@7a406197 has been removed from sip session's ongoingTransactions ? true 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.updateReadyToInvalidate]-[DEBUG] ongoingTransactions false for sipsession (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@5b414746 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation null 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation terminate false 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.getSipApplicationSession]-[DEBUG] trying to find sip application session with key 7599adf4;MySipServlet 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.setToTag]-[DEBUG] Totag HFcU3mmj6pHQe currentKeyToTag HFcU3mmj6pHQe 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) is valid ? :true 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] Sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) is ready to be invalidated ? :true 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.setReadyToInvalidate]-[DEBUG] readyToInvalidate flag is set to true 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.onReadyToInvalidate]-[DEBUG] invalidateWhenReady flag is set to true 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.invalidate]-[INFO] Invalidating the sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.invalidate]-[DEBUG] sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) is the parent session, checking derived sessions 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.invalidate]-[DEBUG] derived session (KtgZ29USetpNj;vN8t8Xm8yXg2K;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) false true TERMINATED 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.removeSipSession]-[DEBUG] Removing a sip session with the key : (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:14,312 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.removeSipSession]-[DEBUG] Trying to remove sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.removeSipSession]-[DEBUG] Sip Session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) still contains derived sip sessions so not removing 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.removeSipSession]-[DEBUG] Derived sip sessions left are : 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.removeSipSession]-[DEBUG] Derived sip session : (KtgZ29USetpNj;vN8t8Xm8yXg2K;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) isValid true isReadyToInvalidate false 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.updateReadyToInvalidateState]-[DEBUG] underlying sip sessions 0 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.updateReadyToInvalidateState]-[DEBUG] All sip sessions and http session are ready to be invalidated, no timers alive, can invalidate this application session 7599adf4;MySipServlet 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipSessionImpl.invalidate]-[DEBUG] 1 ongoing transactions still present in the following sip session (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) on invalidation 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@18580a0e 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Transaction:setState 5 gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 branchID = z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 isClient = true 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:564][SIPClientTransactionImpl.java:1414][SIPClientTransactionImpl.java:1449][SipSessionImpl.java:1055][SipSessionImpl.java:1911][SipSessionImpl.java:1500][SipApplicationDispatcherImpl.java:1238][SipApplicationDispatcherImpl.java:143][SipApplicationDispatcherImpl.java:1632][ThreadPoolExecutor.java:1110][ThreadPoolExecutor.java:603][Thread.java:722] 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] sipDialogs = [] default dialog null retval null 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] sip app session 7599adf4;MySipServlet is valid ? :true 2016-05-14 14:14:14,313 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] Sip app session 7599adf4;MySipServlet is ready to be invalidated ? :true 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.tryToInvalidate]-[DEBUG] tryToInvalidate:[isValidInternal=true,readyToInvalidate=true,invalidateWhenReady=true] 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.tryToInvalidate]-[DEBUG] tryToInvalidate:[allSipSessionInvalidated=true,allHttpSessionsInvalidated=true] 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.notifySipApplicationSessionListeners]-[DEBUG] notifying sip application session listeners of context MySipServlet of following event READYTOINVALIDATE 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.notifySipApplicationSessionListeners]-[DEBUG] notifying sip application session listener com.egoonet.servlet.sip.MySipServlet of context MySipServlet of following event READYTOINVALIDATE 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [com.egoonet.servlet.sip.MySipServlet.sessionReadyToInvalidate]-[INFO] _____________________暂时还不想释放sip applicatoin session[7599adf4;MySipServlet] 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.invalidate]-[INFO] Invalidating the following sip application session 7599adf4;MySipServlet 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.removeSipApplicationSession]-[DEBUG] Removing a sip application session with the key : 7599adf4;MySipServlet 2016-05-14 14:14:14,314 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.notifySipApplicationSessionListeners]-[DEBUG] notifying sip application session listeners of context MySipServlet of following event DELETION 2016-05-14 14:14:14,315 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.notifySipApplicationSessionListeners]-[DEBUG] notifying sip application session listener com.egoonet.servlet.sip.MySipServlet of context MySipServlet of following event DELETION 2016-05-14 14:14:14,315 [MSS-Executor-Thread-7] [com.egoonet.servlet.sip.MySipServlet.sessionDestroyed]-[INFO] _____________________sip application session[7599adf4;MySipServlet]已销毁 2016-05-14 14:14:14,315 [MSS-Executor-Thread-7] [org.mobicents.servlet.sip.core.session.SipApplicationSessionImpl.invalidate]-[INFO] The following sip application session 7599adf4;MySipServlet has been invalidated 2016-05-14 14:14:14,694 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] removing = gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 isReliable false 2016-05-14 14:14:14,694 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Removing Transaction = z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 transaction = gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] REMOVED client tx gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 KEY = z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] handleEvent javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]currentTransaction = gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353this.sipListener = org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36eesipEvent.source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SipProviderImpl.java:175][SIPTransactionStack.java:1900][SIPClientTransactionImpl.java:1893][SIPClientTransactionImpl.java:253][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] sipEvent = javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] About to deliver transactionTerminatedEvent 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 2016-05-14 14:14:14,695 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = null 2016-05-14 14:14:14,696 [DefaultSipTimerThread] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processTransactionTerminated]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 terminated => INVITE sip:9988003@192.168.4.30:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:14,697 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 0 serverTransactionTable 1 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:14,697 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[INFO] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 terminated => INVITE sip:9988003@192.168.4.30:5080 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.89:5060;branch=z9hG4bK7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bK7cy3ae6rFN43c;received=192.168.4.204 Max-Forwards: 68 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266547 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Allow-Events: talk,hold,conference,refer Content-Type: application/sdp Content-Disposition: session X-FS-Support: update_display,send_info Remote-Party-ID: "Extension 9988001" ;party=calling;screen=yes;privacy=off Record-Route: Content-Length: 207 v=0 o=FreeSWITCH 1462373943 1462373944 IN IP4 192.168.4.204 s=FreeSWITCH c=IN IP4 192.168.4.204 t=0 0 m=audio 40190 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2016-05-14 14:14:14,697 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanup : z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 2016-05-14 14:14:14,697 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.session.SipSessionImpl.removeOngoingTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPClientTransactionImpl@f0fd5353 has been removed from sip session's ongoingTransactions ? false 2016-05-14 14:14:14,697 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPClientTransaction.logDebug]-[DEBUG] cleanupOnTimer: z9hg4bk7599adf4_1180947b_a0004b13-319b-4d29-bf38-d0152695ace0 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.session.SipSessionImpl.updateReadyToInvalidate]-[DEBUG] ongoingTransactions null for sipsession (KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.message.TransactionApplicationData.cleanUp]-[DEBUG] cleaning up the application data org.mobicents.servlet.sip.message.TransactionApplicationData@18580a0e 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation null 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.session.SipSessionImpl.cleanDialogInformation]-[DEBUG] cleanDialogInformation terminate false 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.getSipApplicationSession]-[DEBUG] trying to find sip application session with key 7599adf4;MySipServlet 2016-05-14 14:14:14,698 [MSS-Executor-Thread-8] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.tryToInvalidateSession]-[DEBUG] sip session already invalidated(KtgZ29USetpNj;0e0bee76-943b-1234-4ba8-000c29680286;7599adf4;MySipServlet) 2016-05-14 14:14:16,201 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] executing TransactionTimerJ() : z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,202 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] SIPServerTransaction.fireTimeoutTimer this = gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 current state = 3 method = BYE 2016-05-14 14:14:16,202 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Transaction:setState 5 gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 branchID = z9hG4bKDrK5jN82mvtQa isClient = false 2016-05-14 14:14:16,202 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:564][SIPServerTransactionImpl.java:1572][SIPServerTransactionImpl.java:1282][SIPServerTransactionImpl.java:1614][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:16,202 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] removinggov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 2016-05-14 14:14:16,203 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup : z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,204 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Removing Transaction = z9hg4bkdrk5jn82mvtqa transaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 2016-05-14 14:14:16,205 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionStack.java:1842][SIPServerTransactionImpl.java:1971][SIPServerTransactionImpl.java:1284][SIPServerTransactionImpl.java:1614][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:16,205 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removePendingTx: z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,205 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] handleEvent javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]currentTransaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66this.sipListener = org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36eesipEvent.source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:16,206 [DefaultSipTimerThread] [gov.nist.javax.sip.SipProviderImpl.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SipProviderImpl.java:175][SIPTransactionStack.java:1863][SIPServerTransactionImpl.java:1971][SIPServerTransactionImpl.java:1284][SIPServerTransactionImpl.java:1614][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:16,206 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] sipEvent = javax.sip.TransactionTerminatedEvent[source=gov.nist.javax.sip.SipProviderImpl@415dd847]source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:16,206 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] About to deliver transactionTerminatedEvent 2016-05-14 14:14:16,206 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = null 2016-05-14 14:14:16,206 [DefaultSipTimerThread] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] tx = gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 2016-05-14 14:14:16,207 [DefaultSipTimerThread] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processTransactionTerminated]-[INFO] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 terminated => BYE sip:mod_sofia@192.168.4.204:11180 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.30:5080;rport=5080;branch=z9hG4bKDrK5jN82mvtQa;received=192.168.4.30 Max-Forwards: 70 From: ;tag=BSm6rjFXQ1B7r To: "Extension 9988001" ;tag=jHQ60eBpHH02p Call-ID: 9cfabe62-943a-1234-4ba8-000c29680286 CSeq: 91267148 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.5.12b~32bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 2016-05-14 14:14:16,209 [DefaultSipTimerThread] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] sessionKey is null 2016-05-14 14:14:16,210 [DefaultSipTimerThread] [org.mobicents.servlet.sip.message.TransactionApplicationData.getSipSessionKey]-[DEBUG] local session Key is null 2016-05-14 14:14:16,210 [DefaultSipTimerThread] [org.mobicents.servlet.sip.message.TransactionApplicationData.getSipSessionKey]-[DEBUG] session Key from sipservletmessage is null 2016-05-14 14:14:16,210 [DefaultSipTimerThread] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] session Key is null, retrieved from the txAppData org.mobicents.servlet.sip.message.TransactionApplicationData@547a8dfd 2016-05-14 14:14:16,210 [DefaultSipTimerThread] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] sessionKey is null 2016-05-14 14:14:16,211 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 0 serverTransactionTable 0 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:16,212 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup on timer : z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,212 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] removinggov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 2016-05-14 14:14:16,212 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup : z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,216 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Removing Transaction = z9hg4bkdrk5jn82mvtqa transaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 2016-05-14 14:14:16,216 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionStack.java:1842][SIPServerTransactionImpl.java:1971][SIPServerTransactionImpl.java:1615][DefaultSipTimer.java:67][Timer.java:555][Timer.java:505] 2016-05-14 14:14:16,216 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removePendingTx: z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,217 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removeTransaction: Table size : clientTransactionTable 0 serverTransactionTable 0 mergetTable 0 terminatedServerTransactionsPendingAck 0 forkedClientTransactionTable 0 pendingTransactions 0 2016-05-14 14:14:16,218 [DefaultSipTimerThread] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup on timer : z9hg4bkdrk5jn82mvtqa 2016-05-14 14:14:16,218 [MSS-Executor-Thread-9] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[INFO] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@79125b66 terminated => BYE sip:mod_sofia@192.168.4.204:11180 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.30:5080;rport=5080;branch=z9hG4bKDrK5jN82mvtQa;received=192.168.4.30 Max-Forwards: 70 From: ;tag=BSm6rjFXQ1B7r To: "Extension 9988001" ;tag=jHQ60eBpHH02p Call-ID: 9cfabe62-943a-1234-4ba8-000c29680286 CSeq: 91267148 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.5.12b~32bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 2016-05-14 14:14:16,219 [MSS-Executor-Thread-9] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.run]-[DEBUG] no sip session were returned for this key null and message BYE sip:mod_sofia@192.168.4.204:11180 SIP/2.0 Via: SIP/2.0/UDP 192.168.4.30:5080;rport=5080;branch=z9hG4bKDrK5jN82mvtQa;received=192.168.4.30 Max-Forwards: 70 From: ;tag=BSm6rjFXQ1B7r To: "Extension 9988001" ;tag=jHQ60eBpHH02p Call-ID: 9cfabe62-943a-1234-4ba8-000c29680286 CSeq: 91267148 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.5.12b~32bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 2016-05-14 14:14:33,425 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] UDPMessageChannel: processIncomingDataPacket : peerAddress = 192.168.4.204/11180 Length = 757 2016-05-14 14:14:33,426 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logInfo]-[INFO] Setting SIPMessage peerPacketSource to: /192.168.4.204:11180 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logInfo]-[INFO] Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ]]> 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] looking for pending tx for :z9hg4bkbh36ht963rxeb 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] disableTimeoutTimer gov.nist.javax.sip.stack.SIPServerTransactionImpl@ffffffff 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] Creating Server Transactionnull 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPServerTransactionImpl.java:521][SIPTransactionStack.java:1779][SipStackImpl.java:168][SIPTransactionStack.java:1548][UDPMessageChannel.java:561][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Setting Branch id : z9hG4bKBH36Ht963rXeB 2016-05-14 14:14:33,427 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] newSIPServerRequest( BYE:z9hG4bKBH36Ht963rXeB):gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.NistSipMessageFactoryImpl.logDebug]-[DEBUG] Returning request interface for BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 gov.nist.javax.sip.DialogFilter@1d25dad6 sipTransaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] acquireSem [[[[gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:1250][SIPTransactionStack.java:1573][UDPMessageChannel.java:561][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] acquireSem() returning : true 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] About to process BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 /gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] processRequest: BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] tx state = -1 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Setting Branch id : z9hG4bKBH36Ht963rXeB 2016-05-14 14:14:33,428 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Transaction:setState 1 gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 branchID = z9hG4bKBH36Ht963rXeB isClient = false 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:564][SIPServerTransactionImpl.java:1572][SIPServerTransactionImpl.java:723][UDPMessageChannel.java:578][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] setPassToListener() 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] PROCESSING INCOMING REQUEST BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 Route: Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 transactionChannel = gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 listening point = 192.168.4.89:5060 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] transaction state = Trying Transaction 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] getDialog(0e0bee76-943b-1234-4ba8-000c29680286:vn8t8xm8yxg2k:ktgz29usetpnj) : returning null 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] dialogId = 0e0bee76-943b-1234-4ba8-000c29680286:vn8t8xm8yxg2k:ktgz29usetpnj 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] dialog = null 2016-05-14 14:14:33,429 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] BYE Tx = gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 isMapped =false 2016-05-14 14:14:33,430 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] CHECK FOR OUT OF SEQ MESSAGE null transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,430 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.DialogFilter.logDebug]-[DEBUG] BYE transaction.isMapped = false 2016-05-14 14:14:33,430 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] handleEvent gov.nist.javax.sip.RequestEventExt[source=gov.nist.javax.sip.SipProviderImpl@415dd847]currentTransaction = gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565this.sipListener = org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36eesipEvent.source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:33,430 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.SipProviderImpl.logDebug]-[DEBUG] Dialog = null 2016-05-14 14:14:33,430 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.SipProviderImpl.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SipProviderImpl.java:175][DialogFilter.java:1324][SIPServerTransactionImpl.java:811][UDPMessageChannel.java:578][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] sipEvent = gov.nist.javax.sip.RequestEventExt[source=gov.nist.javax.sip.SipProviderImpl@415dd847]source = gov.nist.javax.sip.SipProviderImpl@415dd847 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] deliverEvent : BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 sipEvent.serverTx = null 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] serverTx: looking for key z9hg4bkbh36ht963rxeb existing={} 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] findTransaction: returning : null 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] looking for pending tx for :z9hg4bkbh36ht963rxeb 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] putPendingTransaction: gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] Calling listener BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 2016-05-14 14:14:33,431 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] Calling listener gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[DEBUG] sipApplicationDispatcher org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl@199c36ee, Got a request event BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 Route: Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] serverTx: looking for key z9hg4bkbh36ht963rxeb existing={} 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] findTransaction: returning : null 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] putTransactionHash : key = z9hg4bkbh36ht963rxeb 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] getDialog(0e0bee76-943b-1234-4ba8-000c29680286:vn8t8xm8yxg2k:ktgz29usetpnj) : returning null 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[DEBUG] ServerTx ref gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,432 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[DEBUG] Dialog ref null 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipNetworkInterfaceManagerImpl.findMatchingListeningPoint]-[DEBUG] Checked Listening Point 192.168.4.89/5060:udp against existing listening points, found ExtendedListeningPoint:ipAddress=192.168.4.89, port=5060, transport=udp, globalIpAddress=null, gloablPort=-1, hostNames=null, useLoadBalancer=false, mostOutboundAddress=192.168.4.89 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.isExternal]-[DEBUG] hostNames.contains(192.168.4.89)=falsehostNames.contains(192.168.4.89:5060)=false | listeningPoint found = ExtendedListeningPoint:ipAddress=192.168.4.89, port=5060, transport=udp, globalIpAddress=null, gloablPort=-1, hostNames=null, useLoadBalancer=false, mostOutboundAddress=192.168.4.89 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.isExternal]-[DEBUG] the triplet host/port/transport : 192.168.4.89/5060/udp is external : false 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[DEBUG] the request is for a proxy application, thus it is a subsequent request 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletRequestImpl.setRoutingState]-[DEBUG] setting routing state to SUBSEQUENT 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[DEBUG] Routing State SUBSEQUENT 2016-05-14 14:14:33,433 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.dispatchers.SubsequentRequestDispatcher.dispatchMessage]-[DEBUG] Routing of Subsequent Request BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 transactionId = null transactionType false 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 transactionId = null transactionType false 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.getSipApplicationSession]-[DEBUG] trying to find sip application session with key 7599adf4;MySipServlet 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.session.SipManagerDelegate.dumpSipApplicationSessions]-[DEBUG] sip application sessions present in the session manager 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.dispatchers.SubsequentRequestDispatcher.dispatchMessage]-[DEBUG] routeOrphanRequests = false for context MySipServlet appSession=null 2016-05-14 14:14:33,434 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 transactionId = null transactionType false 2016-05-14 14:14:33,435 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest]-[ERROR] Unexpected exception while processing request BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 org.mobicents.servlet.sip.core.DispatcherException: Cannot find the corresponding sip application session to this subsequent request BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 Max-Forwards: 70 From: "Extension 9988001" ;tag=KtgZ29USetpNj To: ;tag=vN8t8Xm8yXg2K Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 CSeq: 91266548 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.23~64bit Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,MESSAGE,INFO,UPDATE,REGISTER,REFER,NOTIFY Supported: timer,path,replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 with the following popped route header , it may already have been invalidated or timed out at org.mobicents.servlet.sip.core.dispatchers.SubsequentRequestDispatcher.dispatchMessage(SubsequentRequestDispatcher.java:248) at org.mobicents.servlet.sip.core.SipApplicationDispatcherImpl.processRequest(SipApplicationDispatcherImpl.java:861) at gov.nist.javax.sip.EventScanner.deliverRequestEvent(EventScanner.java:250) at gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:146) at gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:185) at gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:1324) at gov.nist.javax.sip.stack.SIPServerTransactionImpl.processRequest(SIPServerTransactionImpl.java:811) at gov.nist.javax.sip.stack.UDPMessageChannel.processMessage(UDPMessageChannel.java:578) at gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:524) at gov.nist.javax.sip.stack.UDPMessageChannel.run(UDPMessageChannel.java:319) at java.lang.Thread.run(Thread.java:722) 2016-05-14 14:14:33,435 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getTransaction]-[DEBUG] transaction gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 transactionId = null transactionType false 2016-05-14 14:14:33,436 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] sipServerTransaction::sendMessage SIP/2.0 481 Call leg/Transaction does not exist 2016-05-14 14:14:33,436 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] enableTimeoutTimer gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 tickCount 64 currentTickCount = -1 2016-05-14 14:14:33,436 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] Transaction:setState 3 gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 branchID = z9hG4bKBH36Ht963rXeB isClient = false 2016-05-14 14:14:33,436 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:564][SIPServerTransactionImpl.java:1572][SIPServerTransactionImpl.java:959][SIPServerTransactionImpl.java:907][SIPServerTransactionImpl.java:1516][MessageDispatcher.java:144][MessageDispatcher.java:124][SipApplicationDispatcherImpl.java:867][EventScanner.java:250][EventScanner.java:146][SipProviderImpl.java:185][DialogFilter.java:1324][SIPServerTransactionImpl.java:811][UDPMessageChannel.java:578][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,437 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] starting TransactionTimerJ() : z9hg4bkbh36ht963rxeb time 64 2016-05-14 14:14:33,437 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] cleanup on timer : z9hg4bkbh36ht963rxeb 2016-05-14 14:14:33,437 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] sendMessage : tx = gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 getState = Completed Transaction 2016-05-14 14:14:33,437 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPServerTransaction.logDebug]-[DEBUG] sipServerTransaction::sendResponse SIP/2.0 481 Call leg/Transaction does not exist 2016-05-14 14:14:33,438 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] Creating message channel 192.168.4.204/11180 2016-05-14 14:14:33,438 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] :sendMessage 192.168.4.204/11180 messageSize = 408 2016-05-14 14:14:33,438 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] sendMessage 192.168.4.204/11180 SIP/2.0 481 Call leg/Transaction does not exist To: ;tag=vN8t8Xm8yXg2K Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 CSeq: 91266548 BYE Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 From: "Extension 9988001" ;tag=KtgZ29USetpNj Server: Mobicents Sip Servlets 3.0.0-SNAPSHOT Content-Length: 0 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logInfo]-[INFO] ;tag=vN8t8Xm8yXg2K Via: SIP/2.0/UDP 192.168.4.204:11180;rport=11180;branch=z9hG4bKBH36Ht963rXeB;received=192.168.4.204 CSeq: 91266548 BYE Call-ID: 0e0bee76-943b-1234-4ba8-000c29680286 From: "Extension 9988001" ;tag=KtgZ29USetpNj Server: Mobicents Sip Servlets 3.0.0-SNAPSHOT Content-Length: 0 ]]> 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] sessionKey is null 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.TransactionApplicationData.getSipSessionKey]-[DEBUG] local session Key is null 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.TransactionApplicationData.getSipSessionKey]-[DEBUG] session Key from sipservletmessage is null 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] session Key is null, retrieved from the txAppData org.mobicents.servlet.sip.message.TransactionApplicationData@51245091 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [org.mobicents.servlet.sip.message.SipServletMessageImpl.getSipSession]-[DEBUG] sessionKey is null 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] Done processing Message BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 2016-05-14 14:14:33,439 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.EventScanner.logDebug]-[DEBUG] Done processing Message BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] semRelease ]]]]gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:1285][SIPTransactionImpl.java:1272][SIPServerTransactionImpl.java:1900][EventScanner.java:281][EventScanner.java:146][SipProviderImpl.java:185][DialogFilter.java:1324][SIPServerTransactionImpl.java:811][UDPMessageChannel.java:578][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransactionStack.logDebug]-[DEBUG] removePendingTx: z9hg4bkbh36ht963rxeb 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logDebug]-[DEBUG] semRelease ]]]]gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.SIPTransaction.logStackTrace]-[DEBUG] [CommonLoggerLog4j.java:59][CommonLogger.java:111][SIPTransactionImpl.java:1285][SIPTransactionImpl.java:1272][SIPServerTransactionImpl.java:1900][UDPMessageChannel.java:583][UDPMessageChannel.java:524][UDPMessageChannel.java:319][Thread.java:722] 2016-05-14 14:14:33,440 [Mobicents-SIP-Servlets-UDPMessageChannelThread-32] [gov.nist.javax.sip.stack.UDPMessageChannel.logDebug]-[DEBUG] Done processing BYE sip:9988002@192.168.4.160:11180;transport=udp SIP/2.0 /gov.nist.javax.sip.stack.SIPServerTransactionImpl@30a2e565