<div><div dir="ltr"><div dir="ltr"><br></div><div class="gmail_quote"></div></div></div><div><div dir="ltr" class="gmail_attr">On Mon, Sep 30, 2019 at 4:10 PM Ciprian Dosoftei <<a href="mailto:ciprian.dosoftei@gmail.com" target="_blank">ciprian.dosoftei@gmail.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr">Hi Nathan,<div><br></div><div>If you could provide the full SIP trace for a sample, that would be great.</div></div></blockquote><div><br></div></div><div><div>Sure, see below.</div></div><div><div><br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div>While the Contact header is indeed typically pointing back at the standard SIP IP/port combination (i.e. non-WebSocket), most JS SIP client library gracefully work around it (even without rport hints).</div></div></blockquote><div><br></div></div><div><div>So this is a know issue?</div></div><div><div> </div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr"><div>Do you use a public stack for the client portion or a implementation?<br></div></div></blockquote><div><br></div></div><div><div>We are using SIP.js on the web side and Libre SIP stack for iOS and Android, both have issues with this bug.</div><div><br></div><div> +OK log level  [7]</div>freeswitch@as1-east> sofia global siptrace on<br>+OK Global siptrace on<br>recv 2642 bytes from wss/[50.246.121.173]:34010 at 20:27:36.440748:<br>------------------------------------------------------------------------<br>INVITE <a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a> SIP/2.0<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK6235908<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>><br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>CSeq: 1 INVITE<br>Call-ID: reo4dv21qm4aauclal7r<br>Max-Forwards: 70<br>X-Foo: true<br>Contact: <sip:0s9riicj@vg5qpm92h7dm.invalid;transport=ws;ob><br>Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER<br>Supported: outbound<br>User-Agent: <a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a><br>Content-Type: application/sdp<br>Content-Length: 2108<br><br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=sendrecv<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtpmap:111 opus/48000/2<br>a=rtcp-fb:111 transport-cc<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>2019-09-30 20:27:36.431835 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>2019-09-30 20:27:36.431835 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) Running State Change CS_NEW (Cur 3 Tot 925)<br>2019-09-30 20:27:36.431835 [DEBUG] sofia.c:10242 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> receiving invite from <a href="http://50.246.121.173:34010" target="_blank">50.246.121.173:34010</a> version: 1.10.0 -release 64bit<br>2019-09-30 20:27:36.431835 [DEBUG] sofia.c:10336 verifying acl "internal" for ip/port <a href="http://50.246.121.173:0" target="_blank">50.246.121.173:0</a>.<br>2019-09-30 20:27:36.431835 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State NEW<br>send 853 bytes to wss/[50.246.121.173]:34010 at 20:27:36.445754:<br>------------------------------------------------------------------------<br>SIP/2.0 407 Proxy Authentication Required<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK6235908;received=50.246.121.173;rport=34010<br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>>;tag=Up8tp3554QHpj<br>Call-ID: reo4dv21qm4aauclal7r<br>CSeq: 1 INVITE</div><div><br>User-Agent: FreeSWITCH-mod_sofia/1.10.0-release~64bit<br>Accept: application/sdp<br>Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>Supported: path, replaces<br>Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br></div><div>Proxy-Authenticate: Digest realm="<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>", nonce="921a7927-e0ba-4eb3-8532-cb4c1490721c", algorithm=MD5, qop="auth"<br>Content-Length: 0<br><br>2019-09-30 20:27:36.431835 [DEBUG] sofia.c:2426 detaching session 59f602c8-5c38-4c8b-984f-7f09197e9fa3<br>recv 316 bytes from wss/[50.246.121.173]:34010 at 20:27:36.505757:<br>------------------------------------------------------------------------<br>ACK <a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a> SIP/2.0<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK6235908<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>>;tag=Up8tp3554QHpj<br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>Call-ID: reo4dv21qm4aauclal7r<br>CSeq: 1 ACK<br>Max-Forwards: 70<br>Content-Length: 0<br><br>recv 2902 bytes from wss/[50.246.121.173]:34010 at 20:27:36.505833:<br>------------------------------------------------------------------------<br>INVITE <a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a> SIP/2.0<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK9166577<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>><br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>CSeq: 2 INVITE<br>Call-ID: reo4dv21qm4aauclal7r<br>Max-Forwards: 70<br>Proxy-Authorization: Digest algorithm=MD5, username="beth", realm="<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>", nonce="921a7927-e0ba-4eb3-8532-cb4c1490721c", uri="<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>", response="68b33afa613ebdf80f0694bc454dac65", qop=auth, cnonce="k3s2qg42vh0i", nc=00000001<br>X-Foo: true<br>Contact: <sip:0s9riicj@vg5qpm92h7dm.invalid;transport=ws;ob><br>Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER<br>Supported: outbound<br>User-Agent: <a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a><br>Content-Type: application/sdp<br>Content-Length: 2108<br><br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=sendrecv<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtpmap:111 opus/48000/2<br>a=rtcp-fb:111 transport-cc<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>2019-09-30 20:27:36.491837 [DEBUG] sofia.c:2535 Re-attaching to session 59f602c8-5c38-4c8b-984f-7f09197e9fa3<br>2019-09-30 20:27:36.511840 [DEBUG] sofia.c:10242 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> receiving invite from <a href="http://50.246.121.173:34010" target="_blank">50.246.121.173:34010</a> version: 1.10.0 -release 64bit<br>2019-09-30 20:27:36.511840 [DEBUG] sofia.c:10336 verifying acl "internal" for ip/port <a href="http://50.246.121.173:0" target="_blank">50.246.121.173:0</a>.<br>2019-09-30 20:27:36.531844 [DEBUG] sofia.c:11432 Setting NAT mode based on via received<br>2019-09-30 20:27:36.531844 [DEBUG] sofia.c:7289 Channel sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> entering state [received][100]<br>2019-09-30 20:27:36.531844 [DEBUG] sofia.c:7299 Remote SDP:<br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br><br>2019-09-30 20:27:36.531844 [DEBUG] sofia.c:7702 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State Change CS_NEW -> CS_INIT<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) Running State Change CS_INIT (Cur 3 Tot 925)<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State INIT<br>2019-09-30 20:27:36.531844 [DEBUG] mod_sofia.c:93 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> SOFIA INIT<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:40 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Standard INIT<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State Change CS_INIT -> CS_ROUTING<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State INIT going to sleep<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) Running State Change CS_ROUTING (Cur 3 Tot 925)<br>2019-09-30 20:27:36.531844 [DEBUG] switch_channel.c:2332 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) Callstate Change DOWN -> RINGING<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State ROUTING<br>2019-09-30 20:27:36.531844 [DEBUG] mod_sofia.c:154 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> SOFIA ROUTING<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:236 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Standard ROUTING<br>2019-09-30 20:27:36.531844 [INFO] mod_dialplan_xml.c:637 Processing <a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> <beth>->nathan in context default<br>send 352 bytes to wss/[50.246.121.173]:34010 at 20:27:36.543879:<br>------------------------------------------------------------------------<br>SIP/2.0 100 Trying<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK9166577;received=50.246.121.173;rport=34010<br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>><br>Call-ID: reo4dv21qm4aauclal7r<br>CSeq: 2 INVITE<br>User-Agent: FreeSWITCH-mod_sofia/1.10.0-release~64bit<br>Content-Length: 0<br><br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> parsing [default->global] continue=true<br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Absolute Condition [global]<br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action info() <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> parsing [default->Local_Users] continue=false<br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Regex (PASS) [Local_Users] destination_number(nathan) =~ /((?!^\d+$)^[a-zA-Z0-9_\-\.]+$)$/ break=on-false<br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action set(continue_on_fail=false) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action set(hangup_after_bridge=true) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action set(bypass_media=true) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action lua(endpoint_lookup.lua) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action set(call_timeout=20) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action bridge(${dial_to_string}) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action set(bypass_media=false) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action answer() <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action sleep(1000) <br>Dialplan: sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Action voicemail(${voicemail_string}) <br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State Change CS_ROUTING -> CS_EXECUTE<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State ROUTING going to sleep<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) Running State Change CS_EXECUTE (Cur 3 Tot 925)<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>) State EXECUTE<br>2019-09-30 20:27:36.531844 [DEBUG] mod_sofia.c:209 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> SOFIA EXECUTE<br>2019-09-30 20:27:36.531844 [DEBUG] switch_core_state_machine.c:329 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> Standard EXECUTE<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> info()<br>2019-09-30 20:27:36.531844 [INFO] mod_dptools.c:1885 CHANNEL_DATA:<br>Channel-State: [CS_EXECUTE]<br>Channel-Call-State: [RINGING]<br>Channel-State-Number: [4]<br>Channel-Name: [sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>Unique-ID: [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>Call-Direction: [inbound]<br>Presence-Call-Direction: [inbound]<br>Channel-HIT-Dialplan: [true]<br>Channel-Presence-ID: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>Channel-Call-UUID: [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>Answer-State: [ringing]<br>Caller-Direction: [inbound]<br>Caller-Logical-Direction: [inbound]<br>Caller-Username: [beth]<br>Caller-Dialplan: [XML]<br>Caller-Caller-ID-Name: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>Caller-Caller-ID-Number: [beth]<br>Caller-Orig-Caller-ID-Name: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>Caller-Orig-Caller-ID-Number: [beth]<br>Caller-Network-Addr: [50.246.121.173]<br>Caller-ANI: [beth]<br>Caller-Destination-Number: [nathan]<br>Caller-Unique-ID: [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>Caller-Source: [mod_sofia]<br>Caller-Context: [default]<br>Caller-Channel-Name: [sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>Caller-Profile-Index: [1]<br>Caller-Profile-Created-Time: [1569875256531844]<br>Caller-Channel-Created-Time: [1569875256531844]<br>Caller-Channel-Answered-Time: [0]<br>Caller-Channel-Progress-Time: [0]<br>Caller-Channel-Progress-Media-Time: [0]<br>Caller-Channel-Hangup-Time: [0]<br>Caller-Channel-Transfer-Time: [0]<br>Caller-Channel-Resurrect-Time: [0]<br>Caller-Channel-Bridged-Time: [0]<br>Caller-Channel-Last-Hold: [0]<br>Caller-Channel-Hold-Accum: [0]<br>Caller-Screen-Bit: [true]<br>Caller-Privacy-Hide-Name: [false]<br>Caller-Privacy-Hide-Number: [false]<br>variable_direction: [inbound]<br>variable_uuid: [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>variable_session_id: [925]<br>variable_sip_from_user: [beth]<br>variable_sip_from_uri: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>variable_sip_from_host: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_video_media_flow: [disabled]<br>variable_audio_media_flow: [disabled]<br>variable_text_media_flow: [disabled]<br>variable_channel_name: [sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>variable_sip_call_id: [reo4dv21qm4aauclal7r]<br>variable_sip_local_network_addr: [18.211.78.106]<br>variable_sip_network_ip: [50.246.121.173]<br>variable_sip_network_port: [34010]<br>variable_sip_invite_stamp: [1569875256511840]<br>variable_sip_received_ip: [50.246.121.173]<br>variable_sip_received_port: [34010]<br>variable_sip_via_protocol: [wss]<br>variable_sip_authorized: [true]<br>variable_Event-Name: [REQUEST_PARAMS]<br>variable_Core-UUID: [7e617e4b-996a-445f-a4d4-c6fc5db9b2cf]<br>variable_FreeSWITCH-Hostname: [as1-east]<br>variable_FreeSWITCH-Switchname: [as1-east]<br>variable_FreeSWITCH-IPv4: [10.0.0.237]<br>variable_FreeSWITCH-IPv6: [::1]<br>variable_Event-Date-Local: [2019-09-30 20:27:36]<br>variable_Event-Date-GMT: [Mon, 30 Sep 2019 20:27:36 GMT]<br>variable_Event-Date-Timestamp: [1569875256511840]<br>variable_Event-Calling-File: [sofia.c]<br>variable_Event-Calling-Function: [sofia_handle_sip_i_invite]<br>variable_Event-Calling-Line-Number: [10504]<br>variable_Event-Sequence: [152629]<br>variable_sip_number_alias: [beth]<br>variable_sip_auth_username: [beth]<br>variable_sip_auth_realm: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_number_alias: [beth]<br>variable_requested_user_name: [beth]<br>variable_requested_domain_name: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_user_context: [default]<br>variable_effective_caller_id_name: [Beth User]<br>variable_effective_caller_id_number: [beth]<br>variable_outbound_caller_id_name: [Beth User]<br>variable_user_name: [beth]<br>variable_domain_name: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_sip_from_user_stripped: [beth]<br>variable_sip_from_tag: [36ov91agi3]<br>variable_sofia_profile_name: [internal]<br>variable_sofia_profile_url: [<a href="http://sip:mod_sofia@18.211.78.106:5060" target="_blank">sip:mod_sofia@18.211.78.106:5060</a>]<br>variable_recovery_profile_name: [internal]<br>variable_sip_full_via: [SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK9166577;received=50.246.121.173;rport=34010]<br>variable_sip_from_display: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>variable_sip_full_from: ["<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3]<br>variable_sip_full_to: [<<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>>]<br>variable_sip_allow: [ACK, CANCEL, INVITE, MESSAGE, BYE, OPTIONS, INFO, NOTIFY, REFER]<br>variable_sip_req_user: [nathan]<br>variable_sip_req_uri: [<a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a>]<br>variable_sip_req_host: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_sip_to_user: [nathan]<br>variable_sip_to_uri: [<a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a>]<br>variable_sip_to_host: [<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]<br>variable_sip_contact_params: [transport=ws;ob]<br>variable_sip_contact_user: [0s9riicj]<br>variable_sip_contact_uri: [0s9riicj@vg5qpm92h7dm.invalid]<br>variable_sip_contact_host: [vg5qpm92h7dm.invalid]<br>variable_rtp_use_codec_string: [OPUS,PCMU,VP8]<br>variable_sip_user_agent: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>variable_sip_via_host: [vg5qpm92h7dm.invalid]<br>variable_sip_via_rport: [34010]<br>variable_max_forwards: [70]<br>variable_presence_id: [<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>]<br>variable_sip_h_X-Foo: [true]<br>variable_sip_nat_detected: [true]<br>variable_switch_r_sdp: [v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>]<br>variable_ep_codec_string: [mod_opus.opus@48000h@20i@2c,CORE_PCM_MODULE.PCMU@8000h@20i@64000b]<br>variable_endpoint_disposition: [DELAYED NEGOTIATION]<br>variable_DP_MATCH: [ARRAY::nathan|:nathan]<br>variable_call_uuid: [59f602c8-5c38-4c8b-984f-7f09197e9fa3]<br>variable_current_application: [info]<br><br><br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> set(continue_on_fail=false)<br>2019-09-30 20:27:36.531844 [DEBUG] mod_dptools.c:1672 SET sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> [continue_on_fail]=[false]<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> set(hangup_after_bridge=true)<br>2019-09-30 20:27:36.531844 [DEBUG] mod_dptools.c:1672 SET sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> [hangup_after_bridge]=[true]<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> set(bypass_media=true)<br>2019-09-30 20:27:36.531844 [DEBUG] mod_dptools.c:1672 SET sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> [bypass_media]=[true]<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> lua(endpoint_lookup.lua)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('voicemail_string', 'default <a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a> nathan')<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 SEARCH QUERY: SELECT server_host FROM sip_registrations WHERE sip_user = 'nathan' AND sip_host = '<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>' AND ping_status = 'Reachable';<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 FOUND AT HOST: 10.0.0.12<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 FOUND AT HOST: 10.0.0.237<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 User's <a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a> toll_allow value is <voicemail><br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 Voicemail enabled<br>2019-09-30 20:27:36.591836 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('continue_on_fail', 'true')<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 ADDING HOST: 10.0.0.12 TO DIAL STRING<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 REMOTE REGISTERED USER!<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 ADDING HOST: 10.0.0.237 TO DIAL STRING<br>2019-09-30 20:27:36.591836 [INFO] switch_cpp.cpp:1443 LOCAL REGISTERED USER!<br>2019-09-30 20:27:36.591836 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('dial_to_string', '[sip_h_X-voicemailed=true,sip_h_X-local-call=true,sip_invite_domain=<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>,${sofia_contact(<a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a>)}')<br>2019-09-30 20:27:36.591836 [DEBUG] switch_cpp.cpp:1187 sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> destroy/unlink session from object<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> set(call_timeout=20)<br>2019-09-30 20:27:36.591836 [DEBUG] mod_dptools.c:1672 SET sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> [call_timeout]=[20]<br>EXECUTE [depth=0] sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> bridge([sip_h_X-voicemailed=true,sip_h_X-local-call=true,sip_invite_domain=<a href="http://stage.illumy1.com" target="_blank">stage.illumy1.com</a>]sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>,sofia/internal/sip:j7fd3415@geutlen0uga7.invalid;transport=ws;received=50.246.121.173:39318;fs_nat=yes;fs_path=sip%3Aj7fd3415%4050.246.121.173%3A39318%3Btransport%3Dwss,sofia/internal/sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=50.246.121.173:34738;fs_nat=yes;fs_path=sip%3Ar6ucrocm%4050.246.121.173%3A34738%3Btransport%3Dwss)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_ivr_originate.c:2212 Parsing global variables<br>2019-09-30 20:27:36.591836 [DEBUG] switch_ivr_originate.c:2757 Parsing session specific variables<br>2019-09-30 20:27:36.591836 [NOTICE] switch_channel.c:1118 New Channel sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> [971aae94-0463-4c9f-809d-10dfbcb265bc]<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:5096 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State Change CS_NEW -> CS_INIT<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Running State Change CS_INIT (Cur 4 Tot 926)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State INIT<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:93 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> SOFIA INIT<br>2019-09-30 20:27:36.591836 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/j7fd3415@geutlen0uga7.invalid [83e36ab7-8275-4a5e-9afd-f9356da57b06]<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:5096 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State Change CS_NEW -> CS_INIT<br>2019-09-30 20:27:36.591836 [DEBUG] sofia_glue.c:1618 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> sending invite version: 1.10.0 -release 64bit<br>Local SDP:<br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br><br>2019-09-30 20:27:36.591836 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/r6ucrocm@3val7ifjblg6.invalid [56c0cd2d-e19b-4c45-a278-81ed26d09636]<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:5096 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State Change CS_NEW -> CS_INIT<br>send 2935 bytes to udp/[10.0.0.12]:5080 at 20:27:36.605793:<br>------------------------------------------------------------------------<br>INVITE <a href="http://sip:nathan@10.0.0.12:5080" target="_blank">sip:nathan@10.0.0.12:5080</a> SIP/2.0<br>Via: SIP/2.0/UDP 10.0.0.237:5080;rport;branch=z9hG4bKHmc9rvvg1aUeQ<br>Max-Forwards: 69<br>From: "Beth User" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=UXNaNXZpme5SD<br>To: <<a href="http://sip:nathan@10.0.0.12:5080" target="_blank">sip:nathan@10.0.0.12:5080</a>><br>Call-ID: 94fd528d-5e63-1238-2787-0251a831415e<br>CSeq: 10383836 INVITE<br>Contact: <<a href="http://sip:mod_sofia@10.0.0.237:5080" target="_blank">sip:mod_sofia@10.0.0.237:5080</a>><br>User-Agent: FreeSWITCH-mod_sofia/1.10.0-release~64bit</div><div><br>Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY<br></div><div>Supported: timer, path, replaces<br>Allow-Events: talk, hold, conference, refer<br>Content-Type: application/sdp<br>Content-Disposition: session<br>Content-Length: 2097<br>X-Foo: true<br>X-voicemailed: true<br>X-local-call: true<br>X-FS-Support: update_display,send_info<br>Remote-Party-ID: "Beth User" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;party=calling;screen=yes;privacy=off<br><br>v=0<br>o=- 3923831751587036465 2 IN IP4 10.0.0.237<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:40 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> Standard INIT<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:48 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State Change CS_INIT -> CS_ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State INIT going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/j7fd3415@geutlen0uga7.invalid) Running State Change CS_INIT (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Running State Change CS_ROUTING (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] sofia.c:7289 Channel sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> entering state [calling][0]<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State INIT<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:93 sofia/internal/j7fd3415@geutlen0uga7.invalid SOFIA INIT<br>2019-09-30 20:27:36.591836 [DEBUG] sofia.c:7289 Channel sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> entering state [terminated][503]<br>2019-09-30 20:27:36.591836 [NOTICE] sofia.c:8522 Hangup sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:154 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> SOFIA ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State ROUTING going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Running State Change CS_HANGUP (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] sofia_glue.c:1587 sip:j7fd3415@50.246.121.173:39318;transport=wss Setting proxy route to sofia/internal/j7fd3415@geutlen0uga7.invalid<br>2019-09-30 20:27:36.591836 [DEBUG] sofia_glue.c:1618 sofia/internal/j7fd3415@geutlen0uga7.invalid sending invite version: 1.10.0 -release 64bit<br>Local SDP:<br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br><br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:848 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Callstate Change DOWN -> HANGUP<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:850 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State HANGUP<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:460 Channel sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> hanging up, cause: NORMAL_TEMPORARY_FAILURE<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:60 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:850 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State HANGUP going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:620 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State Change CS_HANGUP -> CS_REPORTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Running State Change CS_REPORTING (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:936 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State REPORTING<br>2019-09-30 20:27:36.591836 [DEBUG] mod_odbc_cdr.c:345 sql INSERT INTO cdr_table_both (CallId) VALUES ('971aae94-0463-4c9f-809d-10dfbcb265bc')<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:40 sofia/internal/j7fd3415@geutlen0uga7.invalid Standard INIT<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State Change CS_INIT -> CS_ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State INIT going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/j7fd3415@geutlen0uga7.invalid) Running State Change CS_ROUTING (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:154 sofia/internal/j7fd3415@geutlen0uga7.invalid SOFIA ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State ROUTING going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/j7fd3415@geutlen0uga7.invalid) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State CONSUME_MEDIA<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/j7fd3415@geutlen0uga7.invalid) State CONSUME_MEDIA going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Running State Change CS_INIT (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State INIT<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:93 sofia/internal/r6ucrocm@3val7ifjblg6.invalid SOFIA INIT<br>2019-09-30 20:27:36.591836 [DEBUG] sofia_glue.c:1587 sip:r6ucrocm@50.246.121.173:34738;transport=wss Setting proxy route to sofia/internal/r6ucrocm@3val7ifjblg6.invalid<br>2019-09-30 20:27:36.591836 [DEBUG] sofia_glue.c:1618 sofia/internal/r6ucrocm@3val7ifjblg6.invalid sending invite version: 1.10.0 -release 64bit<br>Local SDP:<br>v=0<br>o=- 3923831751587036465 2 IN IP4 127.0.0.1<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br><br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:40 sofia/internal/r6ucrocm@3val7ifjblg6.invalid Standard INIT<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State Change CS_INIT -> CS_ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State INIT going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Running State Change CS_ROUTING (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] mod_sofia.c:154 sofia/internal/r6ucrocm@3val7ifjblg6.invalid SOFIA ROUTING<br>2019-09-30 20:27:36.591836 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State ROUTING going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Running State Change CS_CONSUME_MEDIA (Cur 6 Tot 928)<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State CONSUME_MEDIA<br>2019-09-30 20:27:36.591836 [DEBUG] switch_core_state_machine.c:663 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State CONSUME_MEDIA going to sleep<br>2019-09-30 20:27:36.591836 [DEBUG] mod_odbc_cdr.c:345 sql INSERT INTO cdr_table_b_leg (CallId, orig_id, term_id, ClientId) VALUES ('59f602c8-5c38-4c8b-984f-7f09197e9fa3', '971aae94-0463-4c9f-809d-10dfbcb265bc', '94fd528d-5e63-1238-2787-0251a831415e', '971aae94-0463-4c9f-809d-10dfbcb265bc')<br>2019-09-30 20:27:36.591836 [DEBUG] sofia.c:7289 Channel sofia/internal/j7fd3415@geutlen0uga7.invalid entering state [calling][0]<br>send 3150 bytes to wss/[50.246.121.173]:34738 at 20:27:36.611364:<br>------------------------------------------------------------------------<br>INVITE sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a> SIP/2.0<br>Via: SIP/2.0/WSS 18.211.78.106:443;rport;branch=z9hG4bKye2ergXZB23jK<br>Route: <<a href="http://sip:r6ucrocm@50.246.121.173:34738" target="_blank">sip:r6ucrocm@50.246.121.173:34738</a>>;transport=wss<br>Max-Forwards: 69<br>From: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;tag=yHm5UmrgvjmeN</div><div><br>To: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a>><br></div><div>Call-ID: 94fe2e19-5e63-1238-2787-0251a831415e<br>CSeq: 10383836 INVITE<br>Contact: <<a href="http://sip:mod_sofia@18.211.78.106:5060" target="_blank">sip:mod_sofia@18.211.78.106:5060</a>><br>User-Agent: FreeSWITCH-mod_sofia/1.10.0-release~64bit</div><div><br>Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>Supported: path, replaces<br>Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br></div><div>Content-Type: application/sdp<br>Content-Disposition: session<br>Content-Length: 2097<br>X-Foo: true<br>Remote-Party-ID: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;party=calling;screen=yes;privacy=off<br><br>v=0<br>o=- 3923831751587036465 2 IN IP4 10.0.0.237<br>s=-<br>t=0 0<br>a=group:BUNDLE 0<br>a=msid-semantic: WMS DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>m=audio 61006 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 110 112 113 126<br>c=IN IP4 18.204.23.155<br>a=rtpmap:111 opus/48000/2<br>a=fmtp:111 minptime=10;useinbandfec=1<br>a=rtpmap:103 ISAC/16000<br>a=rtpmap:104 ISAC/32000<br>a=rtpmap:9 G722/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:106 CN/32000<br>a=rtpmap:105 CN/16000<br>a=rtpmap:13 CN/8000<br>a=rtpmap:110 telephone-event/48000<br>a=rtpmap:112 telephone-event/32000<br>a=rtpmap:113 telephone-event/16000<br>a=rtpmap:126 telephone-event/8000<br>a=rtcp:9 IN IP4 0.0.0.0<br>a=candidate:1978755251 1 udp 2122260223 10.88.65.80 34027 typ host generation 0 network-id 1 network-cost 10<br>a=candidate:3702450022 1 udp 1686052607 50.246.121.173 34027 typ srflx raddr 10.88.65.80 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:3425785639 1 udp 41885439 18.204.23.155 61006 typ relay raddr 50.246.121.173 rport 34027 generation 0 network-id 1 network-cost 10<br>a=candidate:997364803 1 tcp 1518280447 10.88.65.80 9 typ host tcptype active generation 0 network-id 1 network-cost 10<br>a=ice-ufrag:EVJX<br>a=ice-pwd:ppoOZcwEGehOPKMM03ol7g5r<br>a=ice-options:trickle<br>a=fingerprint:sha-256 37:F4:29:14:94:6A:87:51:7E:44:81:FA:71:BF:22:D7:71:0C:02:93:49:61:36:3A:A6:AA:B5:34:2B:CA:1F:55<br>a=setup:actpass<br>a=mid:0<br>a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level<br>a=extmap:2 <a href="http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01" target="_blank">http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01</a><br>a=extmap:3 urn:ietf:params:rtp-hdrext:sdes:mid<br>a=extmap:4 urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id<br>a=extmap:5 urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id<br>a=msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=rtcp-mux<br>a=rtcp-fb:111 transport-cc<br>a=ssrc:767982924 cname:D8OaexP97luPw4OL<br>a=ssrc:767982924 msid:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc 4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>a=ssrc:767982924 mslabel:DrxEOjCVvHNi3DtuKJs175ibnTtb2huY2WVc<br>a=ssrc:767982924 label:4fdf8bec-c8e1-42d1-a872-db6864ef5ac2<br>2019-09-30 20:27:36.591836 [DEBUG] sofia.c:7289 Channel sofia/internal/r6ucrocm@3val7ifjblg6.invalid entering state [calling][0]<br>2019-09-30 20:27:36.611834 [DEBUG] switch_core_state_machine.c:174 sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a> Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE<br>2019-09-30 20:27:36.611834 [DEBUG] switch_core_state_machine.c:936 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State REPORTING going to sleep<br>2019-09-30 20:27:36.611834 [DEBUG] switch_core_state_machine.c:611 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) State Change CS_REPORTING -> CS_DESTROY<br>2019-09-30 20:27:36.611834 [DEBUG] switch_core_session.c:1726 Session 926 (sofia/external/<a href="http://nathan@10.0.0.12:5080" target="_blank">nathan@10.0.0.12:5080</a>) Locked, Waiting on external entities<br>recv 386 bytes from wss/[50.246.121.173]:34738 at 20:27:36.651849:<br>------------------------------------------------------------------------<br>SIP/2.0 100 Trying<br>Via: SIP/2.0/WSS 18.211.78.106:443;rport;branch=z9hG4bKye2ergXZB23jK<br>From: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;tag=yHm5UmrgvjmeN</div><div><br>To: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a>><br></div><div>CSeq: 10383836 INVITE<br>Call-ID: 94fe2e19-5e63-1238-2787-0251a831415e<br>Supported: outbound<br>User-Agent: <a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a><br>Content-Length: 0<br><br>recv 461 bytes from wss/[50.246.121.173]:34738 at 20:27:36.656498:<br>------------------------------------------------------------------------<br>SIP/2.0 180 Ringing<br>Via: SIP/2.0/WSS 18.211.78.106:443;rport;branch=z9hG4bKye2ergXZB23jK<br>From: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;tag=yHm5UmrgvjmeN<br>To: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a>>;tag=9eibedbcg9<br>CSeq: 10383836 INVITE<br>Call-ID: 94fe2e19-5e63-1238-2787-0251a831415e</div><div><br>Supported: outbound<br>User-Agent: <a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a><br>Contact: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws><br>Content-Length: 0<br><br></div><div>2019-09-30 20:27:36.651842 [DEBUG] sofia.c:7289 Channel sofia/internal/r6ucrocm@3val7ifjblg6.invalid entering state [proceeding][180]<br>2019-09-30 20:27:36.651842 [NOTICE] sofia.c:7400 Ring-Ready sofia/internal/r6ucrocm@3val7ifjblg6.invalid!<br>2019-09-30 20:27:36.651842 [NOTICE] mod_sofia.c:2505 Ring-Ready sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>!<br>send 760 bytes to wss/[50.246.121.173]:34010 at 20:27:36.666536:<br>------------------------------------------------------------------------<br>SIP/2.0 180 Ringing<br>Via: SIP/2.0/WSS vg5qpm92h7dm.invalid;branch=z9hG4bK9166577;received=50.246.121.173;rport=34010<br>From: "<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>" <<a href="mailto:sip%3Abeth@stage.illumy1.com" target="_blank">sip:beth@stage.illumy1.com</a>>;tag=36ov91agi3<br>To: <<a href="mailto:sip%3Anathan@stage.illumy1.com" target="_blank">sip:nathan@stage.illumy1.com</a>>;tag=vZ1Kryp91078D<br>Call-ID: reo4dv21qm4aauclal7r</div><div><br>CSeq: 2 INVITE<br>Contact: <sip:nathan@18.211.78.106:5060;transport=udp><br>User-Agent: FreeSWITCH-mod_sofia/1.10.0-release~64bit<br>Accept: application/sdp<br>Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE<br>Supported: path, replaces<br>Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer<br>Content-Length: 0<br><br></div><div>2019-09-30 20:27:36.651842 [DEBUG] switch_channel.c:3437 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Callstate Change DOWN -> RINGING<br>2019-09-30 20:27:36.651842 [DEBUG] sofia.c:7289 Channel sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a> entering state [early][180]<br>2019-09-30 20:27:36.651842 [NOTICE] switch_ivr_originate.c:553 Ring Ready sofia/internal/<a href="mailto:beth@stage.illumy1.com" target="_blank">beth@stage.illumy1.com</a>!<br>recv 418 bytes from wss/[50.246.121.173]:34738 at 20:27:38.973706:<br>------------------------------------------------------------------------<br>SIP/2.0 480 Temporarily Unavailable<br>Via: SIP/2.0/WSS 18.211.78.106:443;rport;branch=z9hG4bKye2ergXZB23jK<br>From: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;tag=yHm5UmrgvjmeN<br>To: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a>>;tag=9eibedbcg9<br>CSeq: 10383836 INVITE<br>Call-ID: 94fe2e19-5e63-1238-2787-0251a831415e<br>Supported: outbound<br>User-Agent: <a href="mailto:nathan@stage.illumy1.com" target="_blank">nathan@stage.illumy1.com</a><br>Content-Length: 0<br><br>send 485 bytes to wss/[50.246.121.173]:34738 at 20:27:38.973840:<br>------------------------------------------------------------------------<br>ACK sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a> SIP/2.0<br>Via: SIP/2.0/WSS 18.211.78.106:443;rport;branch=z9hG4bKye2ergXZB23jK<br>Route: <<a href="http://sip:r6ucrocm@50.246.121.173:34738" target="_blank">sip:r6ucrocm@50.246.121.173:34738</a>>;transport=wss<br>Max-Forwards: 69<br>From: "Beth User" <<a href="mailto:sip%3Abeth@18.211.78.106" target="_blank">sip:beth@18.211.78.106</a>>;tag=yHm5UmrgvjmeN<br>To: <sip:r6ucrocm@3val7ifjblg6.invalid;transport=ws;received=<a href="http://50.246.121.173:34738" target="_blank">50.246.121.173:34738</a>>;tag=9eibedbcg9<br>Call-ID: 94fe2e19-5e63-1238-2787-0251a831415e<br>CSeq: 10383836 ACK<br>Content-Length: 0<br><br>2019-09-30 20:27:38.971902 [DEBUG] sofia.c:7289 Channel sofia/internal/r6ucrocm@3val7ifjblg6.invalid entering state [terminated][480]<br>2019-09-30 20:27:38.971902 [NOTICE] sofia.c:8522 Hangup sofia/internal/r6ucrocm@3val7ifjblg6.invalid [CS_CONSUME_MEDIA] [NO_USER_RESPONSE]<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Running State Change CS_HANGUP (Cur 6 Tot 928)<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Callstate Change RINGING -> HANGUP<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State HANGUP<br>2019-09-30 20:27:38.971902 [DEBUG] mod_sofia.c:460 Channel sofia/internal/r6ucrocm@3val7ifjblg6.invalid hanging up, cause: NO_USER_RESPONSE<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:60 sofia/internal/r6ucrocm@3val7ifjblg6.invalid Standard HANGUP, cause: NO_USER_RESPONSE<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State HANGUP going to sleep<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State Change CS_HANGUP -> CS_REPORTING<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Running State Change CS_REPORTING (Cur 6 Tot 928)<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State REPORTING<br>2019-09-30 20:27:38.971902 [DEBUG] mod_odbc_cdr.c:345 sql INSERT INTO cdr_table_both (CallId, TEST_id) VALUES ('56c0cd2d-e19b-4c45-a278-81ed26d09636', '<a href="mailto:beth@18.211.78.106" target="_blank">beth@18.211.78.106</a>')<br>2019-09-30 20:27:38.971902 [DEBUG] mod_odbc_cdr.c:345 sql INSERT INTO cdr_table_b_leg (TelNumberFull, CallId, orig_id, CallerID, IP, term_id, ClientId) VALUES ('r6ucrocm', '59f602c8-5c38-4c8b-984f-7f09197e9fa3', '56c0cd2d-e19b-4c45-a278-81ed26d09636', 'beth', '50.246.121.173', '94fe2e19-5e63-1238-2787-0251a831415e', '56c0cd2d-e19b-4c45-a278-81ed26d09636')<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:174 sofia/internal/r6ucrocm@3val7ifjblg6.invalid Standard REPORTING, cause: NO_USER_RESPONSE<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State REPORTING going to sleep<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) State Change CS_REPORTING -> CS_DESTROY<br>2019-09-30 20:27:38.971902 [DEBUG] switch_core_session.c:1726 Session 928 (sofia/internal/r6ucrocm@3val7ifjblg6.invalid) Locked, Waiting on external entities<br>
</div>-- <br><div dir="ltr" class="gmail_signature" data-smartmail="gmail_signature"><div dir="ltr"><div><br>><><br>nathan stratton</div></div></div>