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-;rport Max-Forwards: 70 Contact: <sip:[email protected]:37498;rinstance=1cade11b5d526682;transport=tcp> 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
