[Freeswitch-users] retreiving voicemail dropping after 30 seconds
Michael Collins
msc at freeswitch.org
Tue Nov 20 22:42:41 MSK 2012
Which pb entry is it?
-MC
On Mon, Nov 19, 2012 at 7:35 PM, Jason Holden <jason.holden at start.ca> wrote:
> ** **
>
> alrighty.****
>
> I added the log with the sip trace to pastebin.****
>
> Guess I was having a case of the Mondays.****
>
> Anyone able to give me their opinions on what is going on?****
>
> The one thing I notice is for some reason my public IP is showing up in
> the trace. Wouldn’t think it should be though since I am communicating on
> my LAN.****
>
> ** **
> ------------------------------
>
> *From:* Michael Collins [mailto:msc at freeswitch.org]
> *Sent:* Monday, November 19, 2012 5:39 PM
> *To:* FreeSWITCH Users Help
> *Subject:* Re: [Freeswitch-users] retreiving voicemail dropping after 30
> seconds****
>
> ** **
>
> ** **
>
> On Mon, Nov 19, 2012 at 1:32 PM, Jason Holden <jason.holden at start.ca>
> wrote:****
>
> I can not log on to the page but the following is the cli log.****
>
> Sure you can! Just read the challenge dialog a bit more closely. ;)
> Also, select "FreeSWITCH Log" as the syntax highlighting. Don't forget to
> turn on SIP trace:
> ****sofia**** profile internal siptrace on
>
> -MC
> ****
>
> Also I am on my local LAN.****
>
> ****
>
> ****
>
> 2012-11-19 17:16:46.630373 [NOTICE] switch_channel.c:953 New Channel ****
> sofia****/internal/201-entros at 192.168.15.9[1107676f-36a3-4bc2-b413-525c898bb3b6]
> ****
>
> 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change CS_NEW***
> *
>
> 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:433 (****
> sofia****/internal/201-entros at 192.168.15.9) State NEW****
>
> 2012-11-19 17:16:46.650375 [DEBUG] sofia.c:7726 IP 192.168.15.2 Rejected
> by acl "domains". Falling back to Digest auth.****
>
> 2012-11-19 17:16:46.650375 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.650375 [DEBUG] sofia.c:1755 detaching session
> 1107676f-36a3-4bc2-b413-525c898bb3b6****
>
> 2012-11-19 17:16:46.650375 [WARNING] sofia_reg.c:1481 SIP auth challenge
> (INVITE) on ****sofia**** profile 'internal' for [*97 at 192.168.15.9] from
> ip 192.168.15.2****
>
> 2012-11-19 17:16:46.670376 [DEBUG] sofia.c:1847 Re-attaching to session
> 1107676f-36a3-4bc2-b413-525c898bb3b6****
>
> 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia.c:7726 IP 192.168.15.2 Rejected
> by acl "domains". Falling back to Digest auth.****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia.c:5596 Channel ****sofia****
> /internal/201-entros at 192.168.15.9 entering state [received][100]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia.c:5607 Remote SDP:****
>
> v=0****
>
> o=- 6486898 6486898 IN IP4 192.168.15.2****
>
> s=-****
>
> c=IN IP4 192.168.15.2****
>
> t=0 0****
>
> m=audio 16392 RTP/AVP 0 100 101****
>
> a=rtpmap:0 PCMU/8000****
>
> a=rtpmap:100 NSE/8000****
>
> a=fmtp:100 192-193****
>
> a=rtpmap:101 telephone-event/8000****
>
> a=fmtp:101 0-15****
>
> a=ptime:20****
>
> ****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia_glue.c:5136 Audio Codec Compare
> [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia_glue.c:5136 Audio Codec Compare
> [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia_glue.c:3093 Set Codec
> sofia/internal/201-entros at 192.168.15.9 PCMU/8000 20 ms 160 samples 64000
> bits****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_codec.c:111 ****sofia****
> /internal/201-entros at 192.168.15.9 Original read codec set to PCMU:0****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia_glue.c:5265 Set 2833 dtmf
> send/recv payload to 101****
>
> 2012-11-19 17:16:46.690372 [DEBUG] sofia.c:5824 (****sofia****/internal/
> 201-entros at 192.168.15.9) State Change CS_NEW -> CS_INIT****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change CS_INIT**
> **
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (****
> sofia****/internal/201-entros at 192.168.15.9) State INIT****
>
> 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 ****sofia****/internal/
> 201-entros at 192.168.15.9 SOFIA INIT****
>
> 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (****sofia****
> /internal/201-entros at 192.168.15.9) State Change CS_INIT -> CS_ROUTING****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (****
> sofia****/internal/201-entros at 192.168.15.9) State INIT going to sleep****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change
> CS_ROUTING****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_channel.c:1988 (****sofia****
> /internal/201-entros at 192.168.15.9) Callstate Change DOWN -> RINGING****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:470 (****
> sofia****/internal/201-entros at 192.168.15.9) State ROUTING****
>
> 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 **sofia**/internal/
> 201-entros at 192.168.15.9 ****SOFIA**** ROUTING****
>
> 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 ****
> sofia****/internal/201-entros at 192.168.15.9 Standard ROUTING****
>
> 2012-11-19 17:16:46.690372 [INFO] mod_dialplan_xml.c:498 Processing home
> <201-entros>->*97 in context default****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->AtlasVoice.911] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->AtlasVoice.10d] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false***
> *
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->AtlasVoice.11d] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false
> ****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->AtlasVoice.tollfree] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [AtlasVoice.tollfree] destination_number(*97) =~
> /^1?(8(00|55|66|77|88)[2-9]\d{6})$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->call-direction] continue=true****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/
> break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 ANTI-Action
> set(call_direction=local) ****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->group-intercept] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->redial] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->call_privacy] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->call_return] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->intercept-ext] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false***
> *
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->extension-intercom] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/
> break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->send_to_voicemail] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/
> break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->vmain] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (FAIL)
> [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
> ****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 parsing
> [default->vmain_user] continue=false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Regex (PASS)
> [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Action answer() *
> ***
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Action
> sleep(1000) ****
>
> Dialplan: ****sofia****/internal/201-entros at 192.168.15.9 Action
> voicemail(check default ${domain_name} ${caller_id_number}) ****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:167 (****
> sofia****/internal/201-entros at 192.168.15.9) State Change CS_ROUTING ->
> CS_EXECUTE****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (****
> sofia****/internal/201-entros at 192.168.15.9) State ROUTING going to sleep**
> **
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change
> CS_EXECUTE****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:477 (****
> sofia****/internal/201-entros at 192.168.15.9) State EXECUTE****
>
> 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 ****sofia****/internal/
> 201-entros at 192.168.15.9 SOFIA EXECUTE****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 ****
> sofia****/internal/201-entros at 192.168.15.9 Standard EXECUTE****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9set(call_direction=local)
> ****
>
> 2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 ****sofia****
> /internal/201-entros at 192.168.15.9 SET [call_direction]=[local]****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 answer()****
>
> 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [****sofia*
> ***/internal/201-entros at 192.168.15.9] 192.168.15.9 port 24942 ->
> 192.168.15.2 port 16392 codec: 0 ms: 20****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_rtp.c:1927 Starting timer [soft]
> 160 bytes per 20ms****
>
> 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3614 Set 2833 dtmf send
> payload to 101****
>
> 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3620 Set 2833 dtmf receive
> payload to 101****
>
> 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3647 ****sofia****
> /internal/201-entros at 192.168.15.9 Set rtp dtmf delay to 40****
>
> 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:856 Local SDP ****sofia****
> /internal/201-entros at 192.168.15.9:****
>
> v=0****
>
> o=FreeSWITCH 1353300664 1353300665 IN IP4 192.168.15.9****
>
> s=FreeSWITCH****
>
> c=IN IP4 192.168.15.9****
>
> t=0 0****
>
> m=audio 24942 RTP/AVP 0 101****
>
> a=rtpmap:0 PCMU/8000****
>
> a=rtpmap:101 telephone-event/8000****
>
> a=fmtp:101 0-16****
>
> a=silenceSupp:off - - - -****
>
> a=ptime:20****
>
> a=sendrecv****
>
> ****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel ****sofia****
> /internal/201-entros at 192.168.15.9 entering state [completed][200]****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_core_session.c:830 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (****sofia****
> /internal/201-entros at 192.168.15.9) Callstate Change RINGING -> ACTIVE****
>
> 2012-11-19 17:16:46.710380 [NOTICE] mod_dptools.c:1176 Channel [****sofia*
> ***/internal/201-entros at 192.168.15.9] has been answered****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(1000)****
>
> 2012-11-19 17:16:46.750379 [DEBUG] switch_rtp.c:3606 Correct ip/port
> confirmed.****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 voicemail(check
> default 192.168.15.9 201-entros)****
>
> 2012-11-19 17:16:47.730206 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:16:47.990165 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:16:48.010904 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-enter_pass.wav] (en:en)****
>
> 2012-11-19 17:16:48.030171 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:48.930025 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF 5:1604*
> ***
>
> 2012-11-19 17:16:48.930025 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-enter_pass.wav
> ****
>
> 2012-11-19 17:16:49.289963 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF 9:1444*
> ***
>
> 2012-11-19 17:16:49.709899 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF 2:1524*
> ***
>
> 2012-11-19 17:16:50.169828 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF 7:1524*
> ***
>
> 2012-11-19 17:16:55.151063 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF #:1604*
> ***
>
> 2012-11-19 17:16:55.271043 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:16:55.291060 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-you_have.wav] (en:en)****
>
> 2012-11-19 17:16:55.311050 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:55.869947 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-you_have.wav**
> **
>
> 2012-11-19 17:16:55.989933 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[0] (en:en)****
>
> 2012-11-19 17:16:56.009933 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:56.769808 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/0.wav****
>
> 2012-11-19 17:16:56.889794 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-new.wav] (en:en)****
>
> 2012-11-19 17:16:56.909794 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:57.269730 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-new.wav****
>
> 2012-11-19 17:16:57.389720 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-messages.wav] (en:en)****
>
> 2012-11-19 17:16:57.389720 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:58.049611 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-messages.wav**
> **
>
> 2012-11-19 17:16:58.170590 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:16:58.190605 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-you_have.wav] (en:en)****
>
> 2012-11-19 17:16:58.190605 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:58.730505 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-you_have.wav**
> **
>
> 2012-11-19 17:16:58.850487 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[0] (en:en)****
>
> 2012-11-19 17:16:58.850487 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:16:59.630368 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/0.wav****
>
> 2012-11-19 17:16:59.750348 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-saved.wav] (en:en)****
>
> 2012-11-19 17:16:59.750348 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:00.230274 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-saved.wav****
>
> 2012-11-19 17:17:00.350255 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-messages.wav] (en:en)****
>
> 2012-11-19 17:17:00.350255 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:01.010156 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-messages.wav**
> **
>
> 2012-11-19 17:17:01.250116 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:17:01.270135 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-listen_new.wav] (en:en)****
>
> 2012-11-19 17:17:01.270135 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:02.609908 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-listen_new.wav
> ****
>
> 2012-11-19 17:17:02.729887 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:02.729887 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:03.129826 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:03.249807 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[1] (en:en)****
>
> 2012-11-19 17:17:03.249807 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:03.949702 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/1.wav****
>
> 2012-11-19 17:17:04.069679 [DEBUG] switch_ivr_play_say.c:244 Handle
> execute:[sleep(100)] (en:en)****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(100)****
>
> 2012-11-19 17:17:04.309644 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-listen_saved.wav] (en:en)****
>
> 2012-11-19 17:17:04.309644 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:05.910397 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-listen_saved.wav
> ****
>
> 2012-11-19 17:17:06.030378 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:06.030378 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:06.430318 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:06.550297 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[2] (en:en)****
>
> 2012-11-19 17:17:06.550297 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:07.190204 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/2.wav****
>
> 2012-11-19 17:17:07.310180 [DEBUG] switch_ivr_play_say.c:244 Handle
> execute:[sleep(100)] (en:en)****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(100)****
>
> 2012-11-19 17:17:07.550143 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-advanced.wav] (en:en)****
>
> 2012-11-19 17:17:07.550143 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:08.709964 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-advanced.wav**
> **
>
> 2012-11-19 17:17:08.829946 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:08.829946 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:09.229884 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:09.349864 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[5] (en:en)****
>
> 2012-11-19 17:17:09.349864 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:10.129747 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/5.wav****
>
> 2012-11-19 17:17:10.249724 [DEBUG] switch_ivr_play_say.c:244 Handle
> execute:[sleep(100)] (en:en)****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(100)****
>
> 2012-11-19 17:17:10.489687 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-to_exit.wav] (en:en)****
>
> 2012-11-19 17:17:10.489687 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:11.069598 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-to_exit.wav***
> *
>
> 2012-11-19 17:17:11.089603 [DEBUG] switch_rtp.c:3809 RTP RECV DTMF 5:1524*
> ***
>
> 2012-11-19 17:17:11.170582 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:11.170582 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:11.170582 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:11.410578 [DEBUG] switch_ivr_play_say.c:67 No language
> specified - Using [en]****
>
> 2012-11-19 17:17:11.430568 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-to_record_greeting.wav] (en:en)****
>
> 2012-11-19 17:17:11.430568 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:12.450387 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file
> /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-to_record_greeting.wav
> ****
>
> 2012-11-19 17:17:12.570371 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:12.570371 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:12.970304 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:13.090285 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[1] (en:en)****
>
> 2012-11-19 17:17:13.090285 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:13.790181 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/1.wav****
>
> 2012-11-19 17:17:13.910188 [DEBUG] switch_ivr_play_say.c:244 Handle
> execute:[sleep(100)] (en:en)****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(100)****
>
> 2012-11-19 17:17:14.150128 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-choose_greeting.wav] (en:en)****
>
> 2012-11-19 17:17:14.150128 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:15.089978 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file
> /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-choose_greeting.wav
> ****
>
> 2012-11-19 17:17:15.209957 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:15.209957 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:15.609897 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:15.729878 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[2] (en:en)****
>
> 2012-11-19 17:17:15.729878 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:16.369782 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/2.wav****
>
> 2012-11-19 17:17:16.489760 [DEBUG] switch_ivr_play_say.c:244 Handle
> execute:[sleep(100)] (en:en)****
>
> EXECUTE ****sofia****/internal/201-entros at 192.168.15.9 sleep(100)****
>
> 2012-11-19 17:17:16.709727 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-record_name2.wav] (en:en)****
>
> 2012-11-19 17:17:16.709727 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:17.770561 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-record_name2.wav
> ****
>
> 2012-11-19 17:17:17.870561 [DEBUG] switch_ivr_play_say.c:244 Handle
> play-file:[voicemail/vm-press.wav] (en:en)****
>
> 2012-11-19 17:17:17.870561 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:18.270487 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file /usr/local/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav****
>
> 2012-11-19 17:17:18.390470 [DEBUG] switch_ivr_play_say.c:244 Handle
> say:[3] (en:en)****
>
> 2012-11-19 17:17:18.390470 [DEBUG] switch_ivr_play_say.c:1309 Codec
> Activated L16 at 8000hz 1 channels 20ms****
>
> 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal *
> ***sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel ****sofia****
> /internal/201-entros at 192.168.15.9 entering state [terminating][0]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (****sofia****
> /internal/201-entros at 192.168.15.9) Callstate Change ACTIVE -> HANGUP****
>
> 2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup ****sofia****
> /internal/201-entros at 192.168.15.9 [CS_EXECUTE] [NORMAL_UNSPECIFIED]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:3002 Send signal ****
> sofia****/internal/201-entros at 192.168.15.9 [KILL]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_ivr_play_say.c:1682 done playing
> file file_string://digits/3.wav****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:2685 ****sofia***
> */internal/201-entros at 192.168.15.9 skip receive message
> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:477 (****
> sofia****/internal/201-entros at 192.168.15.9) State EXECUTE going to sleep**
> **
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change CS_HANGUP
> ****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:667 (****
> sofia****/internal/201-entros at 192.168.15.9) State HANGUP****
>
> 2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel ****sofia****
> /internal/201-entros at 192.168.15.9 hanging up, cause: NORMAL_UNSPECIFIED***
> *
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:48 ****
> sofia****/internal/201-entros at 192.168.15.9 Standard HANGUP, cause:
> NORMAL_UNSPECIFIED****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:667 (****
> sofia****/internal/201-entros at 192.168.15.9) State HANGUP going to sleep***
> *
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:446 (****
> sofia****/internal/201-entros at 192.168.15.9) State Change CS_HANGUP ->
> CS_REPORTING****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change
> CS_REPORTING****
>
> 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:749 (****
> sofia****/internal/201-entros at 192.168.15.9) State REPORTING****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 ****
> sofia****/internal/201-entros at 192.168.15.9 Standard REPORTING, cause:
> NORMAL_UNSPECIFIED****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:749 (****
> sofia****/internal/201-entros at 192.168.15.9) State REPORTING going to sleep
> ****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:440 (****
> sofia****/internal/201-entros at 192.168.15.9) State Change CS_REPORTING ->
> CS_DESTROY****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1287 Send signal
> ****sofia****/internal/201-entros at 192.168.15.9 [BREAK]****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (*
> ***sofia****/internal/201-entros at 192.168.15.9) Locked, Waiting on
> external entities****
>
> 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1510 Session 2 (
> ****sofia****/internal/201-entros at 192.168.15.9) Ended****
>
> 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close
> Channel ****sofia****/internal/201-entros at 192.168.15.9 [CS_DESTROY]****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (****
> sofia****/internal/201-entros at 192.168.15.9) Callstate Change HANGUP ->
> DOWN****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:559 (****
> sofia****/internal/201-entros at 192.168.15.9) Running State Change
> CS_DESTROY****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (****
> sofia****/internal/201-entros at 192.168.15.9) State DESTROY****
>
> 2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 ****sofia****/internal/
> 201-entros at 192.168.15.9 SOFIA DESTROY****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 ****
> sofia****/internal/201-entros at 192.168.15.9 Standard DESTROY****
>
> 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (****
> sofia****/internal/201-entros at 192.168.15.9) State DESTROY going to sleep**
> **
>
> ****
>
> ****
> ------------------------------
>
> *From:* Michael Collins [mailto:msc at freeswitch.org]
> *Sent:* Monday, November 19, 2012 12:13 PM
> *To:* FreeSWITCH Users Help
> *Subject:* Re: [Freeswitch-users] retreiving voicemail dropping after 30
> seconds****
>
> ****
>
> Is this device on the same LAN as FreeSWITCH? Get a console log and SIP
> trace and drop it on pastebin.freeswitch.org and the gang here will offer
> some insights.
>
> -MC****
>
> On Fri, Nov 16, 2012 at 10:33 PM, Jason Holden <jason.holden at start.ca>
> wrote:****
>
> Hi.****
>
> When accessing voicemail to listen to messages I am finding that it is
> dropping at 30 seconds each time with a message of 100 sleep timer.****
>
> Does anyone have any recommendations?****
>
> I am using a Sipura 3000 connected to my freeswitch server.****
>
> ****
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org****
>
>
>
>
> --
> Michael S Collins
> Twitter: @mercutioviz
> http://www.FreeSWITCH.org
> http://www.ClueCon.com
> http://www.OSTAG.org****
>
> ****
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org****
>
>
>
>
> --
> Michael S Collins
> Twitter: @mercutioviz
> http://www.FreeSWITCH.org
> http://www.ClueCon.com
> http://www.OSTAG.org****
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
>
>
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
--
Michael S Collins
Twitter: @mercutioviz
http://www.FreeSWITCH.org
http://www.ClueCon.com
http://www.OSTAG.org
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20121120/6a70bd27/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list