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

[nik600]

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