August 2015 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Re: [Freeswitch-users] FS + FreeBSD 10.1 CRASH
by Kim Culhan 18 Aug '15

18 Aug '15

On Mon, August 17, 2015 5:16 pm, Luiz Fernando Softov wrote: > Hi, i am try to install FS in FreeBSD 10.1, but when i try to load > mod_gsmopen, its crash What FreeSWITCH version are you running? I attempted to build 1.4.20 with mod_gsmopen but the module fails to build: gmake[5]: Entering directory '/usr/local/src/freeswitch/freeswitch-1.4.20/src/mod/endpoints/mod_gsmopen' CXX mod_gsmopen_la-mod_gsmopen.lo In file included from mod_gsmopen.cpp:34: ./gsmopen.h:103:10: fatal error: 'ctb-0.16/ctb.h' file not found #include "ctb-0.16/ctb.h" The path appears to be incorrect for ctb.h, I did not try to pursue that further. thanks -kim

4 8

How to manipulate a call after bridge
by Jurijs Ivolga 18 Aug '15

18 Aug '15

Hi, I have quite simple task: I need to call from ext 1000 to ext 1001, after ext 1001 pick-up phone, I need to be able to listen for DTMF and based on DTMF I need to fire some commands in LUA. I tried following code in LUA: api = freeswitch.API() did = session:getVariable("destination_number") domain = session:getVariable("domain_name") session:execute("bridge", "user/".. did .. "@" .. domain) freeswitch.consoleLog("INFO","Called Number " .. did .. "\n") But I can do anything with a call only when destination hang-up. For example Called number will be displayed only after destination ext will hang-up. Is there any possibility to achieve this with LUA script? With kind regards, Jurijs

3 4

config request via amod_amqp
by Sergey Safarov 18 Aug '15

18 Aug '15

Are Is possible to request the configuration xml tree of any module (eg mod_nibblebill) through event processing module such as a (mod_amqp, mod_event_socket, mod_erlang)?

3 3

REFER 403 forbidden
by latit myriam 18 Aug '15

18 Aug '15

Hi, I have a problem with the SIP message REFER. I initiate a conference by entering into a conference room, and then I want Freeswitch to add a new participant. According to the RFC 4579, I sent a REFER message to Freeswitch to add the new participant and I get back a NOTIFY with sipfrag 403 forbidden I'm working with virtual IP adresses and I'm working only in local Below is the REFER message I send REFER sip:3000@192.168.20.3:6060 SIP/2.0 Via: SIP/2.0/UDP 192.168.20.4:7000;branch=z9hG4bKg4534 Max-Forwards: 70 To: <sip:3000@192.168.20.3:6060>;isfocus From: <sip:1000@192.168.20.4:7000>;tag=1832665346 Call-ID: jbDaf1W7hQtswyvf CSeq: 1 REFER Contact: <sip:1000@192.168.20.4:7000> Allow: INVITE,BYE,REGISTER,ACK,OPTIONS,CANCEL,INFO,PRACK,REFER,MESSAGE,PUBLISH Allow-Events: REFER Accept: application/sdp, message/sipfrag Refer-To: <sip:1001@192.168.20.3:5031;method=INVITE> Supported: timer, replaces, path Content-Length: 0 and here is the siptrace of FS recv 466 bytes from udp/[192.168.20.3]:7000 at 13:21:45.621109: ------------------------------------------------------------------------ REFER sip:3000@192.168.20.3:6060 SIP/2.0 Via: SIP/2.0/UDP 192.168.20.3:7000;rport;branch=z9hG4bKg4534 Max-Forwards: 70 To: <sip:3000@192.168.20.3:6060>;isfocus;tag=g2y4Btme55Fje From: <sip:1000@192.168.20.3:7000>;tag=1832665346 Call-ID: jbDaf1W7hQtswyvf CSeq: 476 REFER Contact: <sip:1000@192.168.20.3:7000> Allow: INVITE,BYE,REGISTER,ACK,OPTIONS,CANCEL,INFO,PRACK,REFER,MESSAGE,PUBLISH Refer-To: <sip:1001@192.168.20.3:5001> Supported: replaces Content-Length: 0 ------------------------------------------------------------------------ 2015-08-07 13:21:45.615060 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/1000(a)192.168.20.3 [BREAK] 2015-08-07 13:21:45.615060 [DEBUG] switch_core_session.c:978 Send signal sofia/internal/1000(a)192.168.20.3 [BREAK] 2015-08-07 13:21:45.615060 [DEBUG] sofia.c:7799 Process REFER to [ 1001(a)192.168.20.3] send 722 bytes to udp/[192.168.20.3]:7000 at 13:21:45.624429: ------------------------------------------------------------------------ SIP/2.0 202 Accepted Via: SIP/2.0/UDP 192.168.20.3:7000;rport=7000;branch=z9hG4bKg4534 From: <sip:1000@192.168.20.3:7000>;tag=1832665346 To: <sip:3000@192.168.20.3:6060>;isfocus;tag=g2y4Btme55Fje Call-ID: jbDaf1W7hQtswyvf CSeq: 476 REFER Contact: <sip:3000@192.168.20.3:6060;transport=udp> Expires: 60 User-Agent: FreeSWITCH-mod_sofia/1.4.20+git~20150701T115426Z~0ae8ee7f8f~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Length: 0 ------------------------------------------------------------------------ 2015-08-07 13:21:45.615060 [ERR] sofia.c:8297 Cannot Blind Transfer 1 Legged calls send 888 bytes to udp/[192.168.20.3]:7000 at 13:21:45.624580: ------------------------------------------------------------------------ NOTIFY sip:1000@192.168.20.3:7000 SIP/2.0 Via: SIP/2.0/UDP 192.168.20.3:6060;rport;branch=z9hG4bKgNeKBK5H869jQ Max-Forwards: 70 From: <sip:3000@192.168.20.3>;isfocus;tag=g2y4Btme55Fje To: <sip:1000@192.168.20.3>;tag=1832665346 Call-ID: jbDaf1W7hQtswyvf CSeq: 79137188 NOTIFY Contact: <sip:3000@192.168.20.3:6060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.4.20+git~20150701T115426Z~0ae8ee7f8f~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Event: refer;id=476 Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: terminated;reason=noresource Content-Type: message/sipfrag;version=2.0 Content-Length: 23 SIP/2.0 403 Forbidden ------------------------------------------------------------------------ send 888 bytes to udp/[192.168.20.3]:7000 at 13:21:46.625031: ------------------------------------------------------------------------ NOTIFY sip:1000@192.168.20.3:7000 SIP/2.0 Via: SIP/2.0/UDP 192.168.20.3:6060;rport;branch=z9hG4bKgNeKBK5H869jQ Max-Forwards: 70 From: <sip:3000@192.168.20.3>;isfocus;tag=g2y4Btme55Fje To: <sip:1000@192.168.20.3>;tag=1832665346 Call-ID: jbDaf1W7hQtswyvf CSeq: 79137188 NOTIFY Contact: <sip:3000@192.168.20.3:6060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.4.20+git~20150701T115426Z~0ae8ee7f8f~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Event: refer;id=476 Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: terminated;reason=noresource Content-Type: message/sipfrag;version=2.0 Content-Length: 23 SIP/2.0 403 Forbidden ------------------------------------------------------------------------ send 888 bytes to udp/[192.168.20.3]:7000 at 13:21:48.626014: ------------------------------------------------------------------------ NOTIFY sip:1000@192.168.20.3:7000 SIP/2.0 Via: SIP/2.0/UDP 192.168.20.3:6060;rport;branch=z9hG4bKgNeKBK5H869jQ Max-Forwards: 70 From: <sip:3000@192.168.20.3>;isfocus;tag=g2y4Btme55Fje To: <sip:1000@192.168.20.3>;tag=1832665346 Call-ID: jbDaf1W7hQtswyvf CSeq: 79137188 NOTIFY Contact: <sip:3000@192.168.20.3:6060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.4.20+git~20150701T115426Z~0ae8ee7f8f~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Event: refer;id=476 Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: terminated;reason=noresource Content-Type: message/sipfrag;version=2.0 Content-Length: 23 SIP/2.0 403 Forbidden Thanks for your help.

