Hi,
We encountered a situation where the To field of the INVITE we sent to sprout
is being changed when routing to the next node. It is our understanding that
the To and From header should not be altered unless behaving as B2BUA. Please
see the log snippet below where I highlighted the header in question as the
port information was dropped from the URI when the INVITE is being routed to
the next node. Any feedback is appreciated.
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug pjsip: sip_endpoint.c
Processing incoming message: Request msg INVITE/cseq=1 (rdata0x7f13b0ade090)
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Verbose
common_sip_processing.cpp:87: RX 1688 bytes Request msg INVITE/cseq=1
(rdata0x7f13b0ade090) from TCP 172.27.0.81:54858:
--start msg--
INVITE sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2 SIP/2.0
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2>
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
CSeq: 1 INVITE
Allow-Events: conference
Supported: timer, recipient-list-invite
Contact:
<sip:imserv...@sc01.sales2.iot1.com:5510;as.session=1>;isfocus;+g.oma.sip-im;+g.3gpp.iari-ref="urn%3Aurn-7%3A3gpp-application.ims.iari.rcse.im,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.fthttp,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.geopush"
Accept-Contact: *;+g.oma.sip-im
Allow: INVITE,ACK,CANCEL,BYE,UPDATE,REFER,SUBSCRIBE,MESSAGE,REGISTER,INFO
User-Agent: IM-serv/OMA2.0 Sales2-RMS/5.0
Session-Expires: 600;refresher=uas
P-Asserted-Identity: <sip:+18152579...@sales2.iot1.com>
Subject: Football teams
Contribution-ID: d285786190f6407ebcd0f861f1645183
Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com:5054;transport=TCP;lr;orig;service=scscf>
Content-Type: application/sdp
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Content-Length: 397
Max-Forwards: 70
v=0
o=- 16054947157924516231 16054947157924563475 IN IP4 172.28.0.81
s=-
c=IN IP4 172.28.0.81
t=0 0
m=message 9000 TCP/MSRP *
a=accept-types:message/cpim
a=accept-wrapped-types:text/plain message/imdn+xml
application/im-iscomposing+xml application/vnd.gsma.rcs-ft-http+xml
application/vnd.gsma.rcspushlocation+xml
a=msrp-cema
a=path:msrp://172.28.0.81:9000/Cw2E0W2t;tcp
a=setup:actpass
--end msg--
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug pjutils.cpp:1771: Logging SAS
Call-ID marker, Call-ID 13d56e67-87aa-4ae5-be66-8fc11e800eee
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug thread_dispatcher.cpp:554:
Recieved message 0x7f13b0ade090 on worker thread
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug thread_dispatcher.cpp:571:
Admitted request 0x7f13b0ade090 on worker thread
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug thread_dispatcher.cpp:606:
Incoming message 0x7f13b0ade090 cloned to 0x7f13b0ae4598
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug thread_dispatcher.cpp:625:
Queuing cloned received message 0x7f13b0ae4598 for worker threads with priority 0
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug
event_statistic_accumulator.cpp:32: Accumulate 0 for 0x1a24748
15-06-2018 20:34:40.609 UTC [7f13b53d1700] Debug
event_statistic_accumulator.cpp:32: Accumulate 0 for 0x1a247c0
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug utils.cpp:872: Added IOHook
0x7f14414e8e30 to stack. There are now 1 hooks
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug thread_dispatcher.cpp:178:
Worker thread dequeue message 0x7f13b0ae4598
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug thread_dispatcher.cpp:183:
Request latency so far = 59us
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: sip_endpoint.c
Distributing rdata to modules: Request msg INVITE/cseq=1 (rdata0x7f13b0ae4598)
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug basicproxy.cpp:62: Process
INVITE request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:631:
Sproutlet Proxy transaction (0x7f13840db0f0) created. There are now 2 instances
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug basicproxy.cpp:1318: Report
SAS start marker - trail (15ddc)
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjutils.cpp:719: Cloned
Request msg INVITE/cseq=1 (rdata0x7f13b0ae4598) to tdta0x7f13842c6640
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: tsx0x7f13840a0
Transaction created for Request msg INVITE/cseq=1 (rdata0x7f13b0ae4598)
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: tsx0x7f13840a0 Incoming
Request msg INVITE/cseq=1 (rdata0x7f13b0ae4598) in state Null
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: tsx0x7f13840a0 State
changed from Null to Trying, event=RX_MSG
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug basicproxy.cpp:183:
tsx0x7f13840a0148 - tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: endpoint Response
msg 408/INVITE/cseq=1 (tdta0x7f13842274b0) created
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug basicproxy.cpp:598: Send
immediate 100 Trying response
15-06-2018 20:34:40.609 UTC [7f14414e9700] Info pjutils.cpp:1719: Cloning
header! 139721798612272
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: tsx0x7f13840a0 Sending
Response msg 100/INVITE/cseq=1 (tdta0x7f138421d5a0) in state Trying
15-06-2018 20:34:40.609 UTC [7f14414e9700] Verbose
common_sip_processing.cpp:103: TX 472 bytes Response msg 100/INVITE/cseq=1
(tdta0x7f138421d5a0) to TCP 172.27.0.81:54858:
--start msg--
SIP/2.0 100 Trying
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;received=172.27.0.81;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com;as.session=2>
CSeq: 1 INVITE
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Content-Length: 0
--end msg--
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjsip: tsx0x7f13840a0 State
changed from Trying to Proceeding, event=TX_MSG
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug basicproxy.cpp:183:
tsx0x7f13840a0148 - tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Proceeding
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI:
sip:odi_kcvomzh...@sprout01.sales2.iot1.com:5054;transport=TCP;lr;orig;service=scscf
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - scscf
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:429:
Creating URI for service registrar
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - scscf
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:455:
Constructed URI
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=registrar
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:1276:
Remove top Route header Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com:5054;transport=TCP;lr;orig;service=scscf>
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI:
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=registrar
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - registrar
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:429:
Creating URI for service subscription
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - registrar
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:455:
Constructed URI
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=subscription
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:1276:
Remove top Route header Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=registrar>
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI:
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=subscription
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - subscription
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:429:
Creating URI for service scscf-proxy
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - subscription
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:455:
Constructed URI
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=scscf-proxy
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:1276:
Remove top Route header Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=subscription>
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI:
sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=scscf-proxy
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:302: Found
services param - scscf-proxy
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug scscfsproutlet.cpp:424: S-CSCF
Transaction (0x7f138414a7f0) created
15-06-2018 20:34:40.609 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1384:
Created Sproutlet scscf-proxy-0x7f138414a7f0 for Request msg INVITE/cseq=1
(tdta0x7f13842c6640)
15-06-2018 20:34:40.609 UTC [7f14414e9700] Verbose sproutletproxy.cpp:2487:
Routing Request msg INVITE/cseq=1 (tdta0x7f13842c6640) (1716 bytes) to
downstream sproutlet scscf-proxy:
--start msg--
INVITE sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2 SIP/2.0
Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=scscf-proxy>
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;received=172.27.0.81;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com;as.session=2>
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
CSeq: 1 INVITE
Allow-Events: conference
Supported: timer, recipient-list-invite
Contact:
<sip:imserv...@sc01.sales2.iot1.com:5510;as.session=1>;isfocus;+g.oma.sip-im;+g.3gpp.iari-ref="urn%3Aurn-7%3A3gpp-application.ims.iari.rcse.im,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.fthttp,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.geopush"
Accept-Contact: *;+g.oma.sip-im
Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, SUBSCRIBE, MESSAGE, REGISTER,
INFO
User-Agent: IM-serv/OMA2.0 Sales2-RMS/5.0
Session-Expires: 600;refresher=uas
P-Asserted-Identity: <sip:+18152579...@sales2.iot1.com>
Subject: Football teams
Contribution-ID: d285786190f6407ebcd0f861f1645183
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Max-Forwards: 70
Content-Type: application/sdp
Content-Length: 397
v=0
o=- 16054947157924516231 16054947157924563475 IN IP4 172.28.0.81
s=-
c=IN IP4 172.28.0.81
t=0 0
m=message 9000 TCP/MSRP *
a=accept-types:message/cpim
a=accept-wrapped-types:text/plain message/imdn+xml
application/im-iscomposing+xml application/vnd.gsma.rcs-ft-http+xml
application/vnd.gsma.rcspushlocation+xml
a=msrp-cema
a=path:msrp://172.28.0.81:9000/Cw2E0W2t;tcp
a=setup:actpass
--end msg--
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('EXTERNAL'->'scscf'/'scscf-proxy')
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('EXTERNAL'->'scscf'/'scscf-proxy')
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:2517:
Internal network function boundary: no
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjutils.cpp:736: Cloned
tdta0x7f13842c6640 to tdta0x7f13842bb9a0
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:1450:
Remove top Route header Route:
<sip:odi_kcvomzh...@sprout01.sales2.iot1.com;transport=TCP;lr;orig;service=scscf-proxy>
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug sproutletproxy.cpp:2115:
Adding message 0x7f13842bbfb0 => txdata 0x7f13842bba48 mapping
15-06-2018 20:34:40.609 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1946:
scscf-proxy-0x7f138414a7f0 pass initial request Request msg INVITE/cseq=1
(tdta0x7f13842bb9a0) to Sproutlet
15-06-2018 20:34:40.609 UTC [7f14414e9700] Info scscfsproutlet.cpp:471: S-CSCF
received initial request
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: true, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 3
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug scscfsproutlet.cpp:945: Route
header references this system
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug scscfsproutlet.cpp:958: Found
ODI token KCVomZHKWX
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug aschain.h:109: AsChain inc ref
0x7f138009e4d0 -> 2
15-06-2018 20:34:40.609 UTC [7f14414e9700] Info scscfsproutlet.cpp:965:
Original dialog for odi_KCVomZHKWX found: AsChain-orig[0x7f138009e4d0]:2/2
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug scscfsproutlet.cpp:1004: Got
our Route header, session case orig, OD=AsChain-orig[0x7f138009e4d0]:2/2
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug pjutils.cpp:294: Served user
from P-Asserted-Identity header
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: true, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 4
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: true, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 4
15-06-2018 20:34:40.609 UTC [7f14414e9700] Info scscfsproutlet.cpp:635: Found
served user, so apply services
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug scscfsproutlet.cpp:1384:
Performing originating initiating request processing
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug aschain.cpp:190: Asssociating
original SAS trail 89563 with new message SAS trail 89564
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug ifc.cpp:428: SPT class Method:
result false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug ifc.cpp:428: SPT class Method:
result false
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug ifc.cpp:582: iFC does not match
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug ifc.cpp:589: AND each SPT
match result to determine group result.
OR each group result to determine overall iFC match.
SPT in group 0 is not matched.
SPT in group 1 is not matched.
Group 0 is not matched.
Group 1 is not matched.
15-06-2018 20:34:40.609 UTC [7f14414e9700] Info scscfsproutlet.cpp:1420:
Completed applying originating services
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: false, treat_number_as_phone: true
15-06-2018 20:34:40.609 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info scscfsproutlet.cpp:1436: New
URI string is sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug scscfsproutlet.cpp:1452:
Routing to BGCF
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info scscfsproutlet.cpp:1719:
Routing to BGCF sip:bgcf.sprout.sales2.iot1.com;transport=TCP
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:1621:
Sproutlet send_request 0x7f13842bbfb0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1662:
scscf-proxy-0x7f138414a7f0 sending Request msg INVITE/cseq=1
(tdta0x7f13842bb9a0) on fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2130:
Processing actions from sproutlet - 0 responses, 1 requests, 0 timers
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2170:
Processing request 0x7f13842bba48, fork = 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2334:
scscf-proxy-0x7f138414a7f0 transmitting request on fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2349:
scscf-proxy-0x7f138414a7f0 store reference to non-ACK request Request msg
INVITE/cseq=1 (tdta0x7f13842bb9a0) on fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2122:
Removing message 0x7f13842bbfb0 => txdata 0x7f13842bba48 mapping
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI: sip:bgcf.sprout.sales2.iot1.com;transport=TCP;lr
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:342:
Possible service name bgcf will be used if sprout.sales2.iot1.com is a local
hostname
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1384:
Created Sproutlet bgcf-0x7f138421a4d0 for Request msg INVITE/cseq=1
(tdta0x7f13842bb9a0)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('scscf'->'bgcf'/'bgcf')
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info pjutils.cpp:1719: Cloning
header! 139721798572184
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:2487:
Routing Response msg 100/INVITE/cseq=1 (tdta0x7f13842c2940) (472 bytes) to
upstream sproutlet scscf-proxy:
--start msg--
SIP/2.0 100 Trying
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;received=172.27.0.81;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com;as.session=2>
CSeq: 1 INVITE
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Content-Length: 0
--end msg--
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2115:
Adding message 0x7f13842c2f50 => txdata 0x7f13842c29e8 mapping
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1991:
scscf-proxy-0x7f138414a7f0 received provisional response Response msg
100/INVITE/cseq=1 (tdta0x7f13842c2940) on fork 0, state = Proceeding
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info scscfsproutlet.cpp:728: S-CSCF
received response
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('EXTERNAL'->'scscf'/'scscf-proxy')
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2517:
Internal network function boundary: no
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1698:
scscf-proxy-0x7f138414a7f0 sending Response msg 100/INVITE/cseq=1
(tdta0x7f13842c2940)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2130:
Processing actions from sproutlet - 1 responses, 0 requests, 0 timers
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2216:
Aggregating response with status code 100
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('EXTERNAL'->'scscf'/'scscf-proxy')
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2235:
Discard 100/INVITE response (tdta0x7f13842c2940)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2122:
Removing message 0x7f13842c2f50 => txdata 0x7f13842c29e8 mapping
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjsip: tdta0x7f13842c
Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f13842c2940)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:2487:
Routing Request msg INVITE/cseq=1 (tdta0x7f13842bb9a0) (1679 bytes) to
downstream sproutlet bgcf:
--start msg--
INVITE sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2 SIP/2.0
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;received=172.27.0.81;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com;as.session=2>
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
CSeq: 1 INVITE
Allow-Events: conference
Supported: timer, recipient-list-invite
Contact:
<sip:imserv...@sc01.sales2.iot1.com:5510;as.session=1>;isfocus;+g.oma.sip-im;+g.3gpp.iari-ref="urn%3Aurn-7%3A3gpp-application.ims.iari.rcse.im,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.fthttp,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.geopush"
Accept-Contact: *;+g.oma.sip-im
Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, SUBSCRIBE, MESSAGE, REGISTER,
INFO
User-Agent: IM-serv/OMA2.0 Sales2-RMS/5.0
Session-Expires: 600;refresher=uas
P-Asserted-Identity: <sip:+18152579...@sales2.iot1.com>
Subject: Football teams
Contribution-ID: d285786190f6407ebcd0f861f1645183
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Max-Forwards: 69
Route: <sip:bgcf.sprout.sales2.iot1.com;transport=TCP;lr>
Content-Type: application/sdp
Content-Length: 397
v=0
o=- 16054947157924516231 16054947157924563475 IN IP4 172.28.0.81
s=-
c=IN IP4 172.28.0.81
t=0 0
m=message 9000 TCP/MSRP *
a=accept-types:message/cpim
a=accept-wrapped-types:text/plain message/imdn+xml
application/im-iscomposing+xml application/vnd.gsma.rcs-ft-http+xml
application/vnd.gsma.rcspushlocation+xml
a=msrp-cema
a=path:msrp://172.28.0.81:9000/Cw2E0W2t;tcp
a=setup:actpass
--end msg--
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('scscf'->'bgcf'/'bgcf')
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2504:
Network function boundary: yes ('scscf'->'bgcf'/'bgcf')
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2517:
Internal network function boundary: yes
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjutils.cpp:736: Cloned
tdta0x7f13842bb9a0 to tdta0x7f13842c2940
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:1450:
Remove top Route header Route:
<sip:bgcf.sprout.sales2.iot1.com;transport=TCP;lr>
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2115:
Adding message 0x7f13842c2f50 => txdata 0x7f13842c29e8 mapping
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1946:
bgcf-0x7f138421a4d0 pass initial request Request msg INVITE/cseq=1
(tdta0x7f13842c2940) to Sproutlet
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug acr.cpp:1797: Create RalfACR
for node type BGCF with role Terminating
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug acr.cpp:24: Created ACR
(0x7f138415f780)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug acr.cpp:170: Created BGCF Ralf
ACR
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug acr.cpp:250: Set record type
for I-CSCF, BGCF, IBCF, AS to EVENT_RECORD
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug acr.cpp:1596: Found
P-Charging-Vector header, store information
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjutils.cpp:2536: Not
translating URI
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:139: home
domain: false, local_to_node: false, is_gruu: false, enforce_user_phone: false,
prefer_sip: true, treat_number_as_phone: false
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug uri_classifier.cpp:172:
Classified URI as 5
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug bgcfservice.cpp:163: Getting
route for URI domain sc01.ivc.iot1.com via BGCF lookup
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info bgcfservice.cpp:194: Found
default route
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:1621:
Sproutlet send_request 0x7f13842c2f50
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose sproutletproxy.cpp:1662:
bgcf-0x7f138421a4d0 sending Request msg INVITE/cseq=1 (tdta0x7f13842c2940) on
fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2130:
Processing actions from sproutlet - 0 responses, 1 requests, 0 timers
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2170:
Processing request 0x7f13842c29e8, fork = 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2334:
bgcf-0x7f138421a4d0 transmitting request on fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2349:
bgcf-0x7f138421a4d0 store reference to non-ACK request Request msg
INVITE/cseq=1 (tdta0x7f13842c2940) on fork 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:2122:
Removing message 0x7f13842c2f50 => txdata 0x7f13842c29e8 mapping
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:165: Find
target Sproutlet for request
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:199: Found
next routable URI: sip:kambgcf.sales2.iot1.com;lr
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:342:
Possible service name kambgcf will be used if sales2.iot1.com is a local
hostname
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sproutletproxy.cpp:1007: No
local sproutlet matches request
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjsip: tsx0x7f1384016
Transaction created for Request msg INVITE/cseq=1 (tdta0x7f13842c2940)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:1669: Added
trail identifier 89564 to UAC transaction
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjutils.cpp:510: Next hop node
is encoded in top route header
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sipresolver.cpp:84:
SIPResolver::resolve for name kambgcf.sales2.iot1.com, port 0, transport -1,
family 2
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug utils.cpp:446: Attempt to
parse kambgcf.sales2.iot1.com as IP address
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sipresolver.cpp:147: Do NAPTR
look-up for kambgcf.sales2.iot1.com
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug ttlcache.h:123: Found the
entry in the cache
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sipresolver.cpp:161: NAPTR
resolved to transport 6
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug sipresolver.cpp:281: Do SRV
lookup for _sip._tcp.kambgcf.sales2.iot1.com
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:156: Creating
a lazy iterator for SRV Resolution
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug ttlcache.h:123: Found the
entry in the cache
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:994: Found
SRV records at 2 priority levels
15-06-2018 20:34:40.610 UTC [7f14414e9700] Info pjutils.cpp:989: Resolved
destination URI sip:kambgcf.sales2.iot1.com;lr
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjutils.cpp:510: Next hop node
is encoded in top route header
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:1693: Next hop
kambgcf.sales2.iot1.com is not a stateless proxy
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:1707: Sending
request for sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose baseresolver.cpp:1127:
Processing 1 SRVs with priority 0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:1155:
Selected SRV kambgcf01.sales2.iot1.com:5060, weight = 1
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose baseresolver.cpp:1170: Do A
record look-ups for 1 SRVs
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose dnscachedresolver.cpp:468:
Check cache for kambgcf01.sales2.iot1.com type 1
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug dnscachedresolver.cpp:578:
Pulling 1 records from cache for kambgcf01.sales2.iot1.com A
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:1186: SRV
kambgcf01.sales2.iot1.com:5060 returned 1 IP addresses
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:587:
172.27.0.146:5060;transport=TCP has state: WHITE
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:587:
172.27.0.146:5060;transport=TCP has state: WHITE
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug baseresolver.cpp:1365: Added a
whitelisted server to targets, now have 1 of 1
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:2391: Selected
host 172.27.0.146:5060;transport=TCP (will be blacklisted by default)
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjsip: tsx0x7f1384016 Sending
Request msg INVITE/cseq=1 (tdta0x7f13842c2940) in state Null
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjsip: endpoint Request
msg INVITE/cseq=1 (tdta0x7f13842c2940): skipping target resolution because
address is already set
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose pjsip: tcpc0x7f138427
tcp->base.local_name: 172.27.0.41
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose pjsip: tcpc0x7f138427 TCP
client transport created
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose pjsip: tcpc0x7f138427 TCP
transport 172.27.0.41:5052 is connecting to 172.27.0.146:5060...
15-06-2018 20:34:40.610 UTC [7f14414e9700] Verbose
common_sip_processing.cpp:103: TX 1847 bytes Request msg INVITE/cseq=1
(tdta0x7f13842c2940) to TCP 172.27.0.146:5060:
--start msg--
INVITE sip:imserv...@sc01.ivc.iot1.com:5510;as.session=2 SIP/2.0
Via: SIP/2.0/TCP
172.27.0.41:5052;rport;branch=z9hG4bKPjITQ.nIYhWd3YTUq9YXS6JDB6sfrKq5Yj
Via: SIP/2.0/TCP
scscf.sprout.sales2.iot1.com;branch=z9hG4bKPjqNHu5LgfrVs3vsIS7SNT1UzfjEmpR6qz
Via: SIP/2.0/TCP
sc01.sales2.iot1.com:5510;received=172.27.0.81;branch=z9hG4bKc3e26892-2a39-4ba3-887d-5545a9ef68c6
From: <tel:+18152579478>;tag=46bb82fb-4f96-42b7-9dcc-9f67d20c0d55
To: <sip:imserv...@sc01.ivc.iot1.com;as.session=2>
Call-ID: 13d56e67-87aa-4ae5-be66-8fc11e800eee
CSeq: 1 INVITE
Allow-Events: conference
Supported: timer, recipient-list-invite
Contact:
<sip:imserv...@sc01.sales2.iot1.com:5510;as.session=1>;isfocus;+g.oma.sip-im;+g.3gpp.iari-ref="urn%3Aurn-7%3A3gpp-application.ims.iari.rcse.im,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.fthttp,urn%3Aurn-7%3A3gpp-application.ims.iari.rcs.geopush"
Accept-Contact: *;+g.oma.sip-im
Allow: INVITE, ACK, CANCEL, BYE, UPDATE, REFER, SUBSCRIBE, MESSAGE, REGISTER,
INFO
User-Agent: IM-serv/OMA2.0 Sales2-RMS/5.0
Session-Expires: 600;refresher=uas
P-Asserted-Identity: <sip:+18152579...@sales2.iot1.com>
Subject: Football teams
Contribution-ID: d285786190f6407ebcd0f861f1645183
P-Charging-Vector:
icid-value="58b84e00-61f9-1036-00-00-00-50-56-bb-20-00";orig-ioi=sales2.iot1.com;icid-generated-at=172.27.0.140
Max-Forwards: 68
Route: <sip:kambgcf.sales2.iot1.com;lr>
Content-Type: application/sdp
Content-Length: 397
v=0
o=- 16054947157924516231 16054947157924563475 IN IP4 172.28.0.81
s=-
c=IN IP4 172.28.0.81
t=0 0
m=message 9000 TCP/MSRP *
a=accept-types:message/cpim
a=accept-wrapped-types:text/plain message/imdn+xml
application/im-iscomposing+xml application/vnd.gsma.rcs-ft-http+xml
application/vnd.gsma.rcspushlocation+xml
a=msrp-cema
a=path:msrp://172.28.0.81:9000/Cw2E0W2t;tcp
a=setup:actpass
--end msg--
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug pjsip: tsx0x7f1384016 State
changed from Null to Calling, event=TX_MSG
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:183:
tsx0x7f13840162a8 - tu_on_tsx_state UAC, TSX_STATE TX_MSG state=Calling
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:1942:
tsx0x7f13840162a8 - uac_tsx = 0x7f138423aa60, uas_tsx = 0x7f13840db0f0
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:1950: TX_MSG
event on current UAC transaction
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug basicproxy.cpp:2280: Starting
timer C
15-06-2018 20:34:40.610 UTC [7f14414e9700] Debug thread_dispatcher.cpp:270:
Worker thread completed processing message 0x7f13b0ae4598
_______________________________________________
Clearwater mailing list
Clearwater@lists.projectclearwater.org
http://lists.projectclearwater.org/mailman/listinfo/clearwater_lists.projectclearwater.org
