November 2012 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Freeswitch retries same call 4 times for no reason - incorrect treatment of timers by mod sofia?
by Victor Chukalovskiy 22 Nov '12

22 Nov '12

Hello, Looking for help or comments on this. Reproducible on the FS system that was updated about a month ago. Call scenario: SIP call comes in on "a" leg, SIP call is attempted on the "b" leg. "b" leg replies "404". Description of the problem: FS tries same SIP destination 4 times on the "b" leg instead of doing it once. It does not respond "404" to the "a" leg until it finishes all 4 attempts on the "b" leg. This occurs when "b" leg takes longer than usual to return "404". For example, if "b" leg takes 1.3 seconds until we get "404", FS tries the call on "b" leg 3 more times. When this happens each consequent call attempt on the "b" leg is exactly 2 seconds apart from when the previous call attempt ends. For comparison, in the same scenario and using exact same config, if "b' leg replies "404" sooner (e.g. within 0.3 seconds) FS does not retry this call on the "b" leg anymore. "404" is returned to the "a" leg instantaneously as it should be. I suspect this is a bug in Sofia such that is somehow related to 500ms timer. Is this known behavior? Do you need any more details to be able to verify or comment? Thank you, Victor

3 2

E-mail voicemails - msmtp in Windows
by Victor 21 Nov '12

21 Nov '12

My system is having trouble e-mailing voicemails. This happened when I upgraded from 1.0.head (1/23/2012) to 1.2.3 or 1.3.2. E-mails are being sent to the correct recipient, but they are blank (msmtp logs indicate mailsize=0). Nov 12 15:47:55 host=smtp.gmail.com tls=on auth=on user=noreply(a)*****.com from=31(a)192.168.0.11 recipients=***(a)*****.com mailsize=0 smtpstatus=250 smtpmsg='250 2.0.0 OK 1352764075 os5sm4913355pbc.15' exitcode=EX_OK I see in FreeSWITCH's console "The syntax of the command is incorrect" whenever an e-mail is being sent out. All the configuration files are the same, voicemail files are being written to disk. Only difference/change I know of is the upgrade to 1.2.3. Reverting back to 1.0.x fixes the problem. --Victor

3 3

Paris - mini-DebConf - VoIP - 24 November
by Daniel Pocock 21 Nov '12

21 Nov '12

For those using Debian/Ubuntu (and anybody else is welcome of course), there is a mini-DebConf in Paris this weekend: http://fr2012.mini.debconf.org/ There is a presentation at 16:00 about Debian's role in establishing an alternative to Skype, this will look at some of the packages available on the upcoming Debian 7 (wheezy), and strategic ways of deploying them to build a genuinely free and open cloud for real-time communications. There is no registration fee - all welcome

1 0

Freeswitch Multilingual Setup
by Muhammad Shahzad 21 Nov '12

21 Nov '12

Hi, I am test Freeswitch for Multilingual IVR system. I observed that if I set default_language parameter before "voicemail" or "say" applications, it correctly play specified application in given language. However it has no effect on playback application, it always play the IVR in system default (i.e. English) language IVR. Here is an example extension, <extension name="catchall"> <condition field="destination_number" expression="^(.*)$"> <action application="export" data="default_language=fr"/> <action application="answer"/> <action application="sleep" data="1000"/> <action application="playback" data="voicemail/vm-that_was_an_invalid_ext.wav"/> <action application="hangup"/> </condition> </extension> Many thanks in advance. Thank you.

2 2

Re: [Freeswitch-users] retreiving voicemail dropping after 30 seconds
by Jason Holden 21 Nov '12

