Re: [sipx-users] hairpin transfer from/to pstn causes error :SipUtilities.java:837

Wed, 22 Dec 2010 15:16:18 -0800

It seems that the bridge is unable to determine the itsp-account to use for the outbound INVITE. Are you sure you are using the correct prefix when transferring the call? seeing the entire call from initial INVITE up to the failure point would be better. 

On Wednesday, 22 December, 2010 07:28 PM, Peter van der Salm wrote:

Thanks, Nikolay, Tony and Joegen!


I have checked the srv udp and a records for the trunk.pstnconnect.nl 
<http://trunk.pstnconnect.nl>. Both point at sip.mvoip.nl 
<http://sip.mvoip.nl>, which logically resolves to the same IP address.

So probably not the same cause as your issue Nikolay?

Tony see here:
[r...@sipx ~]# /usr/bin/sipxconfig-config --version
4.2.1-018971.dhubler

Joegen and Tony, this is the sipxbridge log from the event:

"2010-12-21T12:53:27.920000Z":475900:JAVA:INFO:sipx.creativevalley.nl:Thread-25697:00000000:sipXbridge:"[SIPTransaction.java:1197][SIPTransaction.java:1183][EventScanner.java:365][SipProviderImpl.java:192][DialogFilter.java:1278][SIPClientTransaction.java:831][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:619]"
"2010-12-21T12:53:27.920000Z":475901:JAVA:INFO:sipx.creativevalley.nl:Thread-25697:00000000:sipXbridge:"[SIPTransaction.java:1197][SIPTransaction.java:1183][UDPMessageChannel.java:546][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:619]"
"2010-12-21T12:53:27.920000Z":475902:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPDialog.java:1598][SIPDialog.java:596][SIPTransactionStack.java:714][SipProviderImpl.java:516][CallControlManager.java:471][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"
"2010-12-21T12:53:27.921000Z":475903:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPDialog.java:601][SIPTransactionStack.java:714][SipProviderImpl.java:516][CallControlManager.java:471][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"
"2010-12-21T12:53:27.921000Z":475904:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPDialog.java:830][SIPDialog.java:1394][SipProviderImpl.java:523][CallControlManager.java:471][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"

"2010-12-21T12:53:27.921000Z":475905:JAVA:ERR:sipx.creativevalley.nl:PipelineThread-23:00000000:SipUtilities:"Unexpected 
error creating INVITE "

java.lang.NullPointerException

at 
org.sipfoundry.sipxbridge.SipUtilities.createInviteRequest(SipUtilities.java:687)
at 
org.sipfoundry.sipxbridge.BackToBackUserAgent.sendInviteToItsp(BackToBackUserAgent.java:1671)
at 
org.sipfoundry.sipxbridge.CallControlManager.processInvite(CallControlManager.java:621)
at 
org.sipfoundry.sipxbridge.CallControlManager.processRequest(CallControlManager.java:3054)
at 
org.sipfoundry.sipxbridge.SipListenerImpl.processRequest(SipListenerImpl.java:451)

at gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:224)

at 
gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:192)

at gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:1138)

at 
gov.nist.javax.sip.stack.SIPServerTransaction.processRequest(SIPServerTransaction.java:823)
at 
gov.nist.javax.sip.stack.TCPMessageChannel.processMessage(TCPMessageChannel.java:515)
at 
gov.nist.javax.sip.parser.PipelinedMsgParser.run(PipelinedMsgParser.java:361)

at java.lang.Thread.run(Thread.java:619)

