November 2011 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Mod_callcenter Double-Dialing Intermittently
by R H 04 Nov '11

04 Nov '11

Hey Everyone, I may have a bug for Mod_Callcenter but I wanted to see if anyone else might notice a setup issue before I send this on to Jira. I recently launched a production callcenter system for our company. We are currently beta testing the system with a small subset of our callers and we have noticed an issue where every so often (IE.* Intermittent issue*) when a caller arrives in queue and the queue offers the call to the agent,* the offer happens twice. * What gets worse is that once the agent answers one of the calls the other call continues to attempt to reach the agent even while they are talking to the person they are receiving the second call for. When the call times out we get a "NO ANSWER" and then the callcenter system attempts the call again, and again, and again until the agent is done with the caller. Even more interesting is that the agent state and member state keep changing to reflect the state of the second calling thread that keeps trying even though the caller is actually speaking to the agent. Here is a log from the freeswitch events. This was taken using log level 5 in the cli. Lines in BLUE are cli statements I am recording when events are fired that I care about. I am using Mod_managed to listen to events and update information in a company database. Lines in RED are notes about the logs. 2011-11-04 15:44:06.340969 [ALERT] switch_cpp.cpp:1223 PsiQueueEventConsumer - CallLogId Not Detected, Attempting to Create New :: Unavailable(2406740599) calling cc+ --- A CALLER JUST JOINED THE QUEUE --- 2011-11-04 15:44:45.160968 [NOTICE] switch_channel.c:915 New Channel sofia/external/sip:4503@97.75.182.83:59868[35e23ce8-072e-11e1-a636-2d222f47338b] 2011-11-04 15:44:45.160968 [NOTICE] switch_channel.c:915 New Channel sofia/external/sip:4503@97.75.182.83:59868[35e24e68-072e-11e1-a63a-2d222f47338b] --- NOTICE HOW 2 CHANNELS WERE INITIATED TO THE SAME EXTENSION/AGENT --- --- AGENT STATE: 'Receiving' --- 2011-11-04 15:44:45.200941 [NOTICE] sofia.c:5375 Ring-Ready sofia/external/ sip:4503@97.75.182.83:59868! 2011-11-04 15:44:45.481059 [NOTICE] sofia.c:5375 Ring-Ready sofia/external/ sip:4503@97.75.182.83:59868! 2011-11-04 15:44:48.740993 [NOTICE] sofia.c:5983 Channel [sofia/external/ sip:4503@97.75.182.83:59868] has been answered --- THE AGENT ANSWERED ONLY ONE OF THE CALLS --- --- AGENT STATE: 'In A Queue Call' --- 2011-11-04 15:44:55.000947 [NOTICE] switch_ivr_originate.c:3167 Hangup sofia/external/sip:4503@97.75.182.83:59868 [CS_CONSUME_MEDIA] [NO_ANSWER] 2011-11-04 15:44:55.000947 [NOTICE] switch_ivr_originate.c:2459 Cannot create outgoing channel of type [user] cause: [NO_ANSWER] 2011-11-04 15:44:55.000947 [NOTICE] switch_core_session.c:1395 Session 605 (sofia/external/sip:4503@97.75.182.83:59868) Ended 2011-11-04 15:44:55.000947 [NOTICE] switch_core_session.c:1397 Close Channel sofia/external/sip:4503@97.75.182.83:59868 [CS_DESTROY] --- THE SECOND CHANNEL JUST TIMED OUT AND FLAGGED THE CALL AS A "NO ANSWER" --- 2011-11-04 15:44:55.000947 [NOTICE] switch_channel.c:915 New Channel sofia/external/sip:4503@97.75.182.83:59868[3bc0c008-072e-11e1-a642-2d222f47338b] --- AGENT STATE: 'Receiving' --- 2011-11-04 15:44:55.040931 [NOTICE] sofia.c:5375 Ring-Ready sofia/external/ sip:4503@97.75.182.83:59868! --- SECOND CHANNEL ATTEMPTS AGAIN TO REACH THE AGENT, THE AGENT IS ALREADY TALKING TO THE CALLER --- 2011-11-04 15:45:05.000941 [NOTICE] switch_ivr_originate.c:3167 Hangup sofia/external/sip:4503@97.75.182.83:59868 [CS_CONSUME_MEDIA] [NO_ANSWER] 2011-11-04 15:45:05.000941 [NOTICE] switch_ivr_originate.c:2459 Cannot create outgoing channel of type [user] cause: [NO_ANSWER] 2011-11-04 15:45:05.000941 [NOTICE] switch_core_session.c:1395 Session 606 (sofia/external/sip:4503@97.75.182.83:59868) Ended 2011-11-04 15:45:05.000941 [NOTICE] switch_core_session.c:1397 Close Channel sofia/external/sip:4503@97.75.182.83:59868 [CS_DESTROY] --- NO ANSWER TIMEOUT REPEATS --- 2011-11-04 15:45:05.060918 [NOTICE] switch_channel.c:915 New Channel sofia/external/sip:4503@97.75.182.83:59868[41be0a9c-072e-11e1-a647-2d222f47338b] --- AGENT STATE: 'Receiving' --- --- TRIES AGAIN --- 2011-11-04 15:45:05.081035 [NOTICE] sofia.c:5375 Ring-Ready sofia/external/ sip:4503@97.75.182.83:59868! 2011-11-04 15:45:15.001026 [NOTICE] switch_ivr_originate.c:3167 Hangup sofia/external/sip:4503@97.75.182.83:59868 [CS_CONSUME_MEDIA] [NO_ANSWER] 2011-11-04 15:45:15.001026 [NOTICE] switch_ivr_originate.c:2459 Cannot create outgoing channel of type [user] cause: [NO_ANSWER] 2011-11-04 15:45:15.001026 [NOTICE] switch_core_session.c:1395 Session 607 (sofia/external/sip:4503@97.75.182.83:59868) Ended 2011-11-04 15:45:15.001026 [NOTICE] switch_core_session.c:1397 Close Channel sofia/external/sip:4503@97.75.182.83:59868 [CS_DESTROY] --- LOOPS CONTINUALLY FOR 7 MINUTES --- .... --- TRIES AGAIN --- 2011-11-04 15:52:15.040968 [NOTICE] switch_channel.c:915 New Channel sofia/external/sip:4503@97.75.182.83:59868[4208943a-072f-11e1-a72a-2d222f47338b] --- AGENT STATE: 'Receiving' --- 2011-11-04 15:52:15.080896 [NOTICE] sofia.c:5375 Ring-Ready sofia/external/ sip:4503@97.75.182.83:59868! 2011-11-04 15:52:25.000919 [NOTICE] switch_ivr_originate.c:3167 Hangup sofia/external/sip:4503@97.75.182.83:59868 [CS_CONSUME_MEDIA] [NO_ANSWER] 2011-11-04 15:52:25.000919 [NOTICE] switch_ivr_originate.c:2459 Cannot create outgoing channel of type [user] cause: [NO_ANSWER] 2011-11-04 15:52:25.000919 [NOTICE] switch_core_session.c:1395 Session 653 (sofia/external/sip:4503@97.75.182.83:59868) Ended 2011-11-04 15:52:25.000919 [NOTICE] switch_core_session.c:1397 Close Channel sofia/external/sip:4503@97.75.182.83:59868 [CS_DESTROY] 2011-11-04 15:52:28.140959 [NOTICE] switch_ivr_bridge.c:658 Hangup sofia/external/sip:4503@97.75.182.83:59868 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] --- AGENT ACTUALLY HANGS UP ON THE REAL CALL --- --- AGENT STATE: 'Waiting' --- 2011-11-04 15:52:28.180984 [NOTICE] switch_core_session.c:1395 Session 604 (sofia/external/sip:4503@97.75.182.83:59868) Ended 2011-11-04 15:52:28.180984 [NOTICE] switch_core_session.c:1397 Close Channel sofia/external/sip:4503@97.75.182.83:59868 [CS_DESTROY] --- THE LOOP IS ENDED BECAUSE THE MEMBER IS NO LONGER IN THE SYSTEM --- While the agent state keeps changing the agent is actually in a call with the caller this entire time. Events are firing every 10 seconds informing me this agent had a "No Answer" on an inbound call when they are actually talking to the caller. Here are some settings in case you want to know: *freeswitch@internal> callcenter_config queue list* name|strategy|moh_sound|time_base_score|tier_rules_apply|tier_rule_wait_second|tier_rule_wait_multiply_level|tier_rule_no_agent_no_wait|discard_abandoned_after|abandoned_resume_allowed|max_wait_time|max_wait_time_with_no_agent|max_wait_time_with_no_agent_time_reached|record_template *callcenter(a)sip.**<hidden>** .com|longest-idle-agent|local_stream://moh|system|false|300|true|false|60|false|0|0|5|/usr/local/freeswitch/recordings/${strftime(%Y-%m-%d-%H-%M-%S)}.${destination_number}.${caller_id_number}.${uuid}.wav * +OK *freeswitch@internal> callcenter_config agent list* name|system|uuid|type|contact|status|state|max_no_answer|wrap_up_time|reject_delay_time|busy_delay_time|no_answer_delay_time|last_bridge_start|last_bridge_end|last_offered_call|last_status_change|no_answer_count|calls_answered|talk_time|ready_time 7003@sip. <hidden>.com|single_box|6d3dabc4-0732-11e1-a7c6-2d222f47338b|callback|[call_timeout=10]user/4513@sip.<hidden>.com|Available|In a queue call|0|30|0|0|0|1320444899|1320444795|1320444896|1320443529|0|4|1129|0 *7004@sip.<hidden>.com|single_box||callback|[call_timeout=10]user/4503@sip. <hidden>.com|Available|Waiting|0|30|0|0|0|1320445006|1320445099|1320445000|1320434600|0|24|4254|0 * +OK And here are the queue settings in XML. Same as above but easier to read. <queues> <queue name="callcenter@sip.<hidden>.com"> <param name="strategy" value="longest-idle-agent"/> <param name="moh-sound" value="$${hold_music}"/> <param name="record-template" value="$${base_dir}/recordings/${strftime(%Y-%m-%d-%H-%M-%S)}.${destination_number}.${caller_id_number}.${uuid}.wav"/> <param name="time-base-score" value="system"/> <param name="max-wait-time" value="0"/> <param name="max-wait-time-with-no-agent" value="0"/> <param name="max-wait-time-with-no-agent-time-reached" value="5"/> <param name="tier-rules-apply" value="false"/> <param name="tier-rule-wait-second" value="300"/> <param name="tier-rule-wait-multiply-level" value="true"/> <param name="tier-rule-no-agent-no-wait" value="false"/> <param name="discard-abandoned-after" value="60"/> <param name="abandoned-resume-allowed" value="false"/> </queue> </queues> So to summarize, most calls work exactly as expected, but a percentage of our total call volume is currently having this issue. We are taking around 200 calls per day at the moment but we plan to take many more in the coming month. We have another system with an IVR in front of this and it only hands callers of a particular ivr option to this server. I should also note that we are running Dual Quad Core Intel Xeon's, 8GB DDR2, Suse 11.4 - x86_64, Mono 1.8.2. We are also testing 3 Polycom 450's and 5 X-Lite SoftPhones with our agents. Any suggestions or help would be appreciated. Thanks! Ryan H

