Sponsor: VoiceMeUp - Corporate & Wholesale VoIP Services

VoIP Mailing List Archives
Mailing list archives for the VoIP community
 SearchSearch 

[Freeswitch-users] fifo taking > 5 seconds to bridge calls


 
Post new topic   Reply to topic    VoIP Mailing List Archives Forum Index -> freeSWITCH Users
View previous topic :: View next topic  
Author Message
mattdfong at gmail.com
Guest





PostPosted: Sun Apr 19, 2009 12:10 am    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

I tried using fifo in an environment with about 9 agents last week, but ran into some issues, that I'm trying to piece together. The system is setup on a new ubuntu 64-bit machine and it should be plenty fast to handle this load. The delay does not occur when testing with a single agent...so it's hard for me to replicate on my vmware-based development machine, but thought I'd toss this out to the community to see if anyone has any suggestions

I'm using fifo in the reverse sense, in that my "agents" are put into a fifo, and an outbound call (a "contact") take an agent out of the fifo once the call is answered. I did this because it is an outbound call center, not an inbound one, and in reality I have agents waiting for a contact...not callers waiting for an agent.


Anyway, on about all my calls the logs indicate (and the agents I'm working with confirm), it appears to be taking > 5 seconds to actually bridge a call once the outbound "contact" channel is answered by FS and the channel takes an agent out of the fifo. I've attached a snipet of the fs.log and the dialplan below. But from timestamps it appears the delay is caused somewhere between these EXECUTE commands


EXECUTE sofia/external/1XXX4951027 set_user(default@)
EXECUTE sofia/external/1XXX4951027 db(insert/-spymap/1800XXX8234/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/1800XXX8234/1990)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/global/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 answer()
EXECUTE sofia/external/1XXX4951027 set(fifo_caller_consumer_import=hh_stomp,hh_user)


when I looked at this..nothing pops out as out of the ordinary, that should cause >5 seconds delay. Your thoughts please ..


Thanks.


--matt


Full Log:
2009-04-14 16:35:18 [DEBUG] mod_sofia.c:173 sofia_on_execute() sofia/external/1XXX4951027 SOFIA EXECUTE
2009-04-14 16:35:18 [DEBUG] switch_core_state_machine.c:151 switch_core_standard_on_execute() sofia/external/1XXX4951027 Standard EXECUTE
EXECUTE sofia/external/1XXX4951027 set(open=true)
2009-04-14 16:35:18 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [open]=[true]
EXECUTE sofia/external/1XXX4951027 set(use_profile=default)
2009-04-14 16:35:18 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [use_profile]=[default]
EXECUTE sofia/external/1XXX4951027 set_user(default@)
EXECUTE sofia/external/1XXX4951027 db(insert/-spymap/1800XXX8234/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/1800XXX8234/1990)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/global/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 answer()
EXECUTE sofia/external/1XXX4951027 set(fifo_caller_consumer_import=hh_stomp,hh_user)
2009-04-14 16:35:42 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [fifo_caller_consumer_import]=[hh_stomp,hh_user]
EXECUTE sofia/external/1XXX4951027 set(fifo_consumer_exit_key=5)
2009-04-14 16:35:42 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [fifo_consumer_exit_key]=[5]
EXECUTE sofia/external/1XXX4951027 fifo(2 out wait undef undef)
2009-04-14 16:35:42 [DEBUG] switch_channel.c:182 switch_channel_audio_sync() sofia/external/1XXX4951027 receive message [AUDIO_SYNC]
2009-04-14 16:35:43 [DEBUG] switch_ivr_bridge.c:911 switch_ivr_multi_threaded_bridge() sofia/external/1XXX4951027 receive message [BRIDGE]




Dialplan that Inbound "Contact" Calls are placed in
    <extension name="1990"><!--ag_incoming_q-->
      <condition field="destination_number" expression="^1990$">
        <action application="answer"/>
        <action application="set" data="fifo_caller_consumer_import=hh_stomp,hh_user"/>
        <action application="set" data="fifo_consumer_exit_key=5"/>
        <action application="fifo" data="${ag_qcall} out wait undef ${ag_dnc_msg}"/>
      </condition>
    </extension>





Dialplan for "my" Agent Calls:
    <extension name="1992"><!--ag_wait-->
      <condition field="destination_number" expression="^1992$">
        <action application="playback" data="hh/hh-doorbell.wav"/>
        <action application="fifo" data="${ag_qcall} in currency/dollars.wav $${hold_music}"/>
      </condition>
    </extension>
Back to top
anthony.minessale at g...
Guest





PostPosted: Mon Apr 20, 2009 8:18 am    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

it's probably the designed wrapup time for agents.

fifo_consumer_wrapup_time var controls this wait time in milliseconds and the default is 5 sec.



On Sun, Apr 19, 2009 at 12:08 AM, Matthew Fong <mattdfong@gmail.com (mattdfong@gmail.com)> wrote:
Quote:
I tried using fifo in an environment with about 9 agents last week, but ran into some issues, that I'm trying to piece together. The system is setup on a new ubuntu 64-bit machine and it should be plenty fast to handle this load. The delay does not occur when testing with a single agent...so it's hard for me to replicate on my vmware-based development machine, but thought I'd toss this out to the community to see if anyone has any suggestions

I'm using fifo in the reverse sense, in that my "agents" are put into a fifo, and an outbound call (a "contact") take an agent out of the fifo once the call is answered. I did this because it is an outbound call center, not an inbound one, and in reality I have agents waiting for a contact...not callers waiting for an agent.


Anyway, on about all my calls the logs indicate (and the agents I'm working with confirm), it appears to be taking > 5 seconds to actually bridge a call once the outbound "contact" channel is answered by FS and the channel takes an agent out of the fifo. I've attached a snipet of the fs.log and the dialplan below. But from timestamps it appears the delay is caused somewhere between these EXECUTE commands


EXECUTE sofia/external/1XXX4951027 set_user(default@)
EXECUTE sofia/external/1XXX4951027 db(insert/-spymap/1800XXX8234/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/1800XXX8234/1990)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/global/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 answer()
EXECUTE sofia/external/1XXX4951027 set(fifo_caller_consumer_import=hh_stomp,hh_user)


when I looked at this..nothing pops out as out of the ordinary, that should cause >5 seconds delay. Your thoughts please ..


Thanks.


--matt


Full Log:
2009-04-14 16:35:18 [DEBUG] mod_sofia.c:173 sofia_on_execute() sofia/external/1XXX4951027 SOFIA EXECUTE
2009-04-14 16:35:18 [DEBUG] switch_core_state_machine.c:151 switch_core_standard_on_execute() sofia/external/1XXX4951027 Standard EXECUTE
EXECUTE sofia/external/1XXX4951027 set(open=true)
2009-04-14 16:35:18 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [open]=[true]
EXECUTE sofia/external/1XXX4951027 set(use_profile=default)
2009-04-14 16:35:18 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [use_profile]=[default]
EXECUTE sofia/external/1XXX4951027 set_user(default@)
EXECUTE sofia/external/1XXX4951027 db(insert/-spymap/1800XXX8234/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/1800XXX8234/1990)
EXECUTE sofia/external/1XXX4951027 db(insert/-last_dial/global/aa763964-2933-11de-bbea-318f8f194b60)
EXECUTE sofia/external/1XXX4951027 answer()
EXECUTE sofia/external/1XXX4951027 set(fifo_caller_consumer_import=hh_stomp,hh_user)
2009-04-14 16:35:42 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [fifo_caller_consumer_import]=[hh_stomp,hh_user]
EXECUTE sofia/external/1XXX4951027 set(fifo_consumer_exit_key=5)
2009-04-14 16:35:42 [DEBUG] mod_dptools.c:748 set_function() sofia/external/1XXX4951027 SET [fifo_consumer_exit_key]=[5]
EXECUTE sofia/external/1XXX4951027 fifo(2 out wait undef undef)
2009-04-14 16:35:42 [DEBUG] switch_channel.c:182 switch_channel_audio_sync() sofia/external/1XXX4951027 receive message [AUDIO_SYNC]
2009-04-14 16:35:43 [DEBUG] switch_ivr_bridge.c:911 switch_ivr_multi_threaded_bridge() sofia/external/1XXX4951027 receive message [BRIDGE]




Dialplan that Inbound "Contact" Calls are placed in
    <extension name="1990"><!--ag_incoming_q-->
      <condition field="destination_number" expression="^1990$">
        <action application="answer"/>
        <action application="set" data="fifo_caller_consumer_import=hh_stomp,hh_user"/>
        <action application="set" data="fifo_consumer_exit_key=5"/>
        <action application="fifo" data="${ag_qcall} out wait undef ${ag_dnc_msg}"/>
      </condition>
    </extension>





Dialplan for "my" Agent Calls:
    <extension name="1992"><!--ag_wait-->
      <condition field="destination_number" expression="^1992$">
        <action application="playback" data="hh/hh-doorbell.wav"/>
        <action application="fifo" data="${ag_qcall} in currency/dollars.wav $${hold_music}"/>
      </condition>
    </extension>






_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (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




--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/

AIM: anthm
MSN:anthony_minessale@hotmail.com ([email]MSN%3Aanthony_minessale@hotmail.com[/email])
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com ([email]PAYPAL%3Aanthony.minessale@gmail.com[/email])
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org ([email]sip%3A888@conference.freeswitch.org[/email])
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org ([email]googletalk%3Aconf%2B888@conference.freeswitch.org[/email])
pstn:213-799-1400
Back to top
brian at freeswitch.org
Guest





PostPosted: Mon Apr 20, 2009 9:29 pm    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

Update rev. 13094 makes it not do wrap up on nowait.

/b

On Apr 20, 2009, at 8:02 AM, Anthony Minessale wrote:
Quote:
it's probably the designed wrapup time for agents.

fifo_consumer_wrapup_time var controls this wait time in milliseconds and the default is 5 sec.




Brian West
brian@freeswitch.org (brian@freeswitch.org)



-- Meet us at ClueCon! http://www.cluecon.com
Back to top
mattdfong at gmail.com
Guest





PostPosted: Mon Apr 20, 2009 9:54 pm    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

Thanks I'll check it out.

One more quick but related question.


Is there ever an instance when the audio is BRIDGED before the BRIDGE event is fired. Could this fifo issue have bridged audio immediately, but somehow withheld the bridge event from being fired for 5 seconds? A few of my callers were reporting they could hear the Contact, but the BRIDGE event (and my subsequent programming to popup the contact information on screen) was being delayed 5 seconds.


thanks !


--matt

On Tue, Apr 21, 2009 at 9:23 AM, Brian West <brian@freeswitch.org (brian@freeswitch.org)> wrote:
Quote:
Update rev. 13094 makes it not do wrap up on nowait.

/b

On Apr 20, 2009, at 8:02 AM, Anthony Minessale wrote:

Quote:
it's probably the designed wrapup time for agents.

fifo_consumer_wrapup_time var controls this wait time in milliseconds and the default is 5 sec.





Brian West
brian@freeswitch.org (brian@freeswitch.org)



-- Meet us at ClueCon!  http://www.cluecon.com











_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (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

Back to top
mattdfong at gmail.com
Guest





PostPosted: Tue Apr 21, 2009 12:49 am    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

Hi Anthony & Brian,

I have not yet run r13094 in my production environment with live agents, so I cannot give you feedback (but hopefully I'll get a chance to put it into a live system in a few days)


but I re-reviewed the logs I had and I'm not convinced the issue I was having of a delayed bridge was related to the default fifo_consumer_wrapup_time. The reason is:


1) it's not a consistent 5 second delay in bridging...sometimes it's a 2 second delay, sometimes it's as high as a 38 second delay (I can provide logs if needed)
2) In my setup, each consumer (channel that executes fifo out) is always a "fresh/new" channel. My "consumers" do not get recycled, instead they get hungup at the end of the call (while my fifo ins get transferred to another extension, which puts them back into fifo in)


Are these problems still consistent with the issues that were fixed in r13094? I'm a little hesitant to put the system back in a live environment since the fix and diagnosis aren't 100% compatible. As always tho, thanks for the really quick fix and reply. Awesome telephone framework.


--matt

On Tue, Apr 21, 2009 at 9:53 AM, Matthew Fong <mattdfong@gmail.com (mattdfong@gmail.com)> wrote:
Quote:
Thanks I'll check it out.

One more quick but related question.


Is there ever an instance when the audio is BRIDGED before the BRIDGE event is fired. Could this fifo issue have bridged audio immediately, but somehow withheld the bridge event from being fired for 5 seconds? A few of my callers were reporting they could hear the Contact, but the BRIDGE event (and my subsequent programming to popup the contact information on screen) was being delayed 5 seconds.


thanks !


--matt


On Tue, Apr 21, 2009 at 9:23 AM, Brian West <brian@freeswitch.org (brian@freeswitch.org)> wrote:


Quote:

Update rev. 13094 makes it not do wrap up on nowait.

/b

On Apr 20, 2009, at 8:02 AM, Anthony Minessale wrote:

Quote:
it's probably the designed wrapup time for agents.

fifo_consumer_wrapup_time var controls this wait time in milliseconds and the default is 5 sec.





Brian West
brian@freeswitch.org (brian@freeswitch.org)



-- Meet us at ClueCon!  http://www.cluecon.com













_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (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





Back to top
anthony.minessale at g...
Guest





PostPosted: Tue Apr 21, 2009 1:10 pm    Post subject: [Freeswitch-users] fifo taking > 5 seconds to bridge call Reply with quote

There was never really a problem it was just a minor behavioral change.

If you have it in production and need such careful support,
you should send an email to consulting@freeswitch.org (consulting@freeswitch.org) and sign up for a commercial support contract.



On Tue, Apr 21, 2009 at 12:37 AM, Matthew Fong <mattdfong@gmail.com (mattdfong@gmail.com)> wrote:
Quote:
Hi Anthony & Brian,

I have not yet run r13094 in my production environment with live agents, so I cannot give you feedback (but hopefully I'll get a chance to put it into a live system in a few days)


but I re-reviewed the logs I had and I'm not convinced the issue I was having of a delayed bridge was related to the default fifo_consumer_wrapup_time. The reason is:


1) it's not a consistent 5 second delay in bridging...sometimes it's a 2 second delay, sometimes it's as high as a 38 second delay (I can provide logs if needed)
2) In my setup, each consumer (channel that executes fifo out) is always a "fresh/new" channel. My "consumers" do not get recycled, instead they get hungup at the end of the call (while my fifo ins get transferred to another extension, which puts them back into fifo in)


Are these problems still consistent with the issues that were fixed in r13094? I'm a little hesitant to put the system back in a live environment since the fix and diagnosis aren't 100% compatible. As always tho, thanks for the really quick fix and reply. Awesome telephone framework.


--matt


On Tue, Apr 21, 2009 at 9:53 AM, Matthew Fong <mattdfong@gmail.com (mattdfong@gmail.com)> wrote:
Quote:
Thanks I'll check it out.

One more quick but related question.


Is there ever an instance when the audio is BRIDGED before the BRIDGE event is fired. Could this fifo issue have bridged audio immediately, but somehow withheld the bridge event from being fired for 5 seconds? A few of my callers were reporting they could hear the Contact, but the BRIDGE event (and my subsequent programming to popup the contact information on screen) was being delayed 5 seconds.


thanks !


--matt


On Tue, Apr 21, 2009 at 9:23 AM, Brian West <brian@freeswitch.org (brian@freeswitch.org)> wrote:


Quote:

Update rev. 13094 makes it not do wrap up on nowait.

/b

On Apr 20, 2009, at 8:02 AM, Anthony Minessale wrote:

Quote:
it's probably the designed wrapup time for agents.

fifo_consumer_wrapup_time var controls this wait time in milliseconds and the default is 5 sec.





Brian West
brian@freeswitch.org (brian@freeswitch.org)



-- Meet us at ClueCon!  http://www.cluecon.com













_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (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











_______________________________________________
Freeswitch-users mailing list
Freeswitch-users@lists.freeswitch.org (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




--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/

AIM: anthm
MSN:anthony_minessale@hotmail.com ([email]MSN%3Aanthony_minessale@hotmail.com[/email])
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com ([email]PAYPAL%3Aanthony.minessale@gmail.com[/email])
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org ([email]sip%3A888@conference.freeswitch.org[/email])
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org ([email]googletalk%3Aconf%2B888@conference.freeswitch.org[/email])
pstn:213-799-1400
Back to top
Display posts from previous:   
Post new topic   Reply to topic    VoIP Mailing List Archives Forum Index -> freeSWITCH Users All times are GMT - 5 Hours
Page 1 of 1

 
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum


Powered by phpBB © 2001, 2005 phpBB Group

VoiceMeUp - Corporate & Wholesale VoIP Services