21 Nov '12

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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.630373 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros(a)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(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.670376 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros(a)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(a)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(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros(a)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(a)192.168.15.9) State INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:86 sofia/internal/201-entros(a)192.168.15.9 SOFIA INIT 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:126 (sofia/internal/201-entros(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/201-entros(a)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(a)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(a)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(a)192.168.15.9) State ROUTING 2012-11-19 17:16:46.690372 [DEBUG] mod_sofia.c:149 sofia/internal/201-entros(a)192.168.15.9 SOFIA ROUTING 2012-11-19 17:16:46.690372 [DEBUG] switch_core_state_machine.c:117 sofia/internal/201-entros(a)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(a)192.168.15.9 parsing [default->AtlasVoice.911] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [AtlasVoice.911] destination_number(*97) =~ /^(911)$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->AtlasVoice.10d] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [AtlasVoice.10d] destination_number(*97) =~ /^(\d{10})$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->AtlasVoice.11d] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [AtlasVoice.11d] destination_number(*97) =~ /^\+?(\d{11})$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->AtlasVoice.tollfree] continue=false Dialplan: sofia/internal/201-entros(a)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(a)192.168.15.9 parsing [default->call-direction] continue=true Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 ANTI-Action set(call_direction=local) Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->group-intercept] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [group-intercept] destination_number(*97) =~ /^\*8$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->redial] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [redial] destination_number(*97) =~ /^(redial|\*870)$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->call_privacy] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [call_privacy] destination_number(*97) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->call_return] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [call_return] destination_number(*97) =~ /^\*69$|^lcr$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->intercept-ext] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [intercept-ext] destination_number(*97) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->extension-intercom] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [extension-intercom] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->send_to_voicemail] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [send_to_voicemail] destination_number(*97) =~ /^\*99(\d{2,7})$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->vmain] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (FAIL) [vmain] destination_number(*97) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 parsing [default->vmain_user] continue=false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false Dialplan: sofia/internal/201-entros(a)192.168.15.9 Action answer() Dialplan: sofia/internal/201-entros(a)192.168.15.9 Action sleep(1000) Dialplan: sofia/internal/201-entros(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/201-entros(a)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(a)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(a)192.168.15.9) State EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] mod_sofia.c:242 sofia/internal/201-entros(a)192.168.15.9 SOFIA EXECUTE 2012-11-19 17:16:46.710380 [DEBUG] switch_core_state_machine.c:209 sofia/internal/201-entros(a)192.168.15.9 Standard EXECUTE EXECUTE sofia/internal/201-entros(a)192.168.15.9 set(call_direction=local) 2012-11-19 17:16:46.710380 [DEBUG] mod_dptools.c:1344 sofia/internal/201-entros(a)192.168.15.9 SET [call_direction]=[local] EXECUTE sofia/internal/201-entros(a)192.168.15.9 answer() 2012-11-19 17:16:46.710380 [DEBUG] sofia_glue.c:3350 AUDIO RTP [sofia/internal/201-entros(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:16:46.710380 [DEBUG] switch_channel.c:3380 (sofia/internal/201-entros(a)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(a)192.168.15.9] has been answered EXECUTE sofia/internal/201-entros(a)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(a)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(a)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(a)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(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:17:18.710418 [DEBUG] switch_core_session.c:976 Send signal sofia/internal/201-entros(a)192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] sofia.c:5596 Channel sofia/internal/201-entros(a)192.168.15.9 entering state [terminating][0] 2012-11-19 17:17:18.730420 [DEBUG] switch_channel.c:2979 (sofia/internal/201-entros(a)192.168.15.9) Callstate Change ACTIVE -> HANGUP 2012-11-19 17:17:18.730420 [NOTICE] sofia.c:6380 Hangup sofia/internal/201-entros(a)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(a)192.168.15.9 [KILL] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_session.c:1287 Send signal sofia/internal/201-entros(a)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(a)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(a)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(a)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(a)192.168.15.9) State HANGUP 2012-11-19 17:17:18.730420 [DEBUG] mod_sofia.c:503 Channel sofia/internal/201-entros(a)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(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:17:18.730420 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/201-entros(a)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(a)192.168.15.9) State REPORTING 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:92 sofia/internal/201-entros(a)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(a)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(a)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(a)192.168.15.9 [BREAK] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_session.c:1492 Session 2 (sofia/internal/201-entros(a)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(a)192.168.15.9) Ended 2012-11-19 17:17:19.010389 [NOTICE] switch_core_session.c:1514 Close Channel sofia/internal/201-entros(a)192.168.15.9 [CS_DESTROY] 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/201-entros(a)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(a)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(a)192.168.15.9) State DESTROY 2012-11-19 17:17:19.010389 [DEBUG] mod_sofia.c:396 sofia/internal/201-entros(a)192.168.15.9 SOFIA DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:99 sofia/internal/201-entros(a)192.168.15.9 Standard DESTROY 2012-11-19 17:17:19.010389 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/201-entros(a)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(a)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(a)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(a)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

4 6

directory information pulled from db table rather then xml files
by Jason Holden 21 Nov '12

21 Nov '12

Does anyone know if this is possible? If so can you provide a web link with information? I was unable to find any details on this in the wiki.

5 4

FreeSWITCH Community Conference Call Today
by Michael Collins 21 Nov '12

21 Nov '12

Hello all, Today's agenda page is here: http://wiki.freeswitch.org/wiki/FS_weekly_2012_11_21 We are going to do a wiki tutorial, specifically how to add a channel variable to the wiki. We'll also go over script I wrote that produces an HTML page that lists all the channel variables. Talk to you soon! -- Michael S Collins Twitter: @mercutioviz http://www.FreeSWITCH.org http://www.ClueCon.com http://www.OSTAG.org