"2010-12-21T12:53:27.921000Z":475906:JAVA:ERR:sipx.creativevalley.nl:PipelineThread-23:00000000:BackToBackUserAgent:"Error 
occurred during processing of request "
org.sipfoundry.sipxbridge.SipXbridgeException: Unexpected error 
creating INVITE
at 
org.sipfoundry.sipxbridge.SipUtilities.createInviteRequest(SipUtilities.java:837)
at 
org.sipfoundry.sipxbridge.BackToBackUserAgent.sendInviteToItsp(BackToBackUserAgent.java:1671)
at 
org.sipfoundry.sipxbridge.CallControlManager.processInvite(CallControlManager.java:621)
at 
org.sipfoundry.sipxbridge.CallControlManager.processRequest(CallControlManager.java:3054)
at 
org.sipfoundry.sipxbridge.SipListenerImpl.processRequest(SipListenerImpl.java:451)

at gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:224)

at 
gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:192)

at gov.nist.javax.sip.DialogFilter.processRequest(DialogFilter.java:1138)

at 
gov.nist.javax.sip.stack.SIPServerTransaction.processRequest(SIPServerTransaction.java:823)
at 
gov.nist.javax.sip.stack.TCPMessageChannel.processMessage(TCPMessageChannel.java:515)
at 
gov.nist.javax.sip.parser.PipelinedMsgParser.run(PipelinedMsgParser.java:361)

at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.NullPointerException

at 
org.sipfoundry.sipxbridge.SipUtilities.createInviteRequest(SipUtilities.java:687)

