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:[email protected]
>;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
