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