.=======================================================.
| _____ ____ ____ _ ___ |
| | ___/ ___| / ___| | |_ _| |
| | |_ \___ \ | | | | | | |
| | _| ___) | | |___| |___ | | |
| |_| |____/ \____|_____|___| |
| |
.=======================================================.
| 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 <enter> 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