Not receiving error responses
<[email protected]> Fri, 6 Jul 2007 18:32:03 +0100
| Newsgroups | gmane.comp.voip.nist-sip |
|---|---|
| Message-ID | <7C621A303006D04692CD2042DDE5E9CB032925AE@E03MVX4-UKDY.domain1.systemhost.net> |
> Hi,
> I am running into some problems when I simulate a "500 Internal Server
> Error" response to an INVITE coming back into the Jain-Sip stack.
> Mostly it works fine, but on rare occasions, my registered SipListener
> never receives the event containing the SIP responses. Attached are
> two log files which show the difference between a "bad run" and a good
> one. In both cases, the stack auto-generates an ACK message. In the
> second case, it propagates the SIP events back up to the SipListener
> whereas in the first, it never does. All the SipListener receives is
> the TransactionTerminatedEvent.
>
> Any ideas as to why this is happening is much appreciated.
>
> Thanks.
>
> The properties used to start up the stack are:
>
> props.setProperty("gov.nist.javax.sip.LOG_MESSAGE_CONTENT", "true");
> props.setProperty("javax.sip.STACK_NAME",
> this.stackName);
> props.setProperty("javax.sip.RETRANSMISSION_FILTER",
> "on");
> props.setProperty("javax.sip.AUTOMATIC_DIALOG_SUPPORT",
> "off");
>
> The code to create the INVITE is:
> public Request createRequest(String requestUri, String method,
> String callId, long sequenceNumber, Address localParty, String
> localTag, Address remoteParty, String remoteTag, String localSdp) {
> try {
> SipUri requestURI = (SipUri)
> getAddressFactory().createAddress(requestUri).getURI();
>
> // Call-ID:
> CallIdHeader callIdHeader;
> callIdHeader =
> getHeaderFactory().createCallIdHeader(callId);
>
> CSeqHeader cseqHeader =
> getHeaderFactory().createCSeqHeader(sequenceNumber, method);
>
> // From Header:
> FromHeader fromHeader =
> getHeaderFactory().createFromHeader(localParty, localTag);
>
> // To header:
> ToHeader toHeader =
> getHeaderFactory().createToHeader(remoteParty, remoteTag);
>
> // Via header
> ListeningPointImpl lp = (ListeningPointImpl)
> getSipProvider().getListeningPoint(getTransport());
> ViaHeader viaHeader = lp.getViaHeader();
> List<ViaHeader> viaList = new Vector<ViaHeader>();
> viaList.add(viaHeader);
>
> // MaxForwards header:
> MaxForwardsHeader maxForwardsHeader =
> getHeaderFactory().createMaxForwardsHeader(DEFAULT_MAX_FORWARDS_VALUE)
> ;
>
> ContentTypeHeader contentTypeHeader =
> getHeaderFactory().createContentTypeHeader(CONTENT_TYPE_APPLICATION,
> CONTENT_SUBTYPE_SDP);
> Request request =
> getMessageFactory().createRequest(requestURI, method, callIdHeader,
> cseqHeader, fromHeader, toHeader, viaList, maxForwardsHeader,
> contentTypeHeader, localSdp);
>
> // Contact header
> if (Request.INVITE.equals(request.getMethod())) {
> Address contact = getContactAddress(null);
>
> request.addHeader(getHeaderFactory().createContactHeader(contact));
> }
>
> return request;
> } catch (Throwable t) {
> throw new StackException("Failed to create request", t);
> }
> }
>
>
>
> <<jain-sip-good.log>> <<jain-sip-bad.log>>
_______________________________________________
nist-sip mailing list
[email protected]
http://www-x.antd.nist.gov/mailman/listinfo/nist-sip
jain-sip-good.log
(application/octet-stream, 24.3 KB)
2007-07-06 17:03:36,083 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendRequest(SIPClientTransaction.java:856) [sendRequest() INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:414) [Sending Message INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:416) [TransactionState null] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setOriginalRequest(SIPTransaction.java:415) [Setting Branch id : z9hG4bK521bbaf985bb26549e393f3a3b509755] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setState(SIPTransaction.java:548) [Transaction:setState Calling Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a branchID = z9hG4bK521bbaf985bb26549e393f3a3b509755 isClient = true] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:551][SIPClientTransaction.java:1237][SIPClientTransaction.java:454][SIPClientTransaction.java:915][SimpleSipStack.java:501][OutboundDialogBeanImpl.java:101][ConcurrentUpdateManagerImpl.java:26][OutboundDialogBeanImpl.java:107][CallBeanImpl.java:234][null:-2][null:-1][null:-1][null:-1][EventDispatcher.java:103][null:-1][null:-1][null:-1]] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.enableTimeoutTimer(SIPTransaction.java:603) [enableTimeoutTimer gov.nist.javax.sip.stack.SIPClientTransaction@b444215a tickCount 64 currentTickCount = -1] 2007-07-06 17:03:36,133 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[UDPMessageChannel.java:603][MessageChannel.java:183][SIPTransaction.java:734][SIPClientTransaction.java:473][SIPClientTransaction.java:915][SimpleSipStack.java:501][OutboundDialogBeanImpl.java:101][ConcurrentUpdateManagerImpl.java:26][OutboundDialogBeanImpl.java:107][CallBeanImpl.java:234][null:-2][null:-1][null:-1][null:-1][EventDispatcher.java:103][null:-1][null:-1][null:-1]] 2007-07-06 17:03:36,153 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:612) [gov.nist.javax.sip.stack.UDPMessageChannel:sendMessage 10.73.177.95/5061 INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:36,153 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:615) [******************* ] 2007-07-06 17:03:36,153 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:36,153 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:36,153 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:6073" to="10.73.177.95:5061" time="1183737816133" isSender="true" transactionId="z9hg4bk521bbaf985bb26549e393f3a3b509755" callId="a524ffe2034496be56de4f1fa44368ac" firstLine="INVITE sip:[email protected]:5061 SIP/2.0" > <![CDATA[INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ]]> </message> ] 2007-07-06 17:03:36,223 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:284) [UDPMessageChannel: peerAddress = 10.73.177.95/5061] 2007-07-06 17:03:36,253 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:288) [Length = 323] 2007-07-06 17:03:36,253 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:290) [SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,253 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction(SIPTransactionStack.java:748) [clientTx: looking for key z9hg4bk521bbaf985bb26549e393f3a3b509755] 2007-07-06 17:03:36,273 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.NistSipMessageFactoryImpl.newSIPServerResponse(NistSipMessageFactoryImpl.java:108) [Found Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a for SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,313 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:5061" to="10.73.177.95:6073" time="1183737816313" isSender="false" transactionId="z9hg4bk521bbaf985bb26549e393f3a3b509755" callId="a524ffe2034496be56de4f1fa44368ac" firstLine="SIP/2.0 100 Trying" > <![CDATA[SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ]]> </message> ] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:339) [<debug>] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:340) [<![CDATA[ ] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.acquireSem(SIPTransaction.java:1129) [acquireSem [[[[gov.nist.javax.sip.stack.SIPClientTransaction@b444215a] 2007-07-06 17:03:36,323 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:1130][SIPTransactionStack.java:1108][UDPMessageChannel.java:484][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,333 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:510) [processing SIP/2.0 100 Trying current state = Calling Transaction] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:513) [dialog = null] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setState(SIPTransaction.java:548) [Transaction:setState Proceeding Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a branchID = z9hG4bK521bbaf985bb26549e393f3a3b509755 isClient = true] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:551][SIPClientTransaction.java:1237][SIPClientTransaction.java:749][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:953) [PROCESSING INCOMING RESPONSESIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:985) [Transaction = gov.nist.javax.sip.stack.SIPClientTransaction@b444215a] 2007-07-06 17:03:36,353 [Thread-9] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:133) [handleEvent javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@108f8e0]currentTransaction = gov.nist.javax.sip.stack.SIPClientTransaction@b444215athis.sipListener = com.bt.sdk.callcontrol.sip.dialog.DialogSipListener@1de45e2sipEvent.source = gov.nist.javax.sip.SipProviderImpl@108f8e0] 2007-07-06 17:03:36,474 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:288) [Length = 338] 2007-07-06 17:03:36,474 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:290) [SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,474 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction(SIPTransactionStack.java:748) [clientTx: looking for key z9hg4bk521bbaf985bb26549e393f3a3b509755] 2007-07-06 17:03:36,474 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.NistSipMessageFactoryImpl.newSIPServerResponse(NistSipMessageFactoryImpl.java:108) [Found Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a for SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:5061" to="10.73.177.95:6073" time="1183737816484" isSender="false" transactionId="z9hg4bk521bbaf985bb26549e393f3a3b509755" callId="a524ffe2034496be56de4f1fa44368ac" firstLine="SIP/2.0 500 Server Internal Error" > <![CDATA[SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ]]> </message> ] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:339) [<debug>] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:340) [<![CDATA[ ] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.acquireSem(SIPTransaction.java:1129) [acquireSem [[[[gov.nist.javax.sip.stack.SIPClientTransaction@b444215a] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:1130][SIPTransactionStack.java:1108][UDPMessageChannel.java:484][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:510) [processing SIP/2.0 500 Server Internal Error current state = Proceeding Transaction] 2007-07-06 17:03:36,484 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:513) [dialog = null] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:414) [Sending Message ACK sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 CSeq: 1 ACK Content-Length: 0 ] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:416) [TransactionState Proceeding Transaction] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setState(SIPTransaction.java:548) [Transaction:setState Completed Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a branchID = z9hG4bK521bbaf985bb26549e393f3a3b509755 isClient = true] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:551][SIPClientTransaction.java:1237][SIPClientTransaction.java:431][SIPClientTransaction.java:805][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[UDPMessageChannel.java:603][MessageChannel.java:183][SIPTransaction.java:734][SIPClientTransaction.java:436][SIPClientTransaction.java:805][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:612) [gov.nist.javax.sip.stack.UDPMessageChannel:sendMessage 10.73.177.95/5061 ACK sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 CSeq: 1 ACK Content-Length: 0 ] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:615) [******************* ] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:36,494 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:6073" to="10.73.177.95:5061" time="1183737816494" isSender="true" transactionId="z9hg4bk521bbaf985bb26549e393f3a3b509755" callId="a524ffe2034496be56de4f1fa44368ac" firstLine="ACK sip:[email protected]:5061 SIP/2.0" > <![CDATA[ACK sip:[email protected]:5061 SIP/2.0 Call-ID: a524ffe2034496be56de4f1fa44368ac From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 CSeq: 1 ACK Content-Length: 0 ]]> </message> ] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:339) [<debug>] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:340) [<![CDATA[ ] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setState(SIPTransaction.java:548) [Transaction:setState Completed Transaction gov.nist.javax.sip.stack.SIPClientTransaction@b444215a branchID = z9hG4bK521bbaf985bb26549e393f3a3b509755 isClient = true] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:551][SIPClientTransaction.java:1237][SIPClientTransaction.java:812][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:953) [PROCESSING INCOMING RESPONSESIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:985) [Transaction = gov.nist.javax.sip.stack.SIPClientTransaction@b444215a] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:133) [handleEvent javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@108f8e0]currentTransaction = gov.nist.javax.sip.stack.SIPClientTransaction@b444215athis.sipListener = com.bt.sdk.callcontrol.sip.dialog.DialogSipListener@1de45e2sipEvent.source = gov.nist.javax.sip.SipProviderImpl@108f8e0] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:143) [Dialog = null] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SipProviderImpl.java:145][DialogFilter.java:1062][SIPClientTransaction.java:819][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.addEvent(EventScanner.java:81) [addEvent gov.nist.javax.sip.EventWrapper@12cd19d] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.enableTimeoutTimer(SIPTransaction.java:603) [enableTimeoutTimer gov.nist.javax.sip.stack.SIPClientTransaction@b444215a tickCount 64 currentTickCount = -1] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.releaseSem(SIPTransaction.java:1149) [releaseSem [[[[gov.nist.javax.sip.stack.SIPClientTransaction@b444215a] 2007-07-06 17:03:36,504 [Thread-13] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:1150][UDPMessageChannel.java:499][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:36,754 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.run(EventScanner.java:487) [Processing gov.nist.javax.sip.EventWrapper@1e55794nevents 4] 2007-07-06 17:03:36,754 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:129) [sipEvent = javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@108f8e0]source = gov.nist.javax.sip.SipProviderImpl@108f8e0] 2007-07-06 17:03:36,754 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:281) [Calling listener for SIP/2.0 100 Trying ] 2007-07-06 17:03:36,764 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setPassToListener(SIPTransaction.java:1176) [setPassToListener()] 2007-07-06 17:03:36,764 [EventScannerThread] DEBUG [DialogSipListener.processResponse() 154] - Received response SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061> Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 2007-07-06 17:03:36,774 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.run(EventScanner.java:487) [Processing gov.nist.javax.sip.EventWrapper@12cd19dnevents 4] 2007-07-06 17:03:36,774 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:129) [sipEvent = javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@108f8e0]source = gov.nist.javax.sip.SipProviderImpl@108f8e0] 2007-07-06 17:03:36,774 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:281) [Calling listener for SIP/2.0 500 Server Internal Error ] 2007-07-06 17:03:36,774 [EventScannerThread] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setPassToListener(SIPTransaction.java:1176) [setPassToListener()] 2007-07-06 17:03:36,774 [EventScannerThread] DEBUG [DialogSipListener.processResponse() 154] - Received response SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK521bbaf985bb26549e393f3a3b509755 From: <sip:[email protected]>;tag=1856285157 To: <sip:[email protected]:5061>;tag=2 Call-ID: a524ffe2034496be56de4f1fa44368ac CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0
jain-sip-bad.log
(application/octet-stream, 13.9 KB)
2007-07-06 17:03:40,570 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendRequest(SIPClientTransaction.java:856) [sendRequest() INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:40,570 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:414) [Sending Message INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:416) [TransactionState null] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setOriginalRequest(SIPTransaction.java:415) [Setting Branch id : z9hG4bK66ba8fa8b8b565529309027513eb4423] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.setState(SIPTransaction.java:548) [Transaction:setState Calling Transaction gov.nist.javax.sip.stack.SIPClientTransaction@6230f56b branchID = z9hG4bK66ba8fa8b8b565529309027513eb4423 isClient = true] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:551][SIPClientTransaction.java:1237][SIPClientTransaction.java:454][SIPClientTransaction.java:915][SimpleSipStack.java:501][OutboundDialogBeanImpl.java:101][ConcurrentUpdateManagerImpl.java:26][OutboundDialogBeanImpl.java:107][CallBeanImpl.java:234][null:-2][null:-1][null:-1][null:-1][EventDispatcher.java:103][null:-1][null:-1][null:-1]] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.enableTimeoutTimer(SIPTransaction.java:603) [enableTimeoutTimer gov.nist.javax.sip.stack.SIPClientTransaction@6230f56b tickCount 64 currentTickCount = -1] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[UDPMessageChannel.java:603][MessageChannel.java:183][SIPTransaction.java:734][SIPClientTransaction.java:473][SIPClientTransaction.java:915][SimpleSipStack.java:501][OutboundDialogBeanImpl.java:101][ConcurrentUpdateManagerImpl.java:26][OutboundDialogBeanImpl.java:107][CallBeanImpl.java:234][null:-2][null:-1][null:-1][null:-1][EventDispatcher.java:103][null:-1][null:-1][null:-1]] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:612) [gov.nist.javax.sip.stack.UDPMessageChannel:sendMessage 10.73.177.95/5061 INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ] 2007-07-06 17:03:40,580 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage(UDPMessageChannel.java:615) [******************* ] 2007-07-06 17:03:40,590 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:40,590 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:40,590 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:6073" to="10.73.177.95:5061" time="1183737820580" isSender="true" transactionId="z9hg4bk66ba8fa8b8b565529309027513eb4423" callId="72b5b4227b31d5b8650683eac88e0e00" firstLine="INVITE sip:[email protected]:5061 SIP/2.0" > <![CDATA[INVITE sip:[email protected]:5061 SIP/2.0 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061> Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 Max-Forwards: 70 Content-Type: application/sdp Contact: <sip:10.73.177.95:6073> Content-Length: 155 v=0 o=- 1180106343810 0 IN IP4 192.168.2.4 s=BT SDK Call Control c=IN IP4 192.168.2.4 t=0 0 a=inactive m=audio 6100 RTP/AVP 0 a=rtpmap:0 PCMU/8000 ]]> </message> ] 2007-07-06 17:03:40,590 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:339) [<debug>] 2007-07-06 17:03:40,590 [pool-2-thread-5] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:340) [<![CDATA[ ] 2007-07-06 17:03:40,690 [Thread-31] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:284) [UDPMessageChannel: peerAddress = 10.73.177.95/5061] 2007-07-06 17:03:40,690 [Thread-31] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:288) [Length = 322] 2007-07-06 17:03:40,690 [Thread-31] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:290) [SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061>;tag=6 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:40,690 [Thread-31] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction(SIPTransactionStack.java:748) [clientTx: looking for key z9hg4bk66ba8fa8b8b565529309027513eb4423] 2007-07-06 17:03:40,700 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:284) [UDPMessageChannel: peerAddress = 10.73.177.95/5060] 2007-07-06 17:03:40,700 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:288) [Length = 314] 2007-07-06 17:03:40,700 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:290) [SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK109fd2e1249695f85607ccc93769acd1 From: <sip:[email protected]>;tag=39141911 To: <sip:10.73.177.95:5060>;tag=5 Call-ID: 11183b97c6ad195229d4621bf5c515ec CSeq: 1 INVITE Contact: <sip:192.168.2.4:5060;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:40,740 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:985) [Transaction = gov.nist.javax.sip.stack.SIPClientTransaction@bc40e906] 2007-07-06 17:03:40,740 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:133) [handleEvent javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@108f8e0]currentTransaction = gov.nist.javax.sip.stack.SIPClientTransaction@bc40e906this.sipListener = com.bt.sdk.callcontrol.sip.dialog.DialogSipListener@1de45e2sipEvent.source = gov.nist.javax.sip.SipProviderImpl@108f8e0] 2007-07-06 17:03:40,740 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:143) [Dialog = null] 2007-07-06 17:03:40,750 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SipProviderImpl.java:145][DialogFilter.java:1062][SIPClientTransaction.java:752][SIPClientTransaction.java:527][SIPClientTransaction.java:1369][UDPMessageChannel.java:495][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:40,750 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.EventScanner.addEvent(EventScanner.java:81) [addEvent gov.nist.javax.sip.EventWrapper@147e668] 2007-07-06 17:03:40,750 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.releaseSem(SIPTransaction.java:1149) [releaseSem [[[[gov.nist.javax.sip.stack.SIPClientTransaction@bc40e906] 2007-07-06 17:03:40,750 [Thread-32] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:1150][UDPMessageChannel.java:499][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:40,750 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:284) [UDPMessageChannel: peerAddress = 10.73.177.95/5061] 2007-07-06 17:03:40,750 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:288) [Length = 337] 2007-07-06 17:03:40,750 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:290) [SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061>;tag=6 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:40,750 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction(SIPTransactionStack.java:748) [clientTx: looking for key z9hg4bk66ba8fa8b8b565529309027513eb4423] 2007-07-06 17:03:40,750 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.NistSipMessageFactoryImpl.newSIPServerResponse(NistSipMessageFactoryImpl.java:108) [Found Transaction gov.nist.javax.sip.stack.SIPClientTransaction@6230f56b for SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061>;tag=6 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:336) [ ]]>] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:337) [</debug>] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:338) [<message from="10.73.177.95:5061" to="10.73.177.95:6073" time="1183737820770" isSender="false" transactionId="z9hg4bk66ba8fa8b8b565529309027513eb4423" callId="72b5b4227b31d5b8650683eac88e0e00" firstLine="SIP/2.0 500 Server Internal Error" > <![CDATA[SIP/2.0 500 Server Internal Error Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061>;tag=6 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 CSeq: 1 INVITE Contact: <sip:192.168.2.4:5061;transport=UDP> Content-Length: 0 ]]> </message> ] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:339) [<debug>] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:340) [<![CDATA[ ] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPTransaction.acquireSem(SIPTransaction.java:1129) [acquireSem [[[[gov.nist.javax.sip.stack.SIPClientTransaction@6230f56b] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:121) [[SIPTransaction.java:1130][SIPTransactionStack.java:1108][UDPMessageChannel.java:484][UDPMessageChannel.java:255][null:-1]] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:510) [processing SIP/2.0 500 Server Internal Error current state = Calling Transaction] 2007-07-06 17:03:40,770 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:513) [dialog = null] 2007-07-06 17:03:40,780 [Thread-36] DEBUG [LogWriter.logDebug() 206] - gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:414) [Sending Message ACK sip:[email protected]:5061 SIP/2.0 Call-ID: 72b5b4227b31d5b8650683eac88e0e00 From: <sip:[email protected]>;tag=650459360 To: <sip:[email protected]:5061>;tag=6 Via: SIP/2.0/UDP 10.73.177.95:6073;branch=z9hG4bK66ba8fa8b8b565529309027513eb4423 CSeq: 1 ACK Content-Length: 0 ]