Anyone want to take a look at debug file and give me some thoughts? Here is what I have. Its bit complicated.
Seimens PBX is trunked to PBX1. The seimens sends calls to sipxbridge (5080) on PBX1. No nats anywhere PBX1 uses a TLS trunk (5081) to PBX2. The TLS trunk between PBX1 and PBX2 works fine. Calls from the Seimens to PBX2 are routed to PBX1 and then sent to PBX2 via the TLS bridge. This too is working fine. However, if the user on PBX2 tries to transfer then sipxbridge on PBX1 throws an error and tears the call down. Traces show that PBX1 gets the reinvite from PBX2. PBX1 sends it to the Seimens. The seimens respond to PBX1 with a 200OK and a new SDP. Sipxbridge fails with an invalid header. But the 200 OK looks ok to me. Here is the error in sipxbridge. I have tons of traces and debug logs if anything in here sticks out. [UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.629000Z":14336:INCOMING:INFO:pbx1:Thread-243:00000000:sipXbridge:"Read SIP Message:\n----Remote Host:10.193.32.30---- Port: 5060----\nSIP/2.0 200 OK\r\nVia: SIP/2.0/UDP 50.78.90.149:5080;branch=z9hG4bK9230be821100bb27e73786fa40fb7c6b393939;received=10.193.32.35\r\nFrom: <sip:[email protected]>;tag=1002349673\r\nTo: \"JOHNSON, D.\" <sip:[email protected]>;tag=ca633999f2\r\nCall-ID: 2a162a244b3de4d9\r\nCSeq: 1 INVITE\r\nAllow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,UPDATE\r\nContact: <sip:[email protected]:5060>\r\nP-Asserted-Identity: \"JOHNSON, D.\" <sip:[email protected]>\r\nServer: HiPath 4000 V5.0 M5T SIP Stack/4.0.26.26\r\nSession-Expires: 1800;refresher=uas\r\nSupported: timer\r\nContent-Type: application/sdp\r\nContent-Length: 382\r\n\r\nv=0\r\no=MxSIP 134172 277621039 IN IP4 10.193.32.30\r\ns=SIP Call\r\nc=IN IP4 10.193.32.30\r\nt=0 0\r\nm=audio 29100 RTP/AVP 0 8 18 96 98 99\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:96 CLEARMODE/8000\r\na=rtpmap:98 telephone-event/8000\r\na=rtpmap:99 red/8000\r\na=silenceSupp:off - - - -\r\na=fmtp:18 annexb=no\r\na=fmtp:98 0-15,32-36,49\r\na=fmtp:99 98\r\na=sendrecv\r\n====================END====================\n" "2012-06-14T18:18:39.629000Z":14337:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SIPDialog.java:2635][SIPClientTransaction.java:1451][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.630000Z":14338:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:764][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.630000Z":14339:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SIPDialog.java:2635][DialogFilter.java:1274][SIPClientTransaction.java:768][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.631000Z":14340:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SipProviderImpl.java:182][DialogFilter.java:1278][SIPClientTransaction.java:768][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.631000Z":14341:JAVA:DEBUG:pbx1:Thread-243:00000000:ItspAccountInfo:"removeFailureCounter 2a162a244b3de4d9" "2012-06-14T18:18:39.631000Z":14342:JAVA:DEBUG:pbx1:Thread-243:00000000:CallControlManager:"processInviteResponse" "2012-06-14T18:18:39.631000Z":14343:JAVA:DEBUG:pbx1:Thread-243:00000000:CallControlManager:"processInviteResponse : SIP/2.0 200 OK\r\n dialog = gov.nist.javax.sip.stack.SIPDialog@28bd36fa" "2012-06-14T18:18:39.631000Z":14344:JAVA:DEBUG:pbx1:Thread-243:00000000:CallControlManager:"Operation = FORWARD_SDP_SOLICITIATION" "2012-06-14T18:18:39.631000Z":14345:JAVA:DEBUG:pbx1:Thread-243:00000000:BackToBackUserAgent:"Dialog already in table" "2012-06-14T18:18:39.632000Z":14346:JAVA:DEBUG:pbx1:Thread-243:00000000:CallControlManager:"forwardSdpSolicitationResponse" "2012-06-14T18:18:39.632000Z":14347:JAVA:DEBUG:pbx1:Thread-243:00000000:DialogContext:"DialogContext.setLastResponse " "2012-06-14T18:18:39.632000Z":14348:JAVA:DEBUG:pbx1:Thread-243:00000000:DialogContext:"lastResponse = SIP/2.0 200 OK\r\n" "2012-06-14T18:18:39.635000Z":14349:JAVA:DEBUG:pbx1:Thread-243:00000000:DialogContext:"DialogApplicationData.getRtpSession gov.nist.javax.sip.stack.SIPDialog@28bd36fa" "2012-06-14T18:18:39.635000Z":14350:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpTransmitterEndpoint:"replacing session description" "2012-06-14T18:18:39.636000Z":14351:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpTransmitterEndpoint:"isTransmitter = true : Setting ipAddress : 10.193.32.30 Setting port 29100 isOffer = true" "2012-06-14T18:18:39.636000Z":14352:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpReceiverEndpoint:"RtpReceiverEndpoint.setSessionDescription() Old SD = v=0\r\no=sipxbridge 9022186398424415952 1 IN IP4 10.193.32.35\r\ns=SIP Call\r\nc=IN IP4 10.193.32.35\r\nt=0 0\r\nm=audio 31018 RTP/AVP 0 18\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:18 G729/8000\r\na=silenceSupp:off - - - -\r\na=fmtp:18 annexb=no\r\n" "2012-06-14T18:18:39.636000Z":14353:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpReceiverEndpoint:"RtpReceiverEndpoint.setSessionDescription() newSD = v=0\r\no=MxSIP 134172 277621039 IN IP4 10.193.32.30\r\ns=SIP Call\r\nc=IN IP4 10.193.32.30\r\nt=0 0\r\nm=audio 29100 RTP/AVP 0 8 18 96 98 99\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:18 G729/8000\r\na=rtpmap:96 CLEARMODE/8000\r\na=rtpmap:98 telephone-event/8000\r\na=rtpmap:99 red/8000\r\na=silenceSupp:off - - - -\r\na=fmtp:18 annexb=no\r\na=fmtp:98 0-15,32-36,49\r\na=fmtp:99 98\r\na=sendrecv\r\n" "2012-06-14T18:18:39.637000Z":14354:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpReceiverEndpoint:"setSessionDescription at : [SipUtilities.java:2127][RtpReceiverEndpoint.java:90][CallControlManager.java:1585][CallControlManager.java:2771][CallControlManager.java:3078][SipListenerImpl.java:617][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1278][SIPClientTransaction.java:768][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.637000Z":14355:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"Codecs = [0, 18, 9]" "2012-06-14T18:18:39.637000Z":14356:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.637000Z":14357:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.638000Z":14358:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.638000Z":14359:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.638000Z":14360:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.638000Z":14361:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = rtpmap" "2012-06-14T18:18:39.638000Z":14362:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = silenceSupp" "2012-06-14T18:18:39.638000Z":14363:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = fmtp" "2012-06-14T18:18:39.639000Z":14364:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = fmtp" "2012-06-14T18:18:39.639000Z":14365:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = fmtp" "2012-06-14T18:18:39.639000Z":14366:JAVA:DEBUG:pbx1:Thread-243:00000000:SipUtilities:"attrName = sendrecv" "2012-06-14T18:18:39.639000Z":14367:JAVA:DEBUG:pbx1:Thread-243:00000000:RtpReceiverEndpoint:"sessionDescription after fixup : v=0\r\no=sipxbridge 9022186398424415952 1 IN IP4 10.193.32.35\r\ns=SIP Call\r\nc=IN IP4 10.193.32.35\r\nt=0 0\r\nm=audio 31018 RTP/AVP 0 18\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:18 G729/8000\r\na=silenceSupp:off - - - -\r\na=fmtp:18 annexb=no\r\na=sendrecv\r\n" "2012-06-14T18:18:39.640000Z":14368:JAVA:FATAL:pbx1:Thread-243:00000000:SipUtilities:"Unexpected exception creating contact header" java.lang.NullPointerException at org.sipfoundry.sipxbridge.SipUtilities.createContactHeader(SipUtilities.java:295) at org.sipfoundry.sipxbridge.CallControlManager.forwardSdpSolicitationResponse(CallControlManager.java:1599) at org.sipfoundry.sipxbridge.CallControlManager.processInviteResponse(CallControlManager.java:2771) at org.sipfoundry.sipxbridge.CallControlManager.processResponse(CallControlManager.java:3078) at org.sipfoundry.sipxbridge.SipListenerImpl.processResponse(SipListenerImpl.java:617) at gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:291) at gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:192) at gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:1278) at gov.nist.javax.sip.stack.SIPClientTransaction.inviteClientTransaction(SIPClientTransaction.java:768) at gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:535) at gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:1453) at gov.nist.javax.sip.stack.UDPMessageChannel.processMessage(UDPMessageChannel.java:541) at gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:459) at gov.nist.javax.sip.stack.UDPMessageChannel.run(UDPMessageChannel.java:295) at java.lang.Thread.run(Thread.java:662) "2012-06-14T18:18:39.640000Z":14369:JAVA:ERR:pbx1:Thread-243:00000000:CallControlManager:"Exception while processing inbound response " org.sipfoundry.sipxbridge.SipXbridgeException: Unexpected error creating contact header at org.sipfoundry.sipxbridge.SipUtilities.createContactHeader(SipUtilities.java:349) at org.sipfoundry.sipxbridge.CallControlManager.forwardSdpSolicitationResponse(CallControlManager.java:1599) at org.sipfoundry.sipxbridge.CallControlManager.processInviteResponse(CallControlManager.java:2771) at org.sipfoundry.sipxbridge.CallControlManager.processResponse(CallControlManager.java:3078) at org.sipfoundry.sipxbridge.SipListenerImpl.processResponse(SipListenerImpl.java:617) at gov.nist.javax.sip.EventScanner.deliverEvent(EventScanner.java:291) at gov.nist.javax.sip.SipProviderImpl.handleEvent(SipProviderImpl.java:192) at gov.nist.javax.sip.DialogFilter.processResponse(DialogFilter.java:1278) at gov.nist.javax.sip.stack.SIPClientTransaction.inviteClientTransaction(SIPClientTransaction.java:768) at gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:535) at gov.nist.javax.sip.stack.SIPClientTransaction.processResponse(SIPClientTransaction.java:1453) at gov.nist.javax.sip.stack.UDPMessageChannel.processMessage(UDPMessageChannel.java:541) at gov.nist.javax.sip.stack.UDPMessageChannel.processIncomingDataPacket(UDPMessageChannel.java:459) at gov.nist.javax.sip.stack.UDPMessageChannel.run(UDPMessageChannel.java:295) at java.lang.Thread.run(Thread.java:662) Caused by: java.lang.NullPointerException at org.sipfoundry.sipxbridge.SipUtilities.createContactHeader(SipUtilities.java:295) ... 14 more "2012-06-14T18:18:39.641000Z":14370:JAVA:DEBUG:pbx1:Thread-243:00000000:BackToBackUserAgent:"tearing down gov.nist.javax.sip.stack.SIPDialog@28bd36fa dialogState = Confirmed Dialog dialog.isServer false" "2012-06-14T18:18:39.643000Z":14371:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SIPClientTransaction.java:304][SIPTransactionStack.java:1543][SIPTransactionStack.java:1521][SipProviderImpl.java:397][BackToBackUserAgent.java:2365][CallControlManager.java:2832][CallControlManager.java:3078][SipListenerImpl.java:617][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1278][SIPClientTransaction.java:768][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]" "2012-06-14T18:18:39.644000Z":14372:JAVA:INFO:pbx1:Thread-243:00000000:sipXbridge:"[SIPDialog.java:1598][SIPDialog.java:2112][SIPDialog.java:2059][BackToBackUserAgent.java:2367][CallControlManager.java:2832][CallControlManager.java:3078][SipListenerImpl.java:617][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1278][SIPClientTransaction.java:768][SIPClientTransaction.java:535][SIPClientTransaction.java:1453][UDPMessageChannel.java:541][UDPMessageChannel.java:459][UDPMessageChannel.java:295][Thread.java:662]"
_______________________________________________ sipx-users mailing list [email protected] List Archive: http://list.sipfoundry.org/archive/sipx-users/