1 0

Heads up: autogenerated file "build/config/config.sub" removed
by Stefan Knoblich 04 Nov '11

04 Nov '11

Hi list, i just committed a patch that removes one of the autogenerated files from the git tree, so if you get a message like: "build/config/config.sub: No such file or directory" after your next update, try 1) first and if that doesn't fix it, try 2): 1) automake --add-missing 2) The good old, slow and safe: ./bootstrap.sh Unfortunately, this is one of the few files that autotools can't re-create on its own on the next "make" run. Sorry for the inconvenience, stkn -- ------------------------------------------------------------------------------- Stefan Knoblich | Web: http://www.axsentis.de/ axsentis GmbH | http://oss.axsentis.de/ Eupener Str. 74, 50933 Koeln, Germany | Amtsgericht Koeln: HR B 56238 | Email: s.knoblich(a)axsentis.de UST-ID: DE244977565 | JID: s.knoblich(a)jabber.axsentis.de ------------------------------------------------------------------------------- Web: http://stkn.techmage.de/ Email: stkn(a)freeswitch.org IRC: #freeswitch-de @ irc.freenode.net

2 3

P-Asserted-Identity: "Outbound Call" on internal calls
by Erik Dekkers 04 Nov '11

04 Nov '11

Hey list, Recently (at least since two days) freeswitch is sending UPDATE messages with P-Asserted-Identity: "Outbound Call" <sip:NUMBER@DOMAIN> after a call is established on the callers phone. It looks like it’s not updating the callee’s name with this UPDATE message since the callers name is displayed correctly. This is the dialplan I’m using for internal call routing: <extension name="Local_Extension_SIP"> <condition field="destination_number" expression="^([1-4]\d{2})$"> <action application="bridge" data="user/$1"/> </condition> </extension> Full debug + siptrace: http://pastebin.freeswitch.org/17568 If there’s anything I can test please let me know Regards, Erik Dekkers (wvds-nl) [cid:image78e1b4.JPG@fcaf16f0.48a24e65] ABC Westland 555 Tel: +31 174 245 727 P.O. Box 90 Fax: +31 174 248 739 2685 ZH Poeldijk Mob: +31 624 423 009 www.wvds.nl<http://www.wvds.nl/> erik.dekkers(a)wvds.nl<mailto:erik.dekkers@wvds.nl> ________________________________ Bezoek ons op de Horti Fair / Visit us at the Horti Fair 1-4 november 2011 in Amsterdam, stand 11.0302. [cid:image8e900c.JPG@7fc3c225.4aa01c61]<https://form.n200.com/?s=1dvsast0dsqul&c=2quptgqmwol3s&ac=NTWO000229FIK> ________________________________ Wilk van der Sande is gecertificeerd Philips Led Horti Partner. DISCLAIMER Op alle offertes van, opdrachten aan en overeenkomsten met Bosch Inveka B.V. en Wilk van der Sande B.V. zijn de AVAG-verkoopvoorwaarden van toepassing All our quotations, all orders placed and all contracts concluded with Bosch Inveka B.V. and Wilk van der Sande B.V. are subject to the AVAG-Conditions.