2 2

Freeswitch-Acrobits/iPhone-ZRTP error
by Assaf Dahary 18 Aug '15

18 Aug '15

Hi all, When dialing with Acrobits/iPhone app I get most of the times ZRTP errors when making outgoing/incoming calls via Freeswitch. The app starts ZRTP key exchange and then stops with ZRTP error message. Sometimes it works but most of the time it does not. I'm using GSM and PCMU codecs (20sec) on both Acrobits and Freeswitch. Is there a special ZRTP/dialplan setup for Acrobits/iPhone app? Using none acrobits apps with ZRTP on Android with no issues. Here is my dialplan for the incoming call from DID 722157185 to extension 99556678, and FS debug flow which shows the ZRTP error cause: [zrtp respond]: INFO: Authtag HS80 isn't supported by profile. ID=1551 Regards Assaf <?xml version='1.0' encoding='UTF-8' standalone='no'?> <document type='freeswitch/xml'> <section name='dialplan' description='CALL Routing request'> <context name='external'> <extension name='call_to_99556678'> <condition field='destination_number' expression='^722157185$'> <action application='set' data='call_timeout=45'/><action application='set' data='ringback=${us-ring}'/><action application='set' data='instant_ringback=true'/><action application='set' data='x_dst_tariff='/><action application='set' data='x_dialplan_mode=PLIVO-FS'/><action application='set' data='x_account_id=0'/><action application='set' data='effective_caller_id_number=0542526278'/><action application='set' data='effective_caller_id_name='/><action application='set' data='zrtp_secure_media=true'/><action application='set' data='zrtp_enrollment=true'/><action application='set' data='x_call_type=PSTN2SIP'/><action application='set' data='x_user_name=99556678'/><action application='set' data='x_domain_name=confidentialtele.com'/><action application='set' data='proxy_media=false'/><action application='set' data='execute_on_answer=sched_hangup +3600 alloted_timeout' /><action application='set' data='hangup_after_bridge=true'/><action application='set' data='continue_on_fail=true'/><action application='bridge' data='user/99556678(a)confidentialtele.com' /> </condition> </extension> </context> </section> </document> 2015-08-18 07:33:58.946068 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:58.946068 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_NEW 2015-08-18 07:33:58.946068 [DEBUG] sofia.c:7877 sofia/external/0542526278@212.199.157.154:5060 receiving invite from 212.199.157.154:5060 version: 1.2.22 git 468dc29 2014-03-14 21:23:27Z 64bit 2015-08-18 07:33:58.946068 [DEBUG] sofia.c:5815 Channel sofia/external/0542526278@212.199.157.154:5060 entering state [received][100] 2015-08-18 07:33:58.946068 [DEBUG] sofia.c:5828 Remote SDP: v=0 o=Sonus_UAC 25651 22274 IN IP4 212.199.157.154 s=SIP Media Capabilities c=IN IP4 212.199.157.155 t=0 0 m=audio 28286 RTP/AVP 18 8 0 100 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-15 a=maxptime:20 2015-08-18 07:33:58.946068 [DEBUG] sofia.c:6075 (sofia/external/0542526278@212.199.157.154:5060) State Change CS_NEW -> CS_INIT 2015-08-18 07:33:58.946068 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:434 (sofia/external/0542526278@212.199.157.154:5060) State NEW 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_INIT 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:455 (sofia/external/0542526278@212.199.157.154:5060) State INIT 2015-08-18 07:33:58.946068 [DEBUG] mod_sofia.c:87 sofia/external/0542526278@212.199.157.154:5060 SOFIA INIT 2015-08-18 07:33:58.946068 [DEBUG] mod_sofia.c:127 (sofia/external/0542526278@212.199.157.154:5060) State Change CS_INIT -> CS_ROUTING 2015-08-18 07:33:58.946068 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:455 (sofia/external/0542526278@212.199.157.154:5060) State INIT going to sleep 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_ROUTING 2015-08-18 07:33:58.946068 [DEBUG] switch_channel.c:2163 (sofia/external/0542526278@212.199.157.154:5060) Callstate Change DOWN -> RINGING 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:471 (sofia/external/0542526278@212.199.157.154:5060) State ROUTING 2015-08-18 07:33:58.946068 [DEBUG] mod_sofia.c:150 sofia/external/0542526278@212.199.157.154:5060 SOFIA ROUTING 2015-08-18 07:33:58.946068 [DEBUG] switch_core_state_machine.c:117 sofia/external/0542526278@212.199.157.154:5060 Standard ROUTING 2015-08-18 07:33:58.946068 [INFO] mod_dialplan_xml.c:558 Processing 0542526278 <0542526278>->722157185 in context external Dialplan: sofia/external/0542526278@212.199.157.154:5060 parsing [external->call_to_99556678] continue=false Dialplan: sofia/external/0542526278@212.199.157.154:5060 Regex (PASS) [call_to_99556678] destination_number(722157185) =~ /^722157185$/ break=on-false Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(call_timeout=45) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(ringback=${us-ring}) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(instant_ringback=true) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_dst_tariff=) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_dialplan_mode=PLIVO-FS) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_account_id=0) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(effective_caller_id_number=0542526278) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(effective_caller_id_name=) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(zrtp_secure_media=true) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(zrtp_enrollment=true) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_call_type=PSTN2SIP) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_user_name=99556678) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(x_domain_name=confidentialtele.com) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(proxy_media=false) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(execute_on_answer=sched_hangup +3600 alloted_timeout) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(hangup_after_bridge=true) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action set(continue_on_fail=true) Dialplan: sofia/external/0542526278@212.199.157.154:5060 Action bridge(user/99556678(a)confidentialtele.com) 2015-08-18 07:33:58.986050 [DEBUG] switch_core_state_machine.c:167 (sofia/external/0542526278@212.199.157.154:5060) State Change CS_ROUTING -> CS_EXECUTE 2015-08-18 07:33:58.986050 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:58.986050 [DEBUG] switch_core_state_machine.c:471 (sofia/external/0542526278@212.199.157.154:5060) State ROUTING going to sleep 2015-08-18 07:33:58.986050 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_EXECUTE 2015-08-18 07:33:58.986050 [DEBUG] switch_core_state_machine.c:478 (sofia/external/0542526278@212.199.157.154:5060) State EXECUTE 2015-08-18 07:33:58.986050 [DEBUG] mod_sofia.c:243 sofia/external/0542526278@212.199.157.154:5060 SOFIA EXECUTE 2015-08-18 07:33:58.986050 [DEBUG] switch_core_state_machine.c:209 sofia/external/0542526278@212.199.157.154:5060 Standard EXECUTE EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(call_timeout=45) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [call_timeout]=[45] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(ringback=%(2000,4000,440,480)) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [ringback]=[%(2000,4000,440,480)] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(instant_ringback=true) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [instant_ringback]=[true] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_dst_tariff=) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_dst_tariff]=[UNDEF] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_dialplan_mode=PLIVO-FS) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_dialplan_mode]=[PLIVO-FS] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_account_id=0) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_account_id]=[0] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(effective_caller_id_number=0542526278) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [effective_caller_id_number]=[0542526278] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(effective_caller_id_name=) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [effective_caller_id_name]=[UNDEF] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(zrtp_secure_media=true) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [zrtp_secure_media]=[true] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(zrtp_enrollment=true) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [zrtp_enrollment]=[true] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_call_type=PSTN2SIP) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_call_type]=[PSTN2SIP] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_user_name=99556678) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_user_name]=[99556678] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(x_domain_name=confidentialtele.com) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [x_domain_name]=[confidentialtele.com] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(proxy_media=false) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [proxy_media]=[false] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(execute_on_answer=sched_hangup +3600 alloted_timeout) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [execute_on_answer]=[sched_hangup +3600 alloted_timeout] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(hangup_after_bridge=true) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [hangup_after_bridge]=[true] EXECUTE sofia/external/0542526278@212.199.157.154:5060 set(continue_on_fail=true) 2015-08-18 07:33:58.986050 [DEBUG] mod_dptools.c:1402 sofia/external/0542526278@212.199.157.154:5060 SET [continue_on_fail]=[true] EXECUTE sofia/external/0542526278@212.199.157.154:5060 bridge(user/99556678(a)confidentialtele.com) 2015-08-18 07:33:58.986050 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables 2015-08-18 07:33:59.017997 [DEBUG] switch_ivr_originate.c:2069 Parsing global variables 2015-08-18 07:33:59.017997 [DEBUG] switch_event.c:1661 Parsing variable [presence_id]=[99556678(a)confidentialtele.com] 2015-08-18 07:33:59.017997 [NOTICE] switch_channel.c:1050 New Channel sofia/agents/sip:99556678@164.138.115.183:50214 [58014df2-4562-11e5-b0c3-e7d40967f7d3] 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:5236 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_NEW -> CS_INIT 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:5306 [zrtp_passthru] Setting a-leg inherit_codec=true 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:5309 [zrtp_passthru] Setting b-leg absolute_codec_string='PCMU@8000h@20i@64000b' 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_INIT 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:455 (sofia/agents/sip:99556678@164.138.115.183:50214) State INIT 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:87 sofia/agents/sip:99556678@164.138.115.183:50214 SOFIA INIT 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:2716 sip:99556678@164.138.115.183:50214;rinstance=1E740845;transport=tls Setting proxy route to sofia/agents/sip:99556678@164.138.115.183:50214 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:2744 sofia/agents/sip:99556678@164.138.115.183:50214 sending invite version: 1.2.22 git 468dc29 2014-03-14 21:23:27Z 64bit Local SDP: v=0 o=FreeSWITCH 1439860069 1439860070 IN IP4 82.80.217.142 s=FreeSWITCH c=IN IP4 82.80.217.142 t=0 0 m=audio 12370 RTP/AVP 0 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:127 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_INIT -> CS_ROUTING 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:455 (sofia/agents/sip:99556678@164.138.115.183:50214) State INIT going to sleep 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_ROUTING 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:471 (sofia/agents/sip:99556678@164.138.115.183:50214) State ROUTING 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:150 sofia/agents/sip:99556678@164.138.115.183:50214 SOFIA ROUTING 2015-08-18 07:33:59.017997 [DEBUG] switch_ivr_originate.c:67 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:471 (sofia/agents/sip:99556678@164.138.115.183:50214) State ROUTING going to sleep 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.017997 [INFO] switch_ivr_originate.c:1191 Sending early media 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [G729:18:8000:20:8000]/[SILK:117:8000:20:20000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [G729:18:8000:20:8000]/[GSM:3:8000:20:13200] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [G729:18:8000:20:8000]/[PCMU:0:8000:20:64000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMA:8:8000:20:64000]/[SILK:117:8000:20:20000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMA:8:8000:20:64000]/[GSM:3:8000:20:13200] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[SILK:117:8000:20:20000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[GSM:3:8000:20:13200] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:3190 Set Codec sofia/external/0542526278@212.199.157.154:5060 PCMU/8000 20 ms 160 samples 64000 bits 2015-08-18 07:33:59.017997 [DEBUG] switch_core_codec.c:111 sofia/external/0542526278@212.199.157.154:5060 Original read codec set to PCMU:0 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:5451 Set 2833 dtmf send/recv payload to 100 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/external/0542526278@212.199.157.154:5060] 82.80.217.142 port 10872 -> 212.199.157.155 port 28286 codec: 0 ms: 20 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:2040 Starting timer [soft] 160 bytes per 20ms 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: START SESSION INITIALIZATION. sID=1550. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: ZID=353235306439386564366363. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Loading User's profile: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: allowclear: OFF 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: autosecure: ON 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: disclose_bit: OFF 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: signal. role: Unknown 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: TTL: 4294967295 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: SAS schemes: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 B256 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 B32 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Ciphers: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 AES3 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 AES1 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: PK schemes: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 EC25 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 DH3k 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 DH2k 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 Mult 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATL: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 HS32 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Hashes: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 S256 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Session initialization - DONE. sID=1550. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATTACH NEW STREAM to sID=1550: 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <ACTIVE>. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Empty slot was found - initializing new stream with ID=1550. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: Preparing ZRTP Hello according to the Session profile. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATTACH NEW STREAM - DONE. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp mitm]: START REGISTRATION STREAM ID=1550 mode=CLEAR state=ACTIVE. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp engine]: START STREAM ID=1550 mode=CLEAR state=ACTIVE. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1550 CLEAR switching <ACTIVE> ---> <START>. 2015-08-18 07:33:59.017997 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15607 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_CONSUME_MEDIA 2015-08-18 07:33:59.017997 [DEBUG] sofia.c:5815 Channel sofia/agents/sip:99556678@164.138.115.183:50214 entering state [calling][0] 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:490 (sofia/agents/sip:99556678@164.138.115.183:50214) State CONSUME_MEDIA 2015-08-18 07:33:59.017997 [DEBUG] switch_core_state_machine.c:490 (sofia/agents/sip:99556678@164.138.115.183:50214) State CONSUME_MEDIA going to sleep 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 100 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 100 2015-08-18 07:33:59.017997 [DEBUG] sofia_glue.c:3749 sofia/external/0542526278@212.199.157.154:5060 Set rtp dtmf delay to 40 2015-08-18 07:33:59.017997 [NOTICE] sofia_glue.c:4360 Pre-Answer sofia/external/0542526278@212.199.157.154:5060! 2015-08-18 07:33:59.017997 [DEBUG] switch_channel.c:3368 (sofia/external/0542526278@212.199.157.154:5060) Callstate Change RINGING -> EARLY 2015-08-18 07:33:59.017997 [DEBUG] mod_sofia.c:2891 Ring SDP: v=0 o=FreeSWITCH 1439861567 1439861568 IN IP4 82.80.217.142 s=FreeSWITCH c=IN IP4 82.80.217.142 t=0 0 m=audio 10872 RTP/AVP 0 100 a=rtpmap:0 PCMU/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-16 a=ptime:20 a=sendrecv 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:871 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:59.017997 [DEBUG] switch_ivr_originate.c:1248 Raw Codec Activation Success L16@8000hz 1 channel 20ms 2015-08-18 07:33:59.017997 [DEBUG] switch_core_codec.c:219 sofia/external/0542526278@212.199.157.154:5060 Push codec L16:70 2015-08-18 07:33:59.017997 [DEBUG] switch_ivr_originate.c:1316 Play Ringback Tone [%(2000,4000,440,480)] 2015-08-18 07:33:59.017997 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:33:59.056050 [DEBUG] sofia.c:5815 Channel sofia/external/0542526278@212.199.157.154:5060 entering state [early][183] 2015-08-18 07:33:59.086039 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15608 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.126047 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.126047 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:33:59.126047 [DEBUG] sofia.c:5815 Channel sofia/agents/sip:99556678@164.138.115.183:50214 entering state [proceeding][180] 2015-08-18 07:33:59.126047 [NOTICE] sofia.c:5910 Ring-Ready sofia/agents/sip:99556678@164.138.115.183:50214! 2015-08-18 07:33:59.126047 [DEBUG] switch_channel.c:3242 (sofia/agents/sip:99556678@164.138.115.183:50214) Callstate Change DOWN -> RINGING 2015-08-18 07:33:59.186036 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15609 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.236037 [DEBUG] switch_rtp.c:3757 Correct ip/port confirmed. 2015-08-18 07:33:59.386038 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15610 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.586037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15611 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.786037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: WARNING! HELLO have been resent 5 times without a response. Raising ZRTP_EVENT_NO_ZRTP_QUICK event. ID=1550 2015-08-18 07:33:59.786037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15612 size=144. Stream 1550:CLEAR:START 2015-08-18 07:33:59.986037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15613 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:00.196037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15614 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:00.396036 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15615 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:00.596037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15616 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:00.796050 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15617 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:00.996036 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15618 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:01.196037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15619 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:01.396050 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15620 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:01.596036 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15621 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:01.796036 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15622 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:01.996050 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15623 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:02.196037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15624 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:02.396050 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15625 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:02.596050 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3923818383 seq=15626 size=144. Stream 1550:CLEAR:START 2015-08-18 07:34:02.636038 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.636038 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.636038 [DEBUG] sofia.c:5815 Channel sofia/agents/sip:99556678@164.138.115.183:50214 entering state [completing][200] 2015-08-18 07:34:02.636038 [DEBUG] sofia.c:5828 Remote SDP: v=0 o=- 8706103222 65422 IN IP4 172.26.170.170 s=vzrqxyq c=IN IP4 10.0.0.16 t=0 0 m=audio 48928 RTP/AVP 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2015-08-18 07:34:02.636038 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.636038 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.636038 [DEBUG] sofia.c:5815 Channel sofia/agents/sip:99556678@164.138.115.183:50214 entering state [ready][200] 2015-08-18 07:34:02.636038 [DEBUG] sofia_glue.c:5282 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2015-08-18 07:34:02.636038 [DEBUG] sofia_glue.c:3190 Set Codec sofia/agents/sip:99556678@164.138.115.183:50214 PCMU/8000 20 ms 160 samples 64000 bits 2015-08-18 07:34:02.636038 [DEBUG] switch_core_codec.c:111 sofia/agents/sip:99556678@164.138.115.183:50214 Original read codec set to PCMU:0 2015-08-18 07:34:02.636038 [DEBUG] sofia_glue.c:5442 Set 2833 dtmf send payload to 101 2015-08-18 07:34:02.636038 [DEBUG] sofia_glue.c:3449 AUDIO RTP [sofia/agents/sip:99556678@164.138.115.183:50214] 82.80.217.142 port 12370 -> 10.0.0.16 port 48928 codec: 0 ms: 20 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:2040 Starting timer [soft] 160 bytes per 20ms 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: START SESSION INITIALIZATION. sID=1551. 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: ZID=353235306439386564366363. 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: Loading User's profile: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: allowclear: OFF 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: autosecure: ON 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: disclose_bit: OFF 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: signal. role: Initiator 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: TTL: 4294967295 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: SAS schemes: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 B256 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 B32 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: Ciphers: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 AES3 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 AES1 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: PK schemes: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 EC25 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 DH3k 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 DH2k 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 Mult 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATL: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 HS32 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 [ zrtp main]: Hashes: 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 S256 2015-08-18 07:34:02.636038 [DEBUG] switch_rtp.c:948 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp main]: Session initialization - DONE. sID=1551. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATTACH NEW STREAM to sID=1551: 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <ACTIVE>. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp main]: Empty slot was found - initializing new stream with ID=1551. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp main]: Preparing ZRTP Hello according to the Session profile. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp main]: ATTACH NEW STREAM - DONE. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp engine]: START STREAM ID=1551 mode=CLEAR state=ACTIVE. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 CLEAR switching <ACTIVE> ---> <START>. 2015-08-18 07:34:02.646130 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3992876290 seq=8297 size=144. Stream 1551:CLEAR:START 2015-08-18 07:34:02.646130 [DEBUG] sofia_glue.c:3716 Set 2833 dtmf send payload to 101 2015-08-18 07:34:02.646130 [DEBUG] sofia_glue.c:3722 Set 2833 dtmf receive payload to 101 2015-08-18 07:34:02.646130 [DEBUG] sofia_glue.c:3749 sofia/agents/sip:99556678@164.138.115.183:50214 Set rtp dtmf delay to 40 2015-08-18 07:34:02.646130 [DEBUG] switch_channel.c:3601 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.646130 [NOTICE] sofia.c:6604 Channel [sofia/agents/sip:99556678@164.138.115.183:50214] has been answered 2015-08-18 07:34:02.646130 [DEBUG] switch_channel.c:3647 (sofia/agents/sip:99556678@164.138.115.183:50214) Callstate Change RINGING -> ACTIVE 2015-08-18 07:34:02.656044 [DEBUG] switch_core_codec.c:244 sofia/external/0542526278@212.199.157.154:5060 Restore previous codec PCMU:0. 2015-08-18 07:34:02.656044 [DEBUG] mod_sofia.c:866 Local SDP sofia/external/0542526278@212.199.157.154:5060: v=0 o=FreeSWITCH 1439861567 1439861569 IN IP4 82.80.217.142 s=FreeSWITCH c=IN IP4 82.80.217.142 t=0 0 m=audio 10872 RTP/AVP 0 100 a=rtpmap:0 PCMU/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-16 a=ptime:20 a=sendrecv 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:871 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.656044 [NOTICE] switch_ivr_originate.c:3456 Channel [sofia/external/0542526278@212.199.157.154:5060] has been answered EXECUTE sofia/external/0542526278@212.199.157.154:5060 sched_hangup(+3600 alloted_timeout) 2015-08-18 07:34:02.656044 [DEBUG] switch_scheduler.c:243 Added task 790 switch_ivr_schedule_hangup (57f517c6-4562-11e5-b0a7-e7d40967f7d3) to run at 1439876042 2015-08-18 07:34:02.656044 [DEBUG] switch_channel.c:3647 (sofia/external/0542526278@212.199.157.154:5060) Callstate Change EARLY -> ACTIVE 2015-08-18 07:34:02.656044 [DEBUG] switch_ivr_originate.c:3513 Originate Resulted in Success: [sofia/agents/sip:99556678@164.138.115.183:50214] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] sofia.c:5815 Channel sofia/external/0542526278@212.199.157.154:5060 entering state [completed][200] 2015-08-18 07:34:02.656044 [DEBUG] switch_ivr_originate.c:3513 Originate Resulted in Success: [sofia/agents/sip:99556678@164.138.115.183:50214] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:871 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:871 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] switch_ivr_bridge.c:1446 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_EXCHANGE_MEDIA 2015-08-18 07:34:02.656044 [DEBUG] switch_core_state_machine.c:481 (sofia/agents/sip:99556678@164.138.115.183:50214) State EXCHANGE_MEDIA 2015-08-18 07:34:02.656044 [DEBUG] mod_sofia.c:688 SOFIA EXCHANGE_MEDIA 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.656044 [DEBUG] switch_core_session.c:1016 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.696047 [DEBUG] sofia.c:5815 Channel sofia/external/0542526278@212.199.157.154:5060 entering state [ready][200] 2015-08-18 07:34:02.696047 [DEBUG] switch_core_session.c:933 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:02.696047 [DEBUG] switch_core_session.c:933 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:02.696047 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3992876290 seq=8298 size=144. Stream 1551:CLEAR:START 2015-08-18 07:34:02.796037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: WARNING! HELLO Max retransmissions count reached (20 retries). ID=1550 2015-08-18 07:34:02.796037 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1550 CLEAR switching <START> ---> <NOZRTP>. 2015-08-18 07:34:02.816038 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3992876290 seq=8299 size=144. Stream 1551:CLEAR:START 2015-08-18 07:34:02.896037 [INFO] switch_rtp.c:3738 Auto Changing port from 10.0.0.16:48928 to 164.138.115.183:48928 2015-08-18 07:34:03.026038 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <HELLO> ssrc=3992876290 seq=8300 size=144. Stream 1551:CLEAR:START 2015-08-18 07:34:03.116037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Received <HelloACK> packet with ssrc=1504409235 seq=59645/59645 size=28. Stream1551:CLEAR:START. 2015-08-18 07:34:03.116037 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 CLEAR switching <START> ---> <W4HELLO>. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Received <Hello > packet with ssrc=1504409235 seq=59644/59644 size=136. Stream1551:CLEAR:W4HELLO. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Processing HELLO from Acrobits/1.0 V=1.10, P=0, M=0. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: ac=1 cc=1 sc=2 kc=2 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: S256S384AES3HS80EC38DH3kB256B32 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Received HELLO had the same protocol V. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: _zrtp_choose_best_comp() for PKT. local=DH3k remote=DH3k, choosen=DH3k 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Received HELLO Accepted 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp cache]: ache_get(): zid1=353235306439386564366363, zis2=1d786c7acc41df3254401114 MiTM=NO 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp cache]: ache_get(): zid1=353235306439386564366363, zis2=1d786c7acc41df3254401114 MiTM=NO 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp cache]: ache_get() - not found. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp cache]: ache_get(): zid1=353235306439386564366363, zis2=1d786c7acc41df3254401114 MiTM=YES 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Restoring Secrets: lZID=353235306439386564366363 rZID=1d786c7acc41df3254401114. V=1 sID=1551 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: RS1 <d4b34c062dde89f9779ebd04e5dc21f6ac8876ac00a4a483732839883c3a97ff> 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: RS2 <EMPTY> 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: PBX <53b18d74e55ed6eaa28bff74c4f244f25b61cc6ec84e38fe918215e7b3b3e271> 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 CLEAR switching <W4HELLO> ---> <SINITSEC>. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Initiating Secure iteration... ID=1551. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Got mode=DH. Check approval of starting. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Mode=DH Cccepted. Starting ZRTP Initiator Protocol. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: ENTER STATE INITIATING SECURE for ID=1551 mode=DH state=SINITSEC. 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Initiator selected following options: 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Hash: S256 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Cipher: AES3 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: ATL: HS32 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp initiat]: VAD scheme: B256 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Init INITIATOR's Protocol ID=1551 mode=DH... 2015-08-18 07:34:03.136037 [DEBUG] switch_rtp.c:948 [ zrtp dh]: DH TEST: DH3k zrtp_dh_initialize() START. now=1439872443136ms. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [ zrtp dh]: DH TEST: zrtp_dh_initialize() for DH3k was executed ts=1439872443150ms d=14ms. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS id=99f73649cd19c2cc. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS peer_id=4528027f7cf5ae69. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS id=5273a6e53041d6e2. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS peer_id=37fae36a5b9499d7. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS id=bfebfc14937ab973. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS peer_id=b3d1fc6634723ece. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS id=91efde175d88dd1b. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp protoco]: Attach RS peer_id=f77b9dcbaa3856d6. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 DH switching <SINITSEC> ---> <INITSEC>. 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Start Sending COMMIT ID=1551 mode=DH state=INITSEC: 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Hash: S256 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: Cipher: AES3 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: ATL: HS32 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: PK scheme: DH3k 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: VAD scheme: B256 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [zrtp initiat]: hv: 6079e170ef33b35d7d4a03da8c0f072ab09940e0b5d4754973b111033c57c695 2015-08-18 07:34:03.146042 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <COMMIT> ssrc=3992876290 seq=8301 size=132. Stream 1551:DH:INITSEC 2015-08-18 07:34:03.156039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.176038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.196038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.216041 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.236047 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.256038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.276038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.296038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.306040 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <COMMIT> ssrc=3992876290 seq=8302 size=132. Stream 1551:DH:INITSEC 2015-08-18 07:34:03.316039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.336038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.356038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.376038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.396038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.416037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.436038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.456038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.476038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.496038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.516037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.536038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.556038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.576038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.596038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.606040 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <COMMIT> ssrc=3992876290 seq=8303 size=132. Stream 1551:DH:INITSEC 2015-08-18 07:34:03.616040 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.636038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.656039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.676038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.696037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.716043 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.736038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.756038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.776038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.796051 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.816051 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.836053 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.856090 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.876038 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.896037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.916037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Add 172 bytes of entropy to the RNG pool. 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Received <Commit > packet with ssrc=1504409235 seq=59646/59646 size=132. Stream1551:DH:INITSEC. 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [zrtp respond]: Preparse incoming COMMIT. Remote peer wants DH3k:2 mode lic=1 peer M=0. 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [zrtp respond]: INFO: Authtag HS80 isn't supported by profile. ID=1551 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [ zrtp engine]: Enter InitiatingError State with ERROR:<SRTP auth. tag not supported>, notification Enabled. ID=1551 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 DH switching <INITSEC> ---> <INITERROR>. 2015-08-18 07:34:03.936037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Send <ERROR> ssrc=3992876290 seq=8304 size=32. Stream 1551:DH:INITERROR 2015-08-18 07:34:03.956037 [DEBUG] switch_rtp.c:948 [ zrtp utils]: Received <ErrorACK> packet with ssrc=1504409235 seq=59648/59648 size=28. Stream1551:DH:INITERROR. 2015-08-18 07:34:03.956037 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=1551 DH switching <INITERROR> ---> <ERROR>. 2015-08-18 07:34:03.966040 [DEBUG] switch_core_session.c:1016 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:03.976040 [NOTICE] sofia.c:737 Hangup sofia/agents/sip:99556678@164.138.115.183:50214 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2015-08-18 07:34:03.976040 [DEBUG] switch_channel.c:3187 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [KILL] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:645 BRIDGE THREAD DONE [sofia/agents/sip:99556678@164.138.115.183:50214] 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:675 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:481 (sofia/agents/sip:99556678@164.138.115.183:50214) State EXCHANGE_MEDIA going to sleep 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_HANGUP 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:678 (sofia/agents/sip:99556678@164.138.115.183:50214) Callstate Change ACTIVE -> HANGUP 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:680 (sofia/agents/sip:99556678@164.138.115.183:50214) State HANGUP 2015-08-18 07:34:03.976040 [DEBUG] mod_sofia.c:506 Channel sofia/agents/sip:99556678@164.138.115.183:50214 hanging up, cause: NORMAL_CLEARING 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:569 sofia/agents/sip:99556678@164.138.115.183:50214 ending bridge by request from write function 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:645 BRIDGE THREAD DONE [sofia/external/0542526278@212.199.157.154:5060] 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:675 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_ivr_bridge.c:1544 sofia/agents/sip:99556678@164.138.115.183:50214 skip receive message [UNBRIDGE] (channel is hungup already) 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:871 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:48 sofia/agents/sip:99556678@164.138.115.183:50214 Standard HANGUP, cause: NORMAL_CLEARING 2015-08-18 07:34:03.976040 [NOTICE] switch_ivr_bridge.c:1589 Hangup sofia/external/0542526278@212.199.157.154:5060 [CS_EXECUTE] [NORMAL_CLEARING] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:680 (sofia/agents/sip:99556678@164.138.115.183:50214) State HANGUP going to sleep 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:447 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_HANGUP -> CS_REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_channel.c:3187 Send signal sofia/external/0542526278@212.199.157.154:5060 [KILL] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:415 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:764 (sofia/agents/sip:99556678@164.138.115.183:50214) State REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:92 sofia/agents/sip:99556678@164.138.115.183:50214 Standard REPORTING, cause: NORMAL_CLEARING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:764 (sofia/agents/sip:99556678@164.138.115.183:50214) State REPORTING going to sleep 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:2810 sofia/external/0542526278@212.199.157.154:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:478 (sofia/external/0542526278@212.199.157.154:5060) State EXECUTE going to sleep 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_HANGUP 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:678 (sofia/external/0542526278@212.199.157.154:5060) Callstate Change ACTIVE -> HANGUP 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:441 (sofia/agents/sip:99556678@164.138.115.183:50214) State Change CS_REPORTING -> CS_DESTROY 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1351 Send signal sofia/agents/sip:99556678@164.138.115.183:50214 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1559 Session 5559 (sofia/agents/sip:99556678@164.138.115.183:50214) Locked, Waiting on external entities 2015-08-18 07:34:03.976040 [NOTICE] switch_core_session.c:1577 Session 5559 (sofia/agents/sip:99556678@164.138.115.183:50214) Ended 2015-08-18 07:34:03.976040 [NOTICE] switch_core_session.c:1581 Close Channel sofia/agents/sip:99556678@164.138.115.183:50214 [CS_DESTROY] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:680 (sofia/external/0542526278@212.199.157.154:5060) State HANGUP 2015-08-18 07:34:03.976040 [DEBUG] mod_sofia.c:500 sofia/external/0542526278@212.199.157.154:5060 Overriding SIP cause 480 with 200 from the other leg 2015-08-18 07:34:03.976040 [DEBUG] mod_sofia.c:506 Channel sofia/external/0542526278@212.199.157.154:5060 hanging up, cause: NORMAL_CLEARING 2015-08-18 07:34:03.976040 [DEBUG] mod_sofia.c:558 Sending BYE to sofia/external/0542526278@212.199.157.154:5060 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:48 sofia/external/0542526278@212.199.157.154:5060 Standard HANGUP, cause: NORMAL_CLEARING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:680 (sofia/external/0542526278@212.199.157.154:5060) State HANGUP going to sleep 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:447 (sofia/external/0542526278@212.199.157.154:5060) State Change CS_HANGUP -> CS_REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:415 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:764 (sofia/external/0542526278@212.199.157.154:5060) State REPORTING 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:566 (sofia/agents/sip:99556678@164.138.115.183:50214) Callstate Change HANGUP -> DOWN 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:569 (sofia/agents/sip:99556678@164.138.115.183:50214) Running State Change CS_DESTROY 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:579 (sofia/agents/sip:99556678@164.138.115.183:50214) State DESTROY 2015-08-18 07:34:03.976040 [DEBUG] mod_sofia.c:399 sofia/agents/sip:99556678@164.138.115.183:50214 SOFIA DESTROY 2015-08-18 07:34:03.976040 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=1551 mode=DH state=ERROR. 2015-08-18 07:34:03.976040 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <NONE>. 2015-08-18 07:34:03.976040 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2015-08-18 07:34:03.976040 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:99 sofia/agents/sip:99556678@164.138.115.183:50214 Standard DESTROY 2015-08-18 07:34:03.976040 [DEBUG] switch_core_state_machine.c:579 (sofia/agents/sip:99556678@164.138.115.183:50214) State DESTROY going to sleep 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:92 sofia/external/0542526278@212.199.157.154:5060 Standard REPORTING, cause: NORMAL_CLEARING 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:764 (sofia/external/0542526278@212.199.157.154:5060) State REPORTING going to sleep 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:441 (sofia/external/0542526278@212.199.157.154:5060) State Change CS_REPORTING -> CS_DESTROY 2015-08-18 07:34:04.056039 [DEBUG] switch_core_session.c:1351 Send signal sofia/external/0542526278@212.199.157.154:5060 [BREAK] 2015-08-18 07:34:04.056039 [DEBUG] switch_core_session.c:1559 Session 5558 (sofia/external/0542526278@212.199.157.154:5060) Locked, Waiting on external entities 2015-08-18 07:34:04.056039 [NOTICE] switch_core_session.c:1577 Session 5558 (sofia/external/0542526278@212.199.157.154:5060) Ended 2015-08-18 07:34:04.056039 [NOTICE] switch_core_session.c:1581 Close Channel sofia/external/0542526278@212.199.157.154:5060 [CS_DESTROY] 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:566 (sofia/external/0542526278@212.199.157.154:5060) Callstate Change HANGUP -> DOWN 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:569 (sofia/external/0542526278@212.199.157.154:5060) Running State Change CS_DESTROY 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:579 (sofia/external/0542526278@212.199.157.154:5060) State DESTROY 2015-08-18 07:34:04.056039 [DEBUG] mod_sofia.c:399 sofia/external/0542526278@212.199.157.154:5060 SOFIA DESTROY 2015-08-18 07:34:04.056039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=1550 mode=CLEAR state=NOZRTP. 2015-08-18 07:34:04.056039 [DEBUG] switch_rtp.c:948 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <NONE>. 2015-08-18 07:34:04.056039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2015-08-18 07:34:04.056039 [DEBUG] switch_rtp.c:948 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:99 sofia/external/0542526278@212.199.157.154:5060 Standard DESTROY 2015-08-18 07:34:04.056039 [DEBUG] switch_core_state_machine.c:579 (sofia/external/0542526278@212.199.157.154:5060) State DESTROY going to sleep 2015-08-18 07:34:04.156052 [DEBUG] switch_scheduler.c:144 Deleting task 790 switch_ivr_schedule_hangup (57f517c6-4562-11e5-b0a7-e7d40967f7d3)