... 11 more
"2010-12-21T12:53:27.921000Z":475907:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPDialog.java:2635][SIPServerTransaction.java:1392][CallControlUtilities.java:49][BackToBackUserAgent.java:1922][CallControlManager.java:621][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"
"2010-12-21T12:53:27.921000Z":475908:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPDialog.java:1233][SIPDialog.java:2829][SIPServerTransaction.java:1392][CallControlUtilities.java:49][BackToBackUserAgent.java:1922][CallControlManager.java:621][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"
"2010-12-21T12:53:27.922000Z":475909:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SipProviderImpl.java:182][SIPServerTransaction.java:1399][CallControlUtilities.java:49][BackToBackUserAgent.java:1922][CallControlManager.java:621][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"
"2010-12-21T12:53:27.922000Z":475910:JAVA:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"[SIPTransaction.java:556][SIPServerTransaction.java:1476][SIPServerTransaction.java:991][SIPServerTransaction.java:1419][CallControlUtilities.java:49][BackToBackUserAgent.java:1922][CallControlManager.java:621][CallControlManager.java:3054][SipListenerImpl.java:451][EventScanner.java:224][SipProviderImpl.java:192][DialogFilter.java:1138][SIPServerTransaction.java:823][TCPMessageChannel.java:515][PipelinedMsgParser.java:361][Thread.java:619]"

"2010-12-21T12:53:27.923000Z":475911:OUTGOING:INFO:sipx.creativevalley.nl:PipelineThread-23:00000000:sipXbridge:"Sent 
SIP Message :\n----Remote Host:192.168.4.10---- Port: 
34041----\nSIP/2.0 500 Server Internal Error\r\nCall-ID: 
[email protected]\r\ncseq: 1 
INVITE\r\nTo: \"anonymous\" <sip:[email protected]>\r\nVia: 
SIP/2.0/TCP 
192.168.4.10;branch=z9hG4bK-XX-d116aVT2KTjRSX6YVAMHLtkNpQ;rport=34041\r\nVia: 
SIP/2.0/UDP 
192.168.4.10;branch=z9hG4bK-XX-d110HIiEzK8b1sevlCYQ5be`Pg~unFPCqtLM7g8PUnwj51C1Q\r\nVia: 
SIP/2.0/UDP 
192.168.4.10:5090;branch=z9hG4bK846db439f18ce17f4b8a56080e21f04a3331;sipxecs-id=656240e\r\nFrom: 
\"anonymous\" <sip:82.148.198.254>;tag=1063699185\r\nServer: 
sipXecs/4.2.1 sipXecs/sipxbridge (Linux)\r\nContent-Type: 
message/sipfrag\r\nContent-Length: 73\r\n\r\nException Info Unexpected 
error creating INVITE  at 
SipUtilities.java:837--------------------END--------------------\n"


Is there more I could do?

Kind Regards ,

Peter van der Salm



Smart Future cv,
Buys Ballotstraat 14,
3572 ZR Utrecht,
The Netherlands
phone: +31 302 720 278
fax: +31 847 156296
mobile: +31 620 749 471
[email protected] <mailto:[email protected]>



On Dec 22, 2010, at 9:21 , Nikolay Kondratyev wrote:


Recently i have analogous (Internal server Error at sipxbridge with 
similar exeption in the log) problem.
In my case the problem occurs (as far as i can localize it) when  
_sip._udp srv record points to different ip address then the a-record 
for the ITSP domain.

So... just in case... i would check if
dig -t srv _sip._udp.trunk.pstnconnect.nl
and
dig -t a trunk.pstnconnect.nl <http://trunk.pstnconnect.nl>
point to different ip addresses.
If yes (different), then the problem is similar to mine.
Rgds,
Nikolay.
------------------------------------------------------------------------

*From:* [email protected] 
<mailto:[email protected]> 
[mailto:[email protected]] *On Behalf Of *Peter 
van der Salm

*Sent:* Tuesday, December 21, 2010 5:09 PM

*To:* [email protected] 
<mailto:[email protected]>
*Subject:* [sipx-users] hairpin transfer from/to pstn causes error 
:SipUtilities.java:837


    Folks,

    Since upgrading to 4.2.1 we have failed blind transfers for calls
    coming from the pstn and being transferred out to the pstn again.
    the logs show this message:

    Time: 2010-12-21T12:53:27.922414Z
    Frame: 13 sipxbridge.xml:475911 sipXproxy.xml:3091302
    Source: sipx.creativevalley.nl-sipXbridge
    Dest: sipx.creativevalley.nl-SipXProxy

    SIP/2.0 500 Server Internal Error
    Call-ID: [email protected]
    CSeq: 1 INVITE
    To: "anonymous" <sip:[email protected]>
    Via: SIP/2.0/TCP
    192.168.4.10;branch=z9hG4bK-XX-d116aVT2KTjRSX6YVAMHLtkNpQ;rport=34041
    Via: SIP/2.0/UDP
    
192.168.4.10;branch=z9hG4bK-XX-d110HIiEzK8b1sevlCYQ5be`Pg~unFPCqtLM7g8PUnwj51C1Q
    Via: SIP/2.0/UDP
    
192.168.4.10:5090;branch=z9hG4bK846db439f18ce17f4b8a56080e21f04a3331;sipxecs-id=656240e
    From: "anonymous" <sip:82.148.198.254>;tag=1063699185
    Server: sipXecs/4.2.1 sipXecs/sipxbridge (Linux)
    Content-Type: message/sipfrag
    Content-Length: 73

    *Exception Info Unexpected error creating INVITE  at
    SipUtilities.java:837*
    *
    *
    I have attached the sipviewer merged.xml file of the event.
    Has anybody seen this before? Any sugestions on how we can solve
    this, or workaround the issue?
    I am not a sip protocol expert, but what I see looks pretty
    normal to my eye. Except for the java error of course.....

    Thanks!


    Peter van der Salm

_______________________________________________
sipx-users mailing list
[email protected] <mailto:[email protected]>
List Archive: http://list.sipfoundry.org/archive/sipx-users/




Smart Future cv,
Buys Ballotstraat 14,
3572 ZR Utrecht,
The Netherlands
phone: +31 302 720 278
fax: +31 847 156296
mobile: +31 620 749 471
[email protected] <mailto:[email protected]>



Smart Future cv,
Buys Ballotstraat 14,
3572 ZR Utrecht,
The Netherlands
phone: +31 302 720 278
fax: +31 847 156296
mobile: +31 620 749 471
[email protected] <mailto:[email protected]>




_______________________________________________
sipx-users mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users/



_______________________________________________
sipx-users mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users/





















					Reply via email to