2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1000@webrtc.test.lan) State Change CS_ROUTING -> CS_EXECUTE
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@webrtc.test.lan) State ROUTING going to sleep
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@webrtc.test.lan) Running State Change CS_EXECUTE
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@webrtc.test.lan) State EXECUTE
2015-10-21 16:39:09.724082 [DEBUG] mod_sofia.c:196
sofia/internal/1000@webrtc.test.lan SOFIA EXECUTE
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:258
sofia/internal/1000@webrtc.test.lan Standard EXECUTE
EXECUTE
sofia/internal/1000@webrtc.test.lan set(open=true)
2015-10-21 16:39:09.724082 [DEBUG] mod_dptools.c:1498 SET
sofia/internal/1000@webrtc.test.lan [open]=[true]
EXECUTE
sofia/internal/1000@webrtc.test.lan hash(insert/172.22.244.249-spymap/1000/d0f8c272-0203-4de6-ac6b-0b001acfdd69)
EXECUTE
sofia/internal/1000@webrtc.test.lan hash(insert/172.22.244.249-last_dial/1000/5000)
EXECUTE
sofia/internal/1000@webrtc.test.lan hash(insert/172.22.244.249-last_dial/global/d0f8c272-0203-4de6-ac6b-0b001acfdd69)
EXECUTE
sofia/internal/1000@webrtc.test.lan export(RFC2822_DATE=Wed, 21 Oct 2015 16:39:09 +0300)
2015-10-21 16:39:09.724082 [DEBUG] switch_channel.c:1281 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 21 Oct 2015 16:39:09 +0300]
EXECUTE
sofia/internal/1000@webrtc.test.lan answer()
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [opus:111:48000:60:0:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4195 Audio Codec Compare [opus:116:48000:20:0:1] ++++ is saved as a match
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [opus:111:48000:60:0:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [opus:111:48000:60:0:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [opus:111:48000:60:0:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:103:16000:30:32000:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [ISAC:104:32000:30:32000:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [G722:9:8000:60:64000:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [G722:9:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4195 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [G722:9:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [G722:9:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4195 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMU:0:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4195 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [PCMA:8:8000:60:64000:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:105:16000:60:0:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:105:16000:60:0:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:105:16000:60:0:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:105:16000:60:0:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:13:8000:60:0:1]/[opus:116:48000:20:0:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:13:8000:60:0:1]/[G722:9:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMU:0:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:13:8000:60:0:1]/[PCMA:8:8000:20:64000:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4140 Audio Codec Compare [CN:13:8000:60:0:1]/[GSM:3:8000:20:13200:1]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4056 Set telephone-event payload to 126@8000
2015-10-21 16:39:09.724082 [DEBUG] mod_opus.c:461 Opus encoder set bitrate to local settings [-1000bps]
2015-10-21 16:39:09.724082 [DEBUG] mod_opus.c:461 Opus encoder set bitrate to local settings [-1000bps]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:2887 Set Codec
sofia/internal/1000@webrtc.test.lan opus/48000 20 ms 960 samples 0 bits 1 channels
2015-10-21 16:39:09.724082 [DEBUG] switch_core_codec.c:111
sofia/internal/1000@webrtc.test.lan Original read codec set to opus:116
2015-10-21 16:39:09.724082 [WARNING] switch_core_media.c:3254 NO candidate ACL defined, Defaulting to wan.auto
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3284 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.56.1:45502
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3284 Save audio Candidate cid: 1 proto: udp type: host addr: 172.22.244.100:51641
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3284 Save audio Candidate cid: 2 proto: udp type: host addr: 192.168.56.1:56975
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3284 Save audio Candidate cid: 2 proto: udp type: host addr: 172.22.244.100:39520
2015-10-21 16:39:09.724082 [ERR] switch_core_cert.c:210 FP FILE ERR!
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3324 Searching for rtp candidate.
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3324 Searching for rtcp candidate.
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:3368
sofia/internal/1000@webrtc.test.lan no suitable candidates found.
2015-10-21 16:39:09.724082 [DEBUG] switch_core_media.c:4447 Set 2833 dtmf send/recv payload to 126
2015-10-21 16:39:09.724082 [NOTICE] switch_channel.c:3796 Hangup
sofia/internal/1000@webrtc.test.lan [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
2015-10-21 16:39:09.724082 [DEBUG] switch_core_session.c:2796
sofia/internal/1000@webrtc.test.lan skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@webrtc.test.lan) State EXECUTE going to sleep
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@webrtc.test.lan) Running State Change CS_HANGUP
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@webrtc.test.lan) Callstate Change RINGING -> HANGUP
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@webrtc.test.lan) State HANGUP
2015-10-21 16:39:09.724082 [DEBUG] mod_sofia.c:431 Channel
sofia/internal/1000@webrtc.test.lan hanging up, cause: INCOMPATIBLE_DESTINATION
2015-10-21 16:39:09.724082 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 488
2015-10-21 16:39:09.724082 [DEBUG] switch_core_state_machine.c:60
sofia/internal/1000@webrtc.test.lan Standard HANGUP, cause: INCOMPATIBLE_DESTINATION