Thanks a lot Matt. I had made the two domain names same. Now I reverted the deployment name to example.com and SIP OPTIONS are forwarded between the SIP clients.
Now, I am able to make the SIP INVITE reach the application server, but the INVITE request back from the AS is rejected at bono due to " 22-11-2013 00:15:43.364 Warning stateful_proxy.cpp:1186: Rejecting request from untrusted source" and SIP/2.0 403 Forbidden. Here are the logs from bono for SIP requests from SIP client -> Bono -> Sprout -> AS -> Bono. 22-11-2013 00:15:43.315 Debug pjsip: sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0x7f002803e308) 22-11-2013 00:15:43.319 Verbose stack.cpp:199: RX 997 bytes Request msg INVITE/cseq=1 (rdata0x7f002803e308) from TCP 66.126.253.90:60521: --start msg-- INVITE sip:[email protected];transport=TCP SIP/2.0 Via: SIP/2.0/TCP 66.126.253.90:60521;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z-;rport Max-Forwards: 70 Route: <sip:example.com:5054;transport=TCP;lr;orig> Contact: <sip:[email protected]:60521;transport=TCP> To: <sip:[email protected];transport=TCP> From: <sip:[email protected];transport=TCP>;tag=96c55a52 Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE Content-Type: application/sdp Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri User-Agent: Zoiper r20066 Allow-Events: presence, kpml Content-Length: 261 v=0 o=Zoiper 0 0 IN IP4 66.126.253.90 s=Zoiper c=IN IP4 66.126.253.90 t=0 0 m=audio 33000 RTP/AVP 110 3 0 8 98 a=rtpmap:110 speex/8000 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:98 iLBC/8000 a=fmtp:98 mode=30 a=sendrecv --end msg-- 22-11-2013 00:15:43.320 Debug stack.cpp:384: Queuing cloned received message 0x7f0028081738 for worker threads 22-11-2013 00:15:43.320 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028081738 22-11-2013 00:15:43.320 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Request msg INVITE/cseq=1 (rdata0x7f0028081738) 22-11-2013 00:15:43.320 Debug stateful_proxy.cpp:236: Proxy RX request 22-11-2013 00:15:43.320 Debug stateful_proxy.cpp:689: Request received on non-trusted port 5060 22-11-2013 00:15:43.320 Debug stateful_proxy.cpp:880: Perform edge proxy routing for INVITE request 22-11-2013 00:15:43.321 Debug pjutils.cpp:351: Found Route header, URI = sip:example.com:5054;transport=TCP;lr;orig 22-11-2013 00:15:43.321 Debug stateful_proxy.cpp:995: Message received on non-trusted port 5060 22-11-2013 00:15:43.321 Debug flowtable.cpp:137: Find flow for transport tcps0x7f002803e018 (2), remote address 66.126.253.90:60521 22-11-2013 00:15:43.321 Debug flowtable.cpp:574: Dialog count now 2 for flow sip:[email protected] 22-11-2013 00:15:43.321 Debug flowtable.cpp:151: Found flow record 0x7f0024008c20 22-11-2013 00:15:43.321 Debug stateful_proxy.cpp:1035: Message received on known client flow 22-11-2013 00:15:43.321 Debug stateful_proxy.cpp:1054: Request has no P-Preferred-Identity headers, so check for default identity on flow 22-11-2013 00:15:43.321 Debug pjutils.cpp:296: Adding P-Asserted-Identity header: sip:[email protected] 22-11-2013 00:15:43.321 Debug pjutils.cpp:351: Found Route header, URI = sip:example.com:5054;transport=TCP;lr;orig 22-11-2013 00:15:43.322 Info stateful_proxy.cpp:808: Route request to upstream proxy ec2-50-17-60-182.compute-1.amazonaws.com 22-11-2013 00:15:43.322 Debug stateful_proxy.cpp:853: Mark originating 22-11-2013 00:15:43.322 Debug pjutils.cpp:333: Next hop node is encoded in Request-URI 22-11-2013 00:15:43.322 Debug stateful_proxy.cpp:1243: Add record route header(s) 22-11-2013 00:15:43.322 Debug stateful_proxy.cpp:1248: Message received from client - double Record-Route 22-11-2013 00:15:43.322 Debug pjutils.cpp:389: Added Record-Route header, URI = sip:[email protected]:5060;transport=TCP;lr 22-11-2013 00:15:43.322 Debug pjutils.cpp:389: Added Record-Route header, URI = sip:10.239.38.59:5058;transport=TCP;lr 22-11-2013 00:15:43.322 Debug flowtable.cpp:591: Dialog count now 1 for flow sip:[email protected] 22-11-2013 00:15:43.322 Debug stateful_proxy.cpp:487: Trust mode INBOUND_EDGE_CLIENT(,-rsp), serving state None 22-11-2013 00:15:43.322 Debug pjsip: tsx0x7f0024002 Transaction created for Request msg INVITE/cseq=1 (rdata0x7f0028081738) 22-11-2013 00:15:43.322 Debug pjsip: tsx0x7f0024002 Incoming Request msg INVITE/cseq=1 (rdata0x7f0028081738) in state Null 22-11-2013 00:15:43.323 Debug pjsip: tsx0x7f0024002 State changed from Null to Trying, event=RX_MSG 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Trying 22-11-2013 00:15:43.323 Debug pjsip: endpoint Response msg 408/INVITE/cseq=1 (tdta0x7f00240058c0) created 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:2688: Report SAS start marker - trail (86d) 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:2785: Allocating transaction and data for target 0 22-11-2013 00:15:43.323 Debug pjsip: tsx0x7f0024009 Transaction created for Request msg INVITE/cseq=1 (tdta0x7f00240068d0) 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:2805: Adding trail identifier 2157 to UAC transaction 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:2824: Updating request URI and route for target 0 22-11-2013 00:15:43.323 Debug stateful_proxy.cpp:3182: Stripping loose routes from proxied message 22-11-2013 00:15:43.324 Debug stateful_proxy.cpp:3204: Adding a Route header to sip:ec2-50-17-60-182.compute-1.amazonaws.com 22-11-2013 00:15:43.324 Debug stateful_proxy.cpp:3258: Sending request for sip:[email protected];transport=TCP 22-11-2013 00:15:43.324 Debug pjsip: tsx0x7f0024009 Sending Request msg INVITE/cseq=1 (tdta0x7f00240068d0) in state Null 22-11-2013 00:15:43.324 Debug pjsip: sip_resolve.c DNS resolver not available, target 'ec2-50-17-60-182.compute-1.amazonaws.com:5054' type=TCP will be resolved with getaddrinfo() 22-11-2013 00:15:43.324 Debug pjsip: sip_resolve.c Target 'ec2-50-17-60-182.compute-1.amazonaws.com:5054' type=TCP resolved to '10.239.38.59:5054' type=TCP (TCP transport) 22-11-2013 00:15:43.324 Verbose stack.cpp:215: TX 1323 bytes Request msg INVITE/cseq=1 (tdta0x7f00240068d0) to TCP 10.239.38.59:5054: --start msg-- INVITE sip:[email protected];transport=TCP SIP/2.0 Record-Route: <sip:10.239.38.59:5058;transport=TCP;lr> Record-Route: <sip:[email protected]:5060;transport=TCP;lr> Via: SIP/2.0/TCP 10.239.38.59:55185;rport;branch=z9hG4bKPjGKni9MMo-FevN3MQV5PF2Q3MvByVNvrO Via: SIP/2.0/TCP 66.126.253.90:60521;rport=60521;received=66.126.253.90;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z- Max-Forwards: 69 Contact: <sip:[email protected]:60521;transport=TCP> To: <sip:[email protected]> From: <sip:[email protected]>;tag=96c55a52 Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri User-Agent: Zoiper r20066 Allow-Events: presence, kpml P-Asserted-Identity: <sip:[email protected]> Route: <sip:ec2-50-17-60-182.compute-1.amazonaws.com:5054;transport=TCP;lr;orig> Content-Type: application/sdp Content-Length: 261 v=0 o=Zoiper 0 0 IN IP4 66.126.253.90 s=Zoiper c=IN IP4 66.126.253.90 t=0 0 m=audio 33000 RTP/AVP 110 3 0 8 98 a=rtpmap:110 speex/8000 a=rtpmap:3 GSM/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:98 iLBC/8000 a=fmtp:98 mode=30 a=sendrecv --end msg-- 22-11-2013 00:15:43.332 Debug pjsip: tsx0x7f0024009 State changed from Null to Calling, event=TX_MSG 22-11-2013 00:15:43.333 Debug stateful_proxy.cpp:345: tsx0x7f0024009428 - tu_on_tsx_state UAC, TSX_STATE TX_MSG state=Calling 22-11-2013 00:15:43.333 Debug stateful_proxy.cpp:3336: tsx0x7f0024009428 - uac_data = 0x7f0024004530, uas_data = 0x7f0024003eb0 22-11-2013 00:15:43.333 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028081738 22-11-2013 00:15:43.333 Debug stack.cpp:170: Request latency = 13126us 22-11-2013 00:15:43.333 Debug pjsip: sip_endpoint.c Processing incoming message: Response msg 100/INVITE/cseq=1 (rdata0x131b968) 22-11-2013 00:15:43.333 Verbose stack.cpp:199: RX 591 bytes Response msg 100/INVITE/cseq=1 (rdata0x131b968) from TCP 10.239.38.59:5054: --start msg-- SIP/2.0 100 Trying Via: SIP/2.0/TCP 10.239.38.59:55185;rport=55185;received=10.239.38.59;branch=z9hG4bKPjGKni9MMo-FevN3MQV5PF2Q3MvByVNvrO Via: SIP/2.0/TCP 66.126.253.90:60521;rport=60521;received=66.126.253.90;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z- Record-Route: <sip:10.239.38.59:5058;transport=TCP;lr> Record-Route: <sip:[email protected]:5060;transport=TCP;lr> Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. From: <sip:[email protected]>;tag=96c55a52 To: <sip:[email protected]> CSeq: 1 INVITE Content-Length: 0 --end msg-- 22-11-2013 00:15:43.333 Debug stack.cpp:384: Queuing cloned received message 0x7f0028081738 for worker threads 22-11-2013 00:15:43.333 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028081738 22-11-2013 00:15:43.333 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 100/INVITE/cseq=1 (rdata0x7f0028081738) 22-11-2013 00:15:43.334 Debug pjsip: tsx0x7f0024009 Incoming Response msg 100/INVITE/cseq=1 (rdata0x7f0028081738) in state Calling 22-11-2013 00:15:43.334 Debug pjsip: tsx0x7f0024009 State changed from Calling to Proceeding, event=RX_MSG 22-11-2013 00:15:43.334 Debug stateful_proxy.cpp:345: tsx0x7f0024009428 - tu_on_tsx_state UAC, TSX_STATE RX_MSG state=Proceeding 22-11-2013 00:15:43.334 Debug stateful_proxy.cpp:3336: tsx0x7f0024009428 - uac_data = 0x7f0024004530, uas_data = 0x7f0024003eb0 22-11-2013 00:15:43.334 Debug stateful_proxy.cpp:3340: tsx0x7f0024009428 - RX_MSG on active UAC transaction 22-11-2013 00:15:43.334 Debug trustboundary.cpp:55: Strip trusted headers 22-11-2013 00:15:43.334 Debug stateful_proxy.cpp:2408: tsx0x7f0024009428 - Forward provisional response on UAS transaction 22-11-2013 00:15:43.334 Debug pjsip: tsx0x7f0024002 Sending Response msg 100/INVITE/cseq=1 (tdta0x7f002400da30) in state Trying 22-11-2013 00:15:43.335 Verbose stack.cpp:215: TX 471 bytes Response msg 100/INVITE/cseq=1 (tdta0x7f002400da30) to TCP 66.126.253.90:60521: --start msg-- SIP/2.0 100 Trying Via: SIP/2.0/TCP 66.126.253.90:60521;rport=60521;received=66.126.253.90;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z- Record-Route: <sip:10.239.38.59:5058;transport=TCP;lr> Record-Route: <sip:[email protected]:5060;transport=TCP;lr> Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. From: <sip:[email protected]>;tag=96c55a52 To: <sip:[email protected]> CSeq: 1 INVITE Content-Length: 0 --end msg-- 22-11-2013 00:15:43.335 Debug pjsip: tsx0x7f0024002 State changed from Trying to Proceeding, event=TX_MSG 22-11-2013 00:15:43.335 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Proceeding 22-11-2013 00:15:43.335 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028081738 22-11-2013 00:15:43.335 Debug stack.cpp:170: Request latency = 1862us 22-11-2013 00:15:43.362 Debug pjsip: sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=52203606 (rdata0x123f598) 22-11-2013 00:15:43.362 Verbose stack.cpp:199: RX 1131 bytes Request msg INVITE/cseq=52203606 (rdata0x123f598) from UDP 10.36.11.44:5080: --start msg-- INVITE sip:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.36.11.44:5080;rport;branch=z9hG4bK17Ze9gvKg9FmK Route: <sip:ip-10-239-38-59.ec2.internal:5060> Max-Forwards: 67 From: "6505550985" <sip:[email protected]>;tag=NBcFDUUv7872e To: <sip:[email protected]> Call-ID: 0fba104e-cdae-1231-4c9c-12313d251cde CSeq: 52203606 INVITE Contact: <sip:[email protected]:5080> User-Agent: FreeSWITCH-mod_sofia/1.5.7b+git~20131118T071121Z~46f5055914~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 203 P-Served-User: <sip:[email protected]>;sescase=orig;regstate=reg X-FS-Support: update_display,send_info P-Asserted-Identity: "6505550985" <sip:[email protected]> v=0 o=FreeSWITCH 1385051288 1385051289 IN IP4 10.36.11.44 s=FreeSWITCH c=IN IP4 10.36.11.44 t=0 0 m=audio 28052 RTP/AVP 3 0 8 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 --end msg-- 22-11-2013 00:15:43.363 Debug stack.cpp:384: Queuing cloned received message 0x7f0028081738 for worker threads 22-11-2013 00:15:43.363 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028081738 22-11-2013 00:15:43.363 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Request msg INVITE/cseq=52203606 (rdata0x7f0028081738) 22-11-2013 00:15:43.363 Debug stateful_proxy.cpp:236: Proxy RX request 22-11-2013 00:15:43.363 Debug stateful_proxy.cpp:689: Request received on non-trusted port 5060 22-11-2013 00:15:43.363 Debug stateful_proxy.cpp:880: Perform edge proxy routing for INVITE request 22-11-2013 00:15:43.363 Debug pjutils.cpp:351: Found Route header, URI = sip:ip-10-239-38-59.ec2.internal:5060 22-11-2013 00:15:43.363 Debug stateful_proxy.cpp:995: Message received on non-trusted port 5060 22-11-2013 00:15:43.363 Debug flowtable.cpp:137: Find flow for transport udp0x11ef330 (1), remote address 10.36.11.44:5080 22-11-2013 00:15:43.364 Warning stateful_proxy.cpp:1186: Rejecting request from untrusted source 22-11-2013 00:15:43.364 Debug pjsip: endpoint Response msg 403/INVITE/cseq=52203606 (tdta0x7f00240138f0) created 22-11-2013 00:15:43.364 Verbose stack.cpp:215: TX 336 bytes Response msg 403/INVITE/cseq=52203606 (tdta0x7f00240138f0) to UDP 10.36.11.44:5080: --start msg-- SIP/2.0 403 Forbidden Via: SIP/2.0/UDP 10.36.11.44:5080;rport=5080;received=10.36.11.44;branch=z9hG4bK17Ze9gvKg9FmK Call-ID: 0fba104e-cdae-1231-4c9c-12313d251cde From: "6505550985" <sip:[email protected]>;tag=NBcFDUUv7872e To: <sip:[email protected]>;tag=z9hG4bK17Ze9gvKg9FmK CSeq: 52203606 INVITE Content-Length: 0 --end msg-- 22-11-2013 00:15:43.364 Debug pjsip: tdta0x7f002401 Destroying txdata Response msg 403/INVITE/cseq=52203606 (tdta0x7f00240138f0) 22-11-2013 00:15:43.364 Debug pjsip: tdta0x7f002401 Destroying txdata Request msg INVITE/cseq=52203606 (tdta0x7f0024011930) 22-11-2013 00:15:43.364 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028081738 22-11-2013 00:15:43.364 Debug stack.cpp:170: Request latency = 1631us 22-11-2013 00:15:43.366 Debug pjsip: sip_endpoint.c Processing incoming message: Request msg ACK/cseq=52203606 (rdata0x1243498) 22-11-2013 00:15:43.366 Verbose stack.cpp:199: RX 389 bytes Request msg ACK/cseq=52203606 (rdata0x1243498) from UDP 10.36.11.44:5080: --start msg-- ACK sip:[email protected] SIP/2.0 Via: SIP/2.0/UDP 10.36.11.44:5080;rport;branch=z9hG4bK17Ze9gvKg9FmK Route: <sip:ip-10-239-38-59.ec2.internal:5060> Max-Forwards: 67 From: "6505550985" <sip:[email protected]>;tag=NBcFDUUv7872e To: <sip:[email protected]>;tag=z9hG4bK17Ze9gvKg9FmK Call-ID: 0fba104e-cdae-1231-4c9c-12313d251cde CSeq: 52203606 ACK Content-Length: 0 --end msg-- 22-11-2013 00:15:43.366 Debug stack.cpp:384: Queuing cloned received message 0x7f0028084628 for worker threads 22-11-2013 00:15:43.366 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028084628 22-11-2013 00:15:43.366 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Request msg ACK/cseq=52203606 (rdata0x7f0028084628) 22-11-2013 00:15:43.366 Debug stateful_proxy.cpp:236: Proxy RX request 22-11-2013 00:15:43.366 Debug stateful_proxy.cpp:689: Request received on non-trusted port 5060 22-11-2013 00:15:43.367 Debug stateful_proxy.cpp:880: Perform edge proxy routing for ACK request 22-11-2013 00:15:43.367 Debug pjutils.cpp:351: Found Route header, URI = sip:ip-10-239-38-59.ec2.internal:5060 22-11-2013 00:15:43.367 Debug stateful_proxy.cpp:995: Message received on non-trusted port 5060 22-11-2013 00:15:43.367 Debug flowtable.cpp:137: Find flow for transport udp0x11ef330 (1), remote address 10.36.11.44:5080 22-11-2013 00:15:43.367 Warning stateful_proxy.cpp:1191: Discard ACK from untrusted source not directed to Sprout 22-11-2013 00:15:43.367 Debug pjsip: tdta0x7f002401 Destroying txdata Request msg ACK/cseq=52203606 (tdta0x7f0024011930) 22-11-2013 00:15:43.367 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028084628 22-11-2013 00:15:43.367 Debug stack.cpp:170: Request latency = 1255us 22-11-2013 00:15:43.383 Debug pjsip: sip_endpoint.c Processing incoming message: Response msg 403/INVITE/cseq=1 (rdata0x131b968) 22-11-2013 00:15:43.383 Verbose stack.cpp:199: RX 875 bytes Response msg 403/INVITE/cseq=1 (rdata0x131b968) from TCP 10.239.38.59:5054: --start msg-- SIP/2.0 403 Forbidden Via: SIP/2.0/TCP 10.239.38.59:55185;rport=55185;received=10.239.38.59;branch=z9hG4bKPjGKni9MMo-FevN3MQV5PF2Q3MvByVNvrO Via: SIP/2.0/TCP 66.126.253.90:60521;rport=60521;received=66.126.253.90;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z- Max-Forwards: 68 From: <sip:[email protected]>;tag=96c55a52 To: <sip:[email protected]>;tag=m2jpB0aSa0HgK Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.5.7b+git~20131118T071121Z~46f5055914~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, refer Reason: Q.850;cause=21;text="CALL_REJECTED" P-Asserted-Identity: "6505550793" <sip:[email protected]> Content-Length: 0 --end msg-- 22-11-2013 00:15:43.383 Debug stack.cpp:384: Queuing cloned received message 0x7f0028084628 for worker threads 22-11-2013 00:15:43.384 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028084628 22-11-2013 00:15:43.384 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Response msg 403/INVITE/cseq=1 (rdata0x7f0028084628) 22-11-2013 00:15:43.384 Debug pjsip: tsx0x7f0024009 Incoming Response msg 403/INVITE/cseq=1 (rdata0x7f0028084628) in state Proceeding 22-11-2013 00:15:43.384 Debug pjsip: endpoint Request msg ACK/cseq=1 (tdta0x7f0024011930) created. 22-11-2013 00:15:43.384 Verbose stack.cpp:215: TX 437 bytes Request msg ACK/cseq=1 (tdta0x7f0024011930) to TCP 10.239.38.59:5054: --start msg-- ACK sip:[email protected];transport=TCP SIP/2.0 Via: SIP/2.0/TCP 10.239.38.59:55185;rport;branch=z9hG4bKPjGKni9MMo-FevN3MQV5PF2Q3MvByVNvrO Max-Forwards: 70 From: <sip:[email protected]>;tag=96c55a52 To: <sip:[email protected]>;tag=m2jpB0aSa0HgK Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 ACK Route: <sip:ec2-50-17-60-182.compute-1.amazonaws.com:5054;transport=TCP;lr;orig> Content-Length: 0 --end msg-- 22-11-2013 00:15:43.384 Debug pjsip: tsx0x7f0024009 State changed from Proceeding to Completed, event=RX_MSG 22-11-2013 00:15:43.385 Debug stateful_proxy.cpp:345: tsx0x7f0024009428 - tu_on_tsx_state UAC, TSX_STATE RX_MSG state=Completed 22-11-2013 00:15:43.385 Debug stateful_proxy.cpp:3336: tsx0x7f0024009428 - uac_data = 0x7f0024004530, uas_data = 0x7f0024003eb0 22-11-2013 00:15:43.385 Debug stateful_proxy.cpp:3340: tsx0x7f0024009428 - RX_MSG on active UAC transaction 22-11-2013 00:15:43.385 Debug trustboundary.cpp:55: Strip trusted headers 22-11-2013 00:15:43.385 Debug stateful_proxy.cpp:2415: tsx0x7f0024009428 - Final response, so disconnect UAS and UAC transactions 22-11-2013 00:15:43.385 Debug pjsip: tdta0x7f002400 Destroying txdata Response msg 408/INVITE/cseq=1 (tdta0x7f00240058c0) 22-11-2013 00:15:43.385 Debug pjsip: tsx0x7f0024002 Sending Response msg 403/INVITE/cseq=1 (tdta0x7f00240167e0) in state Proceeding 22-11-2013 00:15:43.385 Debug pjsip: tdta0x7f002400 Destroying txdata Response msg 100/INVITE/cseq=1 (tdta0x7f002400da30) 22-11-2013 00:15:43.385 Verbose stack.cpp:215: TX 755 bytes Response msg 403/INVITE/cseq=1 (tdta0x7f00240167e0) to TCP 66.126.253.90:60521: --start msg-- SIP/2.0 403 Forbidden Via: SIP/2.0/TCP 66.126.253.90:60521;rport=60521;received=66.126.253.90;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z- Max-Forwards: 68 From: <sip:[email protected]>;tag=96c55a52 To: <sip:[email protected]>;tag=m2jpB0aSa0HgK Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.5.7b+git~20131118T071121Z~46f5055914~64bit Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, refer Reason: Q.850;cause=21;text="CALL_REJECTED" P-Asserted-Identity: "6505550793" <sip:[email protected]> Content-Length: 0 --end msg-- 22-11-2013 00:15:43.386 Debug pjsip: tsx0x7f0024002 State changed from Proceeding to Completed, event=TX_MSG 22-11-2013 00:15:43.386 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE TX_MSG state=Completed 22-11-2013 00:15:43.386 Debug stateful_proxy.cpp:689: Request received on non-trusted port 5060 22-11-2013 00:15:43.386 Debug stateful_proxy.cpp:2720: Report SAS end marker - trail (86d) 22-11-2013 00:15:43.386 Debug pjsip: tdta0x7f002400 Destroying txdata Request msg INVITE/cseq=1 (tdta0x7f00240068d0) 22-11-2013 00:15:43.386 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028084628 22-11-2013 00:15:43.386 Debug stack.cpp:170: Request latency = 2736us 22-11-2013 00:15:43.394 Debug pjsip: tsx0x7f0024009 Timeout timer event 22-11-2013 00:15:43.394 Debug pjsip: tsx0x7f0024009 State changed from Completed to Terminated, event=TIMER 22-11-2013 00:15:43.394 Debug stateful_proxy.cpp:345: tsx0x7f0024009428 - tu_on_tsx_state UAC, TSX_STATE TIMER state=Terminated 22-11-2013 00:15:43.394 Debug stateful_proxy.cpp:3336: tsx0x7f0024009428 - uac_data = 0x7f0024004530, uas_data = (nil) 22-11-2013 00:15:43.394 Debug pjsip: tsx0x7f0024009 Timeout timer event 22-11-2013 00:15:43.394 Debug pjsip: tsx0x7f0024009 State changed from Terminated to Destroyed, event=TIMER 22-11-2013 00:15:43.394 Debug stateful_proxy.cpp:345: tsx0x7f0024009428 - tu_on_tsx_state UAC, TSX_STATE TIMER state=Destroyed 22-11-2013 00:15:43.394 Debug stateful_proxy.cpp:3336: tsx0x7f0024009428 - uac_data = 0x7f0024004530, uas_data = (nil) 22-11-2013 00:15:43.394 Debug stateful_proxy.cpp:3391: tsx0x7f0024009428 - UAC tsx destroyed 22-11-2013 00:15:43.395 Debug pjsip: tdta0x7f002401 Destroying txdata Request msg ACK/cseq=1 (tdta0x7f0024011930) 22-11-2013 00:15:43.560 Debug pjsip: sip_endpoint.c Processing incoming message: Request msg ACK/cseq=1 (rdata0x7f002803e308) 22-11-2013 00:15:43.560 Verbose stack.cpp:199: RX 424 bytes Request msg ACK/cseq=1 (rdata0x7f002803e308) from TCP 66.126.253.90:60521: --start msg-- ACK sip:[email protected];transport=TCP SIP/2.0 Via: SIP/2.0/TCP 66.126.253.90:60521;branch=z9hG4bK-d8754z-e5e6f12bebac172f-1---d8754z-;rport Max-Forwards: 70 Route: <sip:example.com:5054;transport=TCP;lr;orig> To: <sip:[email protected]>;tag=m2jpB0aSa0HgK From: <sip:[email protected];transport=TCP>;tag=96c55a52 Call-ID: YjA2ODkzZDI1MGU2YWU0Zjk0YTQyMGIwYWY3YjNmZDI. CSeq: 1 ACK Content-Length: 0 --end msg-- 22-11-2013 00:15:43.560 Debug stack.cpp:384: Queuing cloned received message 0x7f0028084628 for worker threads 22-11-2013 00:15:43.560 Debug stack.cpp:162: Worker thread dequeue message 0x7f0028084628 22-11-2013 00:15:43.560 Debug pjsip: sip_endpoint.c Distributing rdata to modules: Request msg ACK/cseq=1 (rdata0x7f0028084628) 22-11-2013 00:15:43.560 Debug pjsip: tsx0x7f0024002 Incoming Request msg ACK/cseq=1 (rdata0x7f0028084628) in state Completed 22-11-2013 00:15:43.561 Debug pjsip: tsx0x7f0024002 State changed from Completed to Confirmed, event=RX_MSG 22-11-2013 00:15:43.561 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE RX_MSG state=Confirmed 22-11-2013 00:15:43.561 Debug stack.cpp:164: Worker thread completed processing message 0x7f0028084628 22-11-2013 00:15:43.561 Debug stack.cpp:170: Request latency = 986us 22-11-2013 00:15:48.561 Debug pjsip: tsx0x7f0024002 Timeout timer event 22-11-2013 00:15:48.561 Debug pjsip: tsx0x7f0024002 State changed from Confirmed to Terminated, event=TIMER 22-11-2013 00:15:48.561 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE TIMER state=Terminated 22-11-2013 00:15:48.563 Debug pjsip: tsx0x7f0024002 Timeout timer event 22-11-2013 00:15:48.563 Debug pjsip: tsx0x7f0024002 State changed from Terminated to Destroyed, event=TIMER 22-11-2013 00:15:48.563 Debug stateful_proxy.cpp:345: tsx0x7f0024002928 - tu_on_tsx_state UAS, TSX_STATE TIMER state=Destroyed 22-11-2013 00:15:48.563 Debug stateful_proxy.cpp:2499: tsx0x7f0024002928 - UAS tsx destroyed 22-11-2013 00:15:48.563 Debug stateful_proxy.cpp:2877: tsx0x7f0024002928 - Cancel 0 pending UAC transactions 22-11-2013 00:15:48.563 Debug stateful_proxy.cpp:2886: tsx0x7f0024002928 - Check target 0, UAC data = (nil), UAC tsx = (nil) 22-11-2013 00:15:48.563 Debug stateful_proxy.cpp:1833: UASTransaction destructor 22-11-2013 00:15:48.564 Debug stateful_proxy.cpp:1850: Disconnect UAC transactions from UAS transaction 22-11-2013 00:15:48.564 Debug stateful_proxy.cpp:1862: Free original request 22-11-2013 00:15:48.564 Debug pjsip: tdta0x7f002400 Destroying txdata Request msg INVITE/cseq=1 (tdta0x7f00240008c0) 22-11-2013 00:15:48.564 Debug stateful_proxy.cpp:1902: UASTransaction destructor completed 22-11-2013 00:15:48.564 Debug pjsip: tdta0x7f002401 Destroying txdata Response msg 403/INVITE/cseq=1 (tdta0x7f00240167e0) 22-11-2013 00:15:48.564 Debug pjsip: tsx0x7f0024002 Transaction destroyed! 22-11-2013 00:15:48.564 Debug pjsip: tsx0x7f0024009 Transaction destroyed! -----Original Message----- From: Matt Williams [mailto:[email protected]] Sent: Thursday, 21 November 2013 2:21 PM To: Thomas Titty Cc: [email protected] Subject: RE: SIP OPTIONS not forwarded at Bono On Thu, Nov 21, 2013 at 05:56:13, Thomas Titty wrote: > To: [email protected] > Subject: [Clearwater] SIP OPTIONS not forwarded at Bono > > Hello, Hi Thomas - it's good to hear from you. > I installed Clearwater all-in-one image on Amazon EC2 and registered 2 > SIP clients. > > But the SIP OPTIONS exchange between the 2 clients are not forwarded > at Bono; it responds with a 200 OK. > > Logs from bono and homestead are given below. > > Pointers to probable causes would be greatly appreciated. Each Clearwater node has two domain names. One is the domain name of the Clearwater deployment (and in the all-in-one image, this is example.com). The other is the domain name of the specific node (e.g. ec2-50-17-60-182.compute-1.amazonaws.com). Requests to the domain name of the Clearwater deployment should be routed as you'd expect (e.g. on to the peer device if applicable). Requests to the node's own domain name are answered by the node itself - hence the 200 OK you're seeing. Does that make sense? Is it possible to send the OPTIONS message via your all-in-one node's IP address but with "example.com" in the request URI? (You might be able to do this by configuring your SIP device with a domain of example.com and then configuring it with a proxy.) If this doesn't work, we can try reconfiguring the domain name of the Clearwater deployment on the all-in-one node. This is a bit more fiddly so let's try the approach above first. Please let me know how you get on. Thanks, Matt _______________________________________________ Clearwater mailing list [email protected] http://lists.projectclearwater.org/listinfo/clearwater
