Hi, Thanks for sending the diagnostics across. There’s an issue in Sprout where it doesn’t correctly parse the route in the bgcf.json file if it’s surrounded by <>. This then leads to Sprout attempting to route to “”, which it can’t resolve! The relevant part of the logs are:
28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:133: Find target Sproutlet for request 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:145: Found top Route header: Route: <sip:10.xxxxxx:5060;transport=UDP;lr;orig> 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:152: Initial request 28-08-2014 12:57:46.448 UTC Debug sproutletproxy.cpp:202: Look for Sproutlet host mapping for “” … 28-08-2014 12:57:46.448 UTC Debug sipresolver.cpp:85: SIPResolver::resolve for name “”, port 0, transport -1, family 2 … 28-08-2014 12:57:46.453 UTC Info pjutils.cpp:919: Resolved destination URI <sip:10.xxxxxx:5060;transport=UDP;lr;orig> to 0 servers A fix for this should go in shortly (you can track the issue at: https://github.com/Metaswitch/sprout/issues/744). In the meantime, can you remove the angle brackets from the route in bgcf.json and reload Sprout (sudo service sprout reload)? Ellie From: Ruggero Schiavi [mailto:[email protected]] Sent: 29 August 2014 08:38 To: Eleanor Merry; [email protected] Subject: Re: [Clearwater] Path header in 200 OK SIP message I tried to use also the Enum.json configuration to see what would change but in fact it doesn't perform the NAPTR query for the number (as expected) but still performs: NAPTR <Root> SRV _sip._udp SRV _sip._tcp I hope these details may simplify the problem solution, Thank you, Abaco 2014-08-28 15:50 GMT+02:00 Ruggero Schiavi <[email protected]<mailto:[email protected]>>: Of course, we are using your IMS solution in our Network, Monaco Telecom, and as I told before, we are now working with the integration of IMS in the fix telephone network. We used the manual installation procedure, configured the Enum server (sip trunk to the non IMS numbers), the user_setting file to point to it and the bgcf in this way: { "routes" : [ { "name" : "toThePSTN", "domain" : "10.xxx.xxx.xxx", "route" : ["<sip:10.xxx.xxx.xxx:5060;transport=UDP;lr;orig>"] } ] } We used an external IMS compliant I-BCF that works without problems. Before the today upgrade we were able to reach the fix network and after not anymore. In the end you find the log, if you need I have a log and pcap file of a previous working call. I noticed that now Sprout performs SVR queries to the DNS after the NAPTR one, this might be the problem. You find the very long log (until service unavailable generation), in future may I put them in pastebin to simplify the reading? Thank you, Abaco 28-08-2014 12:57:46.431 UTC Debug statistic.cpp:103: Send new value for statistic connected_homers, size 2 28-08-2014 12:57:46.431 UTC Debug zmq_lvc.cpp:167: Update to connected_homers statistic 28-08-2014 12:57:46.432 UTC Debug zmq_lvc.cpp:250: Clearing message cache for 0x7f532c005300 28-08-2014 12:57:46.432 UTC Debug statistic.cpp:103: Send new value for statistic xdm_latency_us, size 5 28-08-2014 12:57:46.432 UTC Debug zmq_lvc.cpp:167: Update to xdm_latency_us statistic 28-08-2014 12:57:46.432 UTC Debug zmq_lvc.cpp:250: Clearing message cache for 0x7f532c0257f0 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:94: Processing simservs node: 'originating-identity-presentation' 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:100: OIP enabled 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:94: Processing simservs node: 'originating-identity-presentation-restriction' 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:110: OIR enabled 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:94: Processing simservs node: 'communication-diversion' 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:127: CDIV enabled 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:94: Processing simservs node: 'incoming-communication-barring' 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:154: Inbound Call Barring enabled 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:94: Processing simservs node: 'outgoing-communication-barring' 28-08-2014 12:57:46.433 UTC Debug simservs.cpp:178: Outbound Call Barring enabled 28-08-2014 12:57:46.433 UTC Verbose sproutletproxy.cpp:995: Created Sproutlet mmtel-0x7f52ec10eda0 for Request msg INVITE/cseq=1 (tdta0x7f52ec094230) 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec12bcb0 => txdata 0x7f52ec12b748 mapping 28-08-2014 12:57:46.434 UTC Verbose sproutletproxy.cpp:1429: scscf-0x7f52ec0859c0 received provisional response Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12b6a0) on fork 0, state = Proceeding 28-08-2014 12:57:46.434 UTC Info scscfsproutlet.cpp:419: S-CSCF received response 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:449: Cancelled Sproutlet timer, id = 139994124458928 28-08-2014 12:57:46.434 UTC Verbose sproutletproxy.cpp:1254: scscf-0x7f52ec0859c0 sending Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12b6a0) 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 100 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:1635: Discard 100/INVITE response (tdta0x7f52ec12b6a0) 28-08-2014 12:57:46.434 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec12bcb0 => txdata 0x7f52ec12b748 mapping 28-08-2014 12:57:46.435 UTC Debug pjsip: tdta0x7f52ec12 Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12b6a0) 28-08-2014 12:57:46.435 UTC Debug pjutils.cpp:672: Cloned tdta0x7f52ec094230 to tdta0x7f52ec12b6a0 28-08-2014 12:57:46.435 UTC Debug sproutletproxy.cpp:1107: Remove top Route header Route: <sip:mmtel.ims.prod.lan;lr> 28-08-2014 12:57:46.435 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec12bcb0 => txdata 0x7f52ec12b748 mapping 28-08-2014 12:57:46.435 UTC Verbose sproutletproxy.cpp:1406: mmtel-0x7f52ec10eda0 pass initial request Request msg INVITE/cseq=1 (tdta0x7f52ec12b6a0) to Sproutlet 28-08-2014 12:57:46.435 UTC Debug sproutletappserver.cpp:68: Store onward route-set for request 28-08-2014 12:57:46.435 UTC Debug sproutletappserver.cpp:74: Store header: Route: <sip:[email protected]:5054;lr;orig> 28-08-2014 12:57:46.435 UTC Debug mmtel.cpp:582: Originating Identification Presentation Restriction enabled 28-08-2014 12:57:46.435 UTC Debug mmtel.cpp:617: Identity presentation is not restricted by default 28-08-2014 12:57:46.435 UTC Debug mmtel.cpp:499: Testing call against conditions (0x0) 28-08-2014 12:57:46.436 UTC Debug mmtel.cpp:466: Call barring rule allows call to continue 28-08-2014 12:57:46.436 UTC Debug sproutletappserver.cpp:178: Restore header: Route: <sip:[email protected]:5054;lr;orig> 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1202: Sproutlet send_request 0x7f52ec12bcb0 28-08-2014 12:57:46.436 UTC Verbose sproutletproxy.cpp:1227: mmtel-0x7f52ec10eda0 sending Request msg INVITE/cseq=1 (tdta0x7f52ec12b6a0) on fork 0 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 0 responses, 1 requests 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1575: Processing request 0x7f52ec12b748, fork = 0 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1696: mmtel-0x7f52ec10eda0 transmitting request on fork 0 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1710: mmtel-0x7f52ec10eda0 store reference to non-ACK request Request msg INVITE/cseq=1 (tdta0x7f52ec12b6a0) on fork 0 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec12bcb0 => txdata 0x7f52ec12b748 mapping 28-08-2014 12:57:46.436 UTC Debug sproutletproxy.cpp:133: Find target Sproutlet for request 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:145: Found top Route header: Route: <sip:[email protected]:5054;lr;orig> 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:152: Initial request 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:202: Look for Sproutlet host mapping for ppsprout1.ims.prod.lan 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:181: No Sproutlet found using service name or host 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:185: Find default service for port 5054 28-08-2014 12:57:46.437 UTC Debug scscfsproutlet.cpp:299: S-CSCF Transaction (0x7f52ec10d4b0) created 28-08-2014 12:57:46.437 UTC Verbose sproutletproxy.cpp:995: Created Sproutlet scscf-0x7f52ec10d4b0 for Request msg INVITE/cseq=1 (tdta0x7f52ec12b6a0) 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec12ec20 => txdata 0x7f52ec12e6b8 mapping 28-08-2014 12:57:46.437 UTC Verbose sproutletproxy.cpp:1429: mmtel-0x7f52ec10eda0 received provisional response Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12e610) on fork 0, state = Proceeding 28-08-2014 12:57:46.437 UTC Verbose sproutletproxy.cpp:1254: mmtel-0x7f52ec10eda0 sending Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12e610) 28-08-2014 12:57:46.437 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.438 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 100 28-08-2014 12:57:46.438 UTC Debug sproutletproxy.cpp:1635: Discard 100/INVITE response (tdta0x7f52ec12e610) 28-08-2014 12:57:46.438 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec12ec20 => txdata 0x7f52ec12e6b8 mapping 28-08-2014 12:57:46.438 UTC Debug pjsip: tdta0x7f52ec12 Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f52ec12e610) 28-08-2014 12:57:46.438 UTC Debug pjutils.cpp:672: Cloned tdta0x7f52ec12b6a0 to tdta0x7f52ec12e610 28-08-2014 12:57:46.438 UTC Debug sproutletproxy.cpp:1107: Remove top Route header Route: <sip:[email protected]:5054;lr;orig> 28-08-2014 12:57:46.438 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec12ec20 => txdata 0x7f52ec12e6b8 mapping 28-08-2014 12:57:46.438 UTC Verbose sproutletproxy.cpp:1406: scscf-0x7f52ec10d4b0 pass initial request Request msg INVITE/cseq=1 (tdta0x7f52ec12e610) to Sproutlet 28-08-2014 12:57:46.438 UTC Info scscfsproutlet.cpp:329: S-CSCF received initial request 28-08-2014 12:57:46.438 UTC Debug scscfsproutlet.cpp:539: Route header references this system 28-08-2014 12:57:46.439 UTC Debug scscfsproutlet.cpp:552: Found ODI token ihM1J8mU3k 28-08-2014 12:57:46.439 UTC Debug aschain.h:124: AsChain inc ref 0x7f52ec0e13d0 -> 2 28-08-2014 12:57:46.439 UTC Info scscfsproutlet.cpp:559: Original dialog for odi_ihM1J8mU3k found: AsChain-orig[0x7f52ec0e13d0]:2/1 28-08-2014 12:57:46.439 UTC Debug scscfsproutlet.cpp:574: Got our Route header, session case orig, OD=AsChain-orig[0x7f52ec0e13d0]:2/1 28-08-2014 12:57:46.439 UTC Debug pjutils.cpp:345: Served user from P-Served-User header 28-08-2014 12:57:46.439 UTC Debug pjutils.cpp:240: aor_from_uri converted sip:[email protected] to sip:[email protected] 28-08-2014 12:57:46.439 UTC Debug scscfsproutlet.cpp:637: Retrieved ACR 0x7f52ec0e14c0 for existing AS chain 28-08-2014 12:57:46.439 UTC Info scscfsproutlet.cpp:367: Found served user, so apply services 28-08-2014 12:57:46.439 UTC Debug scscfsproutlet.cpp:792: Performing originating initiating request processing 28-08-2014 12:57:46.439 UTC Info scscfsproutlet.cpp:817: Completed applying originating services 28-08-2014 12:57:46.440 UTC Debug scscfsproutlet.cpp:1162: Translating URI 28-08-2014 12:57:46.440 UTC Debug scscfsproutlet.cpp:209: ENUM is enabled 28-08-2014 12:57:46.440 UTC Debug scscfsproutlet.cpp:215: SIP URI - user = +37799666385<tel:%2B37799666385> 28-08-2014 12:57:46.440 UTC Debug scscfsproutlet.cpp:229: Global number or look-ups allowed for non-global numbers 28-08-2014 12:57:46.440 UTC Debug scscfsproutlet.cpp:233: Performing ENUM lookup for user +37799666385<tel:%2B37799666385> 28-08-2014 12:57:46.441 UTC Debug dnsresolver.cpp:141: Sending DNS NAPTR query for 5.8.3.6.6.6.9.9.7.7.3.e164.arpa 28-08-2014 12:57:46.443 UTC Debug enumservice.cpp:435: Got NAPTR record: 10 100 "E2U+sip" "u" "!^\+377(.*$)!sip:\[email protected]<mailto:[email protected]>!" 28-08-2014 12:57:46.443 UTC Debug enumservice.cpp:70: Split regex into match=^\+377(.*$), replace=sip:\[email protected]<mailto:[email protected]> 28-08-2014 12:57:46.443 UTC Debug enumservice.cpp:375: Enum lookup completes: sip:[email protected]<mailto:sip%[email protected]> 28-08-2014 12:57:46.443 UTC Debug scscfsproutlet.cpp:1172: Update request URI to sip:[email protected]<mailto:sip%[email protected]> 28-08-2014 12:57:46.443 UTC Info scscfsproutlet.cpp:989: Routing to BGCF sip:bgcf.ppsprout1.ims.prod.lan:5054;transport=TCP 28-08-2014 12:57:46.443 UTC Debug sproutletproxy.cpp:1202: Sproutlet send_request 0x7f52ec12ec20 28-08-2014 12:57:46.444 UTC Verbose sproutletproxy.cpp:1227: scscf-0x7f52ec10d4b0 sending Request msg INVITE/cseq=1 (tdta0x7f52ec12e610) on fork 0 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 0 responses, 1 requests 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:1575: Processing request 0x7f52ec12e6b8, fork = 0 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:1696: scscf-0x7f52ec10d4b0 transmitting request on fork 0 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:1710: scscf-0x7f52ec10d4b0 store reference to non-ACK request Request msg INVITE/cseq=1 (tdta0x7f52ec12e610) on fork 0 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec12ec20 => txdata 0x7f52ec12e6b8 mapping 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:133: Find target Sproutlet for request 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:145: Found top Route header: Route: <sip:bgcf.ppsprout1.ims.prod.lan:5054;transport=TCP;lr> 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:152: Initial request 28-08-2014 12:57:46.444 UTC Debug sproutletproxy.cpp:202: Look for Sproutlet host mapping for bgcf.ppsprout1.ims.prod.lan 28-08-2014 12:57:46.444 UTC Verbose sproutletproxy.cpp:995: Created Sproutlet bgcf-0x7f52ec0f1ac0 for Request msg INVITE/cseq=1 (tdta0x7f52ec12e610) 28-08-2014 12:57:46.445 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec145200 => txdata 0x7f52ec144c98 mapping 28-08-2014 12:57:46.445 UTC Verbose sproutletproxy.cpp:1429: scscf-0x7f52ec10d4b0 received provisional response Response msg 100/INVITE/cseq=1 (tdta0x7f52ec144bf0) on fork 0, state = Proceeding 28-08-2014 12:57:46.445 UTC Info scscfsproutlet.cpp:419: S-CSCF received response 28-08-2014 12:57:46.445 UTC Verbose sproutletproxy.cpp:1254: scscf-0x7f52ec10d4b0 sending Response msg 100/INVITE/cseq=1 (tdta0x7f52ec144bf0) 28-08-2014 12:57:46.445 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.445 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 100 28-08-2014 12:57:46.445 UTC Debug sproutletproxy.cpp:1635: Discard 100/INVITE response (tdta0x7f52ec144bf0) 28-08-2014 12:57:46.445 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec145200 => txdata 0x7f52ec144c98 mapping 28-08-2014 12:57:46.446 UTC Debug pjsip: tdta0x7f52ec14 Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f52ec144bf0) 28-08-2014 12:57:46.446 UTC Debug pjutils.cpp:672: Cloned tdta0x7f52ec12e610 to tdta0x7f52ec144bf0 28-08-2014 12:57:46.446 UTC Debug sproutletproxy.cpp:1107: Remove top Route header Route: <sip:bgcf.ppsprout1.ims.prod.lan:5054;transport=TCP;lr> 28-08-2014 12:57:46.446 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec145200 => txdata 0x7f52ec144c98 mapping 28-08-2014 12:57:46.446 UTC Verbose sproutletproxy.cpp:1406: bgcf-0x7f52ec0f1ac0 pass initial request Request msg INVITE/cseq=1 (tdta0x7f52ec144bf0) to Sproutlet 28-08-2014 12:57:46.446 UTC Debug acr.cpp:1495: Create RalfACR for node type BGCF with role Terminating 28-08-2014 12:57:46.446 UTC Debug acr.cpp:48: Created ACR (0x7f52ec147b60) 28-08-2014 12:57:46.446 UTC Debug acr.cpp:195: Created BGCF Ralf ACR 28-08-2014 12:57:46.446 UTC Debug acr.cpp:274: Set record type for I-CSCF, BGCF, IBCF, AS to EVENT_RECORD 28-08-2014 12:57:46.446 UTC Debug bgcfservice.cpp:137: Getting route for URI domain 10.20.2.3 via BGCF lookup 28-08-2014 12:57:46.447 UTC Info bgcfservice.cpp:143: Found route to domain 10.20.2.3 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1202: Sproutlet send_request 0x7f52ec145200 28-08-2014 12:57:46.447 UTC Verbose sproutletproxy.cpp:1227: bgcf-0x7f52ec0f1ac0 sending Request msg INVITE/cseq=1 (tdta0x7f52ec144bf0) on fork 0 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 0 responses, 1 requests 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1575: Processing request 0x7f52ec144c98, fork = 0 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1696: bgcf-0x7f52ec0f1ac0 transmitting request on fork 0 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1710: bgcf-0x7f52ec0f1ac0 store reference to non-ACK request Request msg INVITE/cseq=1 (tdta0x7f52ec144bf0) on fork 0 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec145200 => txdata 0x7f52ec144c98 mapping 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:133: Find target Sproutlet for request 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:145: Found top Route header: Route: <sip:10.20.2.3:5060;transport=UDP;lr;orig> 28-08-2014 12:57:46.447 UTC Debug sproutletproxy.cpp:152: Initial request 28-08-2014 12:57:46.448 UTC Debug sproutletproxy.cpp:202: Look for Sproutlet host mapping for 28-08-2014 12:57:46.448 UTC Debug pjsip: tsx0x7f52ec147 Transaction created for Request msg INVITE/cseq=1 (tdta0x7f52ec144bf0) 28-08-2014 12:57:46.448 UTC Debug basicproxy.cpp:1559: Added trail identifier 17 to UAC transaction 28-08-2014 12:57:46.448 UTC Debug pjutils.cpp:484: Next hop node is encoded in top route header 28-08-2014 12:57:46.448 UTC Debug sipresolver.cpp:85: SIPResolver::resolve for name , port 0, transport -1, family 2 28-08-2014 12:57:46.448 UTC Debug baseresolver.cpp:511: Attempt to parse as IP address 28-08-2014 12:57:46.448 UTC Debug sipresolver.cpp:144: Do NAPTR look-up for 28-08-2014 12:57:46.448 UTC Debug ttlcache.h:128: Entry not in cache, so create new entry 28-08-2014 12:57:46.448 UTC Debug baseresolver.cpp:551: NAPTR cache factory called for 28-08-2014 12:57:46.449 UTC Debug baseresolver.cpp:563: Sending DNS NAPTR query for 28-08-2014 12:57:46.449 UTC Debug dnscachedresolver.cpp:136: Create cache entry pending query 28-08-2014 12:57:46.449 UTC Debug dnscachedresolver.cpp:148: Create and execute DNS query transaction 28-08-2014 12:57:46.449 UTC Debug dnscachedresolver.cpp:154: Wait for query responses 28-08-2014 12:57:46.450 UTC Debug dnscachedresolver.cpp:384: Received DNS response for type NAPTR 28-08-2014 12:57:46.450 UTC Debug dnscachedresolver.cpp:533: Adding to cache expiry list with expiry time of 1409230966 28-08-2014 12:57:46.450 UTC Debug dnscachedresolver.cpp:158: Received all query responses 28-08-2014 12:57:46.450 UTC Debug dnscachedresolver.cpp:179: Pulling 0 records from cache for NAPTR 28-08-2014 12:57:46.450 UTC Debug ttlcache.h:148: Adding entry to expiry list, TTL=300, expiry time = 1409230966 28-08-2014 12:57:46.450 UTC Debug sipresolver.cpp:193: NAPTR lookup failed, so do SRV lookups for UDP and TCP 28-08-2014 12:57:46.450 UTC Verbose dnscachedresolver.cpp:207: Check cache for _sip._udp. type 33 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:210: No entry found in cache 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:213: Create cache entry pending query 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:228: Create and execute DNS query transaction 28-08-2014 12:57:46.451 UTC Verbose dnscachedresolver.cpp:207: Check cache for _sip._tcp. type 33 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:210: No entry found in cache 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:213: Create cache entry pending query 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:228: Create and execute DNS query transaction 28-08-2014 12:57:46.451 UTC Debug dnscachedresolver.cpp:241: Wait for query responses 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:384: Received DNS response for _sip._udp. type SRV 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:533: Adding _sip._udp. to cache expiry list with expiry time of 1409230966 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:384: Received DNS response for _sip._tcp. type SRV 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:533: Adding _sip._tcp. to cache expiry list with expiry time of 1409230966 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:245: Received all query responses 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:275: Pulling 0 records from cache for _sip._udp. SRV 28-08-2014 12:57:46.452 UTC Debug dnscachedresolver.cpp:275: Pulling 0 records from cache for _sip._tcp. SRV 28-08-2014 12:57:46.452 UTC Debug sipresolver.cpp:209: UDP SRV record _sip._udp. returned 0 records 28-08-2014 12:57:46.452 UTC Debug sipresolver.cpp:212: TCP SRV record _sip._tcp. returned 0 records 28-08-2014 12:57:46.453 UTC Debug sipresolver.cpp:232: UDP and TCP SRV queries unsuccessful, default to UDP 28-08-2014 12:57:46.453 UTC Debug sipresolver.cpp:295: Perform A/AAAA record lookup only, name = 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:136: Create cache entry pending query 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:148: Create and execute DNS query transaction 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:154: Wait for query responses 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:384: Received DNS response for type A 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:533: Adding to cache expiry list with expiry time of 1409230966 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:158: Received all query responses 28-08-2014 12:57:46.453 UTC Debug dnscachedresolver.cpp:179: Pulling 0 records from cache for A 28-08-2014 12:57:46.453 UTC Debug baseresolver.cpp:359: Found 0 A/AAAA records, randomizing 28-08-2014 12:57:46.453 UTC Debug baseresolver.cpp:418: Adding 0 servers from blacklist 28-08-2014 12:57:46.453 UTC Info pjutils.cpp:919: Resolved destination URI <sip:10.20.2.3:5060;transport=UDP;lr;orig> to 0 servers 28-08-2014 12:57:46.453 UTC Debug basicproxy.cpp:1586: Sending request for sip:[email protected]<mailto:sip%[email protected]> 28-08-2014 12:57:46.453 UTC Debug basicproxy.cpp:1635: Failed to send request (70006 Not found (PJ_ENOTFOUND)) 28-08-2014 12:57:46.453 UTC Debug basicproxy.cpp:1363: Dissociate UAC transaction 0x7f52ec1449f0 for target 0 28-08-2014 12:57:46.453 UTC Verbose sproutletproxy.cpp:1469: bgcf-0x7f52ec0f1ac0 received error TRANSPORT_ERROR on fork 0, state = Calling 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.454 UTC Debug acr.cpp:1229: Stored 0 subscription identifiers 28-08-2014 12:57:46.454 UTC Verbose sproutletproxy.cpp:1254: bgcf-0x7f52ec0f1ac0 sending Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 503 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1668: 3xx/4xx/5xx/6xx response 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1672: Best 3xx/4xx/5xx/6xx response so far 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1560: All UAC responded 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.454 UTC Verbose sproutletproxy.cpp:1440: scscf-0x7f52ec10d4b0 received final response Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) on fork 0, state = Terminated 28-08-2014 12:57:46.454 UTC Info scscfsproutlet.cpp:419: S-CSCF received response 28-08-2014 12:57:46.454 UTC Verbose sproutletproxy.cpp:1254: scscf-0x7f52ec10d4b0 sending Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 503 28-08-2014 12:57:46.454 UTC Debug sproutletproxy.cpp:1668: 3xx/4xx/5xx/6xx response 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1672: Best 3xx/4xx/5xx/6xx response so far 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1560: All UAC responded 28-08-2014 12:57:46.455 UTC Debug acr.cpp:598: Failed to start session, change record type to EVENT_RECORD 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.455 UTC Verbose sproutletproxy.cpp:1440: mmtel-0x7f52ec10eda0 received final response Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) on fork 0, state = Terminated 28-08-2014 12:57:46.455 UTC Verbose sproutletproxy.cpp:1254: mmtel-0x7f52ec10eda0 sending Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 503 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1668: 3xx/4xx/5xx/6xx response 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1672: Best 3xx/4xx/5xx/6xx response so far 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1560: All UAC responded 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1523: Adding message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.455 UTC Verbose sproutletproxy.cpp:1440: scscf-0x7f52ec0859c0 received final response Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) on fork 0, state = Terminated 28-08-2014 12:57:46.455 UTC Info scscfsproutlet.cpp:419: S-CSCF received response 28-08-2014 12:57:46.455 UTC Verbose sproutletproxy.cpp:1254: scscf-0x7f52ec0859c0 sending Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1538: Processing actions from sproutlet - 1 responses, 0 requests 28-08-2014 12:57:46.455 UTC Debug sproutletproxy.cpp:1618: Aggregating response with status code 503 28-08-2014 12:57:46.456 UTC Debug sproutletproxy.cpp:1668: 3xx/4xx/5xx/6xx response 28-08-2014 12:57:46.456 UTC Debug sproutletproxy.cpp:1672: Best 3xx/4xx/5xx/6xx response so far 28-08-2014 12:57:46.456 UTC Debug sproutletproxy.cpp:1560: All UAC responded 28-08-2014 12:57:46.456 UTC Debug sproutletproxy.cpp:1530: Removing message 0x7f52ec1491a0 => txdata 0x7f52ec148c38 mapping 28-08-2014 12:57:46.456 UTC Debug pjsip: tsx0x7f52ec08d Sending Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) in state Proceeding 28-08-2014 12:57:46.456 UTC Debug pjsip: tdta0x7f52ec09 Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f52ec090320) 28-08-2014 12:57:46.456 UTC Verbose stack.cpp:249: TX 795 bytes Response msg 503/INVITE/cseq=1 (tdta0x7f52ec148b90) to TCP 10.99.99.162:47275<http://10.99.99.162:47275>: --start msg-- SIP/2.0 503 Service Unavailable Via: SIP/2.0/ ;branch=z9hG4bKPjIrnqg53RxY-gcKURGngApeG6g7bQO3fP Via: SIP/2.0/TCP 10.99.99.162:47275;rport=47275;received=10.99.99.162;branch=z9hG4bKPjyf38gSXQ1IeQVJaconeRDN1yHSQV4AKs Via: SIP/2.0/UDP 172.16.31.134:28314;rport=28314;received=172.16.31.134;branch=z9hG4bK-d8754z-33a53a14407fb96c-1---d8754z- Record-Route: <sip:ppsprout1.ims.prod.lan:5054;transport=TCP;lr;service=scscf/charge-orig> Record-Route: <sip:10.99.99.162:5058;transport=TCP;lr> Record-Route: <sip:[email protected]:5060;transport=UDP;lr> Call-ID: NjQ1MTUwMDU4NzIxMWJhYmRhZTc1OTgwMzdmNTRhZTQ From: "Rugs" <sip:[email protected]>;tag=94514c2a To: <sip:+37799666385<tel:%2B37799666385>@ims.prod.lan>;tag=z9hG4bKPjIrnqg53RxY-gcKURGngApeG6g7bQO3fP CSeq: 1 INVITE Content-Length: 0 --end msg-- 2014-08-28 14:50 GMT+02:00 Eleanor Merry <[email protected]<mailto:[email protected]>>: Hi, Thanks for raising this. I've raised an issue for the first problem (https://github.com/Metaswitch/sprout/issues/743) to fix up the missing angle brackets. For the second problem can you please send me the Sprout debug logs? These are in /var/log/sprout/. To set the log level to debug, write log_level=5 to /etc/clearwater/user_settings (creating it if it doesn't exist already), and restart Sprout (sudo service sprout stop, it's automatically starting again by monit). Also, what version of Clearwater are you running, and can you give me more details about the scenario you're testing (so that I can try to reproduce it here)? Ellie -----Original Message----- From: [email protected]<mailto:[email protected]> [mailto:[email protected]<mailto:[email protected]>] On Behalf Of Ruggero Schiavi Sent: 28 August 2014 13:40 To: [email protected]<mailto:[email protected]> Subject: Re: [Clearwater] Path header in 200 OK SIP message Second problem (if you prefer I post a new question) after the update the calls directed to the PSTN are not forwarded but Sprout answers with 503 Service unavailable. I didn't configure the new components just did the upgrade to have bug correction. Please, how can I have them working again? Thank you, Abaco 2014-08-27 16:14 GMT+02:00 Ruggero Schiavi <[email protected]<mailto:[email protected]>>: > Hi, > > We are integrating the Clearwater IMS core in our telecom Network but, > before, we are introducing an external Session Border Controller (SBC) > between the users and the Bono node. > > The SBC handles the REGISTER messages and Bono is able to correctly > authenticate the user sending an 200 OK to our SBC. > > The problem is that the SBC discard the 200 OK message because it > finds an error in one of the Header, to be precise in the path header. > > "Path: sip:4Ukt6/[email protected]:5058;transport=TCP;lr;ob" > > The header is misparsed because of the missing <> that seem to be > required following RFC 3327: http://tools.ietf.org/html/rfc3327 > > Is there a way to correct this header? If not, is it possible to avoid > to put the header in the OK message? > > I hope I made myself clear and I really appreciate any help you can > provide, > > > Abaco > _______________________________________________ Clearwater mailing list [email protected]<mailto:[email protected]> http://lists.projectclearwater.org/listinfo/clearwater _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater
