[asterisk-bugs] [Asterisk 0012680]: Extension state change random delay

[noreply at bugs.digium.com]

A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=12680 
====================================================================== 
Reported By:                corruptor
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   12680
Category:                   Channels/chan_sip/Subscriptions
Reproducibility:            always
Severity:                   minor
Priority:                   normal
Status:                     feedback
Asterisk Version:           1.4.20 
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             05-19-2008 05:04 CDT
Last Modified:              07-09-2008 12:34 CDT
====================================================================== 
Summary:                    Extension state change random delay
Description: 
asterisk-1.4.20-rc3
Grandstream GXP-2000 is registered as 1100 and subscribed to receive
changes about extensions states. 
SIP/1101 is Linksys 942
SIP/1102 is SjPhone.
I've also set notifyringing=yes.

1102 calls 1101. 1101 sends RINGING but asterisk sends NOTIFY to
Grandstream with 3 sec delay. You can see (log is attached) that RINGING
was at 19:11:27 and NOTIFY is sent at 19:11:30.
Later when 1101 answers you can see that device state is changed 9 seconds
after call have been established (19:11:50] DEBUG[24559] devicestate.c:
Changing state for SIP/1102-09551818 - state 4 (Invalid))
When call is over asterisk changes state for one of the extensions almost
instantly but there is 20 seconds delay for 1102 (19:12:20).
Please help to debug this problem.
====================================================================== 

---------------------------------------------------------------------- 
 jvandal - 07-09-08 12:34  
---------------------------------------------------------------------- 
Hi,

Just tested with r129434 (branches/1.4, no custom patches) and still have
delay problems :(

If I correctly read log, the notification has been queued at 19:33:44 and
updated at 19:34:05 ?


[Jul  9 19:33:44] DEBUG[18320] devicestate.c: Notification of state change
to be queued on device/channel SIP/1820
[Jul  9 19:33:44] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:47] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:49] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:51] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:53] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:56] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:33:58] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:34:01] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:34:03] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:34:05] DEBUG[18342] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:34:05] DEBUG[18315] chan_sip.c: Checking device state for peer
1820
[Jul  9 19:34:05] DEBUG[18315] devicestate.c: Changing state for SIP/1820
- state 1 (Not in use)
[Jul  9 19:34:05] DEBUG[18315] chan_sip.c: Checking device state for peer
1820


I currently have only 1 agent logged (AddQueueMember) and the status is
never updated on 'show queues'.

Strange that this problem isn't present on all our 'servers'. 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
07-09-08 12:34  jvandal        Note Added: 0089963                          
======================================================================