1 0

Request for recommended GUI for freeSWITCH
by david.fu＠oocl.com 18 Aug '15

18 Aug '15

Hi All, Besides of Fusion, any recommended GUI for freeSWTICH ? I'm using Fusion. However, sometimes I found that I couldn't update the configuration file using it. Thanks. Best Regards, David IMPORTANT NOTICE Email from OOCL is confidential and may be legally privileged. If it is not intended for you, please delete it immediately unread. The internet cannot guarantee that this communication is free of viruses, interception or interference and anyone who communicates with us by email is taken to accept the risks in doing so. Without limitation, OOCL and its affiliates accept no liability whatsoever and howsoever arising in connection with the use of this email. Under no circumstances shall this email constitute a binding agreement to carry or for provision of carriage services by OOCL, which is subject to the availability of carrier's equipment and vessels and the terms and conditions of OOCL's standard bill of lading which is also available at http://www.oocl.com.

3 3

FreeSWITCH Week in Review (Master Branch) August 8th-August 14th
by Ken Rice 17 Aug '15

17 Aug '15

New Post on freeswitch.org from Kathleen King check it out at http://ift.tt/1IZhsIP FreeSWITCH Week in Review (Master Branch) August 8th-August 14th Hello, again. This past week in the FreeSWITCH master branch we had 7 commits. There were no new features this week, but you should go check out the Verto Communicator that was added last week! Join us on Wednesdays at 12:00 CT for some more FreeSWITCH fun! And head over to freeswitch.com to learn more about FreeSWITCH support. The following bugs were squashed: FS-7930 [mod_conference] Correct termination of conference when the last member with endconf left. FS-7953 [verto communicator] Fixed dialing when typing extension using the keyboard. FS-7958 [mod_conference] Fixed a race condition causing crash in conference video MCU FS-7951 [mod_rayo] Completely clean up mod_rayo if it fails to load FS-7955 [mod_sofia] Fixed a crash caused by invalid contact when using event to send a notify message

