17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [NOTICE] switch_channel.c:1053 New Channel sofia/external/17185551212@178.79.147.47 [17b114d4-bff5-4006-bcd2-8640802a7991] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_NEW 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] sofia.c:8526 sofia/external/17185551212@178.79.147.47 receiving invite from 178.79.147.47:5080 version: 1.5.12b 64bit 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] sofia.c:6364 Channel sofia/external/17185551212@178.79.147.47 entering state [received][100] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] sofia.c:6374 Remote SDP: 17b114d4-bff5-4006-bcd2-8640802a7991 v=0 17b114d4-bff5-4006-bcd2-8640802a7991 o=FreeSWITCH 1434863253 1434863254 IN IP4 178.79.147.47 17b114d4-bff5-4006-bcd2-8640802a7991 s=FreeSWITCH 17b114d4-bff5-4006-bcd2-8640802a7991 c=IN IP4 178.79.147.47 17b114d4-bff5-4006-bcd2-8640802a7991 t=0 0 17b114d4-bff5-4006-bcd2-8640802a7991 m=audio 25976 RTP/AVP 0 8 101 13 17b114d4-bff5-4006-bcd2-8640802a7991 a=rtpmap:101 telephone-event/8000 17b114d4-bff5-4006-bcd2-8640802a7991 a=fmtp:101 0-16 17b114d4-bff5-4006-bcd2-8640802a7991 a=ptime:20 17b114d4-bff5-4006-bcd2-8640802a7991 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] sofia.c:6629 (sofia/external/17185551212@178.79.147.47) State Change CS_NEW -> CS_INIT 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:489 (sofia/external/17185551212@178.79.147.47) State NEW 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_INIT 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:510 (sofia/external/17185551212@178.79.147.47) State INIT 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] mod_sofia.c:87 sofia/external/17185551212@178.79.147.47 SOFIA INIT 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:40 sofia/external/17185551212@178.79.147.47 Standard INIT 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:48 (sofia/external/17185551212@178.79.147.47) State Change CS_INIT -> CS_ROUTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.551398 [DEBUG] switch_core_state_machine.c:510 (sofia/external/17185551212@178.79.147.47) State INIT going to sleep 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_ROUTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_channel.c:2180 (sofia/external/17185551212@178.79.147.47) Callstate Change DOWN -> RINGING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:526 (sofia/external/17185551212@178.79.147.47) State ROUTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] mod_sofia.c:123 sofia/external/17185551212@178.79.147.47 SOFIA ROUTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:164 sofia/external/17185551212@178.79.147.47 Standard ROUTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [INFO] mod_dialplan_xml.c:558 Processing 17185551212 <17185551212>->17182223333 in context public 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 parsing [public->unloop] continue=false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 parsing [public->outside_call] continue=true 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Absolute Condition [outside_call] 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Action set(outside_call=true) 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 parsing [public->incoming_test] continue=false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Regex (PASS) [incoming_test] context(public) =~ /public/ break=on-false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Regex (PASS) [incoming_test] destination_number(17182223333) =~ /^17182223333$/ break=on-false 17b114d4-bff5-4006-bcd2-8640802a7991 Dialplan: sofia/external/17185551212@178.79.147.47 Action park() 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:214 (sofia/external/17185551212@178.79.147.47) State Change CS_ROUTING -> CS_EXECUTE 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:526 (sofia/external/17185551212@178.79.147.47) State ROUTING going to sleep 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_EXECUTE 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:533 (sofia/external/17185551212@178.79.147.47) State EXECUTE 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] mod_sofia.c:178 sofia/external/17185551212@178.79.147.47 SOFIA EXECUTE 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_core_state_machine.c:256 sofia/external/17185551212@178.79.147.47 Standard EXECUTE 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 set(outside_call=true) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] mod_dptools.c:1435 sofia/external/17185551212@178.79.147.47 SET [outside_call]=[true] 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 export(RFC2822_DATE=Sun, 21 Jun 2015 15:20:29 Jerusalem Daylight Time) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:29.571411 [DEBUG] switch_channel.c:1245 EXPORT (export_vars) [RFC2822_DATE]=[Sun, 21 Jun 2015 15:20:29 Jerusalem Daylight Time] 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 park() 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.171108 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.171108 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute set(playback_terminators=any) 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 set(playback_terminators=any) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.171108 [DEBUG] mod_dptools.c:1435 sofia/external/17185551212@178.79.147.47 SET [playback_terminators]=[any] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.591272 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.591272 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute set(rtp_enable_vad_in=true) 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 set(rtp_enable_vad_in=true) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:35.591272 [DEBUG] mod_dptools.c:1435 sofia/external/17185551212@178.79.147.47 SET [rtp_enable_vad_in]=[true] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.411168 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.431181 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute set(rtp_enable_vad_out=true) 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 set(rtp_enable_vad_out=true) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.431181 [DEBUG] mod_dptools.c:1435 sofia/external/17185551212@178.79.147.47 SET [rtp_enable_vad_out]=[true] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.931514 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute answer() 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 answer() 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3528 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3474 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3395 Set telephone-event payload to 101 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:2352 Set Codec sofia/external/17185551212@178.79.147.47 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_codec.c:111 sofia/external/17185551212@178.79.147.47 Original read codec set to PCMU:0 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:3719 Set 2833 dtmf send/recv payload to 101 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:4939 AUDIO RTP [sofia/external/17185551212@178.79.147.47] 10.0.0.50 port 29154 -> 178.79.147.47 port 25976 codec: 0 ms: 20 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:3382 Starting timer [soft] 160 bytes per 20ms 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: START SESSION INITIALIZATION. sID=125. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: ZID=633061336432323930393363. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Loading User's profile: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: allowclear: OFF 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: autosecure: ON 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: disclose_bit: OFF 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: signal. role: Unknown 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: TTL: 4294967295 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: SAS schemes: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 B256 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 B32 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Ciphers: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 AES3 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 AES1 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: PK schemes: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 EC25 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 DH3k 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 DH2k 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 Mult 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: ATL: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 HS32 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Hashes: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 S256 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Session initialization - DONE. sID=125. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: ATTACH NEW STREAM to sID=125: 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp]: Stream ID=0 UNKNOWN switching ---> . 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Empty slot was found - initializing new stream with ID=125. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: Preparing ZRTP Hello according to the Session profile. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp main]: ATTACH NEW STREAM - DONE. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: START STREAM ID=125 mode=CLEAR state=ACTIVE. 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp]: Stream ID=125 CLEAR switching ---> . 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64489 size=144. Stream 125:CLEAR:START 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_rtp.c:6531 Activate VAD codec PCMU 20ms 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:5099 AUDIO RTP Engage VAD for sofia/external/17185551212@178.79.147.47 ( in out ) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:5286 Set 2833 dtmf send payload to 101 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:5292 Set 2833 dtmf receive payload to 101 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_media.c:5320 Set comfort noise payload to 13 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/17185551212@178.79.147.47! 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_channel.c:3395 (sofia/external/17185551212@178.79.147.47) Callstate Change RINGING -> EARLY 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] mod_sofia.c:778 Local SDP sofia/external/17185551212@178.79.147.47: 17b114d4-bff5-4006-bcd2-8640802a7991 v=0 17b114d4-bff5-4006-bcd2-8640802a7991 o=FreeSWITCH 1434860082 1434860083 IN IP4 85.130.191.210 17b114d4-bff5-4006-bcd2-8640802a7991 s=FreeSWITCH 17b114d4-bff5-4006-bcd2-8640802a7991 c=IN IP4 85.130.191.210 17b114d4-bff5-4006-bcd2-8640802a7991 t=0 0 17b114d4-bff5-4006-bcd2-8640802a7991 m=audio 29154 RTP/AVP 0 101 13 17b114d4-bff5-4006-bcd2-8640802a7991 a=rtpmap:0 PCMU/8000 17b114d4-bff5-4006-bcd2-8640802a7991 a=rtpmap:101 telephone-event/8000 17b114d4-bff5-4006-bcd2-8640802a7991 a=fmtp:101 0-16 17b114d4-bff5-4006-bcd2-8640802a7991 a=rtpmap:13 CN/8000 17b114d4-bff5-4006-bcd2-8640802a7991 a=ptime:20 17b114d4-bff5-4006-bcd2-8640802a7991 a=sendrecv 17b114d4-bff5-4006-bcd2-8640802a7991 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_session.c:907 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [NOTICE] mod_dptools.c:1258 Channel [sofia/external/17185551212@178.79.147.47] has been answered 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] switch_channel.c:3685 (sofia/external/17185551212@178.79.147.47) Callstate Change EARLY -> ACTIVE 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:36.951527 [DEBUG] sofia.c:6364 Channel sofia/external/17185551212@178.79.147.47 entering state [completed][200] 2015-06-21 15:20:37.010568 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64490 size=144. Stream 125:CLEAR:START 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.030581 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.030581 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.030581 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.050594 [DEBUG] sofia.c:6364 Channel sofia/external/17185551212@178.79.147.47 entering state [ready][200] 2015-06-21 15:20:37.130648 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64491 size=144. Stream 125:CLEAR:START 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.150661 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.170675 [DEBUG] sofia.c:8362 dispatched freeswitch event for INFO 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:20:37.270741 [DEBUG] switch_rtp.c:5605 Correct ip/port confirmed. 2015-06-21 15:20:37.330781 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64492 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:37.550929 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64493 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:37.751062 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: WARNING! HELLO have been resent 5 times without a response. Raising ZRTP_EVENT_NO_ZRTP_QUICK event. ID=125 2015-06-21 15:20:37.751062 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64494 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:37.951193 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64495 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:38.171342 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64496 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:38.371475 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64497 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:38.590948 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64498 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:38.791080 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64499 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:39.011228 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64500 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:39.211362 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64501 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:39.431509 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64502 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:39.630641 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64503 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:39.850788 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64504 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:40.050922 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64505 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:40.271069 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64506 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:40.471200 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64507 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:40.691350 [DEBUG] switch_rtp.c:1346 [ zrtp utils]: Send ssrc=1580256348 seq=64508 size=144. Stream 125:CLEAR:START 2015-06-21 15:20:40.891481 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: WARNING! HELLO Max retransmissions count reached (20 retries). ID=125 2015-06-21 15:20:40.891481 [DEBUG] switch_rtp.c:1346 [ zrtp]: Stream ID=125 CLEAR switching ---> . 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:25.270747 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:25.290760 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute playback(\\Mchserver\d\Temp\16874514658000.wav) 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 playback(\Mchserver\d\Temp\16874514658000.wav) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:25.390826 [DEBUG] switch_ivr_play_say.c:1305 Codec Activated L16@8000hz 1 channels 20ms 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:29.931329 [DEBUG] switch_ivr_play_say.c:1714 done playing file \Mchserver\d\Temp\16874514658000.wav 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:35.151378 [DEBUG] switch_rtp.c:5819 RTP RECV 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.950683 [DEBUG] switch_core_session.c:1187 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr.c:614 sofia/external/17185551212@178.79.147.47 Command Execute conference(meeting@mykeys) 17b114d4-bff5-4006-bcd2-8640802a7991 EXECUTE sofia/external/17185551212@178.79.147.47 conference(meeting@mykeys) 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:9456 using channel sound prefix: C:/Program Files/FreeSWITCH/sounds/en/us/callie 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] mod_conference.c:8117 Raw Codec Activation Success L16@8000hz 1 channel 20ms 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] mod_conference.c:8163 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2015-06-21 15:21:37.970696 [DEBUG] mod_conference.c:2557 Setup timer success interval: 20 samples: 160 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] switch_core_codec.c:221 sofia/external/17185551212@178.79.147.47 Push codec L16:70 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '0' to 'mute' 2015-06-21 15:21:37.970696 [INFO] switch_ivr_async.c:212 Digit parser mod_conference: Setting realm to 'conf' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 0/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3958 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '*' to 'deaf mute' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding */conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3988 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '9' to 'energy up' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 9/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF39B8 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '8' to 'energy equ' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 8/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF39E8 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '7' to 'energy dn' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 7/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3A18 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '3' to 'vol talk up' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 3/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3A48 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '2' to 'vol talk zero' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 2/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3A78 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '1' to 'vol talk dn' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 1/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3AA8 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '6' to 'vol listen up' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 6/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3AD8 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '5' to 'vol listen zero' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 5/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3B08 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '4' to 'vol listen dn' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding 4/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3B38 2015-06-21 15:21:37.970696 [INFO] mod_conference.c:10057 sofia/external/17185551212@178.79.147.47 binding '#' to 'hangup' 2015-06-21 15:21:37.970696 [DEBUG] switch_ivr_async.c:321 Digit parser mod_conference: binding #/conf/0 callback: 0000000004E8A6B0 data: 0000000007DF3B68 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] switch_core_session.c:907 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:21:37.970696 [DEBUG] mod_conference.c:4206 Setup timer soft success interval: 20 samples: 160 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.010975 [DEBUG] switch_core_session.c:1052 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [NOTICE] sofia.c:926 Hangup sofia/external/17185551212@178.79.147.47 [CS_EXECUTE] [NORMAL_CLEARING] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_channel.c:3218 Send signal sofia/external/17185551212@178.79.147.47 [KILL] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] mod_conference.c:4528 Channel leaving conference, cause: NORMAL_CLEARING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] mod_conference.c:8742 sofia/external/17185551212@178.79.147.47 skip receive message [UNBRIDGE] (channel is hungup already) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_codec.c:246 sofia/external/17185551212@178.79.147.47 Restore previous codec PCMU:0. 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:2887 sofia/external/17185551212@178.79.147.47 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_ivr.c:657 sofia/external/17185551212@178.79.147.47 skip receive message [AUDIO_SYNC] (channel is hungup already) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:2887 sofia/external/17185551212@178.79.147.47 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:533 (sofia/external/17185551212@178.79.147.47) State EXECUTE going to sleep 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_HANGUP 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:733 (sofia/external/17185551212@178.79.147.47) Callstate Change ACTIVE -> HANGUP 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:735 (sofia/external/17185551212@178.79.147.47) State HANGUP 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] mod_sofia.c:413 Channel sofia/external/17185551212@178.79.147.47 hanging up, cause: NORMAL_CLEARING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:58 sofia/external/17185551212@178.79.147.47 Standard HANGUP, cause: NORMAL_CLEARING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:735 (sofia/external/17185551212@178.79.147.47) State HANGUP going to sleep 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:502 (sofia/external/17185551212@178.79.147.47) State Change CS_HANGUP -> CS_REPORTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:470 (sofia/external/17185551212@178.79.147.47) Running State Change CS_REPORTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:821 (sofia/external/17185551212@178.79.147.47) State REPORTING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:102 sofia/external/17185551212@178.79.147.47 Standard REPORTING, cause: NORMAL_CLEARING 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:821 (sofia/external/17185551212@178.79.147.47) State REPORTING going to sleep 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:496 (sofia/external/17185551212@178.79.147.47) State Change CS_REPORTING -> CS_DESTROY 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:1387 Send signal sofia/external/17185551212@178.79.147.47 [BREAK] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_session.c:1614 Session 1362 (sofia/external/17185551212@178.79.147.47) Locked, Waiting on external entities 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [NOTICE] switch_core_session.c:1632 Session 1362 (sofia/external/17185551212@178.79.147.47) Ended 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [NOTICE] switch_core_session.c:1636 Close Channel sofia/external/17185551212@178.79.147.47 [CS_DESTROY] 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:624 (sofia/external/17185551212@178.79.147.47) Running State Change CS_DESTROY 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:634 (sofia/external/17185551212@178.79.147.47) State DESTROY 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] mod_sofia.c:323 sofia/external/17185551212@178.79.147.47 SOFIA DESTROY 2015-06-21 15:22:30.030988 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: STOP STREAM ID=125 mode=CLEAR state=NOZRTP. 2015-06-21 15:22:30.030988 [DEBUG] switch_rtp.c:1346 [ zrtp]: Stream ID=0 UNKNOWN switching ---> . 2015-06-21 15:22:30.030988 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2015-06-21 15:22:30.030988 [DEBUG] switch_rtp.c:1346 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:109 sofia/external/17185551212@178.79.147.47 Standard DESTROY 17b114d4-bff5-4006-bcd2-8640802a7991 2015-06-21 15:22:30.030988 [DEBUG] switch_core_state_machine.c:634 (sofia/external/17185551212@178.79.147.47) State DESTROY going to sleep 2015-06-21 15:22:30.051001 [DEBUG] mod_conference.c:3080 Write Lock ON 2015-06-21 15:22:30.051001 [DEBUG] mod_conference.c:3083 Write Lock OFF