Re: [Freeswitch-users] retreiving voicemail dropping after 30 seconds
I can not log on to the page but the following is the cli log. 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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@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@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 sofia/internal/201-entros@192.168.15.9 SOFIA INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/201-entros@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@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@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@192.168.15.9) State ROUTING 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 sofia/internal/201-entros@192.168.15.9 SOFIA ROUTING 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 sofia/internal/201-entros@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@192.168.15.9 parsing [default->AtlasVoice.911] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.10d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.11d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false Dialplan: sofia/internal/201-entros@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@192.168.15.9 parsing [default->call-direction] continue=true Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->redial] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_return] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain_user] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 Action answer() Dialplan: sofia/internal/201-entros@192.168.15.9 Action sleep(1000) Dialplan: sofia/internal/201-entros@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/201-entros@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@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@192.168.15.9) State EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 sofia/internal/201-entros@192.168.15.9 SOFIA EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 sofia/internal/201-entros@192.168.15.9 Standard EXECUTE EXECUTE sofia/internal/201-entros@192.168.15.9 set(call_direction=local) 2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 sofia/internal/201-entros@192.168.15.9 SET [call_direction]=[local] EXECUTE sofia/internal/201-entros@192.168.15.9 answer() 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (sofia/internal/201-entros@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@192.168.15.9] has been answered EXECUTE sofia/internal/201-entros@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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.wa v 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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@192.168.15.9 entering state [terminating][0] 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (sofia/internal/201-entros@192.168.15.9) Callstate Change ACTIVE -> HANGUP 2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup sofia/internal/201-entros@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@192.168.15.9 [KILL] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal sofia/internal/201-entros@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@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@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@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@192.168.15.9) State HANGUP 2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State REPORTING 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (sofia/internal/201-entros@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@192.168.15.9) Ended 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel sofia/internal/201-entros@192.168.15.9 [CS_DESTROY] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/201-entros@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@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@192.168.15.9) State DESTROY 2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 sofia/internal/201-entros@192.168.15.9 SOFIA DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 sofia/internal/201-entros@192.168.15.9 Standard DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/201-entros@192.168.15.9) State DESTROY going to sleep _____ From: Michael Collins [mailto:msc@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@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@freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@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
You sir, failed the bot test... It tells you right there in the login prompt what the login info is On 11/19/12 3:32 PM, "Jason Holden" <jason.holden@start.ca> wrote:
I can not log on to the page but the following is the cli log. 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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@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@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 sofia/internal/201-entros@192.168.15.9 SOFIA INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/201-entros@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@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@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@192.168.15.9) State ROUTING 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 sofia/internal/201-entros@192.168.15.9 SOFIA ROUTING 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 sofia/internal/201-entros@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@192.168.15.9 parsing [default->AtlasVoice.911] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.10d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.11d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false Dialplan: sofia/internal/201-entros@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@192.168.15.9 parsing [default->call-direction] continue=true Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->redial] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_return] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain_user] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 Action answer() Dialplan: sofia/internal/201-entros@192.168.15.9 Action sleep(1000) Dialplan: sofia/internal/201-entros@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/201-entros@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@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@192.168.15.9) State EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 sofia/internal/201-entros@192.168.15.9 SOFIA EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 sofia/internal/201-entros@192.168.15.9 Standard EXECUTE EXECUTE sofia/internal/201-entros@192.168.15.9 set(call_direction=local) 2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 sofia/internal/201-entros@192.168.15.9 SET [call_direction]=[local] EXECUTE sofia/internal/201-entros@192.168.15.9 answer() 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (sofia/internal/201-entros@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@192.168.15.9] has been answered EXECUTE sofia/internal/201-entros@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@192.168.15.9 entering state [terminating][0] 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (sofia/internal/201-entros@192.168.15.9) Callstate Change ACTIVE -> HANGUP 2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup sofia/internal/201-entros@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@192.168.15.9 [KILL] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal sofia/internal/201-entros@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@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@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@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@192.168.15.9) State HANGUP 2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State REPORTING 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (sofia/internal/201-entros@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@192.168.15.9) Ended 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel sofia/internal/201-entros@192.168.15.9 [CS_DESTROY] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/201-entros@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@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@192.168.15.9) State DESTROY 2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 sofia/internal/201-entros@192.168.15.9 SOFIA DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 sofia/internal/201-entros@192.168.15.9 Standard DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/201-entros@192.168.15.9) State DESTROY going to sleep
From: Michael Collins [mailto:msc@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 <http://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@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Ken http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org irc.freenode.net #freeswitch
On Mon, Nov 19, 2012 at 1:32 PM, Jason Holden <jason.holden@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@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@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State INIT****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 sofia/internal/ 201-entros@192.168.15.9 ****SOFIA**** INIT****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (sofia/internal/ 201-entros@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/201-entros@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@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@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@192.168.15.9) State ROUTING****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 sofia/internal/ 201-entros@192.168.15.9 ****SOFIA**** ROUTING****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 sofia/internal/201-entros@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@192.168.15.9 parsing [default->AtlasVoice.911] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.10d] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false*** *
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.11d] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false ****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false****
Dialplan: sofia/internal/201-entros@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@192.168.15.9 parsing [default->call-direction] continue=true****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 ANTI-Action set(call_direction=local) ****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->group-intercept] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->redial] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_privacy] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_return] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->intercept-ext] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false*** *
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->extension-intercom] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->send_to_voicemail] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain_user] continue=false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false****
Dialplan: sofia/internal/201-entros@192.168.15.9 Action answer() ****
Dialplan: sofia/internal/201-entros@192.168.15.9 Action sleep(1000) ****
Dialplan: sofia/internal/201-entros@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/201-entros@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@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@192.168.15.9) State EXECUTE****
2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 sofia/internal/ 201-entros@192.168.15.9 SOFIA EXECUTE****
2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 sofia/internal/201-entros@192.168.15.9 Standard EXECUTE****
EXECUTE sofia/internal/201-entros@192.168.15.9 set(call_direction=local)** **
2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 sofia/internal/ 201-entros@192.168.15.9 SET [call_direction]=[local]****
EXECUTE sofia/internal/201-entros@192.168.15.9 answer()****
2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel sofia/internal/ 201-entros@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (sofia/internal/ 201-entros@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@192.168.15.9] has been answered****
EXECUTE sofia/internal/201-entros@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms****
2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel sofia/internal/ 201-entros@192.168.15.9 entering state [terminating][0]****
2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (sofia/internal/ 201-entros@192.168.15.9) Callstate Change ACTIVE -> HANGUP****
2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup sofia/internal/ 201-entros@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@192.168.15.9 [KILL]****
2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal sofia/internal/201-entros@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@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@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@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@192.168.15.9) State HANGUP****
2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel sofia/internal/ 201-entros@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State REPORTING****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (sofia/internal/201-entros@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@192.168.15.9) Ended****
2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel sofia/internal/201-entros@192.168.15.9 [CS_DESTROY]****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/201-entros@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@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@192.168.15.9) State DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 sofia/internal/ 201-entros@192.168.15.9 SOFIA DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 sofia/internal/201-entros@192.168.15.9 Standard DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/201-entros@192.168.15.9) State DESTROY going to sleep****
** **
** ** ------------------------------
*From:* Michael Collins [mailto:msc@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@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@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
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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@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@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 sofia/internal/201-entros@192.168.15.9 SOFIA INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/201-entros@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@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@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@192.168.15.9) State ROUTING 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 sofia/internal/201-entros@192.168.15.9 SOFIA ROUTING 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 sofia/internal/201-entros@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@192.168.15.9 parsing [default->AtlasVoice.911] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.10d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.11d] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false Dialplan: sofia/internal/201-entros@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@192.168.15.9 parsing [default->call-direction] continue=true Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->redial] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->call_return] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 parsing [default->vmain_user] continue=false Dialplan: sofia/internal/201-entros@192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false Dialplan: sofia/internal/201-entros@192.168.15.9 Action answer() Dialplan: sofia/internal/201-entros@192.168.15.9 Action sleep(1000) Dialplan: sofia/internal/201-entros@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/201-entros@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@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@192.168.15.9) State EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 sofia/internal/201-entros@192.168.15.9 SOFIA EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 sofia/internal/201-entros@192.168.15.9 Standard EXECUTE EXECUTE sofia/internal/201-entros@192.168.15.9 set(call_direction=local) 2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 sofia/internal/201-entros@192.168.15.9 SET [call_direction]=[local] EXECUTE sofia/internal/201-entros@192.168.15.9 answer() 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@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@192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (sofia/internal/201-entros@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@192.168.15.9] has been answered EXECUTE sofia/internal/201-entros@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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.wa v 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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros@192.168.15.9 entering state [terminating][0] 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (sofia/internal/201-entros@192.168.15.9) Callstate Change ACTIVE -> HANGUP 2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup sofia/internal/201-entros@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@192.168.15.9 [KILL] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal sofia/internal/201-entros@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@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@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@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@192.168.15.9) State HANGUP 2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel sofia/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros@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@192.168.15.9) State REPORTING 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 sofia/internal/201-entros@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@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@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@192.168.15.9 [BREAK] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (sofia/internal/201-entros@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@192.168.15.9) Ended 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel sofia/internal/201-entros@192.168.15.9 [CS_DESTROY] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/201-entros@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@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@192.168.15.9) State DESTROY 2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 sofia/internal/201-entros@192.168.15.9 SOFIA DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 sofia/internal/201-entros@192.168.15.9 Standard DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/201-entros@192.168.15.9) State DESTROY going to sleep _____ From: Michael Collins [mailto:msc@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@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@freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@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@freeswitch.org http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@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
Which pb entry is it? -MC On Mon, Nov 19, 2012 at 7:35 PM, Jason Holden <jason.holden@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal * ***sofia****/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (**** sofia****/internal/201-entros@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal * ***sofia****/internal/201-entros@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@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (**** sofia****/internal/201-entros@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@192.168.15.9) State INIT****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 ****sofia****/internal/ 201-entros@192.168.15.9 SOFIA INIT****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (****sofia**** /internal/201-entros@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (**** sofia****/internal/201-entros@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@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@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@192.168.15.9) State ROUTING****
2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 **sofia**/internal/ 201-entros@192.168.15.9 ****SOFIA**** ROUTING****
2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 **** sofia****/internal/201-entros@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@192.168.15.9 parsing [default->AtlasVoice.911] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.10d] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false*** *
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.11d] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false ****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false****
Dialplan: ****sofia****/internal/201-entros@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@192.168.15.9 parsing [default->call-direction] continue=true****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 ANTI-Action set(call_direction=local) ****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->group-intercept] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->redial] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->call_privacy] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->call_return] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->intercept-ext] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false*** *
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->extension-intercom] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->send_to_voicemail] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->vmain] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false ****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 parsing [default->vmain_user] continue=false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false****
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Action answer() * ***
Dialplan: ****sofia****/internal/201-entros@192.168.15.9 Action sleep(1000) ****
Dialplan: ****sofia****/internal/201-entros@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (**** sofia****/internal/201-entros@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@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@192.168.15.9) State EXECUTE****
2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 ****sofia****/internal/ 201-entros@192.168.15.9 SOFIA EXECUTE****
2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 **** sofia****/internal/201-entros@192.168.15.9 Standard EXECUTE****
EXECUTE ****sofia****/internal/201-entros@192.168.15.9set(call_direction=local) ****
2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 ****sofia**** /internal/201-entros@192.168.15.9 SET [call_direction]=[local]****
EXECUTE ****sofia****/internal/201-entros@192.168.15.9 answer()****
2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [****sofia* ***/internal/201-entros@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel ****sofia**** /internal/201-entros@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@192.168.15.9 [BREAK]****
2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (****sofia**** /internal/201-entros@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@192.168.15.9] has been answered****
EXECUTE ****sofia****/internal/201-entros@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@8000hz 1 channels 20ms****
2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal * ***sofia****/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal * ***sofia****/internal/201-entros@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel ****sofia**** /internal/201-entros@192.168.15.9 entering state [terminating][0]****
2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (****sofia**** /internal/201-entros@192.168.15.9) Callstate Change ACTIVE -> HANGUP****
2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup ****sofia**** /internal/201-entros@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@192.168.15.9 [KILL]****
2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal ****sofia****/internal/201-entros@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@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@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@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@192.168.15.9) State HANGUP****
2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel ****sofia**** /internal/201-entros@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@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (**** sofia****/internal/201-entros@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@192.168.15.9) State REPORTING****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 **** sofia****/internal/201-entros@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@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@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@192.168.15.9 [BREAK]****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (* ***sofia****/internal/201-entros@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@192.168.15.9) Ended****
2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel ****sofia****/internal/201-entros@192.168.15.9 [CS_DESTROY]****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (**** sofia****/internal/201-entros@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@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@192.168.15.9) State DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 ****sofia****/internal/ 201-entros@192.168.15.9 SOFIA DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 **** sofia****/internal/201-entros@192.168.15.9 Standard DESTROY****
2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (**** sofia****/internal/201-entros@192.168.15.9) State DESTROY going to sleep** **
****
**** ------------------------------
*From:* Michael Collins [mailto:msc@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@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@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@freeswitch.org http://www.freeswitchsolutions.com
FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com
Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@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
I'm going to go out on a limb and make a "scientific-wild-assed-guess" since I can't see your pastebin, but since you're seeing external IP addresses I have an idea of what might be going on. Your client/user agent is probably using STUN and is reporting it's external IP address to FS which then uses that address for replies to the client. With most firewall/NAT devices, those replies won't make it back to the device unless you have a port forwarding rule set since the device treats it like it's coming from the outside. If that is indeed the case, then there are two things you can try: 1. Disable STUN on your endpoint (probably not the best solution, especially if you're on a dynamic IP) OR 2. Set a port forwarding rule in your firewall/router/nat device so those repllies make it back. Without the benefit of the pastebin this is just a wild guess so please don't sue me if this is not applicable. Hope everyone has a happy and safe Thanksgiving holiday! - Phil -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/retreiving-voicemail-dropping-... Sent from the freeswitch-users mailing list archive at Nabble.com.
All. I did find the issue just had not had a chance to reply until now. In my internal profile for what ever reason a var was set to reflect my external ip for sip and rtp. Once changed to my LAN information all is fine. -----Original Message----- From: PhilQ [mailto:philq@qsystemsengineering.com] Sent: Wednesday, November 21, 2012 1:25 PM To: freeswitch-users@lists.freeswitch.org Subject: Re: [Freeswitch-users] retreiving voicemail dropping after 30 seconds I'm going to go out on a limb and make a "scientific-wild-assed-guess" since I can't see your pastebin, but since you're seeing external IP addresses I have an idea of what might be going on. Your client/user agent is probably using STUN and is reporting it's external IP address to FS which then uses that address for replies to the client. With most firewall/NAT devices, those replies won't make it back to the device unless you have a port forwarding rule set since the device treats it like it's coming from the outside. If that is indeed the case, then there are two things you can try: 1. Disable STUN on your endpoint (probably not the best solution, especially if you're on a dynamic IP) OR 2. Set a port forwarding rule in your firewall/router/nat device so those repllies make it back. Without the benefit of the pastebin this is just a wild guess so please don't sue me if this is not applicable. Hope everyone has a happy and safe Thanksgiving holiday! - Phil -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/retreiving-voicemail-dropping- after-30-seconds-tp7584653p7584814.html Sent from the freeswitch-users mailing list archive at Nabble.com.
participants (4)
-
Jason Holden
-
Ken Rice
-
Michael Collins
-
PhilQ