Re: Re: pb with jain-sip-presence-proxy : 500 Server Internal Error

Bonjour,

The method should specify the transport. That is why it is deprecated.
However, that is not the reason fro the internal error. Can you set
the debug level to 32 (or debug) and send me the debug log for the
stack. I am sure there is a problem with an exception generated
somewhere.

Ranga

On 5/31/07, Jean-Luc <[email protected]> wrote:
> M. (Neo-Ranga) Ranganathan <mranga <at> gmail.com> writes:
>
> >
> > Hi Jean-Luc,
> >
> > Please look in the debug log.
> >
> > Ranga
> >
>
> Bonjour Ranga,
>
> Thank you for your answer, i give you at the bottom of this mail an extract of
> the file "debug_log.txt" concerning the problem
> i hope you can help me with this ...
>
> When i track the error (500 server internal error) with Eclipse, i find that
> the code which generates the error is in the following lines (in the
>
> class  "RequestForwarding.java") :
>
>   176    ...
>   177    String stackIPAddress = sipStack.getIPAddress();
>   178
>   179    ListeningPoint lp = sipProvider.getListeningPoint();
>   180    if (statefullForwarding) {
>   181        SipURI sipURI = addressFactory.createSipURI(null,
>   182                stackIPAddress);
>   183    ...
>
>    Comments about this :
> a) line 177 : after the execution of this line, the value of stackIPAddress
> is "null" ?!
> b) line 179 : the method getListeningPoint is deprecated, what's the pb ? Is it
> normal ?
> c) line 181 et 182 : when this is executed, the error is generated (500 server
> internal error)
>    perphaps because stackIPAddress is null ?
>
> very kind of you if you can find the pb ...
>
> PS :to understand (at least a little) the JAIN-SIP-PRESENCE-PROXY code, do you
> know a doc well detailed ?
>
> Sorry for my english, and thank you very much for all ...
>
> Jean-Luc
>
>
> Extract of the debug log :
>
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.run
> (UDPMessageChannel.java:221) [UDPMessageChannel: peerAddress =
> 192.168.18.63/5060]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.run
> (UDPMessageChannel.java:225) [Length = 919]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.run
> (UDPMessageChannel.java:227) [INVITE sip:sirejl@flaviol SIP/2.0
> Via: SIP/2.0/UDP 192.168.18.63:5060;rport;branch=z9hG4bK110597597
> From: <sip:coltranej@flaviol>;tag=2100108759
> To: <sip:sirejl@flaviol>
> Call-ID: [email protected]
> CSeq: 20 INVITE
> Contact: <sip:[email protected]:5060>
> Max-Forwards: 5
> User-Agent: Linphone-1.6.0/eXosip
> Subject: Phone call
> Expires: 120
> Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE
> Content-Type: application/sdp
> Content-Length:   428
>
> v=0
> o=coltranej 123456 654321 IN IP4 192.168.18.63
> s=A conversation
> c=IN IP4 192.168.18.63
> t=0 0
> m=audio 7078 RTP/AVP 111 110 0 3 8 101
> a=rtpmap:111 speex/16000/1
> a=rtpmap:110 speex/8000/1
> a=rtpmap:0 PCMU/8000/1
> a=rtpmap:3 GSM/8000/1
> a=rtpmap:8 PCMA/8000/1
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-11
> m=video 9078 RTP/AVP 98 99
> a=rtpmap:98 H263-1998/90000
> a=fmtp:98 CIF=1;QCIF=1
> a=rtpmap:99 MP4V-ES/90000
> ]
> DEBUG - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:312) [ ]]>]
> DEBUG - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:313)
> [</debug>]
> DEBUG - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:314)
> [<message
> from="192.168.18.63:5060"
> to="192.168.18.64:5060"
> time="1180600923242"
> isSender="false"
> transactionId="z9hg4bk110597597"
> callId="[email protected]"
> firstLine="INVITE sip:sirejl@flaviol SIP/2.0"
> debugLine="315"
> >
> <![CDATA[INVITE sip:sirejl@flaviol SIP/2.0
> Via: SIP/2.0/UDP 192.168.18.63:5060;rport;branch=z9hG4bK110597597
> From: <sip:coltranej@flaviol>;tag=2100108759
> To: <sip:sirejl@flaviol>
> Call-ID: [email protected]
> CSeq: 20 INVITE
> Contact: <sip:[email protected]:5060>
> Max-Forwards: 5
> User-Agent: Linphone-1.6.0/eXosip
> Subject: Phone call
> Expires: 120
> Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,SUBSCRIBE,NOTIFY,MESSAGE
> Content-Type: application/sdp
> Content-Length: 428
>
> ]]>
> </message>
> ]
> DEBUG - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:315)
> [<debug>]
> DEBUG - gov.nist.javax.sip.stack.ServerLog.logMessage(ServerLog.java:316) [<!
> [CDATA[ ]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.findPendingTransaction
> (SIPTransactionStack.java:723) [looking for pending tx for
>
> :z9hg4bk110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction.<init>
> (SIPServerTransaction.java:419) [Creating Server Transactionnull]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[SIPServerTransaction.java:421][SIPTransactionStack.java:1007]
>
> [SIPTransactionStack.java:829][UDPMessageChannel.java:370][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.setOriginalRequest
> (SIPTransaction.java:384) [Setting Branch id : z9hG4bK110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.newSIPServerRequest
> (SIPTransactionStack.java:844) [newSIPServerRequest(
>
> INVITE:z9hG4bK110597597):gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.NistSipMessageFactoryImpl.newSIPServerRequest
> (NistSipMessageFactoryImpl.java:62) [Returning request interface for INVITE
>
> sip:sirejl@flaviol SIP/2.0
>  gov.nist.javax.sip.DialogFilter@41d05d messageChannel =
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.acquireSem
> (SIPTransaction.java:1078) [acquireSem
>
> [[[[gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[SIPTransaction.java:1079][SIPTransactionStack.java:854]
>
> [UDPMessageChannel.java:370][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.run
> (UDPMessageChannel.java:393) [About to process INVITE sip:sirejl@flaviol SIP/2.0
> /gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction.processRequest
> (SIPServerTransaction.java:623) [processRequest: INVITE sip:sirejl@flaviol
>
> SIP/2.0
> ]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction.processRequest
> (SIPServerTransaction.java:625) [tx state = null]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.setOriginalRequest
> (SIPTransaction.java:384) [Setting Branch id : z9hG4bK110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.setState
> (SIPTransaction.java:504) [Transaction:setState Trying Transaction
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91 branchID =
> z9hG4bK110597597 isClient = false]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[SIPTransaction.java:507][SIPServerTransaction.java:1311]
>
> [SIPServerTransaction.java:635][UDPMessageChannel.java:397][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.setPassToListener
> (SIPTransaction.java:1125) [setPassToListener()]
> DEBUG - gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:69)
> [PROCESSING INCOMING REQUEST INVITE sip:sirejl@flaviol SIP/2.0
> Via: SIP/2.0/UDP 192.168.18.63:5060;rport;branch=z9hG4bK110597597
> From: <sip:coltranej@flaviol>;tag=2100108759
> To: <sip:sirejl@flaviol>
> Call-ID: [email protected]
> CSeq: 20 INVITE
> Contact: <sip:[email protected]:5060>
> Max-Forwards: 5
> User-Agent: Linphone-1.6.0/eXosip
> Subject: Phone call
> Expires: 120
> Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,SUBSCRIBE,NOTIFY,MESSAGE
> Content-Type: application/sdp
> Content-Length: 428
>
> v=0
> o=coltranej 123456 654321 IN IP4 192.168.18.63
> s=A conversation
> c=IN IP4 192.168.18.63
> t=0 0
> m=audio 7078 RTP/AVP 111 110 0 3 8 101
> a=rtpmap:111 speex/16000/1
> a=rtpmap:110 speex/8000/1
> a=rtpmap:0 PCMU/8000/1
> a=rtpmap:3 GSM/8000/1
> a=rtpmap:8 PCMA/8000/1
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-11
> m=video 9078 RTP/AVP 98 99
> a=rtpmap:98 H263-1998/90000
> a=fmtp:98 CIF=1;QCIF=1
> a=rtpmap:99 MP4V-ES/90000
>  transactionChannel = gov.nist.javax.sip.stack.SIPServerTransaction@70900d91
> listening point = 192.168.18.64:5060]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.getDialog
> (SIPTransactionStack.java:491) [getDialog
>
> ([email protected]:sirejl@flaviol:coltranej@flaviol:2100108759) :
> returning null]
> DEBUG - gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:138)
> [dialogId =
>
> [email protected]:sirejl@flaviol:coltranej@flaviol:2100108759]
> DEBUG - gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:139)
> [dialog = null]
> DEBUG - gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:621)
> [INVITE transaction.isMapped = false]
> DEBUG - gov.nist.javax.sip.SipProviderImpl.handleEvent
> (SipProviderImpl.java:102) [handleEvent javax.sip.RequestEvent
>
> [source=gov.nist.javax.sip.SipProviderImpl@110fe28]currentTransaction =
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91this.sipListener =
>
> [email protected] =
> gov.nist.javax.sip.SipProviderImpl@110fe28]
> DEBUG - gov.nist.javax.sip.SipProviderImpl.handleEvent
> (SipProviderImpl.java:109) [Dialog = null]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[SipProviderImpl.java:114][DialogFilter.java:834]
> [SIPServerTransaction.java:731]
>
> [UDPMessageChannel.java:397][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.EventScanner.addEvent(EventScanner.java:54)
> [addEvent gov.nist.javax.sip.EventWrapper@d6a05e]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.run
> (UDPMessageChannel.java:407) [Done processing INVITE sip:sirejl@flaviol SIP/2.0
> /gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.EventScanner.run(EventScanner.java:400) [Processing
> gov.nist.javax.sip.EventWrapper@d6a05enevents 1]
> DEBUG - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:87)
> [sipEvent = javax.sip.RequestEvent
>
> [source=gov.nist.javax.sip.SipProviderImpl@110fe28]source =
> gov.nist.javax.sip.SipProviderImpl@110fe28]
> DEBUG - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:105)
> [deliverEvent : INVITE sip:sirejl@flaviol SIP/2.0
>  transaction gov.nist.javax.sip.stack.SIPServerTransaction@70900d91
> sipEvent.serverTx = null]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction
> (SIPTransactionStack.java:613) [serverTx: looking for key z9hg4bk110597597
>
> existing=
> {z9hg4bk1941015899=gov.nist.javax.sip.stack.SIPServerTransaction@ffd0b528,
>
> z9hg4bk765215775=gov.nist.javax.sip.stack.SIPServerTransaction@f47f88d6}]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.findPendingTransaction
> (SIPTransactionStack.java:723) [looking for pending tx for
>
> :z9hg4bk110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.putPendingTransaction
> (SIPTransactionStack.java:1222) [putPendingTransaction:
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:154)
> [Calling listener INVITE sip:sirejl@flaviol SIP/2.0
> ]
> DEBUG - gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:158)
> [Calling listener gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG -
> ****************************************************
> Request INVITE received:
> INVITE sip:sirejl@flaviol SIP/2.0
> Via: SIP/2.0/UDP 192.168.18.63:5060;rport;branch=z9hG4bK110597597
> From: <sip:coltranej@flaviol>;tag=2100108759
> To: <sip:sirejl@flaviol>
> Call-ID: [email protected]
> CSeq: 20 INVITE
> Contact: <sip:[email protected]:5060>
> Max-Forwards: 5
> User-Agent: Linphone-1.6.0/eXosip
> Subject: Phone call
> Expires: 120
> Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,REFER,SUBSCRIBE,NOTIFY,MESSAGE
> Content-Type: application/sdp
> Content-Length: 428
>
> v=0
> o=coltranej 123456 654321 IN IP4 192.168.18.63
> s=A conversation
> c=IN IP4 192.168.18.63
> t=0 0
> m=audio 7078 RTP/AVP 111 110 0 3 8 101
> a=rtpmap:111 speex/16000/1
> a=rtpmap:110 speex/8000/1
> a=rtpmap:0 PCMU/8000/1
> a=rtpmap:3 GSM/8000/1
> a=rtpmap:8 PCMA/8000/1
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-11
> m=video 9078 RTP/AVP 98 99
> a=rtpmap:98 H263-1998/90000
> a=fmtp:98 CIF=1;QCIF=1
> a=rtpmap:99 MP4V-ES/90000
>
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.isDialogCreated
> (SIPTransactionStack.java:363) [isDialogCreated : INVITE returning true]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.findTransaction
> (SIPTransactionStack.java:613) [serverTx: looking for key z9hg4bk110597597
>
> existing=
> {z9hg4bk1941015899=gov.nist.javax.sip.stack.SIPServerTransaction@ffd0b528,
>
> z9hg4bk765215775=gov.nist.javax.sip.stack.SIPServerTransaction@f47f88d6}]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.addTransaction
> (SIPTransactionStack.java:1093) [added transaction
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction$SendTrying.<init>
> (SIPServerTransaction.java:239) [scheduled timer for
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.removePendingTransaction
> (SIPTransactionStack.java:740) [removePendingTx: z9hg4bk110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPTransactionStack.addTransactionHash
> (SIPTransactionStack.java:1115) [ putTransactionHash :  key =
>
> z9hg4bk110597597]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction$TransactionTimer.<init>
> (SIPServerTransaction.java:272) [TransactionTimer() : z9hg4bk110597597]
> DEBUG - Proxy, processRequest(), we are responsible for the domain... Let's
> find the contact...
> DEBUG - Proxy, processRequest(), we canonicalized the request-URI
> DEBUG - Proxy, processRequest(), the target set is the set of the contacts URI
> from the  location service
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.setState
> (SIPTransaction.java:504) [Transaction:setState Completed Transaction
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91 branchID =
> z9hG4bK110597597 isClient = false]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[SIPTransaction.java:507][SIPServerTransaction.java:1311]
>
> [SIPServerTransaction.java:842][SIPServerTransaction.java:1264][null:-1][null:-
> 1][EventScanner.java:162][EventScanner.java:404][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.stack.SIPTransaction.enableTimeoutTimer
> (SIPTransaction.java:558) [enableTimeoutTimer
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91 tickCount 64
> currentTickCount = -1]
> DEBUG - gov.nist.javax.sip.stack.SIPServerTransaction.sendMessage
> (SIPServerTransaction.java:940) [sendMessage : tx =
>
> gov.nist.javax.sip.stack.SIPServerTransaction@70900d91 getState = Completed
> Transaction]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.<init>
> (UDPMessageChannel.java:169) [Creating message channel 192.168.18.63/5060]
> DEBUG - gov.nist.core.LogWriter.logStackTrace(LogWriter.java:101)
> [[UDPMessageChannel.java:509][SIPServerTransaction.java:396]
>
> [SIPServerTransaction.java:945][SIPServerTransaction.java:1264][null:-1][null:-
> 1][EventScanner.java:162][EventScanner.java:404][Thread.java:595]]
> DEBUG - gov.nist.javax.sip.stack.UDPMessageChannel.sendMessage
> (UDPMessageChannel.java:609)
> [gov.nist.javax.sip.stack.UDPMessageChannel:sendMessage
>
> 192.168.18.63/5060
> SIP/2.0 500 Server Internal Error
> Via: SIP/2.0/UDP 192.168.18.63:5060;rport;branch=z9hG4bK110597597
> From: <sip:coltranej@flaviol>;tag=2100108759
> To: <sip:sirejl@flaviol>
> Call-ID: [email protected]
> CSeq: 20 INVITE
> Content-Length: 0
>
>
> _______________________________________________
> nist-sip mailing list
> [email protected]
> http://www-x.antd.nist.gov/mailman/listinfo/nist-sip
>


-- 
M. Ranganathan
"There are two ways to write error-free programs; only the third one
works." --  Alan Perlis
http://www.animenewsnetwork.com/encyclopedia/anime.php?id=1052