[asterisk-users] problem with ringinuse=no, queue members receive randomly two calls

[nik600]

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