3 4

How to check if user is behind NAT in a programmatic way?
by Mateusz Bartczak 04 Nov '11

04 Nov '11

Hello I would like to allow direct p2p media (bypassing FS) between registered SIP users, but only if one of them is not behind NAT. I can't force bypass_media=true for all calls, because many of users are behind NAT and this will break communication. I only want to bypass_media for users where it's possible. Is there any way of checking this in a programmatic way? I've checked call variables, SIP registration info from database but I can't find any related variable. Any suggestions?

2 1

Newbie
by Jacob.E.Miles＠L-3Com.com 04 Nov '11

04 Nov '11

I am new to FreeSWITCH, testing to make sure it has all the functionality we need, so I have a few questions I would like to ask. I am coming from using Asterisk PBX and some Cisco Call Manager. As you see my question below you will realize we are currently using SCCP (skinny) based phones, I am trying to talk out customer into moving to SIP phones but no luck yet. 1) Is there good SCCP (skinny) support? 2) If so, which phones (79XX)? 3) Is there Asterisk AMI type messaging to control SCCP phones? Jacob Miles Software Engineer III L-3 Communications - Integrated Systems Greenville Jacob.E.Miles(a)L-3Com.com 903.457.4422

3 3

Bad Contact Header
by Dip Mehta 04 Nov '11