1 0

FreeSWITCH Week in Review (Master Branch) August 1st-August 7th
by Ken Rice 17 Aug '15

17 Aug '15

New Post on freeswitch.org from Kathleen King check it out at http://ift.tt/1WzTr4D FreeSWITCH Week in Review (Master Branch) August 1st-August 7th Hello, again. This past week in the FreeSWITCH master branch we had 17 commits. The new features this week are: new properties added to the amqp configuration, fixed the usage for enable_fallback_format_fields, a fix for a routing key issue in amqp, and the awesome new Verto Communicator! Join us on Wednesdays at 12:00 CT for some more FreeSWITCH fun! And head over to freeswitch.com to learn more about FreeSWITCH support. New features that were added: FS-7806 FS-7803 [mod_amqp] Added new properties to amqp configuration, fixed the usage for enable_fallback_format_fields, and added amqp_util_encode to fix a routing key issue FS-7972 [verto communicator] Creating Verto Communicator Improvements in build system, cross platform support, and packaging: FS-7728 Fixed Windows build issues minus video features The following bugs were squashed: FS-7940 [mod_conference] Fixed an issue where the video image does not appear on the new canvas when switching

1 0

FreeSWITCH Week in Review (Master Branch) July 25th-July 31st
by Ken Rice 17 Aug '15

