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: <sip:1002@104.130.11.232>;tag=dvgo1ufg6i
To: <sip:1001@104.130.11.232>;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" <sip:1001@104.130.11.232>;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: <sip:1001@104.130.11.232>
From: <sip:1002@104.130.11.232>;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: <sip:loahejbu@sh7nvsngi33a.invalid;transport=ws;ob>
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: <sip:1002@104.130.11.232>;tag=t1hatm4phs
To: <sip:1001@104.130.11.232>
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: <sip:1002@104.130.11.232>;tag=t1hatm4phs
To: <sip:1001@104.130.11.232>;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" <sip:1001@104.130.11.232>;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: <sip:1001@104.130.11.232>;tag=98v1aUaXH6N1m
From: <sip:1002@104.130.11.232>;tag=t1hatm4phs
Call-ID: jidiqnu3nsepdd44prtl
Content-Length: 0
CSeq: 4347 ACK