1 0

Re: [Freeswitch-users] changing vm default announcement
by andy 21 Nov '12

21 Nov '12

hi All, thanks for the input, in the end I figured out the problem I have been having - The following works as intended: <action application="set" data="voicemail_greeting_number=1"/> <action application="voicemail" data="default ${domain_name} ${dialed_extension}"/> However, in a lot of the of the example code,for instance default.xml for ext. 1000-1019, this is how voicemail is reached: <action application="bridge" data="loopback/app=voicemail:default ${domain_name} ${dialed_extension}"/> In the second case, <action application="set" data="voicemail_greeting_number=1"/> will not work, probably because the channel variable is not set for the correct channel. I am guessing that "export" instead of "set" may fix this but I did not have a chance to try. I would suggest that some clarification in the wiki between the two ways of calling voicemail would be very useful - when do you "bridge" and when do you just call the voicemail app?

2 1

Which one of the force-<>-domain params?
by Scott 21 Nov '12

21 Nov '12

Hi, just about 100% feature-function:feature-function migrated off of that other platform. Bit of a learning curve but loving freeSWITCH. Thanks for just a great project! All is working. Our edge router's NAT is very robust and configurable (openBSD's pf) and we're using STUN. However, in comparing the old sip traces to the fs sip traces, we have noticed something that I just don't know what to tinker with. With regard to the following fs sip trace output frag, Contact: <sip:gw+trunk-voipms@192.168.2.3 :5080;transport=udp;gw=trunk-voipms> To: <sip:99.1.2.3:5080>;tag=0myeUN32NySac With the 'other' config, we'd instead see, Contact: <sip:gw+trunk-voipms@mydomain.ca :5060;transport=udp;gw=trunk-voipms> To: <sip:mydomain.ca:5060>;tag=0myeUN32NySac Rolling forward, we'd prefer the mydomain.ca flavor. We operate a DNS with split horizon and SRV records, meaning public-outside, in fact, see's/resolves 99.1.2.3, and inside hosts and end points, in fact, see/resolve 192.168.2.3. We think it's one of the force-<>-domain or db-domain params but (our bad) cannot "get it right." Any thoughts as to the correct fs param="?" sip such that the fs sip-domain-string is used where that sip-domain-string that also is properly glued to a matching (resolvable) DNS-string? Thanks,

3 2

NOTIFY gets a 481 error response
by clive engelberg 21 Nov '12

21 Nov '12

Hi guys. Has anyone had this issue where an endpoint does not like the NOTIFY request. ? I am posting the sip trace below. Thanks in advance Clive (NOTIFY sent from Freeswitch) U 2012/11/21 15:39:13.562784 196.41.2.211:5060 -> 41.150.16.236:5060 NOTIFY sip:253118877988@41.150.16.236:5060 SIP/2.0. Via: SIP/2.0/UDP 196.41.2.211;rport;branch=z9hG4bK2KN80mQ12DFZQ. Max-Forwards: 70. From: <sip:253118877988@196.41.2.211>;tag=1N243vyr38aUS. To: <sip:253118877988@196.41.2.211>. Call-ID: adabb26c-ae83-1230-adb4-001aa01d9e0c. CSeq: 36416512 NOTIFY. Contact: <sip:mod_sofia@196.41.2.211:5060>. User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-073f9c3 2010-05-03 13-46-57 -0500. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE. Supported: timer, precondition, path, replaces. Event: message-summary. Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer. Subscription-State: terminated;reason=timeout. Content-Type: application/simple-message-summary. Content-Length: 72. . Messages-Waiting: no. Message-Account: sip:253118877988@196.41.2.211. . (Response) U 2012/11/21 15:39:13.740207 41.150.16.236:5060 -> 196.41.2.211:5060 SIP/2.0 481 Call/Transaction Does Not Exist. Via: SIP/2.0/UDP 196.41.2.211;rport;branch=z9hG4bK2KN80mQ12DFZQ. From: <sip:253118877988@196.41.2.211>;tag=1N243vyr38aUS. To: <sip:253118877988@196.41.2.211>. Call-ID: adabb26c-ae83-1230-adb4-001aa01d9e0c. CSeq: 36416512 NOTIFY. Server: KAREL-Sip-Trunk v. AAE_16 (ms48). Supported: timer,replaces. Contact: <sip:KAREL_trunk@41.150.16.236:5060>. Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,UPDATE,REFER,NOTIFY. Content-Length: 0. ____________________________________________________________ South Africas premier free email service - www.webmail.co.za DHL Express. Simple, secure and affordable. http://www.postnet.co.za/index.php?option=com_content&view=article&id=74&It…

1 0