nik600
2010-May-04 19:54 UTC
[asterisk-users] problem with ringinuse=no, queue members receive randomly two calls
Dear all on a debian amd64 i've installed (from source) asterisk 1.4.30 On the system we have in average 50 concurrent calls in queue and 40 sip members. I'm experiencing an apparently random problem: sometimes some users receive 2 calls from asterisk, apparently ignoring the ringinuse=no settings. It appears on users that are members of many queues As you can see from the log, the user goes in a status Ring+Inuse. Any idea? Why the call is still dispatched to the user if it is not in the "Not in use" status? Thanks to all in advance ************************* ************************* LOG (core debug and verbose set to 5) ************************* ************************* #grep PL1038 full [May 4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '6' (Ringing) [May 4 16:21:08] DEBUG[3035] devicestate.c: Notification of state change to be queued on device/channel SIP/PL1038 [May 4 16:21:08] DEBUG[3022] devicestate.c: No provider found, checking channel drivers for SIP - PL1038 [May 4 16:21:08] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 [May 4 16:21:08] DEBUG[3022] devicestate.c: Changing state for SIP/PL1038 - state 6 (Ringing) [May 4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '6' (Ringing) [May 4 16:21:08] VERBOSE[30453] logger.c: -- SIP/PL1038-00005f7d is ringing [May 4 16:21:08] DEBUG[3035] devicestate.c: Notification of state change to be queued on device/channel SIP/PL1038 [May 4 16:21:08] DEBUG[3022] devicestate.c: No provider found, checking channel drivers for SIP - PL1038 [May 4 16:21:08] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 [May 4 16:21:08] DEBUG[3022] devicestate.c: Changing state for SIP/PL1038 - state 6 (Ringing) [May 4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '6' (Ringing) [May 4 16:21:08] VERBOSE[30268] logger.c: -- SIP/PL1038-00005f7e is ringing [May 4 16:21:10] DEBUG[3035] chan_sip.c: T38 state changed to 0 on channel SIP/PL1038-00005f7e [May 4 16:21:10] DEBUG[3035] devicestate.c: Notification of state change to be queued on device/channel SIP/PL1038 [May 4 16:21:10] DEBUG[3035] chan_sip.c: build_route: Contact hop: <sip:PL1038 at 10.192.37.119> [May 4 16:21:10] DEBUG[30268] devicestate.c: Notification of state change to be queued on device/channel SIP/PL1038 [May 4 16:21:10] DEBUG[3022] devicestate.c: No provider found, checking channel drivers for SIP - PL1038 [May 4 16:21:10] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 [May 4 16:21:10] DEBUG[3022] devicestate.c: Changing state for SIP/PL1038 - state 7 (Ring+Inuse) [May 4 16:21:10] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '7' (Ring+Inuse) [May 4 16:21:10] DEBUG[3022] devicestate.c: No provider found, checking channel drivers for SIP - PL1038 [May 4 16:21:10] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 [May 4 16:21:10] DEBUG[3022] devicestate.c: Changing state for SIP/PL1038 - state 7 (Ring+Inuse) [May 4 16:21:10] VERBOSE[30268] logger.c: -- SIP/PL1038-00005f7e answered SIP/192.168.55.32-00005f59 [May 4 16:21:10] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '7' (Ring+Inuse) [May 4 16:21:14] VERBOSE[30268] logger.c: -- Native bridging SIP/192.168.55.32-00005f59 and SIP/PL1038-00005f7e [May 4 16:21:14] DEBUG[3035] chan_sip.c: T38 state changed to 0 on channel SIP/PL1038-00005f7e [May 4 16:21:14] DEBUG[3035] devicestate.c: Notification of state change to be queued on device/channel SIP/PL1038 [May 4 16:21:14] DEBUG[3035] chan_sip.c: T38 state changed to 0 on channel SIP/PL1038-00005f7e [May 4 16:21:14] DEBUG[3022] devicestate.c: No provider found, checking channel drivers for SIP - PL1038 [May 4 16:21:14] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 [May 4 16:21:14] DEBUG[3022] devicestate.c: Changing state for SIP/PL1038 - state 7 (Ring+Inuse) [May 4 16:21:14] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed to state '7' (Ring+Inuse) [May 4 16:21:15] DEBUG[29938] app_queue.c: Trying 'SIP/PL1038' with metric 0 [May 4 16:21:15] DEBUG[29938] app_queue.c: SIP/PL1038 in use, can't receive call [May 4 16:21:16] DEBUG[30097] app_queue.c: Trying 'SIP/PL1038' with metric 0 [May 4 16:21:16] DEBUG[30097] app_queue.c: SIP/PL1038 in use, can't receive call [ ************************* ************************* config ************************* ************************* sip users: [PL1039] context=mycontext callerid=PhoneLine1039 <1039> secret=pwd1039 type=peer host=dynamic call-limit=3 disallow=all allow=ulaw queues: [queue_1] weight=10 wrapuptime=0 strategy=leastrecent joinempty=no retry=0 autopause=yes setinterfacevar=yes eventwhencalled=yes eventmemberstatus=yes ringinuse=no member => SIP/PL1039 [queue_2] weight=10 wrapuptime=0 strategy=leastrecent joinempty=no retry=0 autopause=yes setinterfacevar=yes eventwhencalled=yes eventmemberstatus=yes ringinuse=no member => SIP/PL1039 [queue_3] weight=10 wrapuptime=0 strategy=leastrecent joinempty=no retry=0 autopause=yes setinterfacevar=yes eventwhencalled=yes eventmemberstatus=yes ringinuse=no member => SIP/PL1039 -- /*************/ nik600 http://www.kumbe.it
nik600
2010-May-06 09:54 UTC
[asterisk-users] problem with ringinuse=no, queue members receive randomly two calls
i get may debug messages like this: DEBUG[30684] channel.c: Internal timing is disabled (option_internal_timing=0 chan->timingfd=-1) Is because dahdi is not installed? Can this be a possible cause of this behaviour? On Tue, May 4, 2010 at 9:54 PM, nik600 <nik600 at gmail.com> wrote:> Dear all > > on a debian amd64 i've installed (from source) asterisk 1.4.30 > > On the system we have in average 50 concurrent calls in queue and 40 > sip members. > > I'm experiencing an apparently random problem: > sometimes some users receive 2 calls from asterisk, apparently > ignoring the ringinuse=no settings. > It appears on users that are members of many queues > > As you can see from the log, the user goes in a status Ring+Inuse. > > Any idea? > Why the call is still dispatched to the user if it is not in the "Not > in use" status? > > Thanks to all in advance > > ************************* > ************************* > LOG > (core debug and verbose set to 5) > ************************* > ************************* > #grep PL1038 full > [May ?4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '6' (Ringing) > [May ?4 16:21:08] DEBUG[3035] devicestate.c: Notification of state > change to be queued on device/channel SIP/PL1038 > [May ?4 16:21:08] DEBUG[3022] devicestate.c: No provider found, > checking channel drivers for SIP - PL1038 > [May ?4 16:21:08] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 > [May ?4 16:21:08] DEBUG[3022] devicestate.c: Changing state for > SIP/PL1038 - state 6 (Ringing) > [May ?4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '6' (Ringing) > [May ?4 16:21:08] VERBOSE[30453] logger.c: ? ? -- SIP/PL1038-00005f7d is ringing > [May ?4 16:21:08] DEBUG[3035] devicestate.c: Notification of state > change to be queued on device/channel SIP/PL1038 > [May ?4 16:21:08] DEBUG[3022] devicestate.c: No provider found, > checking channel drivers for SIP - PL1038 > [May ?4 16:21:08] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 > [May ?4 16:21:08] DEBUG[3022] devicestate.c: Changing state for > SIP/PL1038 - state 6 (Ringing) > [May ?4 16:21:08] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '6' (Ringing) > [May ?4 16:21:08] VERBOSE[30268] logger.c: ? ? -- SIP/PL1038-00005f7e is ringing > [May ?4 16:21:10] DEBUG[3035] chan_sip.c: T38 state changed to 0 on > channel SIP/PL1038-00005f7e > [May ?4 16:21:10] DEBUG[3035] devicestate.c: Notification of state > change to be queued on device/channel SIP/PL1038 > [May ?4 16:21:10] DEBUG[3035] chan_sip.c: build_route: Contact hop: > <sip:PL1038 at 10.192.37.119> > [May ?4 16:21:10] DEBUG[30268] devicestate.c: Notification of state > change to be queued on device/channel SIP/PL1038 > [May ?4 16:21:10] DEBUG[3022] devicestate.c: No provider found, > checking channel drivers for SIP - PL1038 > [May ?4 16:21:10] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 > [May ?4 16:21:10] DEBUG[3022] devicestate.c: Changing state for > SIP/PL1038 - state 7 (Ring+Inuse) > [May ?4 16:21:10] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '7' (Ring+Inuse) > [May ?4 16:21:10] DEBUG[3022] devicestate.c: No provider found, > checking channel drivers for SIP - PL1038 > [May ?4 16:21:10] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 > [May ?4 16:21:10] DEBUG[3022] devicestate.c: Changing state for > SIP/PL1038 - state 7 (Ring+Inuse) > [May ?4 16:21:10] VERBOSE[30268] logger.c: ? ? -- SIP/PL1038-00005f7e > answered SIP/192.168.55.32-00005f59 > [May ?4 16:21:10] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '7' (Ring+Inuse) > [May ?4 16:21:14] VERBOSE[30268] logger.c: ? ? -- Native bridging > SIP/192.168.55.32-00005f59 and SIP/PL1038-00005f7e > [May ?4 16:21:14] DEBUG[3035] chan_sip.c: T38 state changed to 0 on > channel SIP/PL1038-00005f7e > [May ?4 16:21:14] DEBUG[3035] devicestate.c: Notification of state > change to be queued on device/channel SIP/PL1038 > [May ?4 16:21:14] DEBUG[3035] chan_sip.c: T38 state changed to 0 on > channel SIP/PL1038-00005f7e > [May ?4 16:21:14] DEBUG[3022] devicestate.c: No provider found, > checking channel drivers for SIP - PL1038 > [May ?4 16:21:14] DEBUG[3022] chan_sip.c: Checking device state for peer PL1038 > [May ?4 16:21:14] DEBUG[3022] devicestate.c: Changing state for > SIP/PL1038 - state 7 (Ring+Inuse) > [May ?4 16:21:14] DEBUG[3034] app_queue.c: Device 'SIP/PL1038' changed > to state '7' (Ring+Inuse) > [May ?4 16:21:15] DEBUG[29938] app_queue.c: Trying 'SIP/PL1038' with metric 0 > [May ?4 16:21:15] DEBUG[29938] app_queue.c: SIP/PL1038 in use, can't > receive call > [May ?4 16:21:16] DEBUG[30097] app_queue.c: Trying 'SIP/PL1038' with metric 0 > [May ?4 16:21:16] DEBUG[30097] app_queue.c: SIP/PL1038 in use, can't > receive call > [ > > > ************************* > ************************* > config > ************************* > ************************* > > sip users: > [PL1039] > context=mycontext > callerid=PhoneLine1039 <1039> > secret=pwd1039 > type=peer > host=dynamic > call-limit=3 > disallow=all > allow=ulaw > > queues: > [queue_1] > weight=10 > wrapuptime=0 > strategy=leastrecent > joinempty=no > retry=0 > autopause=yes > setinterfacevar=yes > eventwhencalled=yes > eventmemberstatus=yes > ringinuse=no > > member => SIP/PL1039 > > [queue_2] > weight=10 > wrapuptime=0 > strategy=leastrecent > joinempty=no > retry=0 > autopause=yes > setinterfacevar=yes > eventwhencalled=yes > eventmemberstatus=yes > ringinuse=no > > member => SIP/PL1039 > > > [queue_3] > weight=10 > wrapuptime=0 > strategy=leastrecent > joinempty=no > retry=0 > autopause=yes > setinterfacevar=yes > eventwhencalled=yes > eventmemberstatus=yes > ringinuse=no > > member => SIP/PL1039 > > > > -- > /*************/ > nik600 > http://www.kumbe.it >-- /*************/ nik600 http://www.kumbe.it