Original Sip Trace before changing codec negotation settings: Mon May 08 2017 15:02:31 GMT+1200 (New Zealand Standard Time) | sip.transport | received WebSocket text message: SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/WS 8cl1o75ecd4v.invalid;branch=z9hG4bK635563;received=111.69.178.179;rport=59909 Max-Forwards: 70 From: ;tag=dvgo1ufg6i To: ;tag=663v5Smt28KFr Call-ID: 5dd9vgn8aofl4p8pcmre CSeq: 6467 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit 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=88;text="INCOMPATIBLE_DESTINATION" Content-Length: 0 Remote-Party-ID: "1001" ;party=calling;privacy=off;screen=no 2017-05-08 04:01:54.569551 [ERR] mod_sofia.c:2491 CODEC NEGOTIATION ERROR. SDP: v=0 o=- 4107240291395641169 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE data a=msid-semantic: WMS m=application 59754 DTLS/SCTP 5000 c=IN IP4 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 59754 typ host generation 0 network-cost 50 a=candidate:519633664 1 udp 2113939711 IP6 typ host generation 0 network-cost 50 a=candidate:842163049 1 udp 1677729535 IP 59754 typ srflx raddr 192.168.178.32 rport 59754 generation 0 network-cost 50 a=ice-ufrag:mcEl a=ice-pwd:RTt4tpf5/pvftC5JN6zydT0/ a=fingerprint:sha-256 22:1F:B8:FF:EA:63:8A:64:33:09:D5:B5:4A:08:6D:22:A1:9A:94:0A:34:CB:49:FF:A0:2A:9D:DB:D5:6A:B5:28 a=setup:actpass a=mid:data a=sctpmap:5000 webrtc-datachannel 1024 2017-05-08 04:05:46.089495 [NOTICE] switch_channel.c:3522 Hangup sofia/internal/1001@104.130.11.232 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION] 2017-05-08 04:05:46.089495 [DEBUG] switch_ivr_originate.c:1221 sofia/internal/1001@104.130.11.232 Media Establishment Failed. 2017-05-08 04:05:46.089495 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 2017-05-08 04:05:46.089495 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/hmk51v8e@dossu8lknnc7.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2017-05-08 04:05:46.089495 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] COMPLETE DEBUG LOG: 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1002@104.130.11.232 [225e4f44-e81f-4e5f-a947-d53528e33ed1] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_NEW (Cur 1 Tot 28) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:10028 sofia/internal/1002@104.130.11.232 receiving invite from 111.69.178.179:50029 version: 1.9.0 -380-c66a012 64bit 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:10199 IP 111.69.178.179 Rejected by acl "domains". Falling back to Digest auth. 2017-05-08 22:25:41.981253 [DEBUG] sofia.c:2405 detaching session 225e4f44-e81f-4e5f-a947-d53528e33ed1 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:41.981253 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1002@104.130.11.232) State NEW 2017-05-08 22:25:42.221265 [DEBUG] sofia.c:2514 Re-attaching to session 225e4f44-e81f-4e5f-a947-d53528e33ed1 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:10028 sofia/internal/1002@104.130.11.232 receiving invite from 111.69.178.179:50029 version: 1.9.0 -380-c66a012 64bit 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:10199 IP 111.69.178.179 Rejected by acl "domains". Falling back to Digest auth. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:11325 Setting NAT mode based on websockets 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7247 Channel sofia/internal/1002@104.130.11.232 entering state [received][100] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7257 Remote SDP: 225e4f44-e81f-4e5f-a947-d53528e33ed1 v=0 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=- 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024 225e4f44-e81f-4e5f-a947-d53528e33ed1 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] sofia.c:7649 (sofia/internal/1002@104.130.11.232) State Change CS_NEW -> CS_INIT 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_INIT (Cur 1 Tot 28) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@104.130.11.232) State INIT 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:93 sofia/internal/1002@104.130.11.232 SOFIA INIT 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1002@104.130.11.232 Standard INIT 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1002@104.130.11.232) State Change CS_INIT -> CS_ROUTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1002@104.130.11.232) State INIT going to sleep 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_ROUTING (Cur 1 Tot 28) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:2249 (sofia/internal/1002@104.130.11.232) Callstate Change DOWN -> RINGING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@104.130.11.232) State ROUTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:154 sofia/internal/1002@104.130.11.232 SOFIA ROUTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1002@104.130.11.232 Standard ROUTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] mod_dialplan_xml.c:637 Processing 1002 <1002>->1001 in context default 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->unloop] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->tod_example] continue=true 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Date/TimeMatch (FAIL) [tod_example] break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->holiday_example] continue=true 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Date/TimeMatch (FAIL) [holiday_example] break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->global-intercept] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global-intercept] destination_number(1001) =~ /^886$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->group-intercept] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->intercept-ext] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->redial] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|870)$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->global] continue=true 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${default_password}($AXB67ky32$) =~ /^1234$/ break=never 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_$225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [global] ${switch_r_sdp}(v=0 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=- 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024 225e4f44-e81f-4e5f-a947-d53528e33ed1 ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Absolute Condition [global] 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/global/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->snom-demo-2] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [snom-demo-2] destination_number(1001) =~ /^9001$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->snom-demo-1] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [snom-demo-1] destination_number(1001) =~ /^9000$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->eavesdrop] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->eavesdrop] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^779$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call_return] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$|^869$|^lcr$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->del-group] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [del-group] destination_number(1001) =~ /^80(\d{2})$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->add-group] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [add-group] destination_number(1001) =~ /^81(\d{2})$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call-group-simo] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call-group-simo] destination_number(1001) =~ /^82(\d{2})$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->call-group-order] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [call-group-order] destination_number(1001) =~ /^83(\d{2})$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->extension-intercom] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (FAIL) [extension-intercom] destination_number(1001) =~ /^8(10[01][0-9])$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 parsing [default->Local_Extension] continue=false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Regex (PASS) [Local_Extension] destination_number(1001) =~ /^(10[01][0-9])$/ break=on-false 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action export(dialed_extension=1001) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(1 b s execute_extension::dx XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.w$225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(3 b s execute_extension::cf XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bind_meta_app(4 b s execute_extension::att_xfer XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(ringback=${us-ring}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(transfer_ringback=local_stream://moh) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(call_timeout=30) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(hangup_after_bridge=true) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(continue_on_fail=true) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bridge(user/${dialed_extension}@${domain_name}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action answer() 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action sleep(1000) 225e4f44-e81f-4e5f-a947-d53528e33ed1 Dialplan: sofia/internal/1002@104.130.11.232 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension}) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1002@104.130.11.232) State Change CS_ROUTING -> CS_EXECUTE 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1002@104.130.11.232) State ROUTING going to sleep 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_EXECUTE (Cur 1 Tot 28) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@104.130.11.232) State EXECUTE 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_sofia.c:209 sofia/internal/1002@104.130.11.232 SOFIA EXECUTE 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1002@104.130.11.232 Standard EXECUTE 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-spymap/1002/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/1002/1001) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/global/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 export(RFC2822_DATE=Mon, 08 May 2017 22:25:42 +0000) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 export(dialed_extension=1001) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1001] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(1 b s execute_extension::dx XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *1 execute_extension::dx XML features 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(2 b s record_session::/var/lib/freeswitch/recordings/1002.2017-05-08-22-25-42.wav) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *2 record_session::/var/lib/freeswitch/recordings/1002.2017-05-08-22-25-42.wav 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(3 b s execute_extension::cf XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *3 execute_extension::cf XML features 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bind_meta_app(4 b s execute_extension::att_xfer XML features) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [INFO] switch_ivr_async.c:4252 Bound B-Leg: *4 execute_extension::att_xfer XML features 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(ringback=%(2000,4000,440,480)) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [ringback]=[%(2000,4000,440,480)] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(transfer_ringback=local_stream://moh) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [transfer_ringback]=[local_stream://moh] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(call_timeout=30) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [call_timeout]=[30] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(hangup_after_bridge=true) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [hangup_after_bridge]=[true] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(continue_on_fail=true) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [continue_on_fail]=[true] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-call_return/1001/1002) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/1001/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 set(called_party_callgroup=techsupport) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] mod_dptools.c:1570 SET sofia/internal/1002@104.130.11.232 [called_party_callgroup]=[techsupport] 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/techsupport/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial_ext/global/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 hash(insert/104.130.11.232-last_dial/techsupport/225e4f44-e81f-4e5f-a947-d53528e33ed1) 225e4f44-e81f-4e5f-a947-d53528e33ed1 EXECUTE sofia/internal/1002@104.130.11.232 bridge(user/1001@104.130.11.232) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000] to event225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [dialed_extension]=[1001] to event 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.241262 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 08 May 2017 22:25:42 +0000] to event225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_channel.c:1250 sofia/internal/1002@104.130.11.232 EXPORTING[export_vars] [dialed_extension]=[1001] to event 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.261251 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid [76ab1f55-6a07-4a68-a7e4-360519c4acf1] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:5012 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_NEW -> CS_INIT 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_INIT (Cur 2 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State INIT 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:93 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA INIT 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia_glue.c:1267 sip:is2jtitt@111.69.178.179:50038;transport=ws Setting proxy route to sofia/internal/is2jtitt@cieonafgfl9q.invalid 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia_glue.c:1298 sofia/internal/is2jtitt@cieonafgfl9q.invalid sending invite version: 1.9.0 -380-c66a012 64bit 76ab1f55-6a07-4a68-a7e4-360519c4acf1 Local SDP: 76ab1f55-6a07-4a68-a7e4-360519c4acf1 v=0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 o=FreeSWITCH 1494265954 1494265955 IN IP4 104.130.11.232 76ab1f55-6a07-4a68-a7e4-360519c4acf1 s=FreeSWITCH 76ab1f55-6a07-4a68-a7e4-360519c4acf1 c=IN IP4 104.130.11.232 76ab1f55-6a07-4a68-a7e4-360519c4acf1 t=0 0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=msid-semantic: WMS Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh 76ab1f55-6a07-4a68-a7e4-360519c4acf1 m=audio 16388 RTP/SAVPF 9 0 8 101 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:9 G722/8000 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:0 PCMU/8000 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:8 PCMA/8000 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:101 telephone-event/8000 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=fingerprint:sha-256 76:9A:20:F8:92:01:15:BC:4F:B7:4E:6C:D8:B7:10:2E:B8:D1:19:2E:E3:5F:96:62:D4:B5:82:2B:1D:82:57:A8 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=setup:actpass 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-mux 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp:16388 IN IP4 104.130.11.232 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 cname:JPrAmDIgupg2B4au 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 msid:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh a0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 mslabel:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2971565262 label:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDha0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-ufrag:tzRaNtr6wcKOodVX 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-pwd:Tdo5GXc0ciTEdTYxjFxWZPTl 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:0170082732 1 udp 659136 104.130.11.232 16388 typ host generation 0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:0170082732 2 udp 659136 104.130.11.232 16388 typ host generation 0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ptime:20 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=sendrecv 76ab1f55-6a07-4a68-a7e4-360519c4acf1 m=video 16384 RTP/SAVPF 102 76ab1f55-6a07-4a68-a7e4-360519c4acf1 b=AS:1024 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtpmap:102 VP8/90000 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=sendrecv 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=fingerprint:sha-256 76:9A:20:F8:92:01:15:BC:4F:B7:4E:6C:D8:B7:10:2E:B8:D1:19:2E:E3:5F:96:62:D4:B5:82:2B:1D:82:57:A8 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=setup:actpass 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-mux 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp:16384 IN IP4 104.130.11.232 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 ccm fir 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 ccm tmmbr 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 nack 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=rtcp-fb:102 nack pli 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 cname:JPrAmDIgupg2B4au 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 msid:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh v0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 mslabel:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDh 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ssrc:2224445067 label:Zwmk1Glh30QaIlYh3yywQnLrtdUNrmDhv0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-ufrag:IFzrWUdM2OfbcPov 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=ice-pwd:yDpZFPT8jhFy8WwGrWYeG1Qz 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:6684695135 1 udp 659136 104.130.11.232 16384 typ host generation 0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=candidate:6684695135 2 udp 659135 104.130.11.232 16384 typ host generation 0 76ab1f55-6a07-4a68-a7e4-360519c4acf1 a=end-of-candidates 76ab1f55-6a07-4a68-a7e4-360519c4acf1 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:40 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard INIT 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_INIT -> CS_ROUTING 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State INIT going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_ROUTING (Cur 2 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] sofia.c:7247 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid entering state [calling][0] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State ROUTING 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] mod_sofia.c:154 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA ROUTING 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_ROUTING -> CS_CONSUME_MEDIA 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State ROUTING going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State CONSUME_MEDIA 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.261251 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State CONSUME_MEDIA going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [DEBUG] sofia.c:7247 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid entering state [proceeding][180] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [NOTICE] sofia.c:7355 Ring-Ready sofia/internal/is2jtitt@cieonafgfl9q.invalid! 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.541258 [DEBUG] switch_channel.c:3353 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Callstate Change DOWN -> RINGING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [INFO] switch_ivr_originate.c:1220 Sending early media 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [ERR] mod_sofia.c:2491 CODEC NEGOTIATION ERROR. SDP: 225e4f44-e81f-4e5f-a947-d53528e33ed1 v=0 225e4f44-e81f-4e5f-a947-d53528e33ed1 o=- 2572589166536569753 2 IN IP4 127.0.0.1 225e4f44-e81f-4e5f-a947-d53528e33ed1 s=- 225e4f44-e81f-4e5f-a947-d53528e33ed1 t=0 0 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=group:BUNDLE data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=msid-semantic: WMS 225e4f44-e81f-4e5f-a947-d53528e33ed1 m=application 52428 DTLS/SCTP 5000 225e4f44-e81f-4e5f-a947-d53528e33ed1 c=IN IP4 111.69.178.179 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 52428 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 52429 typ host generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=candidate:842163049 1 udp 1677729535 111.69.178.179 52428 typ srflx raddr 192.168.178.32 rport 52428 generation 0 network-cost 50 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-ufrag:g5pc 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=ice-pwd:jqxsQvsj7iQ+9s2Qn4WEX6Vb 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=fingerprint:sha-256 51:DD:BE:FE:0B:22:47:B5:60:A1:23:2E:D4:58:C8:68:ED:6A:7C:C3:81:4E:20:AC:CE:2F:6C:8E:00:CF:AF:FD 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=setup:actpass 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=mid:data 225e4f44-e81f-4e5f-a947-d53528e33ed1 a=sctpmap:5000 webrtc-datachannel 1024 225e4f44-e81f-4e5f-a947-d53528e33ed1 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_channel.c:3522 Hangup sofia/internal/1002@104.130.11.232 [CS_EXECUTE] [INCOMPATIBLE_DESTINATION] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:1221 sofia/internal/1002@104.130.11.232 Media Establishment Failed. 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:3928 Hangup sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_ivr_originate.c:2850 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_ivr_originate.c:3838 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [INFO] mod_dptools.c:3490 Originate Failed. Cause: ORIGINATOR_CANCEL 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:2884 sofia/internal/1002@104.130.11.232 skip receive message [PHONE_EVENT] (channel is hungup already) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1002@104.130.11.232) State EXECUTE going to sleep 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_HANGUP (Cur 2 Tot 29) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1002@104.130.11.232) Callstate Change RINGING -> HANGUP 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@104.130.11.232) State HANGUP 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1002@104.130.11.232 hanging up, cause: INCOMPATIBLE_DESTINATION 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:588 Responding to INVITE with: 488 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1002@104.130.11.232 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1002@104.130.11.232) State HANGUP going to sleep 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1002@104.130.11.232) State Change CS_HANGUP -> CS_REPORTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1002@104.130.11.232) Running State Change CS_REPORTING (Cur 2 Tot 29) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@104.130.11.232) State REPORTING 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1002@104.130.11.232 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1002@104.130.11.232) State REPORTING going to sleep 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1002@104.130.11.232) State Change CS_REPORTING -> CS_DESTROY 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:1712 Session 28 (sofia/internal/1002@104.130.11.232) Locked, Waiting on external entities 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1730 Session 28 (sofia/internal/1002@104.130.11.232) Ended 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1734 Close Channel sofia/internal/1002@104.130.11.232 [CS_DESTROY] 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1002@104.130.11.232) Running State Change CS_DESTROY (Cur 1 Tot 29) 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@104.130.11.232) State DESTROY 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:354 sofia/internal/1002@104.130.11.232 SOFIA DESTROY 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1002@104.130.11.232 Standard DESTROY 225e4f44-e81f-4e5f-a947-d53528e33ed1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1002@104.130.11.232) State DESTROY going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_HANGUP (Cur 1 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Callstate Change RINGING -> HANGUP 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State HANGUP 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:449 Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid hanging up, cause: ORIGINATOR_CANCEL 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:513 Sending CANCEL to sofia/internal/is2jtitt@cieonafgfl9q.invalid 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:60 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard HANGUP, cause: ORIGINATOR_CANCEL 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State HANGUP going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_HANGUP -> CS_REPORTING 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_REPORTING (Cur 1 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State REPORTING 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:174 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard REPORTING, cause: ORIGINATOR_CANCEL 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State REPORTING going to sleep 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State Change CS_REPORTING -> CS_DESTROY 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_session.c:1712 Session 29 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Locked, Waiting on external entities 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1730 Session 29 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Ended 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [NOTICE] switch_core_session.c:1734 Close Channel sofia/internal/is2jtitt@cieonafgfl9q.invalid [CS_DESTROY] 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) Running State Change CS_DESTROY (Cur 0 Tot 29) 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State DESTROY 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] mod_sofia.c:354 sofia/internal/is2jtitt@cieonafgfl9q.invalid SOFIA DESTROY 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:181 sofia/internal/is2jtitt@cieonafgfl9q.invalid Standard DESTROY 76ab1f55-6a07-4a68-a7e4-360519c4acf1 2017-05-08 22:25:42.561248 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/is2jtitt@cieonafgfl9q.invalid) State DESTROY going to sleep SIP TRACE of socket: INVITE sip:1001@104.130.11.232 SIP/2.0 Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474 Max-Forwards: 70 To: From: ;tag=t1hatm4phs Call-ID: jidiqnu3nsepdd44prtl CSeq: 4347 INVITE Proxy-Authorization: Digest algorithm=MD5, username="1002", realm="104.130.11.232", nonce="0868d677-8e38-45c1-85d7-c49a57c209ba", uri="sip:1001@104.130.11.232", response="e46204eefde8bbf3b631512d0c389ee7", qop=auth, cnonce="egm2tqu2mfgq", nc=00000001 Contact: Allow: ACK,CANCEL,INVITE,MESSAGE,BYE,OPTIONS,INFO,NOTIFY,REFER Supported: outbound User-Agent: SIP.js/0.7.7 Content-Type: application/sdp Content-Length: 761 v=0 o=- 1578489363367963671 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE data a=msid-semantic: WMS m=application 61279 DTLS/SCTP 5000 c=IN IP4 111.69.178.179 a=candidate:1520298561 1 udp 2113937151 192.168.178.32 61279 typ host generation 0 network-cost 50 a=candidate:505634932 1 udp 2113939711 2406:e007:2f57:1:18cd:41cb:c133:81a9 61280 typ host generation 0 network-cost 50 a=candidate:842163049 1 udp 1677729535 111.69.178.179 61279 typ srflx raddr 192.168.178.32 rport 61279 generation 0 network-cost 50 a=ice-ufrag:28pV a=ice-pwd:WY4lXqVRHE3wlwfyRv4gWdVe a=fingerprint:sha-256 71:62:91:79:02:E8:BF:BF:0A:3D:C4:12:66:97:3F:BF:27:A6:83:0A:E8:A6:1D:BF:6B:57:C3:B0:28:B8:C5:BC a=setup:actpass a=mid:data a=sctpmap:5000 webrtc-datachannel 1024 SIP/2.0 100 Trying Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474;received=111.69.178.179;rport=51195 From: ;tag=t1hatm4phs To: Call-ID: jidiqnu3nsepdd44prtl CSeq: 4347 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit Content-Length: 0 SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474;received=111.69.178.179;rport=51195 Max-Forwards: 70 From: ;tag=t1hatm4phs To: ;tag=98v1aUaXH6N1m Call-ID: jidiqnu3nsepdd44prtl CSeq: 4347 INVITE User-Agent: FreeSWITCH-mod_sofia/1.9.0-380-c66a012~64bit 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=88;text="INCOMPATIBLE_DESTINATION" Content-Length: 0 Remote-Party-ID: "1001" ;party=calling;privacy=off;screen=no ACK sip:1001@104.130.11.232 SIP/2.0 Via: SIP/2.0/WS sh7nvsngi33a.invalid;branch=z9hG4bK1445474 To: ;tag=98v1aUaXH6N1m From: ;tag=t1hatm4phs Call-ID: jidiqnu3nsepdd44prtl Content-Length: 0 CSeq: 4347 ACK