freeswitchforum.com https://freeswitchforum.com/ |
|
авторизация voicemail https://freeswitchforum.com/viewtopic.php?f=6&t=158 |
Страница 1 из 1 |
Автор: | sugiura [ 13 июн 2013 10:16 ] |
Заголовок сообщения: | авторизация voicemail |
Добрый день! Проблема такая: freeswitch+bluebox, есть группа из двух телефонов (1001 и 1002) под номером 2001. Voicemail на том же номере. Dialplan: Код: <extension name="main_number_7" continue="true"> <condition field="destination_number" expression="^2001$"> <action application="set" bluebox="settingEndBridge" data="hangup_after_bridge=true"/> <action application="set" bluebox="settingFail" data="continue_on_fail=true"/> <action application="set" bluebox="autoPlay" data="vm_auto_play=false"/> <action application="set" bluebox="settingTimeout" data="call_timeout=30"/> <action application="set" bluebox="settingRing" data="ringback=${us-ring}"/> <action application="set" bluebox="settingRingXfer" data="transfer_ringback=${us-ring}"/> <action application="export" bluebox="sipCalleeIdName" data="sip_callee_id_name=2001"/> <action application="export" bluebox="sipCalleeIdNumber" data="sip_callee_id_number=2001"/> <action application="export" bluebox="settingContinueOnTimeout" data="originate_continue_on_timeou <action application="bridge" data="[ignore_early_media=true,leg_timeout=30]user/1001@$${location_1 <!--Voicemail--> <action application="answer"/> <action application="sleep" data="1000"/> <action application="set" data="voicemail_authorized=${sip_authorized}"/> <action application="voicemail" data="default voicemail_1 2001"/> <action application="hangup"/> </condition> </extension> При звонке (с третьего номера, зарегистрированного в sip) и включении voicemail'а, идет оповещение о том, что нужно оставить сообщение после сигнала (всё нормально). При нажатии * должна начинаться процедура авторизации: "Введите свой идентификатор", после чего должно последовать "Введите свой пароль". При вводе идентификатора, voicemail говорит "До свидания" и отключается. Если в диалплане сменить Код: <action application="voicemail" data="default voicemail_1 2001"/> на Код: <action application="voicemail" data="check default voicemail_1 2001"/> авторизация проходит успешно и мне сообщают, есть ли сохраненные сообщения и т.д. Т.е. всё хорошо, НО! при этом отсутствует оповещении о надобности оставить сообщение после гудка. В directory у меня так: Код: <domain name="voicemail_1"> <groups> <group name="default"> <users> <user bluebox_id="5" id="2001"> <params> <param name="sip-forbid-register" value="true"/> <param name="password" value="2001"/> <param name="vm-password" value="2001"/> <param name="vm-message-ext" value="wav"/> <param name="vm-keep-local-after-email" value="true"/> <param name="vm-attach-file" value="false"/> <param name="vm-email-all-messages" value="false"/> </params> <variables> <variable name="timezone" value="Asia/Irkutsk"/> </variables> </user> </users> </group> </groups> </domain> В voicemail.conf.xml так: Код: <param name="operator-extension" value="operator XML default"/> <param name="operator-key" value="9"/> <param name="vmain-extension" value="vmain XML default"/> <param name="vmain-key" value="*"/> upd: в логах после попытки авторизации висит такая строка: Код: [WARNING] mod_voicemail.c:2394 Can't find user [2001@194.143.148.10] После чего voicemail прощается. Вопрос: как сделать так, чтобы всё работало? Чтобы был *beep* и работающая авторизация. |
Автор: | sugiura [ 18 июн 2013 05:36 ] |
Заголовок сообщения: | Re: авторизация voicemail |
Пардон, диалплан криво скопировал:) Код: <extension name="main_number_7" continue="true">
<condition field="destination_number" expression="^2001$"> <action application="set" bluebox="settingEndBridge" data="hangup_after_bridge=true"/> <action application="set" bluebox="settingFail" data="continue_on_fail=true"/> <action application="set" bluebox="autoPlay" data="vm_auto_play=false"/> <action application="set" bluebox="settingTimeout" data="call_timeout=30"/> <action application="set" bluebox="settingRing" data="ringback=${us-ring}"/> <action application="set" bluebox="settingRingXfer" data="transfer_ringback=${us-ring}"/> <action application="export" bluebox="sipCalleeIdName" data="sip_callee_id_name=2001"/> <action application="export" bluebox="sipCalleeIdNumber" data="sip_callee_id_number=2001"/> <action application="export" bluebox="settingContinueOnTimeout" data="originate_continue_on_timeout=true"/> <action application="bridge" data="[ignore_early_media=true,leg_timeout=30]user/1001@$${location_1}|[ignore_early_media=true,leg_timeout=30]user/1002@$${location_1}"/> <!--Voicemail--> <action application="answer"/> <action application="sleep" data="1000"/> <action application="set" data="voicemail_authorized=${sip_authorized}"/> <action application="voicemail" data="default voicemail_1 2001"/> <action application="hangup"/> </condition> </extension> |
Автор: | sugiura [ 18 июн 2013 09:39 ] |
Заголовок сообщения: | Re: авторизация voicemail |
Вопрос снимается, разобрался. Добавил в диалплане: Код: <action application="set" data="domain_name=voicemail_1"/>
|
Автор: | oper [ 26 фев 2014 18:32 ] |
Заголовок сообщения: | Re: авторизация voicemail |
Приветствую, не знаю куда написать. У меня проблема более простая, нужно просто оставлять голосовое сообщение пользователю. При тестах внутри FS всё работает нормально, логика отробатывает, файл записывается. Но если звонить через Медиант 1000 - пишется первые 10 секунд и все. Может кто то сталкивался с такой проблемой? Тестирую так: звоню на номер 200. Код: <extension name="VoiceMail_Extensiontest"> <condition field="destination_number" expression="^(200)$"> <action application="export" data="dialed_extension=$1"/> <action application="set" data="ringback=${us-ring}"/> <action application="set" data="call_timeout=35"/> <!--<action application="set" data="continue_on_fail=NORMAL_TEMPORARY_FAILURE,USER_BUSY,NO_ANSWER,TIMEOUT,NO_ROUTE_DESTINATION"/> --> <action application="set" data="continue_on_fail=true"/> <!--<action application="bridge" data="user/${dialed_extension}@${domain_name}"/>--> <action application="answer"/> <action application="sleep" data="1000"/> <action application="bridge" data="loopback/app=voicemail:default ${domain_name} 180"/> </condition> </extension> Лог FS при звонке из вне тут. Код: freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:33.411625 [NOTICE] switch_channel.c:1030 New Channel sofia/internal/4498@10.10.10. 41 [1745f6bb-34b8-49db-9865-583e44b12dbd] 2014-02-26 17:56:33.411625 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.411625 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.411625 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_NEW 2014-02-26 17:56:33.411625 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/4498@10.10.10.41) State NEW 2014-02-26 17:56:33.442875 [DEBUG] sofia.c:7818 IP 10.10.10.41 Approved by acl "domains[]". Access Granted. 2014-02-26 17:56:33.442875 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [received][100] 2014-02-26 17:56:33.442875 [DEBUG] sofia.c:5719 Remote SDP: v=0 o=AudiocodesGW 156214759 156214438 IN IP4 10.10.10.41 s=Phone-Call c=IN IP4 10.10.10.41 t=0 0 m=audio 6910 RTP/AVP 0 8 4 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=fmtp:4 annexa=no a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=rtcp:6911 IN IP4 10.10.10.41 2014-02-26 17:56:33.442875 [DEBUG] sofia.c:5933 (sofia/internal/4498@10.10.10.41) State Change CS_NEW -> CS_INIT 2014-02-26 17:56:33.442875 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_INIT 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/4498@10.10.10.41) State INIT 2014-02-26 17:56:33.442875 [DEBUG] mod_sofia.c:87 sofia/internal/4498@10.10.10.41 SOFIA INIT 2014-02-26 17:56:33.442875 [DEBUG] mod_sofia.c:127 (sofia/internal/4498@10.10.10.41) State Change CS_INIT -> CS_ROUTING 2014-02-26 17:56:33.442875 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/4498@10.10.10.41) State INIT going to sleep 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_ROUTING 2014-02-26 17:56:33.442875 [DEBUG] switch_channel.c:2111 (sofia/internal/4498@10.10.10.41) Callstate Change DOWN -> RINGING 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/4498@10.10.10.41) State ROUTING 2014-02-26 17:56:33.442875 [DEBUG] mod_sofia.c:150 sofia/internal/4498@10.10.10.41 SOFIA ROUTING 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:117 sofia/internal/4498@10.10.10.41 Standard ROUTING 2014-02-26 17:56:33.442875 [INFO] mod_dialplan_xml.c:558 Processing 4498 <4498>->200 in context default Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->unloop] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->call_debug] continue=true Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->ringback_options] continue=true Dialplan: sofia/internal/4498@10.10.10.41 Absolute Condition [ringback_options] Dialplan: sofia/internal/4498@10.10.10.41 Action set(ringback=${ru-ring}) Dialplan: sofia/internal/4498@10.10.10.41 Action set(transfer_ringback=D:/ExpertSolutions/freeswitch/sounds/music/8000/es-on-hold.wav) Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-extensions] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-extensions] destination_number(200) =~ /^([1]\d{2})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-queues] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-queues] destination_number(200) =~ /^(8\d{2})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-city] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-city] destination_number(200) =~ /^9(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->moscow] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [moscow] destination_number(200) =~ /^01(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->Nortel-moscow] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [Nortel-moscow] destination_number(200) =~ /^00(1\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->kaluga-tsiolkovsky] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [kaluga-tsiolkovsky] destination_number(200) =~ /^02(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->from_mediant] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [from_mediant] destination_number(200) =~ /^019$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->commercial] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [commercial] destination_number(200) =~ /^014$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->secretary] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [secretary] destination_number(200) =~ /^020$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->es-helpdesk] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [es-helpdesk] destination_number(200) =~ /^016$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->VoiceMail_Extensiontest] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (PASS) [VoiceMail_Extensiontest] destination_number(200) =~ /^(200)$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 Action export(dialed_extension=200) Dialplan: sofia/internal/4498@10.10.10.41 Action set(ringback=${us-ring}) Dialplan: sofia/internal/4498@10.10.10.41 Action set(call_timeout=35) Dialplan: sofia/internal/4498@10.10.10.41 Action set(continue_on_fail=true) Dialplan: sofia/internal/4498@10.10.10.41 Action answer() Dialplan: sofia/internal/4498@10.10.10.41 Action sleep(1000) Dialplan: sofia/internal/4498@10.10.10.41 Action bridge(loopback/app=voicemail:default ${domain_name} 180) 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/4498@10.10.10.41) State Change CS_ROUTING -> CS_EXECUTE 2014-02-26 17:56:33.442875 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/4498@10.10.10.41) State ROUTING going to sleep 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_EXECUTE 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/4498@10.10.10.41) State EXECUTE 2014-02-26 17:56:33.442875 [DEBUG] mod_sofia.c:243 sofia/internal/4498@10.10.10.41 SOFIA EXECUTE 2014-02-26 17:56:33.442875 [DEBUG] switch_core_state_machine.c:209 sofia/internal/4498@10.10.10.41 Standard EXECUTE EXECUTE sofia/internal/4498@10.10.10.41 set(ringback=%(800,3200,425)) 2014-02-26 17:56:33.442875 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [ringback]=[%(800,3200,425)] EXECUTE sofia/internal/4498@10.10.10.41 set(transfer_ringback=D:/ExpertSolutions/freeswitch/sounds/music/8000/es-on-hold.wav) 2014-02-26 17:56:33.442875 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [transfer_ringback]=[D:/ExpertSolutions/freeswitch /sounds/music/8000/es-on-hold.wav] EXECUTE sofia/internal/4498@10.10.10.41 export(dialed_extension=200) 2014-02-26 17:56:33.442875 [DEBUG] switch_channel.c:1222 EXPORT (export_vars) [dialed_extension]=[200] EXECUTE sofia/internal/4498@10.10.10.41 set(ringback=%(2000,4000,440,480)) 2014-02-26 17:56:33.442875 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [ringback]=[%(2000,4000,440,480)] EXECUTE sofia/internal/4498@10.10.10.41 set(call_timeout=35) 2014-02-26 17:56:33.442875 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [call_timeout]=[35] EXECUTE sofia/internal/4498@10.10.10.41 set(continue_on_fail=true) 2014-02-26 17:56:33.442875 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [continue_on_fail]=[true] EXECUTE sofia/internal/4498@10.10.10.41 answer() 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:5181 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:3124 Set Codec sofia/internal/4498@10.10.10.41 PCMU/8000 20 ms 160 samples 64000 bits 2014-02-26 17:56:33.458500 [DEBUG] switch_core_codec.c:111 sofia/internal/4498@10.10.10.41 Original read codec set to PCMU:0 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:5312 Set 2833 dtmf send/recv payload to 101 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:3383 AUDIO RTP [sofia/internal/4498@10.10.10.41] 192.168.12.22 port 22264 -> 10.10.10.41 por t 6910 codec: 0 ms: 20 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:1985 Starting timer [soft] 160 bytes per 20ms 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: START SESSION INITIALIZATION. sID=75. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: ZID=306130613638376430393363. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Loading User's profile: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: allowclear: OFF 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: autosecure: ON 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: disclose_bit: OFF 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: signal. role: Unknown 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: TTL: 4294967295 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: SAS schemes: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 B256 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 B32 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Ciphers: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 AES3 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 AES1 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: PK schemes: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 EC25 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 DH3k 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 DH2k 2014-02-26 17:56:33.45850 0 [DEBUG] switch_rtp.c:928 Mult 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: ATL: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 HS32 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Hashes: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 S256 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Session initialization - DONE. sID=75. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: ATTACH NEW STREAM to sID=75: 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <ACTIVE>. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Empty slot was found - initializing new stream with ID=75. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: Preparing ZRTP Hello according to the Session profile. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp main]: ATTACH NEW STREAM - DONE. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp engine]: START STREAM ID=75 mode=CLEAR state=ACTIVE. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp]: Stream ID=75 CLEAR switching <ACTIVE> ---> <START>. 2014-02-26 17:56:33.458500 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48322 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:3647 Set 2833 dtmf send payload to 101 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:3653 Set 2833 dtmf receive payload to 101 2014-02-26 17:56:33.458500 [DEBUG] sofia_glue.c:3680 sofia/internal/4498@10.10.10.41 Set rtp dtmf delay to 40 2014-02-26 17:56:33.458500 [NOTICE] sofia_glue.c:4291 Pre-Answer sofia/internal/4498@10.10.10.41! 2014-02-26 17:56:33.458500 [DEBUG] switch_channel.c:3299 (sofia/internal/4498@10.10.10.41) Callstate Change RINGING -> EARLY 2014-02-26 17:56:33.458500 [DEBUG] mod_sofia.c:864 Local SDP sofia/internal/4498@10.10.10.41: v=0 o=FreeSWITCH 1393400729 1393400730 IN IP4 192.168.12.22 s=FreeSWITCH c=IN IP4 192.168.12.22 t=0 0 m=audio 22264 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2014-02-26 17:56:33.458500 [DEBUG] switch_core_session.c:854 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.458500 [NOTICE] mod_dptools.c:1205 Channel [sofia/internal/4498@10.10.10.41] has been answered 2014-02-26 17:56:33.458500 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.458500 [DEBUG] switch_channel.c:3576 (sofia/internal/4498@10.10.10.41) Callstate Change EARLY -> ACTIVE 2014-02-26 17:56:33.458500 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [completed][200] EXECUTE sofia/internal/4498@10.10.10.41 sleep(1000) 2014-02-26 17:56:33.489750 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.489750 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.489750 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:33.505375 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [ready][200] 2014-02-26 17:56:33.505375 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48323 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:33.521000 [DEBUG] switch_rtp.c:3706 Correct ip/port confirmed. 2014-02-26 17:56:33.630375 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48324 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:33.833500 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48325 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:34.052250 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48326 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:34.255375 [DEBUG] switch_rtp.c:928 [ zrtp engine]: WARNING! HELLO have been resent 5 times without a response. Raising ZRT P_EVENT_NO_ZRTP_QUICK event. ID=75 2014-02-26 17:56:34.255375 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48327 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:34.458500 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48328 size=144. Stream 75:CLEAR:STA RT EXECUTE sofia/internal/4498@10.10.10.41 bridge(loopback/app=voicemail:default 192.168.12.22 180) 2014-02-26 17:56:34.474125 [DEBUG] switch_channel.c:1176 sofia/internal/4498@10.10.10.41 EXPORTING[export_vars] [dialed_extension]=[200] to event 2014-02-26 17:56:34.474125 [DEBUG] switch_ivr_originate.c:2050 Parsing global variables 2014-02-26 17:56:34.474125 [NOTICE] switch_channel.c:1030 New Channel loopback/app=voicemail:default 192.168.12.22 180-a [fef1cacd-513e-4521 -9d9c-860d7b9446b8] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:157 loopback/app=voicemail:default 192.168.12.22 180-a setup codec PCMU/8000/20 2014-02-26 17:56:34.474125 [NOTICE] switch_channel.c:1028 Rename Channel loopback/app=voicemail:default 192.168.12.22 180-a->loopback/voicem ail-a [fef1cacd-513e-4521-9d9c-860d7b9446b8] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:1161 (loopback/voicemail-a) State Change CS_NEW -> CS_INIT 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_INIT 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-a) State INIT 2014-02-26 17:56:34.474125 [NOTICE] switch_channel.c:1030 New Channel loopback/voicemail-b [d22cef04-8d45-4be4-a18c-f1fe3b653f66] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:157 loopback/voicemail-b setup codec PCMU/8000/20 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:272 (loopback/voicemail-b) State Change CS_NEW -> CS_INIT 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:357 (loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-a) State INIT going to sleep 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_INIT 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-b) State INIT 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:357 (loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-b) State INIT going to sleep 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_channel.c:2111 (loopback/voicemail-b) Callstate Change DOWN -> RINGING 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-a) State ROUTING 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:389 loopback/voicemail-a CHANNEL ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_ivr_originate.c:67 (loopback/voicemail-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-b) State ROUTING 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-a) State ROUTING going to sleep 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:389 loopback/voicemail-b CHANNEL ROUTING 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:408 (loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-b) State ROUTING going to sleep 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_EXECUTE 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:489 (loopback/voicemail-a) State CONSUME_MEDIA 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:655 CHANNEL CONSUME_MEDIA 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:489 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:477 (loopback/voicemail-b) State EXECUTE 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:431 loopback/voicemail-b CHANNEL EXECUTE 2014-02-26 17:56:34.474125 [DEBUG] switch_core_state_machine.c:209 loopback/voicemail-b Standard EXECUTE EXECUTE loopback/voicemail-b pre_answer() 2014-02-26 17:56:34.474125 [NOTICE] mod_loopback.c:948 Pre-Answer loopback/voicemail-a! 2014-02-26 17:56:34.474125 [DEBUG] switch_channel.c:3295 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] switch_channel.c:3299 (loopback/voicemail-a) Callstate Change DOWN -> EARLY 2014-02-26 17:56:34.474125 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:34.474125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:34.474125 [NOTICE] mod_dptools.c:1240 Pre-Answer loopback/voicemail-b! 2014-02-26 17:56:34.474125 [DEBUG] switch_channel.c:3299 (loopback/voicemail-b) Callstate Change RINGING -> EARLY EXECUTE loopback/voicemail-b voicemail(default 192.168.12.22 180) 2014-02-26 17:56:34.489750 [DEBUG] switch_ivr_originate.c:3484 Originate Resulted in Success: [loopback/voicemail-a] 2014-02-26 17:56:34.489750 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:34.489750 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:34.489750 [DEBUG] switch_core_session.c:854 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:34.489750 [DEBUG] switch_ivr_bridge.c:1421 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2014-02-26 17:56:34.489750 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:34.489750 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:34.489750 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA 2014-02-26 17:56:34.489750 [DEBUG] switch_core_state_machine.c:480 (loopback/voicemail-a) State EXCHANGE_MEDIA 2014-02-26 17:56:34.489750 [DEBUG] mod_loopback.c:617 CHANNEL LOOPBACK 2014-02-26 17:56:34.583500 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en] 2014-02-26 17:56:34.599125 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en] 2014-02-26 17:56:34.661625 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48329 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:34.864750 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48330 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:35.067875 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48331 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:35.271000 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48332 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:35.474125 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48333 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:35.646000 [DEBUG] switch_channel.c:3530 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:35.646000 [NOTICE] mod_loopback.c:943 Channel [loopback/voicemail-a] has been answered 2014-02-26 17:56:35.646000 [DEBUG] switch_channel.c:3576 (loopback/voicemail-a) Callstate Change EARLY -> ACTIVE 2014-02-26 17:56:35.646000 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:35.646000 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:35.646000 [NOTICE] switch_ivr_play_say.c:409 Channel [loopback/voicemail-b] has been answered 2014-02-26 17:56:35.646000 [DEBUG] switch_channel.c:3576 (loopback/voicemail-b) Callstate Change EARLY -> ACTIVE 2014-02-26 17:56:35.646000 [DEBUG] switch_ivr_play_say.c:599 Raw Codec Activated 2014-02-26 17:56:35.646000 [DEBUG] switch_core_codec.c:219 loopback/voicemail-b Push codec L16:70 2014-02-26 17:56:35.661625 [DEBUG] switch_core_session.c:916 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:35.661625 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:35.661625 [DEBUG] switch_core_session.c:916 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:35.692875 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48334 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:35.896000 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48335 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:36.099125 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48336 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:36.302250 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48337 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:36.505375 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48338 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:36.708500 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48339 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:36.911625 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48340 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:37.130375 [DEBUG] switch_rtp.c:928 [ zrtp utils]: Send <HELLO> ssrc=1441964721 seq=48341 size=144. Stream 75:CLEAR:STA RT 2014-02-26 17:56:37.333500 [DEBUG] switch_rtp.c:928 [ zrtp engine]: WARNING! HELLO Max retransmissions count reached (20 retries). ID=75 2014-02-26 17:56:37.333500 [DEBUG] switch_rtp.c:928 [ zrtp]: Stream ID=75 CLEAR switching <START> ---> <NOZRTP>. freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.692875 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10. 10.41 [BREAK] 2014-02-26 17:56:45.708500 [NOTICE] sofia.c:716 Hangup sofia/internal/4498@10.10.10.41 [CS_EXECUTE] [NORMAL_UNSPECIFIED] 2014-02-26 17:56:45.708500 [DEBUG] switch_channel.c:3130 Send signal sofia/internal/4498@10.10.10.41 [KILL] 2014-02-26 17:56:45.708500 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:45.708500 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [sofia/internal/4498@10.10.10.41] 2014-02-26 17:56:45.708500 [DEBUG] switch_ivr_bridge.c:658 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:45.708500 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [loopback/voicemail-a] 2014-02-26 17:56:45.724125 [DEBUG] switch_ivr_bridge.c:658 Send signal sofia/internal/4498@10.10.10.41 [BREAK] freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [NOTICE] switch_ivr_bridge.c:721 Hangup loopback/voicemail-a [CS_EXCHANG E_MEDIA] [NORMAL_CLEARING] 2014-02-26 17:56:45.724125 [DEBUG] switch_channel.c:3130 Send signal loopback/voicemail-a [KILL] 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:480 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_HANGUP 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-a) State HANGUP 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:542 loopback/voicemail-a CHANNEL HANGUP freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [NOTICE] mod_loopback.c:558 Hangup loopback/voicemail-b [CS_EXECUTE] [NO RMAL_CLEARING] 2014-02-26 17:56:45.724125 [DEBUG] switch_channel.c:3130 Send signal loopback/voicemail-b [KILL] 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_ivr_bridge.c:1519 loopback/voicemail-a skip receive message [UNBRIDGE] (channel is hungup already) 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-a Standard HANGUP, cause: NORMAL_CLEARING freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-a) State HANGUP going to sleep 2014-02-26 17:56:45.724125 [DEBUG] switch_ivr_bridge.c:1522 sofia/internal/4498@10.10.10.41 skip receive message [UNBRIDGE] (channel is hung up already) 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:689 (loopback/voicemail-a) Callstate Change ACTIVE -> HANGUP 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:446 (loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:2732 sofia/internal/4498@10.10.10.41 skip receive message [APPLICATION_EXEC_COMPLET E] (channel is hungup already) 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/4498@10.10.10.41) State EXECUTE going to sleep 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_HANGUP 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-a) State REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-a Standard REPORTING, cause: NORMAL_CLEARING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-a) State REPORTING going to sleep 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/4498@10.10.10.41) State HANGUP 2014-02-26 17:56:45.724125 [DEBUG] mod_sofia.c:504 Channel sofia/internal/4498@10.10.10.41 hanging up, cause: NORMAL_UNSPECIFIED 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:440 (loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-26 17:56:45.724125 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1542 Session 162 (loopback/voicemail-a) Locked, Waiting on external entities 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:48 sofia/internal/4498@10.10.10.41 Standard HANGUP, cause: NORMAL_UNSPECIFIED 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/4498@10.10.10.41) State HANGUP going to sleep 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:689 (sofia/internal/4498@10.10.10.41) Callstate Change ACTIVE -> HANGUP freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/4498@10.10.10.41) State Change CS_HANGUP -> CS_REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:761 (sofia/internal/4498@10.10.10.41) State REPORTING 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:92 sofia/internal/4498@10.10.10.41 Standard REPORTING, cause: NORMAL_UNSPECIF IED 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:761 (sofia/internal/4498@10.10.10.41) State REPORTING going to sleep freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/4498@10.10.10.41) State Change CS_REPORTING -> CS_DESTROY 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-26 17:56:45.724125 [DEBUG] switch_core_session.c:1542 Session 161 (sofia/internal/4498@10.10.10.41) Locked, Waiting on external enti ties 2014-02-26 17:56:45.724125 [NOTICE] switch_core_session.c:1560 Session 161 (sofia/internal/4498@10.10.10.41) Ended 2014-02-26 17:56:45.724125 [NOTICE] switch_core_session.c:1564 Close Channel sofia/internal/4498@10.10.10.41 [CS_DESTROY] freeswitch@192.168.12.22:18021@internal> 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/4498@10.10.10.41 ) Callstate Change HANGUP -> DOWN 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/4498@10.10.10.41) Running State Change CS_DESTROY 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/4498@10.10.10.41) State DESTROY 2014-02-26 17:56:45.724125 [DEBUG] mod_sofia.c:397 sofia/internal/4498@10.10.10.41 SOFIA DESTROY 2014-02-26 17:56:45.724125 [DEBUG] switch_rtp.c:928 [ zrtp engine]: STOP STREAM ID=75 mode=CLEAR state=NOZRTP. 2014-02-26 17:56:45.724125 [DEBUG] switch_rtp.c:928 [ zrtp]: Stream ID=0 UNKNOWN switching <NONE> ---> <NONE>. 2014-02-26 17:56:45.724125 [DEBUG] switch_rtp.c:928 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2014-02-26 17:56:45.724125 [DEBUG] switch_rtp.c:928 [ zrtp engine]: STOP STREAM ID=0 mode=UNKNOWN state=NONE. 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:99 sofia/internal/4498@10.10.10.41 Standard DESTROY 2014-02-26 17:56:45.724125 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/4498@10.10.10.41) State DESTROY going to sleep 2014-02-26 17:56:45.739750 [DEBUG] switch_core_codec.c:244 loopback/voicemail-b Restore previous codec PCMU:0. 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:2750 Deliver VM to 180@192.168.12.22 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:1865 Update MWI: Processing for 180@192.168.12.22 in inbox 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:1888 Update MWI: Messages Waiting yes 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:1889 Update MWI: Update Reason NEW 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:1890 Update MWI: Message Account 180@192.168.12.22 2014-02-26 17:56:45.739750 [DEBUG] mod_voicemail.c:1891 Update MWI: Voice Message 5/0 2014-02-26 17:56:45.739750 [DEBUG] switch_core_session.c:2732 loopback/voicemail-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:477 (loopback/voicemail-b) State EXECUTE going to sleep 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_HANGUP 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-b) State HANGUP 2014-02-26 17:56:45.739750 [DEBUG] mod_loopback.c:542 loopback/voicemail-b CHANNEL HANGUP 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-b Standard HANGUP, cause: NORMAL_CLEARING 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-b) State HANGUP going to sleep 2014-02-26 17:56:45.739750 [DEBUG] switch_core_state_machine.c:689 (loopback/voicemail-b) Callstate Change ACTIVE -> HANGUP 2014-02-26 17:56:45.739750 [NOTICE] switch_core_session.c:1560 Session 162 (loopback/voicemail-a) Ended 2014-02-26 17:56:45.739750 [NOTICE] switch_core_session.c:1564 Close Channel loopback/voicemail-a [CS_DESTROY] 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:446 (loopback/voicemail-b) State Change CS_HANGUP -> CS_REPORTING 2014-02-26 17:56:45.755375 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:45.755375 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_REPORTING 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:565 (loopback/voicemail-a) Callstate Change HANGUP -> DOWN 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:568 (loopback/voicemail-a) Running State Change CS_DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-b) State REPORTING 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-a) State DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-a Standard DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-a) State DESTROY going to sleep 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-b Standard REPORTING, cause: NORMAL_CLEARING 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-b) State REPORTING going to sleep 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:440 (loopback/voicemail-b) State Change CS_REPORTING -> CS_DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-26 17:56:45.755375 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-26 17:56:45.755375 [DEBUG] switch_core_session.c:1542 Session 163 (loopback/voicemail-b) Locked, Waiting on external entities 2014-02-26 17:56:45.755375 [NOTICE] switch_core_session.c:1560 Session 163 (loopback/voicemail-b) Ended 2014-02-26 17:56:45.755375 [NOTICE] switch_core_session.c:1564 Close Channel loopback/voicemail-b [CS_DESTROY] 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:565 (loopback/voicemail-b) Callstate Change HANGUP -> DOWN 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:568 (loopback/voicemail-b) Running State Change CS_DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-b) State DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-b Standard DESTROY 2014-02-26 17:56:45.755375 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-b) State DESTROY going to sleep voicemail.conf Код: <configuration name="voicemail.conf" description="Voicemail"> <settings> </settings> <profiles> <profile name="default"> <param name="file-extension" value="wav"/> <param name="terminator-key" value="#"/> <param name="max-login-attempts" value="3"/> <param name="digit-timeout" value="10000"/> <param name="min-record-len" value="3"/> <param name="max-record-len" value="30"/> <param name="max-retries" value="1"/> <param name="tone-spec" value="%(1000, 0, 640)"/> <param name="callback-dialplan" value="XML"/> <param name="callback-context" value="default"/> <param name="play-new-messages-key" value="1"/> <param name="play-saved-messages-key" value="2"/> <!-- play-new-messages-lifo and play-saved-messages-lifo default is false, playing oldest messages first <param name="play-new-messages-lifo" value="false"/> <param name="play-saved-messages-lifo" value="false"/> --> <param name="login-keys" value="0"/> <param name="main-menu-key" value="0"/> <param name="config-menu-key" value="5"/> <param name="record-greeting-key" value="1"/> <param name="choose-greeting-key" value="2"/> <param name="change-pass-key" value="6"/> <param name="record-name-key" value="3"/> <param name="record-file-key" value="3"/> <param name="listen-file-key" value="1"/> <param name="save-file-key" value="2"/> <param name="delete-file-key" value="7"/> <param name="undelete-file-key" value="8"/> <param name="email-key" value="4"/> <param name="pause-key" value="0"/> <param name="restart-key" value="1"/> <param name="ff-key" value="6"/> <param name="rew-key" value="4"/> <param name="skip-greet-key" value="#"/> <param name="previous-message-key" value="1"/> <param name="next-message-key" value="3"/> <param name="skip-info-key" value="*"/> <param name="repeat-message-key" value="0"/> <param name="record-silence-threshold" value="0"/> <param name="record-silence-hits" value="2"/> <param name="web-template-file" value="web-vm.tpl"/> <param name="db-password-override" value="false"/> <param name="allow-empty-password-auth" value="true"/> <!-- if you need to change the sample rate of the recorded files e.g. gmail voicemail player --> <!--<param name="record-sample-rate" value="11025"/>--> <!-- the next two both must be set for this to be enabled the extension is in the format of <dest> [<dialplan>] [<context>] --> <param name="operator-extension" value="operator XML default"/> <param name="operator-key" value="9"/> <param name="vmain-extension" value="vmain XML default"/> <param name="vmain-key" value="*"/> <!-- playback created files as soon as they were recorded by default --> <!--<param name="auto-playback-recordings" value="true"/>--> <email> <param name="template-file" value="voicemail.tpl"/> <param name="notify-template-file" value="notify-voicemail.tpl"/> <!-- this is the format voicemail_time will have --> <param name="date-fmt" value="%A, %B %d %Y, %I %M %p"/> <param name="email-from" value="${voicemail_account}@${voicemail_domain}"/> </email> <!--<param name="storage-dir" value="/tmp"/>--> <!--<param name="odbc-dsn" value="dsn:user:pass"/>--> <!--<param name="record-comment" value="Your Comment"/>--> <!--<param name="record-title" value="Your Title"/>--> <!--<param name="record-copyright" value="Your Copyright"/>--> </profile> </profiles> </configuration> UPD в логах wireshark вижу что отбой идет от медианта |
Автор: | Vlad1983 [ 26 фев 2014 20:26 ] |
Заголовок сообщения: | Re: авторизация voicemail |
zrtp реально используется при звонках с медианта? |
Автор: | oper [ 27 фев 2014 12:49 ] |
Заголовок сообщения: | Re: авторизация voicemail |
Влад, zrtp не используется, отключил его совсем. В логах по прежнему медиант рвет сессию если звонить на голосовую почту(через 10 сек), но не рвет если звонить на экстеншен. Код: cause: NORMAL_CLEARING Код: freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> 2014-02-27 12:07:34.646000 [NOTICE] switch_channel.c:1030 New Channel sofia/internal/4498@10.10.10. 41 [16be0021-d335-43a7-b85f-6a7811494cb5] 2014-02-27 12:07:34.646000 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.646000 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.646000 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_NEW 2014-02-27 12:07:34.646000 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/4498@10.10.10.41) State NEW 2014-02-27 12:07:34.661625 [DEBUG] sofia.c:7818 IP 10.10.10.41 Approved by acl "domains[]". Access Granted. 2014-02-27 12:07:34.661625 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [received][100] 2014-02-27 12:07:34.661625 [DEBUG] sofia.c:5719 Remote SDP: v=0 o=AudiocodesGW 69570169 69569845 IN IP4 10.10.10.41 s=Phone-Call c=IN IP4 10.10.10.41 t=0 0 m=audio 6910 RTP/AVP 0 8 4 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:4 G723/8000 a=fmtp:4 annexa=no a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=rtcp:6911 IN IP4 10.10.10.41 2014-02-27 12:07:34.661625 [DEBUG] sofia.c:5933 (sofia/internal/4498@10.10.10.41) State Change CS_NEW -> CS_INIT 2014-02-27 12:07:34.661625 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.661625 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_INIT 2014-02-27 12:07:34.661625 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/4498@10.10.10.41) State INIT 2014-02-27 12:07:34.661625 [DEBUG] mod_sofia.c:87 sofia/internal/4498@10.10.10.41 SOFIA INIT 2014-02-27 12:07:34.661625 [DEBUG] mod_sofia.c:127 (sofia/internal/4498@10.10.10.41) State Change CS_INIT -> CS_ROUTING 2014-02-27 12:07:34.661625 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.661625 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/4498@10.10.10.41) State INIT going to sleep 2014-02-27 12:07:34.661625 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_ROUTING 2014-02-27 12:07:34.661625 [DEBUG] switch_channel.c:2111 (sofia/internal/4498@10.10.10.41) Callstate Change DOWN -> RINGING 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/4498@10.10.10.41) State ROUTING 2014-02-27 12:07:34.677250 [DEBUG] mod_sofia.c:150 sofia/internal/4498@10.10.10.41 SOFIA ROUTING 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:117 sofia/internal/4498@10.10.10.41 Standard ROUTING 2014-02-27 12:07:34.677250 [INFO] mod_dialplan_xml.c:558 Processing 4498 <4498>->200 in context default Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->unloop] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->call_debug] continue=true Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->ringback_options] continue=true Dialplan: sofia/internal/4498@10.10.10.41 Absolute Condition [ringback_options] Dialplan: sofia/internal/4498@10.10.10.41 Action set(ringback=${ru-ring}) Dialplan: sofia/internal/4498@10.10.10.41 Action set(transfer_ringback=D:/ExpertSolutions/freeswitch/sounds/music/8000/es-on-hold.wav) Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-extensions] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-extensions] destination_number(200) =~ /^([1]\d{2})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-queues] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-queues] destination_number(200) =~ /^(8\d{2})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->local-city] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [local-city] destination_number(200) =~ /^9(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->moscow] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [moscow] destination_number(200) =~ /^01(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->Nortel-moscow] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [Nortel-moscow] destination_number(200) =~ /^00(1\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->kaluga-tsiolkovsky] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [kaluga-tsiolkovsky] destination_number(200) =~ /^02(\d{2,})$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->from_mediant] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [from_mediant] destination_number(200) =~ /^019$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->commercial] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [commercial] destination_number(200) =~ /^014$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->secretary] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [secretary] destination_number(200) =~ /^020$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->es-helpdesk] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (FAIL) [es-helpdesk] destination_number(200) =~ /^016$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 parsing [default->VoiceMail_Extensiontest] continue=false Dialplan: sofia/internal/4498@10.10.10.41 Regex (PASS) [VoiceMail_Extensiontest] destination_number(200) =~ /^(200)$/ break=on-false Dialplan: sofia/internal/4498@10.10.10.41 Action export(dialed_extension=200) Dialplan: sofia/internal/4498@10.10.10.41 Action set(ringback=${us-ring}) Dialplan: sofia/internal/4498@10.10.10.41 Action set(call_timeout=35) Dialplan: sofia/internal/4498@10.10.10.41 Action set(continue_on_fail=true) Dialplan: sofia/internal/4498@10.10.10.41 Action answer() Dialplan: sofia/internal/4498@10.10.10.41 Action sleep(1000) Dialplan: sofia/internal/4498@10.10.10.41 Action bridge(loopback/app=voicemail:default ${domain_name} 180) 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/4498@10.10.10.41) State Change CS_ROUTING -> CS_EXECUTE 2014-02-27 12:07:34.677250 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/4498@10.10.10.41) State ROUTING going to sleep 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_EXECUTE 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/4498@10.10.10.41) State EXECUTE 2014-02-27 12:07:34.677250 [DEBUG] mod_sofia.c:243 sofia/internal/4498@10.10.10.41 SOFIA EXECUTE 2014-02-27 12:07:34.677250 [DEBUG] switch_core_state_machine.c:209 sofia/internal/4498@10.10.10.41 Standard EXECUTE EXECUTE sofia/internal/4498@10.10.10.41 set(ringback=%(800,3200,425)) 2014-02-27 12:07:34.677250 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [ringback]=[%(800,3200,425)] EXECUTE sofia/internal/4498@10.10.10.41 set(transfer_ringback=D:/ExpertSolutions/freeswitch/sounds/music/8000/es-on-hold.wav) 2014-02-27 12:07:34.677250 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [transfer_ringback]=[D:/ExpertSolutions/freeswitch /sounds/music/8000/es-on-hold.wav] EXECUTE sofia/internal/4498@10.10.10.41 export(dialed_extension=200) 2014-02-27 12:07:34.677250 [DEBUG] switch_channel.c:1222 EXPORT (export_vars) [dialed_extension]=[200] EXECUTE sofia/internal/4498@10.10.10.41 set(ringback=%(2000,4000,440,480)) 2014-02-27 12:07:34.677250 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [ringback]=[%(2000,4000,440,480)] EXECUTE sofia/internal/4498@10.10.10.41 set(call_timeout=35) 2014-02-27 12:07:34.677250 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [call_timeout]=[35] EXECUTE sofia/internal/4498@10.10.10.41 set(continue_on_fail=true) 2014-02-27 12:07:34.677250 [DEBUG] mod_dptools.c:1373 sofia/internal/4498@10.10.10.41 SET [continue_on_fail]=[true] EXECUTE sofia/internal/4498@10.10.10.41 answer() 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:5181 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:3124 Set Codec sofia/internal/4498@10.10.10.41 PCMU/8000 20 ms 160 samples 64000 bits 2014-02-27 12:07:34.677250 [DEBUG] switch_core_codec.c:111 sofia/internal/4498@10.10.10.41 Original read codec set to PCMU:0 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:5312 Set 2833 dtmf send/recv payload to 101 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:3383 AUDIO RTP [sofia/internal/4498@10.10.10.41] 192.168.12.22 port 25774 -> 10.10.10.41 por t 6910 codec: 0 ms: 20 2014-02-27 12:07:34.677250 [DEBUG] switch_rtp.c:1985 Starting timer [soft] 160 bytes per 20ms 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:3647 Set 2833 dtmf send payload to 101 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:3653 Set 2833 dtmf receive payload to 101 2014-02-27 12:07:34.677250 [DEBUG] sofia_glue.c:3680 sofia/internal/4498@10.10.10.41 Set rtp dtmf delay to 40 2014-02-27 12:07:34.677250 [NOTICE] sofia_glue.c:4291 Pre-Answer sofia/internal/4498@10.10.10.41! 2014-02-27 12:07:34.692875 [DEBUG] switch_channel.c:3299 (sofia/internal/4498@10.10.10.41) Callstate Change RINGING -> EARLY 2014-02-27 12:07:34.692875 [DEBUG] mod_sofia.c:864 Local SDP sofia/internal/4498@10.10.10.41: v=0 o=FreeSWITCH 1393462680 1393462681 IN IP4 192.168.12.22 s=FreeSWITCH c=IN IP4 192.168.12.22 t=0 0 m=audio 25774 RTP/AVP 0 a=rtpmap:0 PCMU/8000 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2014-02-27 12:07:34.692875 [DEBUG] switch_core_session.c:854 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.692875 [NOTICE] mod_dptools.c:1205 Channel [sofia/internal/4498@10.10.10.41] has been answered 2014-02-27 12:07:34.692875 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.692875 [DEBUG] switch_channel.c:3576 (sofia/internal/4498@10.10.10.41) Callstate Change EARLY -> ACTIVE 2014-02-27 12:07:34.692875 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [completed][200] EXECUTE sofia/internal/4498@10.10.10.41 sleep(1000) 2014-02-27 12:07:34.708500 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.708500 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.708500 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:34.724125 [DEBUG] sofia.c:5708 Channel sofia/internal/4498@10.10.10.41 entering state [ready][200] 2014-02-27 12:07:34.771000 [DEBUG] switch_rtp.c:3706 Correct ip/port confirmed. EXECUTE sofia/internal/4498@10.10.10.41 bridge(loopback/app=voicemail:default 192.168.12.22 180) 2014-02-27 12:07:35.692875 [DEBUG] switch_channel.c:1176 sofia/internal/4498@10.10.10.41 EXPORTING[export_vars] [dialed_extension]=[200] to event 2014-02-27 12:07:35.692875 [DEBUG] switch_ivr_originate.c:2050 Parsing global variables 2014-02-27 12:07:35.692875 [NOTICE] switch_channel.c:1030 New Channel loopback/app=voicemail:default 192.168.12.22 180-a [db4b0e8b-9ad4-40d8 -a6e5-8f89e4089971] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:157 loopback/app=voicemail:default 192.168.12.22 180-a setup codec PCMU/8000/20 2014-02-27 12:07:35.692875 [NOTICE] switch_channel.c:1028 Rename Channel loopback/app=voicemail:default 192.168.12.22 180-a->loopback/voicem ail-a [db4b0e8b-9ad4-40d8-a6e5-8f89e4089971] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:1161 (loopback/voicemail-a) State Change CS_NEW -> CS_INIT 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_INIT 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-a) State INIT 2014-02-27 12:07:35.692875 [NOTICE] switch_channel.c:1030 New Channel loopback/voicemail-b [5a77ef90-4683-411a-8a9a-e6a65bd1a6e5] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:157 loopback/voicemail-b setup codec PCMU/8000/20 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:272 (loopback/voicemail-b) State Change CS_NEW -> CS_INIT 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:357 (loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-a) State INIT going to sleep 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_INIT 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-b) State INIT 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:357 (loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:454 (loopback/voicemail-b) State INIT going to sleep 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_channel.c:2111 (loopback/voicemail-b) Callstate Change DOWN -> RINGING 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-a) State ROUTING 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:389 loopback/voicemail-a CHANNEL ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_ivr_originate.c:67 (loopback/voicemail-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-a) State ROUTING going to sleep 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-b) State ROUTING 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:389 loopback/voicemail-b CHANNEL ROUTING 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:408 (loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:470 (loopback/voicemail-b) State ROUTING going to sleep 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_EXECUTE 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:489 (loopback/voicemail-a) State CONSUME_MEDIA 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:655 CHANNEL CONSUME_MEDIA 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:477 (loopback/voicemail-b) State EXECUTE 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:489 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:431 loopback/voicemail-b CHANNEL EXECUTE 2014-02-27 12:07:35.692875 [DEBUG] switch_core_state_machine.c:209 loopback/voicemail-b Standard EXECUTE EXECUTE loopback/voicemail-b pre_answer() 2014-02-27 12:07:35.692875 [NOTICE] mod_loopback.c:948 Pre-Answer loopback/voicemail-a! 2014-02-27 12:07:35.692875 [DEBUG] switch_channel.c:3295 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] switch_channel.c:3299 (loopback/voicemail-a) Callstate Change DOWN -> EARLY 2014-02-27 12:07:35.692875 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:35.692875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:35.692875 [NOTICE] mod_dptools.c:1240 Pre-Answer loopback/voicemail-b! 2014-02-27 12:07:35.692875 [DEBUG] switch_channel.c:3299 (loopback/voicemail-b) Callstate Change RINGING -> EARLY EXECUTE loopback/voicemail-b voicemail(default 192.168.12.22 180) 2014-02-27 12:07:35.708500 [DEBUG] switch_ivr_originate.c:3484 Originate Resulted in Success: [loopback/voicemail-a] 2014-02-27 12:07:35.708500 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:35.708500 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:35.708500 [DEBUG] switch_core_session.c:854 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:35.708500 [DEBUG] switch_ivr_bridge.c:1421 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2014-02-27 12:07:35.708500 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA 2014-02-27 12:07:35.708500 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:35.708500 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:35.708500 [DEBUG] switch_core_state_machine.c:480 (loopback/voicemail-a) State EXCHANGE_MEDIA 2014-02-27 12:07:35.708500 [DEBUG] mod_loopback.c:617 CHANNEL LOOPBACK 2014-02-27 12:07:35.802250 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en] 2014-02-27 12:07:35.802250 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en] 2014-02-27 12:07:36.849125 [DEBUG] switch_channel.c:3530 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:36.849125 [NOTICE] mod_loopback.c:943 Channel [loopback/voicemail-a] has been answered 2014-02-27 12:07:36.849125 [DEBUG] switch_channel.c:3576 (loopback/voicemail-a) Callstate Change EARLY -> ACTIVE 2014-02-27 12:07:36.849125 [DEBUG] switch_core_session.c:854 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:36.849125 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:36.849125 [NOTICE] switch_ivr_play_say.c:409 Channel [loopback/voicemail-b] has been answered 2014-02-27 12:07:36.849125 [DEBUG] switch_channel.c:3576 (loopback/voicemail-b) Callstate Change EARLY -> ACTIVE 2014-02-27 12:07:36.849125 [DEBUG] switch_ivr_play_say.c:599 Raw Codec Activated 2014-02-27 12:07:36.849125 [DEBUG] switch_core_codec.c:219 loopback/voicemail-b Push codec L16:70 2014-02-27 12:07:36.864750 [DEBUG] switch_core_session.c:916 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:36.864750 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:36.864750 [DEBUG] switch_core_session.c:916 Send signal sofia/internal/4498@10.10.10.41 [BREAK] freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> freeswitch@192.168.12.22:18021@internal> 2014-02-27 12:07:46.896000 [DEBUG] switch_core_session.c:999 Send signal sofia/internal/4498@10.10. 10.41 [BREAK] 2014-02-27 12:07:46.911625 [NOTICE] sofia.c:716 Hangup sofia/internal/4498@10.10.10.41 [CS_EXECUTE] [NORMAL_UNSPECIFIED] 2014-02-27 12:07:46.911625 [DEBUG] switch_channel.c:3130 Send signal sofia/internal/4498@10.10.10.41 [KILL] 2014-02-27 12:07:46.911625 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:46.911625 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [sofia/internal/4498@10.10.10.41] 2014-02-27 12:07:46.911625 [DEBUG] switch_ivr_bridge.c:658 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:46.911625 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_ivr_bridge.c:633 BRIDGE THREAD DONE [loopback/voicemail-a] 2014-02-27 12:07:46.927250 [DEBUG] switch_ivr_bridge.c:658 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:46.927250 [NOTICE] switch_ivr_bridge.c:721 Hangup loopback/voicemail-a [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2014-02-27 12:07:46.927250 [DEBUG] switch_channel.c:3130 Send signal loopback/voicemail-a [KILL] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:480 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_HANGUP 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-a) State HANGUP 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:542 loopback/voicemail-a CHANNEL HANGUP 2014-02-27 12:07:46.927250 [NOTICE] mod_loopback.c:558 Hangup loopback/voicemail-b [CS_EXECUTE] [NORMAL_CLEARING] 2014-02-27 12:07:46.927250 [DEBUG] switch_channel.c:3130 Send signal loopback/voicemail-b [KILL] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-a Standard HANGUP, cause: NORMAL_CLEARING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-a) State HANGUP going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:689 (loopback/voicemail-a) Callstate Change ACTIVE -> HANGUP 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:446 (loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-a) Running State Change CS_REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-a) State REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-a Standard REPORTING, cause: NORMAL_CLEARING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-a) State REPORTING going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:440 (loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-a [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] mod_loopback.c:595 loopback/voicemail-a CHANNEL KILL 2014-02-27 12:07:46.927250 [DEBUG] switch_ivr_bridge.c:1522 sofia/internal/4498@10.10.10.41 skip receive message [UNBRIDGE] (channel is hung up already) 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1542 Session 40 (loopback/voicemail-a) Locked, Waiting on external entities 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:2732 sofia/internal/4498@10.10.10.41 skip receive message [APPLICATION_EXEC_COMPLET E] (channel is hungup already) 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/4498@10.10.10.41) State EXECUTE going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_HANGUP 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/4498@10.10.10.41) State HANGUP 2014-02-27 12:07:46.927250 [DEBUG] mod_sofia.c:504 Channel sofia/internal/4498@10.10.10.41 hanging up, cause: NORMAL_UNSPECIFIED 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:48 sofia/internal/4498@10.10.10.41 Standard HANGUP, cause: NORMAL_UNSPECIFIED 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:678 (sofia/internal/4498@10.10.10.41) State HANGUP going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:689 (sofia/internal/4498@10.10.10.41) Callstate Change ACTIVE -> HANGUP 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/4498@10.10.10.41) State Change CS_HANGUP -> CS_REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/4498@10.10.10.41) Running State Change CS_REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:761 (sofia/internal/4498@10.10.10.41) State REPORTING 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:92 sofia/internal/4498@10.10.10.41 Standard REPORTING, cause: NORMAL_UNSPECIF IED 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:761 (sofia/internal/4498@10.10.10.41) State REPORTING going to sleep 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/4498@10.10.10.41) State Change CS_REPORTING -> CS_DESTROY 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1334 Send signal sofia/internal/4498@10.10.10.41 [BREAK] 2014-02-27 12:07:46.927250 [DEBUG] switch_core_session.c:1542 Session 39 (sofia/internal/4498@10.10.10.41) Locked, Waiting on external entit ies 2014-02-27 12:07:46.927250 [NOTICE] switch_core_session.c:1560 Session 39 (sofia/internal/4498@10.10.10.41) Ended 2014-02-27 12:07:46.927250 [NOTICE] switch_core_session.c:1564 Close Channel sofia/internal/4498@10.10.10.41 [CS_DESTROY] 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:565 (sofia/internal/4498@10.10.10.41) Callstate Change HANGUP -> DOWN 2014-02-27 12:07:46.927250 [DEBUG] switch_core_state_machine.c:568 (sofia/internal/4498@10.10.10.41) Running State Change CS_DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/4498@10.10.10.41) State DESTROY 2014-02-27 12:07:46.942875 [DEBUG] mod_sofia.c:397 sofia/internal/4498@10.10.10.41 SOFIA DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:99 sofia/internal/4498@10.10.10.41 Standard DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/4498@10.10.10.41) State DESTROY going to sleep 2014-02-27 12:07:46.942875 [DEBUG] switch_core_codec.c:244 loopback/voicemail-b Restore previous codec PCMU:0. 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:2750 Deliver VM to 180@192.168.12.22 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:1865 Update MWI: Processing for 180@192.168.12.22 in inbox 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:1888 Update MWI: Messages Waiting yes 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:1889 Update MWI: Update Reason NEW 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:1890 Update MWI: Message Account 180@192.168.12.22 2014-02-27 12:07:46.942875 [DEBUG] mod_voicemail.c:1891 Update MWI: Voice Message 8/0 2014-02-27 12:07:46.942875 [DEBUG] switch_core_session.c:2732 loopback/voicemail-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:477 (loopback/voicemail-b) State EXECUTE going to sleep 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_HANGUP 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-b) State HANGUP 2014-02-27 12:07:46.942875 [DEBUG] mod_loopback.c:542 loopback/voicemail-b CHANNEL HANGUP 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:48 loopback/voicemail-b Standard HANGUP, cause: NORMAL_CLEARING 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:678 (loopback/voicemail-b) State HANGUP going to sleep 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:689 (loopback/voicemail-b) Callstate Change ACTIVE -> HANGUP 2014-02-27 12:07:46.942875 [NOTICE] switch_core_session.c:1560 Session 40 (loopback/voicemail-a) Ended 2014-02-27 12:07:46.942875 [NOTICE] switch_core_session.c:1564 Close Channel loopback/voicemail-a [CS_DESTROY] 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:446 (loopback/voicemail-b) State Change CS_HANGUP -> CS_REPORTING 2014-02-27 12:07:46.942875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:46.942875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:415 (loopback/voicemail-b) Running State Change CS_REPORTING 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-b) State REPORTING 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:565 (loopback/voicemail-a) Callstate Change HANGUP -> DOWN 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:92 loopback/voicemail-b Standard REPORTING, cause: NORMAL_CLEARING 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:761 (loopback/voicemail-b) State REPORTING going to sleep 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:568 (loopback/voicemail-a) Running State Change CS_DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:440 (loopback/voicemail-b) State Change CS_REPORTING -> CS_DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_session.c:1334 Send signal loopback/voicemail-b [BREAK] 2014-02-27 12:07:46.942875 [DEBUG] mod_loopback.c:595 loopback/voicemail-b CHANNEL KILL 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-a) State DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_session.c:1542 Session 41 (loopback/voicemail-b) Locked, Waiting on external entities 2014-02-27 12:07:46.942875 [NOTICE] switch_core_session.c:1560 Session 41 (loopback/voicemail-b) Ended 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-a Standard DESTROY 2014-02-27 12:07:46.942875 [NOTICE] switch_core_session.c:1564 Close Channel loopback/voicemail-b [CS_DESTROY] 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-a) State DESTROY going to sleep 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:565 (loopback/voicemail-b) Callstate Change HANGUP -> DOWN 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:568 (loopback/voicemail-b) Running State Change CS_DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-b) State DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:99 loopback/voicemail-b Standard DESTROY 2014-02-27 12:07:46.942875 [DEBUG] switch_core_state_machine.c:578 (loopback/voicemail-b) State DESTROY going to sleep freeswitch@192.168.12.22:18021@internal> |
Автор: | Vlad1983 [ 27 фев 2014 14:08 ] |
Заголовок сообщения: | Re: авторизация voicemail |
если <action application="bridge" data="loopback/app=voicemail:default ${domain_name} 180"/> заменить на <action application="voicemail" data="default ${domain_name} 180" /> ? |
Автор: | oper [ 27 фев 2014 14:19 ] |
Заголовок сообщения: | Re: авторизация voicemail |
так пробовал, ситуация такая же, в итоге оставил как в вики. Поковыряю медиант пока |
Автор: | Vlad1983 [ 27 фев 2014 14:59 ] |
Заголовок сообщения: | Re: авторизация voicemail |
снять дамп и сигналки и RTP |
Автор: | oper [ 27 фев 2014 15:41 ] |
Заголовок сообщения: | Re: авторизация voicemail |
Логи посмотрел, ситуация стала яснее, разрешил. Проблема была в том что на Медианте есть параметр Disconnect on Broken Connection. По дефолту он равен 10 секунд, после чего если нет ответных пакетов вызов рвется. Сейчас поставил 40 сек. Скорее всего приложение войсмейл не шлет ничего в линию. В FS есть пареметр который может слать пакеты, тем самым "держа" линию(комфортный шум). https://wiki.freeswitch.org/wiki/VAD_and_CNG |
Страница 1 из 1 | Часовой пояс: UTC + 4 часа |
Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/ |