04 Nov '11

Hi , I am trying to register x-lite 4.1 on freeswitch. And i am using the default extensions : 1003 and password 1234 However i am unable to get this registered and get the following error on the console. Output of nano /usr/local/freeswitch/conf/directory/default/1003.xml <include> <user id="1003"> <params> <param name="password" value="$${default_password}"/> <param name="vm-password" value="1003"/> </params> <variables> <variable name="toll_allow" value="domestic,international,local"/> <variable name="accountcode" value="*1003*"/> <variable name="user_context" value="default"/> <variable name="effective_caller_id_name" value="*Extension 1003*"/> <variable name="effective_caller_id_number" value="1003"/> <variable name="outbound_caller_id_name" value="$${outbound_caller_name}"/> <variable name="outbound_caller_id_number" value="$${outbound_caller_id}"/> <variable name="callgroup" value="techsupport"/> </variables> </user> </include> recv 572 bytes from udp/[122.176.XX.XX:]:13334 at 16:18:23.774556: ------------------------------------------------------------------------ REGISTER sip:199.167.XX.XX: SIP/2.0 Via: SIP/2.0/UDP 122.176.144.226:13334 ;branch=z9hG4bK-d8754z-9ba4ee0f5e6ae5a7-1---d8754z-;rport Max-Forwards: 70 Contact: <sip:1003@122.176.XX.XX:13334;rinstance=cd614363113a396f> To: "Extension 1003"<sip:1003@199.167..XX.XX> From: "Extension 1003"<sip:1003@199.167..XX.XX>;tag=77bad5c7 Call-ID: MTRmNzQ3MDUyMTM4MGNhOGUzZjdlNWYyNWRlZDNjNmQ. CSeq: 1 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO User-Agent: X-Lite 4 release 4.1 stamp 63214 Content-Length: 0 ------------------------------------------------------------------------ send 715 bytes to udp/[122.176.XX.XX:]:13334 at 16:18:23.775265: ------------------------------------------------------------------------ * SIP/2.0 401 Unauthorized* Via: SIP/2.0/UDP 122.176.144.226:13334 ;branch=z9hG4bK-d8754z-9ba4ee0f5e6ae5a7-1---d8754z-;rport=13334 From: "Extension 1003"<sip:1003@199.167..XX.XX>;tag=77bad5c7 To: "Extension 1003" <sip:1003@199.167..XX.XX>;tag=gpjScQ0eXXj6K Call-ID: MTRmNzQ3MDUyMTM4MGNhOGUzZjdlNWYyNWRlZDNjNmQ. CSeq: 1 REGISTER User-Agent: FreeSWITCH-mod_sofia/1.0.head-git-7b10506 2011-10-26 08-45-04 -0500 Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces WWW-Authenticate: Digest realm="199.167.145.102", nonce="740c2df0-0637-11e1-9e6e-6b39c14e0024", algorithm=MD5, qop="auth" Content-Length: 0 send 340 bytes to udp/[122.176.144.226]:13293 at 16:10:09.247665: ------------------------------------------------------------------------ * SIP/2.0 400 Bad Contact Header* Via: SIP/2.0/UDP 122.176.XX.XX:;branch=z9hG4bK-d8754z-7c90ddcd2100ff77-1---d8754z-;rport=13293 From: "1003" <sip:1003@199.167.XX.XX>;tag=d09ca84c To: "1003" <sip:1003@199.167.XX.XX>;tag=DU6e75D452FeH Call-ID: YjM3NWZiZjdkMzMwNTJhMjZkNmRhMWFkMGVkZGM1ZWI. CSeq: 4 REGISTER Content-Length: Errors suggest this to be authentication issue. However i am not able to resolve this. Please advice. Regards Dip

