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]>: > 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 > 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 > 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]!" > 28-08-2014 12:57:46.443 UTC Debug enumservice.cpp:70: Split regex into > match=^\+377(.*$), replace=sip:\[email protected] > 28-08-2014 12:57:46.443 UTC Debug enumservice.cpp:375: Enum lookup > completes: sip:[email protected] > 28-08-2014 12:57:46.443 UTC Debug scscfsproutlet.cpp:1172: Update request > URI to 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] > 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: > --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 > @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]>: > > 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]] On Behalf Of Ruggero >> Schiavi >> Sent: 28 August 2014 13:40 >> To: [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]>: >> >> > 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] >> http://lists.projectclearwater.org/listinfo/clearwater >> > > _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater
