.=======================================================. | _____ ____ ____ _ ___ | | | ___/ ___| / ___| | |_ _| | | | |_ \___ \ | | | | | | | | | _| ___) | | |___| |___ | | | | |_| |____/ \____|_____|___| | | | .=======================================================. | Anthony Minessale II, Ken Rice, | | Michael Jerris, Travis Cross | | FreeSWITCH (http://www.freeswitch.org) | | Paypal Donations Appreciated: paypal@freeswitch.org | | Brought to you by ClueCon http://www.cluecon.com/ | .=======================================================. .=======================================================================================================. | ____ _ ____ | | / ___| |_ _ ___ / ___|___ _ __ | | | | | | | | |/ _ \ | / _ \| '_ \ | | | |___| | |_| | __/ |__| (_) | | | | | | \____|_|\__,_|\___|\____\___/|_| |_| | | | | _____ _ _ ____ __ | | |_ _|__| | ___ _ __ | |__ ___ _ __ _ _ / ___|___ _ __ / _| ___ _ __ ___ _ __ ___ ___ | | | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | | / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \ | | | | __/ | __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | | _| __/ | | __/ | | | (_| __/ | | |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, | \____\___/|_| |_|_| \___|_| \___|_| |_|\___\___| | | |_| |___/ | | _____ _ _ | | | ____|_ _____ _ __ _ _ / \ _ _ __ _ _ _ ___| |_ | | | _| \ \ / / _ \ '__| | | | / _ \| | | |/ _` | | | / __| __| | | | |___ \ V / __/ | | |_| | / ___ \ |_| | (_| | |_| \__ \ |_ | | |_____| \_/ \___|_| \__, | /_/ \_\__,_|\__, |\__,_|___/\__| | | |___/ |___/ | | ____ _ ____ | | __ ____ ____ __ / ___| |_ _ ___ / ___|___ _ __ ___ ___ _ __ ___ | | \ \ /\ / /\ \ /\ / /\ \ /\ / / | | | | | | |/ _ \ | / _ \| '_ \ / __/ _ \| '_ ` _ \ | | \ V V / \ V V / \ V V / _ | |___| | |_| | __/ |__| (_) | | | | _ | (_| (_) | | | | | | | | \_/\_/ \_/\_/ \_/\_/ (_) \____|_|\__,_|\___|\____\___/|_| |_| (_) \___\___/|_| |_| |_| | | | .=======================================================================================================. Type /help to see a list of commands +OK log level [7] freeswitch@DESKTOP-5PS5MGH> 2016-08-03 19:22:00.979309 [INFO] mod_verto.c:4025 192.168.0.11:52770 Client Connect. 2016-08-03 19:22:00.979309 [INFO] mod_verto.c:1978 192.168.0.11:52770 Starting client thread. 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:1370 READ 192.168.0.11:52770 [{ "jsonrpc": "2.0", "method": "login", "params": { "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" }, "id": 2 }] 2016-08-03 19:22:01.039354 [INFO] mod_verto.c:1267 192.168.0.11:52770 re-connecting session f3116205-59f7-1a56-6b06-e23e4645f500 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:604 WRITE 192.168.0.11:52770 [{ "jsonrpc": "2.0", "id": 2, "error": { "code": -32000, "message": "Authentication Required" } }] 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:1370 READ 192.168.0.11:52770 [{ "jsonrpc": "2.0", "method": "login", "params": { "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" }, "id": 1 }] 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:604 WRITE 192.168.0.11:52770 [{ "jsonrpc": "2.0", "id": 1, "error": { "code": -32000, "message": "Authentication Required" } }] 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:1370 READ 192.168.0.11:52770 [{ "jsonrpc": "2.0", "method": "login", "params": { "login": "trillionss@xbipin.com", "passwd": "pass", "loginParams": { }, "userVariables": { }, "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" }, "id": 3 }] 2016-08-03 19:22:01.039354 [ALERT] mod_verto.c:604 WRITE 192.168.0.11:52770 [{ "jsonrpc": "2.0", "id": 3, "result": { "message": "logged in", "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" } }] 2016-08-03 19:22:08.859223 [ALERT] mod_verto.c:1370 READ 192.168.0.11:52770 [{ "jsonrpc": "2.0", "method": "verto.invite", "params": { "sdp": "v=0\r\no=- 3018471058368447081 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=group:BUNDLE audio\r\na=msid-semantic: WMS I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW\r\nm=audio 52625 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 126\r\nc=IN IP4 86.99.124.187\r\na=rtcp:52628 IN IP4 86.99.124.187\r\na=candidate:922509271 1 udp 2122255103 2001::5ef5:79fb:38d8:392f:a99c:8344 52623 typ host generation 0 network-id 2\r\na=candidate:2237255773 1 udp 2122194687 169.254.101.10 52624 typ host generation 0 network-id 3\r\na=candidate:3988902457 1 udp 2122129151 192.168.0.11 52625 typ host generation 0 network-id 1\r\na=candidate:922509271 2 udp 2122255102 2001::5ef5:79fb:38d8:392f:a99c:8344 52626 typ host generation 0 network-id 2\r\na=candidate:2237255773 2 udp 2122194686 169.254.101.10 52627 typ host generation 0 network-id 3\r\na=candidate:3988902457 2 udp 2122129150 192.168.0.11 52628 typ host generation 0 network-id 1\r\na=candidate:2021630759 1 tcp 1518275327 2001::5ef5:79fb:38d8:392f:a99c:8344 9 typ host tcptype active generation 0 network-id 2\r\na=candidate:3420073133 1 tcp 1518214911 169.254.101.10 9 typ host tcptype active generation 0 network-id 3\r\na=candidate:2739023561 1 tcp 1518149375 192.168.0.11 9 typ host tcptype active generation 0 network-id 1\r\na=candidate:2021630759 2 tcp 1518275326 2001::5ef5:79fb:38d8:392f:a99c:8344 9 typ host tcptype active generation 0 network-id 2\r\na=candidate:3420073133 2 tcp 1518214910 169.254.101.10 9 typ host tcptype active generation 0 network-id 3\r\na=candidate:2739023561 2 tcp 1518149374 192.168.0.11 9 typ host tcptype active generation 0 network-id 1\r\na=candidate:466725869 1 udp 1685921535 86.99.124.187 52625 typ srflx raddr 192.168.0.11 rport 52625 generation 0 network-id 1\r\na=candidate:466725869 2 udp 1685921534 86.99.124.187 52628 typ srflx raddr 192.168.0.11 rport 52628 generation 0 network-id 1\r\na=ice-ufrag:s++TdQnUXpWu0DOr\r\na=ice-pwd:TQCtkr2gdDWnAHi12lTZnYYv\r\na=fingerprint:sha-256 B6:24:49:5E:BB:CE:18:F9:BF:2A:14:65:95:B1:6A:6D:54:B1:7B:AF:4D:CD:3B:D1:AC:DF:38:E9:D6:60:25:D9\r\na=setup:actpass\r\na=mid:audio\r\na=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level\r\na=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:111 opus/48000/2\r\na=rtcp-fb:111 transport-cc\r\na=fmtp:111 minptime=10;useinbandfec=1\r\na=rtpmap:103 ISAC/16000\r\na=rtpmap:104 ISAC/32000\r\na=rtpmap:9 G722/8000\r\na=rtpmap:0 PCMU/8000\r\na=rtpmap:8 PCMA/8000\r\na=rtpmap:106 CN/32000\r\na=rtpmap:105 CN/16000\r\na=rtpmap:13 CN/8000\r\na=rtpmap:126 telephone-event/8000\r\na=maxptime:60\r\na=ssrc:164895446 cname:7y6XBJM1HSeAZDum\r\na=ssrc:164895446 msid:I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW 6ca3b444-ad8a-478b-9968-8e203b627b10\r\na=ssrc:164895446 mslabel:I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW\r\na=ssrc:164895446 label:6ca3b444-ad8a-478b-9968-8e203b627b10\r\n", "dialogParams": { "useVideo": false, "useStereo": false, "screenShare": false, "useCamera": "none", "useMic": "f0ceca0920127577edd263ef38b6e316412a27fbec70948ba4acbbfa53eb9dfe", "useSpeak": "default", "tag": "webcam", "localTag": null, "login": "trillionss@xbipin.com", "videoParams": { "minWidth": 320, "minHeight": 180, "maxWidth": 320, "maxHeight": 180, "minFrameRate": 15 }, "destination_number": "2002", "caller_id_name": "1234", "caller_id_number": "", "outgoingBandwidth": 19443, "incomingBandwidth": 90354, "dedEnc": false, "mirrorInput": false, "userVariables": { "email": "", "avatar": "http://gravatar.com/avatar/d41d8cd98f00b204e9800998ecf8427e.png?s=600" }, "callID": "f00246a4-2136-48ef-5f03-5b58fe15b677", "remote_caller_id_name": "Outbound Call", "remote_caller_id_number": "2002" }, "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" }, "id": 4 }] 2016-08-03 19:22:08.859223 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_session.c:613 N/A set UUID=f00246a4-2136-48ef-5f03-5b58fe15b677 2016-08-03 19:22:08.859223 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/2002 [f00246a4-2136-48ef-5f03-5b58fe15b677] 2016-08-03 19:22:08.859223 [DEBUG] mod_verto.c:3579 Remote SDP verto.rtc/2002: v=0 o=- 3018471058368447081 2 IN IP4 127.0.0.1 s=- t=0 0 a=group:BUNDLE audio a=msid-semantic: WMS I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW m=audio 52625 UDP/TLS/RTP/SAVPF 111 103 104 9 0 8 106 105 13 126 c=IN IP4 86.99.124.187 a=rtcp:52628 IN IP4 86.99.124.187 a=candidate:922509271 1 udp 2122255103 2001::5ef5:79fb:38d8:392f:a99c:8344 52623 typ host generation 0 network-id 2 a=candidate:2237255773 1 udp 2122194687 169.254.101.10 52624 typ host generation 0 network-id 3 a=candidate:3988902457 1 udp 2122129151 192.168.0.11 52625 typ host generation 0 network-id 1 a=candidate:922509271 2 udp 2122255102 2001::5ef5:79fb:38d8:392f:a99c:8344 52626 typ host generation 0 network-id 2 a=candidate:2237255773 2 udp 2122194686 169.254.101.10 52627 typ host generation 0 network-id 3 a=candidate:3988902457 2 udp 2122129150 192.168.0.11 52628 typ host generation 0 network-id 1 a=candidate:2021630759 1 tcp 1518275327 2001::5ef5:79fb:38d8:392f:a99c:8344 9 typ host tcptype active generation 0 network-id 2 a=candidate:3420073133 1 tcp 1518214911 169.254.101.10 9 typ host tcptype active generation 0 network-id 3 a=candidate:2739023561 1 tcp 1518149375 192.168.0.11 9 typ host tcptype active generation 0 network-id 1 a=candidate:2021630759 2 tcp 1518275326 2001::5ef5:79fb:38d8:392f:a99c:8344 9 typ host tcptype active generation 0 network-id 2 a=candidate:3420073133 2 tcp 1518214910 169.254.101.10 9 typ host tcptype active generation 0 network-id 3 a=candidate:2739023561 2 tcp 1518149374 192.168.0.11 9 typ host tcptype active generation 0 network-id 1 a=candidate:466725869 1 udp 1685921535 86.99.124.187 52625 typ srflx raddr 192.168.0.11 rport 52625 generation 0 network-id 1 a=candidate:466725869 2 udp 1685921534 86.99.124.187 52628 typ srflx raddr 192.168.0.11 rport 52628 generation 0 network-id 1 a=ice-ufrag:s++TdQnUXpWu0DOr a=ice-pwd:TQCtkr2gdDWnAHi12lTZnYYv a=fingerprint:sha-256 B6:24:49:5E:BB:CE:18:F9:BF:2A:14:65:95:B1:6A:6D:54:B1:7B:AF:4D:CD:3B:D1:AC:DF:38:E9:D6:60:25:D9 a=setup:actpass a=mid:audio a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time a=sendrecv a=rtcp-mux a=rtpmap:111 opus/48000/2 a=rtcp-fb:111 transport-cc a=fmtp:111 minptime=10;useinbandfec=1 a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=maxptime:60 a=ssrc:164895446 cname:7y6XBJM1HSeAZDum a=ssrc:164895446 msid:I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW 6ca3b444-ad8a-478b-9968-8e203b627b10 a=ssrc:164895446 mslabel:I6aZuIEDFEgPlqVwboc9SC4C9ebgurNwpuaW a=ssrc:164895446 label:6ca3b444-ad8a-478b-9968-8e203b627b10 2016-08-03 19:22:08.859223 [ALERT] mod_verto.c:604 WRITE 192.168.0.11:52770 [{ "jsonrpc": "2.0", "id": 4, "result": { "message": "CALL CREATED", "callID": "f00246a4-2136-48ef-5f03-5b58fe15b677", "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" } }] 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:543 (verto.rtc/2002) Running State Change CS_INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:586 (verto.rtc/2002) State INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:40 verto.rtc/2002 Standard INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/2002) State Change CS_INIT -> CS_ROUTING 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:586 (verto.rtc/2002) State INIT going to sleep 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:543 (verto.rtc/2002) Running State Change CS_ROUTING 2016-08-03 19:22:08.859223 [DEBUG] switch_channel.c:2249 (verto.rtc/2002) Callstate Change DOWN -> RINGING 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:602 (verto.rtc/2002) State ROUTING 2016-08-03 19:22:08.859223 [DEBUG] mod_rtc.c:89 verto.rtc/2002 RTC ROUTING 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:236 verto.rtc/2002 Standard ROUTING 2016-08-03 19:22:08.859223 [INFO] mod_dialplan_xml.c:637 Processing 1234 <0000000000>->2002 in context lanusersdialout Dialplan: verto.rtc/2002 parsing [lanusersdialout->call_xlite] continue=false Dialplan: verto.rtc/2002 Regex (PASS) [call_xlite] destination_number(2002) =~ /^2002$/ break=on-false Dialplan: verto.rtc/2002 Action bridge(user/trillions@${domain_name}) 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:286 (verto.rtc/2002) State Change CS_ROUTING -> CS_EXECUTE 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:602 (verto.rtc/2002) State ROUTING going to sleep 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:543 (verto.rtc/2002) Running State Change CS_EXECUTE 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:609 (verto.rtc/2002) State EXECUTE 2016-08-03 19:22:08.859223 [DEBUG] mod_rtc.c:120 verto.rtc/2002 RTC EXECUTE 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:328 verto.rtc/2002 Standard EXECUTE EXECUTE verto.rtc/2002 bridge(user/trillions@192.168.0.11) 2016-08-03 19:22:08.859223 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables 2016-08-03 19:22:08.859223 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables 2016-08-03 19:22:08.859223 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/trillions@192.168.0.11:9040 [06e0486b-7cb7-4635-aa14-b1b7b08652bb] 2016-08-03 19:22:08.859223 [DEBUG] mod_sofia.c:4754 (sofia/internal/trillions@192.168.0.11:9040) State Change CS_NEW -> CS_INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/trillions@192.168.0.11:9040) State INIT 2016-08-03 19:22:08.859223 [DEBUG] mod_sofia.c:90 sofia/internal/trillions@192.168.0.11:9040 SOFIA INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_port_allocator.c:183 UDP port robustness check for port 1024 pass 2016-08-03 19:22:08.859223 [DEBUG] sofia_glue.c:1249 sofia/internal/trillions@192.168.0.11:9040 sending invite version: 1.7.0 git 18f1d56 2016-07-24 04:35:30Z 32bit Local SDP: v=0 o=Talkycom 1470236704 1470236705 IN IP4 192.168.0.11 s=Talkycom c=IN IP4 192.168.0.11 t=0 0 m=audio 1024 RTP/AVP 102 9 0 8 103 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=20000; maxplaybackrate=16000; sprop-maxcapturerate=16000; ptime=20; minptime=10; maxptime=40; stereo=1 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:103 telephone-event/48000 a=fmtp:103 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:40 sofia/internal/trillions@192.168.0.11:9040 Standard INIT 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/trillions@192.168.0.11:9040) State Change CS_INIT -> CS_ROUTING 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/trillions@192.168.0.11:9040) State INIT going to sleep 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_ROUTING 2016-08-03 19:22:08.859223 [DEBUG] sofia.c:6937 Channel sofia/internal/trillions@192.168.0.11:9040 entering state [calling][0] 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/trillions@192.168.0.11:9040) State ROUTING 2016-08-03 19:22:08.859223 [DEBUG] mod_sofia.c:143 sofia/internal/trillions@192.168.0.11:9040 SOFIA ROUTING 2016-08-03 19:22:08.859223 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/trillions@192.168.0.11:9040) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2016-08-03 19:22:08.859223 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/trillions@192.168.0.11:9040) State ROUTING going to sleep 2016-08-03 19:22:08.879751 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_CONSUME_MEDIA 2016-08-03 19:22:08.879751 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/trillions@192.168.0.11:9040) State CONSUME_MEDIA 2016-08-03 19:22:08.879751 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/trillions@192.168.0.11:9040) State CONSUME_MEDIA going to sleep 2016-08-03 19:22:09.158715 [DEBUG] sofia.c:6937 Channel sofia/internal/trillions@192.168.0.11:9040 entering state [proceeding][180] 2016-08-03 19:22:16.819211 [ALERT] mod_verto.c:1370 READ 192.168.0.11:52770 [{ "jsonrpc": "2.0", "method": "verto.bye", "params": { "dialogParams": { "useVideo": false, "useStereo": false, "screenShare": false, "useCamera": "none", "useMic": "f0ceca0920127577edd263ef38b6e316412a27fbec70948ba4acbbfa53eb9dfe", "useSpeak": "default", "tag": "webcam", "localTag": null, "login": "trillionss@xbipin.com", "videoParams": { "minWidth": 320, "minHeight": 180, "maxWidth": 320, "maxHeight": 180, "minFrameRate": 15 }, "destination_number": "2002", "caller_id_name": "1234", "caller_id_number": "", "outgoingBandwidth": 19443, "incomingBandwidth": 90354, "dedEnc": false, "mirrorInput": false, "userVariables": { "email": "", "avatar": "http://gravatar.com/avatar/d41d8cd98f00b204e9800998ecf8427e.png?s=600" }, "callID": "f00246a4-2136-48ef-5f03-5b58fe15b677", "remote_caller_id_name": "Outbound Call", "remote_caller_id_number": "2002" }, "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" }, "id": 5 }] 2016-08-03 19:22:16.819211 [NOTICE] mod_verto.c:2748 Hangup verto.rtc/2002 [CS_EXECUTE] [NORMAL_CLEARING] 2016-08-03 19:22:16.819211 [ALERT] mod_verto.c:604 WRITE 192.168.0.11:52770 [{ "jsonrpc": "2.0", "id": 5, "result": { "callID": "f00246a4-2136-48ef-5f03-5b58fe15b677", "message": "CALL ENDED", "causeCode": 16, "cause": "NORMAL_CLEARING", "sessid": "f3116205-59f7-1a56-6b06-e23e4645f500" } }] 2016-08-03 19:22:16.819211 [NOTICE] switch_ivr_originate.c:3522 Hangup sofia/internal/trillions@192.168.0.11:9040 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 2016-08-03 19:22:16.819211 [DEBUG] switch_ivr_originate.c:3739 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_HANGUP 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/trillions@192.168.0.11:9040) Callstate Change DOWN -> HANGUP 2016-08-03 19:22:16.819211 [NOTICE] switch_ivr_originate.c:2762 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] 2016-08-03 19:22:16.819211 [DEBUG] switch_ivr_originate.c:3750 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/trillions@192.168.0.11:9040) State HANGUP 2016-08-03 19:22:16.819211 [DEBUG] mod_sofia.c:438 Channel sofia/internal/trillions@192.168.0.11:9040 hanging up, cause: ORIGINATOR_CANCEL 2016-08-03 19:22:16.819211 [INFO] mod_dptools.c:3401 Originate Failed. Cause: ORIGINATOR_CANCEL 2016-08-03 19:22:16.819211 [DEBUG] switch_core_session.c:2797 verto.rtc/2002 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:609 (verto.rtc/2002) State EXECUTE going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:543 (verto.rtc/2002) Running State Change CS_HANGUP 2016-08-03 19:22:16.819211 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/trillions@192.168.0.11:9040 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:60 sofia/internal/trillions@192.168.0.11:9040 Standard HANGUP, cause: ORIGINATOR_CANCEL 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:809 (verto.rtc/2002) Callstate Change RINGING -> HANGUP 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/trillions@192.168.0.11:9040) State HANGUP going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/trillions@192.168.0.11:9040) State Change CS_HANGUP -> CS_REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:811 (verto.rtc/2002) State HANGUP 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:60 verto.rtc/2002 Standard HANGUP, cause: NORMAL_CLEARING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:811 (verto.rtc/2002) State HANGUP going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:578 (verto.rtc/2002) State Change CS_HANGUP -> CS_REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:543 (verto.rtc/2002) Running State Change CS_REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/trillions@192.168.0.11:9040) State REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:174 sofia/internal/trillions@192.168.0.11:9040 Standard REPORTING, cause: ORIGINATOR_CANCEL 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/trillions@192.168.0.11:9040) State REPORTING going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:897 (verto.rtc/2002) State REPORTING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:174 verto.rtc/2002 Standard REPORTING, cause: NORMAL_CLEARING 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:897 (verto.rtc/2002) State REPORTING going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/trillions@192.168.0.11:9040) State Change CS_REPORTING -> CS_DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:569 (verto.rtc/2002) State Change CS_REPORTING -> CS_DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_session.c:1647 Session 9 (sofia/internal/trillions@192.168.0.11:9040) Locked, Waiting on external entities 2016-08-03 19:22:16.819211 [DEBUG] switch_core_session.c:1647 Session 8 (verto.rtc/2002) Locked, Waiting on external entities 2016-08-03 19:22:16.819211 [NOTICE] switch_core_session.c:1665 Session 8 (verto.rtc/2002) Ended 2016-08-03 19:22:16.819211 [NOTICE] switch_core_session.c:1665 Session 9 (sofia/internal/trillions@192.168.0.11:9040) Ended 2016-08-03 19:22:16.819211 [NOTICE] switch_core_session.c:1669 Close Channel verto.rtc/2002 [CS_DESTROY] 2016-08-03 19:22:16.819211 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/trillions@192.168.0.11:9040 [CS_DESTROY] 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:700 (verto.rtc/2002) Running State Change CS_DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/trillions@192.168.0.11:9040) Running State Change CS_DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:710 (verto.rtc/2002) State DESTROY 2016-08-03 19:22:16.819211 [DEBUG] mod_rtc.c:132 verto.rtc/2002 RTC DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:181 verto.rtc/2002 Standard DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:710 (verto.rtc/2002) State DESTROY going to sleep 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/trillions@192.168.0.11:9040) State DESTROY 2016-08-03 19:22:16.819211 [DEBUG] mod_sofia.c:343 sofia/internal/trillions@192.168.0.11:9040 SOFIA DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:181 sofia/internal/trillions@192.168.0.11:9040 Standard DESTROY 2016-08-03 19:22:16.819211 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/trillions@192.168.0.11:9040) State DESTROY going to sleep 2016-08-03 19:22:35.439125 [DEBUG] sofia_reg.c:2429 Changing expire time to 120 by request of proxy sip:10.10.0.1 2016-08-03 19:22:37.799015 [DEBUG] mod_verto.c:1862 BAD READ -1000 2016-08-03 19:22:37.799015 [INFO] mod_verto.c:2005 192.168.0.11:52770 Ending client thread. 2016-08-03 19:22:37.799015 [INFO] mod_verto.c:2013 192.168.0.11:52770 Thread ended