2 1

Refer-To does not allow to add 'to-tag' and 'from-tag'
by Mariusz Czulada 04 Nov '11

04 Nov '11

Hi All, I have a problem with my FSW instance (version 1.0.7 on RHER 5.6 64bit). Freeswitch does not allow to pass 'to-tag' or 'from-tag' in addition to "Replaces=" while deflecting a call. This action works: <action application="deflect" data="sip:conf_t0_2@10.79.21.108?Replaces={sip_call_id}" /> The session I try to deflect is set from Broadworks 16 via ALU IMS core (ISC 5450). Our supplier claims that to successfully handle REFER from FSW, Refer-To header should include also 'to-tag' and 'from-tag'. Example I got looks like this: Refer-To: sip:conf_t0_2@10.79.21.108?Replaces=1387BD211B831526@172.16.10.114;to-tag=1728507150-1263982355499;from-tag=xUDOxkDMyYD But when I set action to: <action application="deflect" data="sip:conf_t0_2@10.79.21.108?Replaces=${sip_call_id};to-tag=${sip_to_tag};from-tag=${sip_from_tag}" /> in cosole log I can find: EXECUTE sofia/internal/+48399500015(a)neofon.tp.pl deflect(sip:conf_t0_2@10.79.21.108?Replaces=BW131151222041111-105561828@10.8.80.100;from-tag=139928099-1320408711222-) and FSW freezes until other side issues BYE. Is this a problem on my side (like different action is needed or some vars should be set)? Or is it related to FSW/mod_sofia (source)? TIA for your help or suggesions, Mariusz

1 1

QoS
by Hynek Cihlar 04 Nov '11

04 Nov '11

Hi all, here's an excellent article on Linux QoS http://wiki.linuxwall.info/doku.php/en:ressources:dossiers:networking:traff… . Hynek

2 1

Help with Using Mod_AVMD
by Dave 04 Nov '11

04 Nov '11

Hello, I am creating an external application that uses FreeSWITCH ESL to initiate an outbound call and then run a LUA script. Within the LUA script I am executing AVMD. I want to see if its a human or voicemail on the other end. The outbound call is made and the LUA script executes. Currently I would just like to see a consoleLog entry made by the onInput function in the LUA script, but that function does not seem to be called. AVMD starts the file is streamed then AVMD stops. As shown in the log. I think I'm missing something. Dave Goodwin Pertinent FreeSWITCH Log Info: ------------------------------------------------------------------------------------------------------- EXECUTE sofia/internal/7202126254(a)xxx.xx.xxx.xx avmd(start) 2011-11-03 11:05:27.291678 [DEBUG] switch_core_media_bug.c:360 Attaching BUG to sofia/internal/7202126254(a)xxx.xx.xxx.xx 2011-11-03 11:05:28.311249 [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16@8000hz 1 channels 20ms 2011-11-03 11:05:28.350313 [DEBUG] switch_rtp.c:3082 Correct ip/port confirmed. 2011-11-03 11:05:40.235535 [DEBUG] switch_ivr_play_say.c:1649 done playing file EXECUTE sofia/internal/7202126254(a)xxx.xx.xxx.xx avmd(stop) 2011-11-03 11:05:40.237488 [DEBUG] switch_core_media_bug.c:467 Removing BUG from sofia/internal/7202126254(a)xxx.xx.xxx.xx Lua Script: ------------------------------------------------------------------------------------------------------- function onInput(session, type, obj) if type == "dtmf" and obj['digit'] == '1' and human_detected == false then human_detected = true freeswitch.consoleLog("INFO","Human Detected\n") return "break" end if type == "event" and voicemail_detected == false then voicemail_detected = true freeswitch.consoleLog("INFO","Voicemail Detected\n") return "break" else freeswitch.consoleLog("INFO","Nothing Happened\n") return "break" end end if session:ready() then --- the call has been answered. session:setInputCallback("onInput") session:sleep(1000) session:execute("avmd","start") session:sleep(1000) session:streamFile("ivr/" .. wav_file) session:execute("avmd","stop") session:sleep(1000) fileName = "Completed " .. tostring(call_from_num) .. os.time() .. tostring(call_to_num) freeswitch.consoleLog("INFO","FileName = " .. fileName .. "\n") local myfile, ErrStr = io.open("log/outcall/" .. fileName,"w") if myfile then myfile:write(call_from_num .. "|", call_to_num .. "|" , os.date()) myfile:flush() myfile:close() else freeswitch.consoleLog("INFO","Error writing File = '" .. ErrStr .. "'\n") end --if end --if

2 1

Audio clipping in mod_conference
by Roland Hänel 03 Nov '11

03 Nov '11

Hi, During thorough testing of our conference solution which is based on FreeSwitch / mod_conference, we noticed some 'audio clipping' during the conference. Typically, if a conference member didn't have audio for some time and then begins to speak, the first syllable will be clipped and cannot be heard by the other conference participants. We ruled out a number of possible causes, and finally discovered that the energy level detection algorith seems to be the source of the problem. Setting the energy level for all participants to "0" heals the problem (of course now the noise might be higher since every participant's noise contributes to the total noise), but this setting is not very useful since the "talking detection" seems to rely on the same level, i.e. with energy "0", all members are marked talking all the time (which effectively renders the "talking" display useless). Is there any chance to tune the energy level detection time (!), or to de-couple talking detection an energy level detection in FreeSwitch? Best regards, Roland

4 14