17 Aug '15

New Post on freeswitch.org from Kathleen King check it out at http://ift.tt/1IZhsIN FreeSWITCH Week in Review (Master Branch) July 25th-July 31st Hello, again. This past week in the FreeSWITCH master branch we had 41 commits. The new features this week are: improved mod_png to allow snapshot of single legged calls, added session UUID to lua error logs and added session UUID to embedded language (lua, javascript, etc) logs when session sanity check fails, improved the xml fetch lookup for channels on nightmare transfer, and added uuid_redirect API command to mod_commands. Join us on Wednesdays at 12:00 CT for some more FreeSWITCH fun! And head over to freeswitch.com to learn more about FreeSWITCH support. New features that were added: FS-7900 [mod_png] Allow snapshot of single legged calls FS-7912 [mod_lua] Added session UUID to lua error logs, if known and added session UUID to embedded language (lua, javascript, etc) logs when session sanity check fails FS-7760 [mod_sofia] Improved the xml fetch lookup for channels on nightmare transfer FS-7922 [mod_commands] Added uuid_redirect API command. This provides the equivalent functionality of the dptools “redirect” application as an api command. The following bugs were squashed: FS-7769 [mod_conference] Fixed vmute on personal canvas and fixed changing layouts on personal canvas FS-7893 [mod_conference] Fixed a bug causing muxing write thread to occasionally not close on shutdown FS-7904 Fixed alpha image patching FS-7906 [mod_av] Correct crash from multi-threaded opening or closing of multiple files at the same time FS-7913 [mod_conference] Fixed miscast variable FS-7918 [mod_kazoo] Small fixes in mod_kazoo FS-7917 [mod_sofia] Fixed default config, we really shouldn’t be setting ext-*-ip settings for ipv6 profiles FS-7908 FS-7092 Fixed the generated sdp including telephone-event for the rates of video codecs (90000) when it should only be audio codec rates FS-7927 Fixed a typo in variable name: eavesdrop_annnounce_macro

1 0

Feature request/suggestion - "say" w/ tts_engine backing
by Nathan Neulinger 17 Aug '15

17 Aug '15

It would be useful to be able to leverage the functionality of say - for smart parsing/breaking up of the text, but to request that it use the setting for 'tts_engine' to actually get the audio instead of using the recorded files. Implementation possibility: Allow specifying a 'voice' for say via a separate variable (so as to not change the calling convention) - to specify the prefix directory it searches in for audio files. If the desired file isn't present, fall back to generating the words with the configured tts engine. (optional, selectable?) Cache the resulting generated word/phrase for future reuse -- Nathan ------------------------------------------------------------ Nathan Neulinger nneul(a)mst.edu Missouri S&T Information Technology (573) 612-1412 System Administrator - Architect

3 6