Hi Matthew, Thanks for raising this, and for figuring out that CNAME records cause it! I think there are two issues here - firstly that Clearwater's DNS resolver doesn't support CNAME records, and secondly that if we get a CNAME record, we misclassify it as an AAAA record and read a spurious IPv6 address (instead of ignoring it and logging a warning that CNAME is unsupported, which I think would be a better stopgap until full CNAME support gets implemented).
I've raised https://github.com/Metaswitch/cpp-common/issues/224 to track the issue - feel free to watch it for updates. It sounds like you've got things working by switching from CNAME records to A records, though, so you don't need an immediate fix - is that right? Thanks again, Rob -----Original Message----- From: [email protected] [mailto:[email protected]] On Behalf Of Matthew M. Gamble Sent: 29 December 2014 21:10 To: Matthew M. Gamble; [email protected] Subject: Re: [Clearwater] Bono / Sprout communication issue on manual install Following up on my own question, the issue was DNS related. Apparently the DNS stack does not like the CNAMEs - since we only had one of each node, I setup the DNS as follows (example Ips) sprout-01 IN A 3.0.0.1 sprout IN CNAME sprout-01 The name resolves fine, but bono wasn¹t working. When I changed the DNS to have sprout.<zone> point to an IP instead of a CNAME things started to work again. The same is true for other inter-node communication - it appears nothing likes CNAMEs - is there a reason for that? On 12/29/14, 3:03 PM, "Matthew M. Gamble" <[email protected]> wrote: >I¹m working on a manual install in our lab and Bono is having issues >communicating to sprout when a client sends a register request (see >below). The error appears to be when bono attempts to send the request >to sprout, when this error is generated: >29-12-2014 19:54:53.416 UTC Warning pjsip: tsx0x7f9fa4005 Failed to >send Request msg REGISTER/cseq=1 (tdta0x7f9fa4003950)! err=171060 >(Unsupported transport (PJSIP_EUNSUPTRANSPORT)). > >The really confusing thing is prior to that bono appears to somehow >grab an IPv6 address (5036:a4:9f7f:0:3500:::5054) for sprout however >the internal DNS servers only have IPv4 addresses. I¹ve included the >log snippit of the register request please let me know what else I >need to include to help troubleshoot this. > >Thanks! > > > >Full log: > >--start msg-- > >REGISTER sip:ims.rovvr.ca SIP/2.0 >Via: SIP/2.0/TCP >172.16.1.122:37498;branch=z9hG4bK-d8754z-7975774666b13031-1---d8754z-;r >por >t >Max-Forwards: 70 >Contact: ><sip:[email protected]:37498;rinstance=1cade11b5d526682;transport >=tc >p> >To: "6474787201"<sip:[email protected]> >From: "6474787201"<sip:[email protected]>;tag=2055be16 >Call-ID: MjY5NjUwNzIxNzY3OWU4NmNjNDhlNzAxNzA2ODMwNWU >CSeq: 1 REGISTER >Expires: 3600 >Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, >SUBSCRIBE, INFO >User-Agent: Bria 3 release 3.5.5 stamp 71243 >Content-Length: 0 > > >--end msg-- >29-12-2014 19:54:53.410 UTC Debug stack.cpp:467: Queuing cloned >received message 0x7f9fa004eb68 for worker threads >29-12-2014 19:54:53.410 UTC Debug statistic.cpp:103: Send new value for >statistic queue_size, size 5 >29-12-2014 19:54:53.410 UTC Debug zmq_lvc.cpp:172: Update to queue_size >statistic >29-12-2014 19:54:53.410 UTC Debug zmq_lvc.cpp:256: Clearing message >cache for 0x7f9fc00335b0 >29-12-2014 19:54:53.410 UTC Debug stack.cpp:204: Worker thread dequeue >message 0x7f9fa004eb68 >29-12-2014 19:54:53.410 UTC Debug pjsip: sip_endpoint.c Distributing >rdata to modules: Request msg REGISTER/cseq=1 (rdata0x7f9fa004eb68) >29-12-2014 19:54:53.410 UTC Debug stateful_proxy.cpp:258: Proxy RX >request >29-12-2014 19:54:53.411 UTC Debug pjutils.cpp:676: Cloned Request msg >REGISTER/cseq=1 (rdata0x7f9fa004eb68) to tdta0x7f9fa4007e70 >29-12-2014 19:54:53.411 UTC Debug stateful_proxy.cpp:951: Request >received on non-trusted port 5060 >29-12-2014 19:54:53.411 UTC Debug stateful_proxy.cpp:1223: Perform >access proxy routing for REGISTER request >29-12-2014 19:54:53.411 UTC Debug stateful_proxy.cpp:1260: Message >requires outbound support >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:91: Find or create flow >for transport tcps0x7f9fa0003868 (2), remote address >216.235.11.118:62955 >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:296: Added transport >listener for flow 0x7f9fa40029e0 >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:106: Added flow record >0x7f9fa40029e0 >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:233: Reporting current >flow count: 1 >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:602: Dialog count now 2 >for flow >29-12-2014 19:54:53.411 UTC Debug stateful_proxy.cpp:1272: Found or >created flow data record, token = 0OvM00p+qt >29-12-2014 19:54:53.411 UTC Debug flowtable.cpp:619: Dialog count now 1 >for flow >29-12-2014 19:54:53.412 UTC Debug stateful_proxy.cpp:1181: Mark >originating >29-12-2014 19:54:53.412 UTC Info stateful_proxy.cpp:1202: Route request >to upstream proxy sip:sprout.ims.rovvr.ca:5054;transport=TCP;lr;orig >29-12-2014 19:54:53.412 UTC Debug acr.cpp:1706: Create RalfACR for node >type P-CSCF with role Originating >29-12-2014 19:54:53.412 UTC Debug acr.cpp:48: Created ACR >(0x7f9fa40031e0) >29-12-2014 19:54:53.412 UTC Debug acr.cpp:166: Created P-CSCF Ralf ACR >29-12-2014 19:54:53.412 UTC Debug acr.cpp:205: Set record type for >P/S-CSCF >29-12-2014 19:54:53.412 UTC Debug acr.cpp:213: Non-dialog message => >EVENT_RECORD >29-12-2014 19:54:53.412 UTC Debug acr.cpp:1434: Stored 0 subscription >identifiers >29-12-2014 19:54:53.412 UTC Debug stateful_proxy.cpp:594: Trust mode >INBOUND_EDGE_CLIENT(,-rsp,-pch), serving state None >29-12-2014 19:54:53.412 UTC Debug pjsip: tsx0x7f9fa4009 Transaction >created for Request msg REGISTER/cseq=1 (rdata0x7f9fa004eb68) >29-12-2014 19:54:53.412 UTC Debug stateful_proxy.cpp:2245: >UASTransaction constructor (0x7f9fa40098a0) >29-12-2014 19:54:53.413 UTC Debug stateful_proxy.cpp:2246: ACR >(0x7f9fa40031e0) >29-12-2014 19:54:53.413 UTC Debug pjsip: tsx0x7f9fa4009 Incoming >Request msg REGISTER/cseq=1 (rdata0x7f9fa004eb68) in state Null >29-12-2014 19:54:53.413 UTC Debug pjsip: tsx0x7f9fa4009 State changed >from Null to Trying, event=RX_MSG >29-12-2014 19:54:53.413 UTC Debug stateful_proxy.cpp:366: >tsx0x7f9fa4009138 - tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying >29-12-2014 19:54:53.413 UTC Debug pjsip: endpoint Response msg >408/REGISTER/cseq=1 (tdta0x7f9fa4001890) created >29-12-2014 19:54:53.413 UTC Debug stateful_proxy.cpp:3692: Report SAS >start marker - trail (1d) >29-12-2014 19:54:53.413 UTC Debug trustboundary.cpp:67: Add P-Charging >headers >29-12-2014 19:54:53.413 UTC Debug stateful_proxy.cpp:3781: Allocating >transaction and data for target 0 >29-12-2014 19:54:53.413 UTC Debug pjsip: tsx0x7f9fa4005 Transaction >created for Request msg REGISTER/cseq=1 (tdta0x7f9fa4003950) >29-12-2014 19:54:53.413 UTC Debug stateful_proxy.cpp:3802: Adding trail >identifier 29 to UAC transaction >29-12-2014 19:54:53.414 UTC Debug stateful_proxy.cpp:3821: Updating >request URI and route for target 0 >29-12-2014 19:54:53.414 UTC Debug stateful_proxy.cpp:4187: Stripping >loose routes from proxied message >29-12-2014 19:54:53.414 UTC Debug stateful_proxy.cpp:4217: Adding a >Route header to sip:sprout.ims.rovvr.ca:5054;transport=TCP >29-12-2014 19:54:53.414 UTC Debug stateful_proxy.cpp:4255: Resolve next >hop destination >29-12-2014 19:54:53.414 UTC Debug pjutils.cpp:505: Next hop node is >encoded in top route header >29-12-2014 19:54:53.414 UTC Debug sipresolver.cpp:85: >SIPResolver::resolve for name sprout.ims.rovvr.ca, port 5054, transport >6, family 2 >29-12-2014 19:54:53.414 UTC Debug baseresolver.cpp:511: Attempt to >parse sprout.ims.rovvr.ca as IP address >29-12-2014 19:54:53.414 UTC Debug sipresolver.cpp:127: Port is >specified >29-12-2014 19:54:53.414 UTC Debug sipresolver.cpp:295: Perform A/AAAA >record lookup only, name = sprout.ims.rovvr.ca >29-12-2014 19:54:53.414 UTC Debug statistic.cpp:103: Send new value for >statistic client_count, size 1 >29-12-2014 19:54:53.414 UTC Debug zmq_lvc.cpp:172: Update to >client_count statistic >29-12-2014 19:54:53.415 UTC Debug zmq_lvc.cpp:256: Clearing message >cache for 0x7f9fc00008c0 >29-12-2014 19:54:53.415 UTC Debug statistic.cpp:103: Send new value for >statistic incoming_requests, size 1 >29-12-2014 19:54:53.415 UTC Debug zmq_lvc.cpp:172: Update to >incoming_requests statistic >29-12-2014 19:54:53.415 UTC Debug zmq_lvc.cpp:256: Clearing message >cache for 0x7f9fc002a270 >29-12-2014 19:54:53.415 UTC Debug dnscachedresolver.cpp:179: Pulling 1 >records from cache for sprout.ims.rovvr.ca A >29-12-2014 19:54:53.416 UTC Debug baseresolver.cpp:359: Found 1 A/AAAA >records, randomizing >29-12-2014 19:54:53.416 UTC Debug baseresolver.cpp:501: >5036:a4:9f7f:0:3500:::5054 transport 6 is not blacklisted >29-12-2014 19:54:53.416 UTC Debug baseresolver.cpp:380: Added a server, >now have 1 of 5 >29-12-2014 19:54:53.416 UTC Debug baseresolver.cpp:418: Adding 0 >servers from blacklist >29-12-2014 19:54:53.416 UTC Info pjutils.cpp:940: Resolved destination >URI sip:sprout.ims.rovvr.ca:5054;transport=TCP;lr;orig to 1 servers >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:4293: Sending >request for sip:ims.rovvr.ca >29-12-2014 19:54:53.416 UTC Debug pjsip: tsx0x7f9fa4005 Sending Request >msg REGISTER/cseq=1 (tdta0x7f9fa4003950) in state Null >29-12-2014 19:54:53.416 UTC Debug pjsip: endpoint Request msg >REGISTER/cseq=1 (tdta0x7f9fa4003950): skipping target resolution >because address is already set >29-12-2014 19:54:53.416 UTC Warning pjsip: tsx0x7f9fa4005 Failed to >send Request msg REGISTER/cseq=1 (tdta0x7f9fa4003950)! err=171060 >(Unsupported transport (PJSIP_EUNSUPTRANSPORT)) >29-12-2014 19:54:53.416 UTC Debug pjsip: tsx0x7f9fa4005 State changed >from Null to Terminated, event=TRANSPORT_ERROR >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:366: >tsx0x7f9fa40059b8 - tu_on_tsx_state UAC, TSX_STATE TRANSPORT_ERROR >state=Terminated >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:4400: >tsx0x7f9fa40059b8 - uac_data = 0x7f9fa4009c40, uas_data = >0x7f9fa40098a0 >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:4419: Failed to >connected to server, so add to blacklist >29-12-2014 19:54:53.416 UTC Debug baseresolver.cpp:471: Add >5036:a4:9f7f:0:3500:::5054 transport 6 to blacklist for 30 seconds >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:4461: >tsx0x7f9fa40059b8 - UAC tsx terminated while still connected to UAS tsx >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:4464: Timeout or >transport error >29-12-2014 19:54:53.416 UTC Debug stateful_proxy.cpp:3440: >tsx0x7f9fa40059b8 - Not forked request >29-12-2014 19:54:53.416 UTC Debug pjsip: tsx0x7f9fa4009 Sending >Response msg 408/REGISTER/cseq=1 (tdta0x7f9fa4001890) in state Trying >29-12-2014 19:54:53.416 UTC Verbose stack.cpp:257: TX 406 bytes >Response msg 408/REGISTER/cseq=1 (tdta0x7f9fa4001890) to TCP >216.235.11.118:62955: >_______________________________________________ >Clearwater mailing list >[email protected] >http://lists.projectclearwater.org/listinfo/clearwater _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater
