recv 818 bytes from udp/[10.3.3.26]:5060 at 16:06:24.562561: ------------------------------------------------------------------------ INVITE sip:17806860331@voice.polarisnetworks.ca SIP/2.0 Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-c18fe00b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 101 INVITE Max-Forwards: 70 Contact: 5878272002 Expires: 240 User-Agent: Linksys/PAP2T-5.1.6(LS) Content-Length: 247 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura, replaces Content-Type: application/sdp v=0 o=- 406268 406268 IN IP4 10.3.3.26 s=- c=IN IP4 10.3.3.26 t=0 0 m=audio 16440 RTP/AVP 8 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv ------------------------------------------------------------------------ send 309 bytes to udp/[10.3.3.26]:5060 at 16:06:24.563166: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-c18fe00b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 101 INVITE User-Agent: FreeSWITCH Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:24.559488 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/5878272002@voice.polarisnetworks.ca [916c2d98-c758-11e8-9d0e-c3d2c916e7a3] 2018-10-03 16:06:24.559488 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_NEW (Cur 1 Tot 36) 2018-10-03 16:06:24.559488 [DEBUG] sofia.c:9834 sofia/internal/5878272002@voice.polarisnetworks.ca receiving invite from 10.3.3.26:5060 version: 1.6.17 64bit 2018-10-03 16:06:24.559488 [DEBUG] sofia.c:10005 IP 10.3.3.26 Rejected by acl "domains". Falling back to Digest auth. send 824 bytes to udp/[10.3.3.26]:5060 at 16:06:24.566858: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-c18fe00b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: ;tag=7H3gXyQea9Sye Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 101 INVITE User-Agent: FreeSWITCH Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Proxy-Authenticate: Digest realm="voice.polarisnetworks.ca", nonce="916c8cfc-c758-11e8-9d0f-c3d2c916e7a3", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:24.559488 [DEBUG] sofia.c:2334 detaching session 916c2d98-c758-11e8-9d0e-c3d2c916e7a3 2018-10-03 16:06:24.559488 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [17806860331@voice.polarisnetworks.ca] from ip 10.3.3.26 2018-10-03 16:06:24.559488 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/5878272002@voice.polarisnetworks.ca) State NEW recv 442 bytes from udp/[10.3.3.26]:5060 at 16:06:24.582048: ------------------------------------------------------------------------ ACK sip:17806860331@voice.polarisnetworks.ca SIP/2.0 Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-c18fe00b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: ;tag=7H3gXyQea9Sye Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 101 ACK Max-Forwards: 70 Contact: 5878272002 User-Agent: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 ------------------------------------------------------------------------ recv 1090 bytes from udp/[10.3.3.26]:5060 at 16:06:24.591014: ------------------------------------------------------------------------ INVITE sip:17806860331@voice.polarisnetworks.ca SIP/2.0 Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-beb255b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 102 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="5878272002",realm="voice.polarisnetworks.ca",nonce="916c8cfc-c758-11e8-9d0f-c3d2c916e7a3",uri="sip:17806860331@voice.polarisnetworks.ca",algorithm=MD5,response="3147e80a86a64ee560364738ddb17fea",qop=auth,nc=00000001,cnonce="f49c8cef" Contact: 5878272002 Expires: 240 User-Agent: Linksys/PAP2T-5.1.6(LS) Content-Length: 247 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura, replaces Content-Type: application/sdp v=0 o=- 406268 406268 IN IP4 10.3.3.26 s=- c=IN IP4 10.3.3.26 t=0 0 m=audio 16440 RTP/AVP 8 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv ------------------------------------------------------------------------ send 308 bytes to udp/[10.3.3.26]:5060 at 16:06:24.591502: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-beb255b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 102 INVITE User-Agent: FreeSWITCH Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:24.579415 [DEBUG] sofia.c:2442 Re-attaching to session 916c2d98-c758-11e8-9d0e-c3d2c916e7a3 2018-10-03 16:06:24.599416 [DEBUG] sofia.c:9834 sofia/internal/5878272002@voice.polarisnetworks.ca receiving invite from 10.3.3.26:5060 version: 1.6.17 64bit 2018-10-03 16:06:24.599416 [DEBUG] sofia.c:10005 IP 10.3.3.26 Rejected by acl "domains". Falling back to Digest auth. 2018-10-03 16:06:24.619416 [DEBUG] sofia.c:7048 Channel sofia/internal/5878272002@voice.polarisnetworks.ca entering state [received][100] 2018-10-03 16:06:24.619416 [DEBUG] sofia.c:7058 Remote SDP: v=0 o=- 406268 406268 IN IP4 10.3.3.26 s=- c=IN IP4 10.3.3.26 t=0 0 m=audio 16440 RTP/AVP 8 100 101 a=rtpmap:8 PCMA/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMU:0:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMA:8:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4472 Audio Codec Compare [PCMA:8:8000:20:64000:1] is saved as a near-match 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G7221:115:32000:20:48000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G7221:107:16000:20:32000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G722:9:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[GSM:3:8000:20:13200:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[PCMU:0:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[PCMA:8:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[G7221:115:32000:20:48000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[G7221:107:16000:20:32000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[G722:9:8000:20:64000:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [NSE:100:8000:30:0:1]/[GSM:3:8000:20:13200:1] 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 101@8000 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4543 Substituting codec PCMA@30i@8000h@1c 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/5878272002@voice.polarisnetworks.ca PCMA/8000 30 ms 240 samples 64000 bits 1 channels 2018-10-03 16:06:24.619416 [DEBUG] switch_core_codec.c:111 sofia/internal/5878272002@voice.polarisnetworks.ca Original read codec set to PCMA:8 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 101@8000 2018-10-03 16:06:24.619416 [DEBUG] switch_core_media.c:4754 sofia/internal/5878272002@voice.polarisnetworks.ca Set 2833 dtmf send payload to 101 recv payload to 101 2018-10-03 16:06:24.619416 [DEBUG] sofia.c:7471 (sofia/internal/5878272002@voice.polarisnetworks.ca) State Change CS_NEW -> CS_INIT 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_INIT (Cur 1 Tot 36) 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/5878272002@voice.polarisnetworks.ca) State INIT 2018-10-03 16:06:24.619416 [DEBUG] mod_sofia.c:90 sofia/internal/5878272002@voice.polarisnetworks.ca SOFIA INIT 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:40 sofia/internal/5878272002@voice.polarisnetworks.ca Standard INIT 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/5878272002@voice.polarisnetworks.ca) State Change CS_INIT -> CS_ROUTING 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/5878272002@voice.polarisnetworks.ca) State INIT going to sleep 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_ROUTING (Cur 1 Tot 36) 2018-10-03 16:06:24.619416 [DEBUG] switch_channel.c:2249 (sofia/internal/5878272002@voice.polarisnetworks.ca) Callstate Change DOWN -> RINGING 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/5878272002@voice.polarisnetworks.ca) State ROUTING 2018-10-03 16:06:24.619416 [DEBUG] mod_sofia.c:143 sofia/internal/5878272002@voice.polarisnetworks.ca SOFIA ROUTING 2018-10-03 16:06:24.619416 [DEBUG] switch_core_state_machine.c:236 sofia/internal/5878272002@voice.polarisnetworks.ca Standard ROUTING 2018-10-03 16:06:24.619416 [INFO] mod_dialplan_xml.c:637 Processing 5878272002 <5878272002>->17806860331 in context voice.polarisnetworks.ca Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->user_exists] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_exists] () =~ // break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE 2018-10-03 16:06:24.799427 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7eff0c0db020 Connected. 2018-10-03 16:06:24.839428 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7eff0c0db020 released. EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(user_exists=false) 2018-10-03 16:06:24.839428 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [user_exists]=[false] Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->call-direction] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca ANTI-Action set(call_direction=local) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->variables] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [variables] () =~ // break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action export(origination_callee_id_name=${destination_number}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->user_record] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] () =~ // break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE 2018-10-03 16:06:24.859428 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7eff0c0db020 Connected. 2018-10-03 16:06:24.879441 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7eff0c0db020 released. 2018-10-03 16:06:24.899422 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7eff0c0db020 Connected. 2018-10-03 16:06:24.899422 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7eff0c0db020 released. EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(user_record=) 2018-10-03 16:06:24.899422 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [user_record]=[UNDEF] Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(from_user_exists=true) 2018-10-03 16:06:24.919426 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [from_user_exists]=[true] Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(from_user_record=) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [from_user_record]=[UNDEF] Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->redial] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [redial] destination_number(17806860331) =~ /^(redial|\*870)$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [redial] () =~ // break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->speed_dial] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [speed_dial] destination_number(17806860331) =~ /^\*0(.*)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->agent_status] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [agent_status] destination_number(17806860331) =~ /^\*22$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->agent_status_id] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [agent_status_id] destination_number(17806860331) =~ /^\*23$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->group-intercept] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [group-intercept] destination_number(17806860331) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->page-extension] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [page-extension] destination_number(17806860331) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->eavesdrop] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [eavesdrop] destination_number(17806860331) =~ /^\*33(\d{2,7})$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->call_privacy] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call_privacy] destination_number(17806860331) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->call_return] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call_return] destination_number(17806860331) =~ /^\*69$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->extension_queue] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [extension_queue] destination_number(17806860331) =~ /^\*800(.*)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->intercept-ext] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [intercept-ext] destination_number(17806860331) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->intercept-ext-polycom] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [intercept-ext-polycom] destination_number(17806860331) =~ /^\*97(\d+)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->dx] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [dx] destination_number(17806860331) =~ /^dx$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->att_xfer] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [att_xfer] destination_number(17806860331) =~ /^att_xfer$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->extension-to-voicemail] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->send_to_voicemail] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [send_to_voicemail] destination_number(17806860331) =~ /^\*99(\d{2,10})$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->vmain] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [vmain] destination_number(17806860331) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->xfer_vm] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [xfer_vm] destination_number(17806860331) =~ /^xfer_vm$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->is_transfer] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [is_transfer] destination_number(17806860331) =~ /^is_transfer$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->vmain_user] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [vmain_user] destination_number(17806860331) =~ /^\*97$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->SSConference] continue= Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [SSConference] destination_number(17806860331) =~ /^299$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->SSCORPIVR] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [SSCORPIVR] destination_number(17806860331) =~ /^200$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->test] continue= Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [test] destination_number(17806860331) =~ /^300$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->cf] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [cf] destination_number(17806860331) =~ /^cf$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->delay_echo] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [delay_echo] destination_number(17806860331) =~ /^\*9195$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->echo] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [echo] destination_number(17806860331) =~ /^\*9196$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->is_zrtp_secure] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca ANTI-Action eval(not_secure) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->milliwatt] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [milliwatt] destination_number(17806860331) =~ /^\*9197$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->is_secure] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->tone_stream] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [tone_stream] destination_number(17806860331) =~ /^\*9198$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->hold_music] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [hold_music] destination_number(17806860331) =~ /^\*9664$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->recordings] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [recordings] destination_number(17806860331) =~ /^\*(732)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->directory] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [directory] destination_number(17806860331) =~ /^\*411$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->wake-up] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [wake-up] destination_number(17806860331) =~ /^\*(925)$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->valet_park] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [valet_park] destination_number(17806860331) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [valet_park] destination_number(17806860331) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [valet_park] destination_number(17806860331) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->operator] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [operator] destination_number(17806860331) =~ /^0$|^operator$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->operator-forward] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [operator-forward] destination_number(17806860331) =~ /^\*000$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->do-not-disturb] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [do-not-disturb] destination_number(17806860331) =~ /^\*77$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [do-not-disturb] destination_number(17806860331) =~ /^\*78$|\*363$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [do-not-disturb] destination_number(17806860331) =~ /^\*79$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->call-forward] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call-forward] destination_number(17806860331) =~ /^\*72$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call-forward] destination_number(17806860331) =~ /^\*73$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call-forward] destination_number(17806860331) =~ /^\*74$/ break=on-true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->follow-me] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [follow-me] destination_number(17806860331) =~ /^\*21$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->clear_sip_auto_answer] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->talking clock date and time] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [talking clock date and time] destination_number(17806860331) =~ /^\*9172$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->talking clock time] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [talking clock time] destination_number(17806860331) =~ /^\*9170$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->talking clock date] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [talking clock date] destination_number(17806860331) =~ /^\*9171$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->call_screen] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->local_extension] continue=true Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [local_extension] ${user_exists}(false) =~ /true/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->voicemail] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [voicemail] ${user_exists}(false) =~ /true/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->iTel_Incoming_Primary.10d] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (FAIL) [iTel_Incoming_Primary.10d] destination_number(17806860331) =~ /^(\d{10})$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca parsing [voice.polarisnetworks.ca->iTel_Incoming_Primary.11d] continue=false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Regex (PASS) [iTel_Incoming_Primary.11d] destination_number(17806860331) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(sip_h_X-accountcode=${accountcode}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(call_direction=outbound) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(hangup_after_bridge=true) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(inherit_codec=true) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(ignore_display_updates=true) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(callee_id_number=17806860331) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action set(continue_on_fail=true) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action bridge(sofia/gateway/e40ff470-da46-45a8-af74-3a2c32c909be/17806860331) Dialplan: sofia/internal/5878272002@voice.polarisnetworks.ca Action bridge(sofia/gateway/05b0a307-7d63-4c3e-990f-9e3c0280a661/17806860331) 2018-10-03 16:06:24.939417 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/5878272002@voice.polarisnetworks.ca) State Change CS_ROUTING -> CS_EXECUTE 2018-10-03 16:06:24.939417 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/5878272002@voice.polarisnetworks.ca) State ROUTING going to sleep 2018-10-03 16:06:24.939417 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_EXECUTE (Cur 1 Tot 36) 2018-10-03 16:06:24.939417 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/5878272002@voice.polarisnetworks.ca) State EXECUTE 2018-10-03 16:06:24.939417 [DEBUG] mod_sofia.c:198 sofia/internal/5878272002@voice.polarisnetworks.ca SOFIA EXECUTE 2018-10-03 16:06:24.939417 [DEBUG] switch_core_state_machine.c:328 sofia/internal/5878272002@voice.polarisnetworks.ca Standard EXECUTE EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(call_direction=local) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [call_direction]=[local] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca export(origination_callee_id_name=17806860331) 2018-10-03 16:06:24.939417 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[17806860331] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(RFC2822_DATE=Wed, 03 Oct 2018 16:06:24 -0600) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [RFC2822_DATE]=[Wed, 03 Oct 2018 16:06:24 -0600] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca hash(insert/voice.polarisnetworks.ca-last_dial/5878272002/17806860331) EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca eval(not_secure) EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(sip_h_X-accountcode=voice.polarisnetworks.ca) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [sip_h_X-accountcode]=[voice.polarisnetworks.ca] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(call_direction=outbound) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [call_direction]=[outbound] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(hangup_after_bridge=true) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [hangup_after_bridge]=[true] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(effective_caller_id_name=Stellar Solutions) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [effective_caller_id_name]=[Stellar Solutions] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(effective_caller_id_number=7804266220) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [effective_caller_id_number]=[7804266220] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(inherit_codec=true) 2018-10-03 16:06:24.939417 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [inherit_codec]=[true] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(ignore_display_updates=true) 2018-10-03 16:06:24.959409 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [ignore_display_updates]=[true] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(callee_id_number=17806860331) 2018-10-03 16:06:24.959409 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [callee_id_number]=[17806860331] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca set(continue_on_fail=true) 2018-10-03 16:06:24.959409 [DEBUG] mod_dptools.c:1530 SET sofia/internal/5878272002@voice.polarisnetworks.ca [continue_on_fail]=[true] EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca bridge(sofia/gateway/e40ff470-da46-45a8-af74-3a2c32c909be/17806860331) 2018-10-03 16:06:24.959409 [DEBUG] switch_channel.c:1250 sofia/internal/5878272002@voice.polarisnetworks.ca EXPORTING[export_vars] [domain_name]=[voice.polarisnetworks.ca] to event 2018-10-03 16:06:24.959409 [DEBUG] switch_channel.c:1250 sofia/internal/5878272002@voice.polarisnetworks.ca EXPORTING[export_vars] [origination_callee_id_name]=[17806860331] to event 2018-10-03 16:06:24.959409 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-10-03 16:06:24.959409 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/17806860331 [91a963a2-c758-11e8-9d27-c3d2c916e7a3] 2018-10-03 16:06:24.959409 [DEBUG] mod_sofia.c:4818 (sofia/internal/17806860331) State Change CS_NEW -> CS_INIT 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_INIT (Cur 2 Tot 37) 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/17806860331) State INIT 2018-10-03 16:06:24.979409 [DEBUG] mod_sofia.c:90 sofia/internal/17806860331 SOFIA INIT 2018-10-03 16:06:24.979409 [DEBUG] sofia_glue.c:1295 sofia/internal/17806860331 sending invite version: 1.6.17 64bit Local SDP: v=0 o=FreeSWITCH 1538585382 1538585383 IN IP4 10.3.3.53 s=FreeSWITCH c=IN IP4 10.3.3.53 t=0 0 m=audio 19002 RTP/AVP 8 101 13 104 105 106 107 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:13 CN/8000 a=rtpmap:105 CN/32000 a=rtpmap:107 CN/16000 a=ptime:30 a=sendrecv m=audio 19002 RTP/AVP 0 8 102 103 9 3 101 13 104 105 106 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:102 G7221/32000 a=fmtp:102 bitrate=48000 a=rtpmap:103 G7221/16000 a=fmtp:103 bitrate=32000 a=rtpmap:9 G722/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:13 CN/8000 a=rtpmap:105 CN/32000 a=rtpmap:107 CN/16000 a=ptime:20 a=sendrecv send 1935 bytes to udp/[65.39.243.100]:5060 at 16:06:24.988465: ------------------------------------------------------------------------ INVITE sip:17806860331@sbc.itel.com SIP/2.0 Via: SIP/2.0/UDP 10.3.3.53;rport;branch=z9hG4bKF2X3BF9F54KQj Max-Forwards: 69 From: "Stellar Solutions" ;tag=93N20mSN4t63N To: Call-ID: 690b3991-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 INVITE Contact: User-Agent: FreeSWITCH Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 896 X-accountcode: voice.polarisnetworks.ca X-FS-Support: update_display,send_info Remote-Party-ID: "Stellar Solutions" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1538585382 1538585383 IN IP4 10.3.3.53 s=FreeSWITCH c=IN IP4 10.3.3.53 t=0 0 m=audio 19002 RTP/AVP 8 101 13 104 105 106 107 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:105 CN/32000 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:107 CN/16000 a=ptime:30 m=audio 19002 RTP/AVP 0 8 102 103 9 3 101 13 104 105 106 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:102 G7221/32000 a=fmtp:102 bitrate=48000 a=rtpmap:103 G7221/16000 a=fmtp:103 bitrate=32000 a=rtpmap:9 G722/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:105 CN/32000 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:107 CN/16000 a=ptime:20 ------------------------------------------------------------------------ 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:40 sofia/internal/17806860331 Standard INIT 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/17806860331) State Change CS_INIT -> CS_ROUTING 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/17806860331) State INIT going to sleep 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_ROUTING (Cur 2 Tot 37) 2018-10-03 16:06:24.979409 [DEBUG] sofia.c:7048 Channel sofia/internal/17806860331 entering state [calling][0] 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/17806860331) State ROUTING 2018-10-03 16:06:24.979409 [DEBUG] mod_sofia.c:143 sofia/internal/17806860331 SOFIA ROUTING 2018-10-03 16:06:24.979409 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/17806860331) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/17806860331) State ROUTING going to sleep 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 37) 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/17806860331) State CONSUME_MEDIA 2018-10-03 16:06:24.979409 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/17806860331) State CONSUME_MEDIA going to sleep recv 475 bytes from udp/[65.39.243.100]:5060 at 16:06:25.029654: ------------------------------------------------------------------------ SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 10.3.3.53:5060;branch=z9hG4bKF2X3BF9F54KQj;received=10.3.3.53;rport=5060 From: "Stellar Solutions" ;tag=93N20mSN4t63N To: ;tag=as0893c208 Call-ID: 690b3991-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 INVITE Server: Itelnetworks Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces Content-Length: 0 ------------------------------------------------------------------------ send 335 bytes to udp/[65.39.243.100]:5060 at 16:06:25.029983: ------------------------------------------------------------------------ ACK sip:17806860331@sbc.itel.com SIP/2.0 Via: SIP/2.0/UDP 10.3.3.53;rport;branch=z9hG4bKF2X3BF9F54KQj Max-Forwards: 69 From: "Stellar Solutions" ;tag=93N20mSN4t63N To: ;tag=as0893c208 Call-ID: 690b3991-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 ACK Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:25.019419 [DEBUG] sofia.c:7048 Channel sofia/internal/17806860331 entering state [terminated][488] 2018-10-03 16:06:25.019419 [NOTICE] sofia.c:8237 Hangup sofia/internal/17806860331 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION] 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_HANGUP (Cur 2 Tot 37) 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/17806860331) Callstate Change DOWN -> HANGUP 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/17806860331) State HANGUP 2018-10-03 16:06:25.019419 [DEBUG] mod_sofia.c:438 Channel sofia/internal/17806860331 hanging up, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:60 sofia/internal/17806860331 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/17806860331) State HANGUP going to sleep 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/17806860331) State Change CS_HANGUP -> CS_REPORTING 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_REPORTING (Cur 2 Tot 37) 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/17806860331) State REPORTING 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:174 sofia/internal/17806860331 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/17806860331) State REPORTING going to sleep 2018-10-03 16:06:25.019419 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/17806860331) State Change CS_REPORTING -> CS_DESTROY 2018-10-03 16:06:25.019419 [DEBUG] switch_core_session.c:1664 Session 37 (sofia/internal/17806860331) Locked, Waiting on external entities 2018-10-03 16:06:25.019419 [DEBUG] switch_ivr_originate.c:3833 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2018-10-03 16:06:25.019419 [INFO] mod_dptools.c:3418 Originate Failed. Cause: INCOMPATIBLE_DESTINATION EXECUTE sofia/internal/5878272002@voice.polarisnetworks.ca bridge(sofia/gateway/05b0a307-7d63-4c3e-990f-9e3c0280a661/17806860331) 2018-10-03 16:06:25.019419 [DEBUG] switch_channel.c:1250 sofia/internal/5878272002@voice.polarisnetworks.ca EXPORTING[export_vars] [domain_name]=[voice.polarisnetworks.ca] to event 2018-10-03 16:06:25.019419 [DEBUG] switch_channel.c:1250 sofia/internal/5878272002@voice.polarisnetworks.ca EXPORTING[export_vars] [origination_callee_id_name]=[17806860331] to event 2018-10-03 16:06:25.019419 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2018-10-03 16:06:25.019419 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/17806860331 [91b49e66-c758-11e8-9d2f-c3d2c916e7a3] 2018-10-03 16:06:25.019419 [DEBUG] mod_sofia.c:4818 (sofia/internal/17806860331) State Change CS_NEW -> CS_INIT 2018-10-03 16:06:25.039421 [NOTICE] switch_core_session.c:1682 Session 37 (sofia/internal/17806860331) Ended 2018-10-03 16:06:25.039421 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/17806860331 [CS_DESTROY] 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/17806860331) Running State Change CS_DESTROY (Cur 2 Tot 38) 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/17806860331) State DESTROY 2018-10-03 16:06:25.039421 [DEBUG] mod_sofia.c:343 sofia/internal/17806860331 SOFIA DESTROY 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:181 sofia/internal/17806860331 Standard DESTROY 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/17806860331) State DESTROY going to sleep 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_INIT (Cur 2 Tot 38) 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/17806860331) State INIT 2018-10-03 16:06:25.039421 [DEBUG] mod_sofia.c:90 sofia/internal/17806860331 SOFIA INIT 2018-10-03 16:06:25.039421 [DEBUG] sofia_glue.c:1295 sofia/internal/17806860331 sending invite version: 1.6.17 64bit Local SDP: v=0 o=FreeSWITCH 1538584465 1538584466 IN IP4 10.3.3.53 s=FreeSWITCH c=IN IP4 10.3.3.53 t=0 0 m=audio 19920 RTP/AVP 8 101 13 104 105 106 107 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:13 CN/8000 a=rtpmap:105 CN/32000 a=rtpmap:107 CN/16000 a=ptime:30 a=sendrecv m=audio 19920 RTP/AVP 0 8 102 103 9 3 101 13 104 105 106 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:102 G7221/32000 a=fmtp:102 bitrate=48000 a=rtpmap:103 G7221/16000 a=fmtp:103 bitrate=32000 a=rtpmap:9 G722/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:13 CN/8000 a=rtpmap:105 CN/32000 a=rtpmap:107 CN/16000 a=ptime:20 a=sendrecv send 1939 bytes to udp/[199.192.104.104]:5060 at 16:06:25.043020: ------------------------------------------------------------------------ INVITE sip:17806860331@sbc2.itel.com SIP/2.0 Via: SIP/2.0/UDP 10.3.3.53;rport;branch=z9hG4bKgBQvDatK2Daae Max-Forwards: 69 From: "Stellar Solutions" ;tag=aDFU2FaS13vpH To: Call-ID: 69138d81-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 INVITE Contact: User-Agent: FreeSWITCH Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 896 X-accountcode: voice.polarisnetworks.ca X-FS-Support: update_display,send_info Remote-Party-ID: "Stellar Solutions" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1538584465 1538584466 IN IP4 10.3.3.53 s=FreeSWITCH c=IN IP4 10.3.3.53 t=0 0 m=audio 19920 RTP/AVP 8 101 13 104 105 106 107 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:105 CN/32000 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:107 CN/16000 a=ptime:30 m=audio 19920 RTP/AVP 0 8 102 103 9 3 101 13 104 105 106 107 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:102 G7221/32000 a=fmtp:102 bitrate=48000 a=rtpmap:103 G7221/16000 a=fmtp:103 bitrate=32000 a=rtpmap:9 G722/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=rtpmap:104 telephone-event/32000 a=fmtp:104 0-16 a=rtpmap:105 CN/32000 a=rtpmap:106 telephone-event/16000 a=fmtp:106 0-16 a=rtpmap:107 CN/16000 a=ptime:20 ------------------------------------------------------------------------ 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:40 sofia/internal/17806860331 Standard INIT 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/17806860331) State Change CS_INIT -> CS_ROUTING 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/17806860331) State INIT going to sleep 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_ROUTING (Cur 2 Tot 38) 2018-10-03 16:06:25.039421 [DEBUG] sofia.c:7048 Channel sofia/internal/17806860331 entering state [calling][0] 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/17806860331) State ROUTING 2018-10-03 16:06:25.039421 [DEBUG] mod_sofia.c:143 sofia/internal/17806860331 SOFIA ROUTING 2018-10-03 16:06:25.039421 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/17806860331) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/17806860331) State ROUTING going to sleep 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 38) 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/17806860331) State CONSUME_MEDIA 2018-10-03 16:06:25.039421 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/17806860331) State CONSUME_MEDIA going to sleep recv 484 bytes from udp/[199.192.104.104]:5060 at 16:06:25.091357: ------------------------------------------------------------------------ SIP/2.0 488 Not acceptable here Via: SIP/2.0/UDP 10.3.3.53:5060;branch=z9hG4bKgBQvDatK2Daae;received=10.3.3.53;rport=5060 From: "Stellar Solutions" ;tag=aDFU2FaS13vpH To: ;tag=as743e02cc Call-ID: 69138d81-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 INVITE Server: Itelnetworks Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE Supported: replaces, timer Content-Length: 0 ------------------------------------------------------------------------ send 338 bytes to udp/[199.192.104.104]:5060 at 16:06:25.091707: ------------------------------------------------------------------------ ACK sip:17806860331@sbc2.itel.com SIP/2.0 Via: SIP/2.0/UDP 10.3.3.53;rport;branch=z9hG4bKgBQvDatK2Daae Max-Forwards: 69 From: "Stellar Solutions" ;tag=aDFU2FaS13vpH To: ;tag=as743e02cc Call-ID: 69138d81-41fb-1237-a3b5-96b7153df19c CSeq: 128966128 ACK Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:25.079435 [DEBUG] sofia.c:7048 Channel sofia/internal/17806860331 entering state [terminated][488] 2018-10-03 16:06:25.079435 [NOTICE] sofia.c:8237 Hangup sofia/internal/17806860331 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION] 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_HANGUP (Cur 2 Tot 38) 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/17806860331) Callstate Change DOWN -> HANGUP 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/17806860331) State HANGUP 2018-10-03 16:06:25.079435 [DEBUG] mod_sofia.c:438 Channel sofia/internal/17806860331 hanging up, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:60 sofia/internal/17806860331 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/17806860331) State HANGUP going to sleep 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/17806860331) State Change CS_HANGUP -> CS_REPORTING 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/17806860331) Running State Change CS_REPORTING (Cur 2 Tot 38) 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/17806860331) State REPORTING 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:174 sofia/internal/17806860331 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/17806860331) State REPORTING going to sleep 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/17806860331) State Change CS_REPORTING -> CS_DESTROY 2018-10-03 16:06:25.079435 [DEBUG] switch_core_session.c:1664 Session 38 (sofia/internal/17806860331) Locked, Waiting on external entities 2018-10-03 16:06:25.079435 [DEBUG] switch_ivr_originate.c:3833 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2018-10-03 16:06:25.079435 [INFO] mod_dptools.c:3418 Originate Failed. Cause: INCOMPATIBLE_DESTINATION 2018-10-03 16:06:25.079435 [NOTICE] switch_core_state_machine.c:385 sofia/internal/5878272002@voice.polarisnetworks.ca has executed the last dialplan instruction, hanging up. 2018-10-03 16:06:25.079435 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/5878272002@voice.polarisnetworks.ca [CS_EXECUTE] [NORMAL_CLEARING] 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/5878272002@voice.polarisnetworks.ca) State EXECUTE going to sleep 2018-10-03 16:06:25.079435 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_HANGUP (Cur 2 Tot 38) 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/5878272002@voice.polarisnetworks.ca) Callstate Change RINGING -> HANGUP 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/5878272002@voice.polarisnetworks.ca) State HANGUP 2018-10-03 16:06:25.099411 [DEBUG] mod_sofia.c:432 sofia/internal/5878272002@voice.polarisnetworks.ca Overriding SIP cause 480 with 488 from the other leg 2018-10-03 16:06:25.099411 [DEBUG] mod_sofia.c:438 Channel sofia/internal/5878272002@voice.polarisnetworks.ca hanging up, cause: NORMAL_CLEARING 2018-10-03 16:06:25.099411 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 488 send 855 bytes to udp/[10.3.3.26]:5060 at 16:06:25.101482: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-beb255b Max-Forwards: 70 From: 5878272002 ;tag=7c71b9d8f3653583o0 To: ;tag=8tv9yS8H7HgHa Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 102 INVITE User-Agent: FreeSWITCH Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 Remote-Party-ID: "17806860331" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:60 sofia/internal/5878272002@voice.polarisnetworks.ca Standard HANGUP, cause: NORMAL_CLEARING 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/5878272002@voice.polarisnetworks.ca) State HANGUP going to sleep 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/5878272002@voice.polarisnetworks.ca) State Change CS_HANGUP -> CS_REPORTING 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_REPORTING (Cur 2 Tot 38) 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/5878272002@voice.polarisnetworks.ca) State REPORTING 2018-10-03 16:06:25.099411 [NOTICE] switch_core_session.c:1682 Session 38 (sofia/internal/17806860331) Ended 2018-10-03 16:06:25.099411 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/17806860331 [CS_DESTROY] 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/17806860331) Running State Change CS_DESTROY (Cur 1 Tot 38) 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/17806860331) State DESTROY 2018-10-03 16:06:25.099411 [DEBUG] mod_sofia.c:343 sofia/internal/17806860331 SOFIA DESTROY 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:181 sofia/internal/17806860331 Standard DESTROY 2018-10-03 16:06:25.099411 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/17806860331) State DESTROY going to sleep recv 714 bytes from udp/[10.3.3.26]:5060 at 16:06:25.119712: ------------------------------------------------------------------------ ACK sip:17806860331@voice.polarisnetworks.ca SIP/2.0 Via: SIP/2.0/UDP 10.3.3.26:5060;branch=z9hG4bK-beb255b From: 5878272002 ;tag=7c71b9d8f3653583o0 To: ;tag=8tv9yS8H7HgHa Call-ID: d7fdac3c-7a01466f@10.3.3.26 CSeq: 102 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="5878272002",realm="voice.polarisnetworks.ca",nonce="916c8cfc-c758-11e8-9d0f-c3d2c916e7a3",uri="sip:17806860331@voice.polarisnetworks.ca",algorithm=MD5,response="3147e80a86a64ee560364738ddb17fea",qop=auth,nc=00000001,cnonce="f49c8cef" Contact: 5878272002 User-Agent: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 ------------------------------------------------------------------------ 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:174 sofia/internal/5878272002@voice.polarisnetworks.ca Standard REPORTING, cause: NORMAL_CLEARING 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/5878272002@voice.polarisnetworks.ca) State REPORTING going to sleep 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/5878272002@voice.polarisnetworks.ca) State Change CS_REPORTING -> CS_DESTROY 2018-10-03 16:06:25.819437 [DEBUG] switch_core_session.c:1664 Session 36 (sofia/internal/5878272002@voice.polarisnetworks.ca) Locked, Waiting on external entities 2018-10-03 16:06:25.819437 [NOTICE] switch_core_session.c:1682 Session 36 (sofia/internal/5878272002@voice.polarisnetworks.ca) Ended 2018-10-03 16:06:25.819437 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/5878272002@voice.polarisnetworks.ca [CS_DESTROY] 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/5878272002@voice.polarisnetworks.ca) Running State Change CS_DESTROY (Cur 0 Tot 38) 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/5878272002@voice.polarisnetworks.ca) State DESTROY 2018-10-03 16:06:25.819437 [DEBUG] mod_sofia.c:343 sofia/internal/5878272002@voice.polarisnetworks.ca SOFIA DESTROY 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:181 sofia/internal/5878272002@voice.polarisnetworks.ca Standard DESTROY 2018-10-03 16:06:25.819437 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/5878272002@voice.polarisnetworks.ca) State DESTROY going to sleep