[Home]

Summary:ASTERISK-14220: after transfer negotiation, releasing doesnt work , both channels are still up....forever
Reporter:oxymoron (oxymoron)Labels:
Date Opened:2009-05-28 14:42:50Date Closed:2009-06-02 12:58:09
Priority:BlockerRegression?No
Status:Closed/CompleteComponents:Channels/chan_iax2
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:when calling internaly on the same network between two peers everything works but when the call ends initiating channels are still active (Up) in asterisk.
They are active forever until asterisk restart or soft hangup of the channels.
IAX client is Zoiper, version 2.18 - 2.19

****** ADDITIONAL INFORMATION ******

console output:
-- Executing [518@default:1] Answer("IAX2/petrak-5470", "") in new stack
-- Executing [518@default:2] Set("IAX2/petrak-5470", callingid=name=tothova,username=tothova,secret=tothova,notransfer=yes,callerid=518,host=dynamic,qualify=no,disallow=all,allow=alaw,ipaddr=172.16.32.224,port=4569,regseconds=1243538479,") in new stack                                                                  
-- Executing [518@default:3] Set("IAX2/petrak-5470", "callingid=name=tothova") in new stack                                                                  
-- Executing [518@default:4] Set("IAX2/petrak-5470", "callingid=tothova") in new stack                                                                      
-- Executing [518@default:5] GotoIf("IAX2/petrak-5470", "1?:lbl_default_1") in new stack
-- Executing [518@default:6] Dial("IAX2/petrak-5470", "IAX2/tothova") in new stack
-- Called tothova
-- Call accepted by 172.16.32.224 (format alaw)
-- Format for call is alaw
-- IAX2/tothova-9775 is rining
-- IAX2/tothova-9775 answered IAX2/petrak-5470
-- Channel 'IAX2/petrak-5470' ready to transfer
-- Channel 'IAX2/tothova-9775' ready to transfer
-- Releasing IAX2/tothova-9775 and IAX2/petrak-5470


IAX DEBUG:
   -- Call accepted by 172.16.32.224 (format alaw)
   -- Format for call is alaw                    
Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK    
  Timestamp: 00003ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: VOICE   Subclass: 8      
  Timestamp: 00020ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK    
  Timestamp: 00020ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: CONTROL Subclass: RINGING
  Timestamp: 00003ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
  Timestamp: 00003ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
   -- IAX2/tothova-7727 is ringing                                          
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: CONTROL Subclass: RINGING
  Timestamp: 00015ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: ACK    
  Timestamp: 00015ms  SCall: 00093  DCall: 11271 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: POKE  
  Timestamp: 00011ms  SCall: 00052  DCall: 00000 [195.168.21.5:4569]        
Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK    
  Timestamp: 00016ms  SCall: 00052  DCall: 00001 [195.168.21.5:4569]        
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: QUELCH
  Timestamp: 02951ms  SCall: 00093  DCall: 11271 [172.16.32.224:4569]        

Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: ACK    
  Timestamp: 02951ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: ANSWER
  Timestamp: 02875ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: ACK    
  Timestamp: 02875ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
   -- IAX2/tothova-7727 answered IAX2/petrak-11271                          
Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 004 Type: CONTROL Subclass: (20?)  
  Timestamp: 02947ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: CONTROL Subclass: (20?)  
  Timestamp: 02863ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 004 Type: IAX     Subclass: TXREQ  
  Timestamp: 02950ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
  APPARENT ADDRES : IPV4 172.16.32.224:4569                                  
  CALL NUMBER     : 94                                                      
  TRANSFER ID     : 1701664586                                              

Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: TXREQ  
  Timestamp: 02888ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
  APPARENT ADDRES : IPV4 172.16.32.224:4569                                  
  CALL NUMBER     : 93                                                      
  TRANSFER ID     : 1701664586                                              

Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 006 Type: IAX     Subclass: ACK    
  Timestamp: 02947ms  SCall: 00093  DCall: 11271 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX     Subclass: ACK    
  Timestamp: 02863ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: VOICE   Subclass: 8      
  Timestamp: 02900ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Tx-Frame Retry[-01] -- OSeqno: 003 ISeqno: 004 Type: IAX     Subclass: ACK    
  Timestamp: 02900ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 004 Type: VOICE   Subclass: 8      
  Timestamp: 02920ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 005 Type: IAX     Subclass: ACK    
  Timestamp: 02920ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 007 Type: IAX     Subclass: TXREADY
  Timestamp: 03013ms  SCall: 00093  DCall: 11271 [172.16.32.224:4569]        
  TRANSFER ID     : 1701664586                                              

   -- Channel 'IAX2/petrak-11271' ready to transfer
Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 005 Type: IAX     Subclass: ACK    
  Timestamp: 03013ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: TXREADY
  Timestamp: 02943ms  SCall: 00094  DCall: 07727 [172.16.32.224:4569]        
  TRANSFER ID     : 1701664586                                              

   -- Channel 'IAX2/tothova-7727' ready to transfer
   -- Releasing IAX2/tothova-7727 and IAX2/petrak-11271
Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 006 Type: IAX     Subclass: TXREL  
  Timestamp: 02948ms  SCall: 07727  DCall: 00094 [172.16.32.224:4569]        
  CALL NUMBER     : 93                                                      

Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 005 Type: IAX     Subclass: TXREL  
  Timestamp: 03007ms  SCall: 11271  DCall: 00093 [172.16.32.224:4569]        
  CALL NUMBER     : 94


and this shows core show channels even when the call is ended

asterisk-peli1*CLI> core show channels
Channel              Location             State   Application(Data)
IAX2/tothova-7727    (None)               Up      AppDial((Outgoing Line))
IAX2/petrak-11271    518@default:6        Up      Dial(IAX2/tothova)
Comments:By: oxymoron (oxymoron) 2009-06-01 09:22:06

I've solved that by sending extension (which is defined in context "default" ; context "default" is default for iax clients) to the subroutine which executes Dial function. But it is strange that it doesn't work directly in that context
By: David Vossel (dvossel) 2009-06-01 17:54:41

I am able to reproduce this issue.  It seems to be isolated to the 1.6.0 branch.
By: Digium Subversion (svnbot) 2009-06-02 12:55:36

Repository: asterisk
Revision: 198824

U   trunk/channels/chan_iax2.c

------------------------------------------------------------------------
r198824 | dvossel | 2009-06-02 12:55:35 -0500 (Tue, 02 Jun 2009) | 8 lines

fixes issue with channels not going down after transfer

Iax2 currently does not support native bridging if the timeoutms value is set.  We check for that in iax2_bridge, but then set timeoutms to 0 by default.  If the timeoutms is not provided it is set to -1. By setting timeoutms to 0 it is processed causing a bridging retry loop.

(closes issue ASTERISK-14220)
Reported by: oxymoron
Tested by: dvossel

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=198824
By: Digium Subversion (svnbot) 2009-06-02 12:56:19

Repository: asterisk
Revision: 198825

_U  branches/1.6.0/
U   branches/1.6.0/channels/chan_iax2.c

------------------------------------------------------------------------
r198825 | dvossel | 2009-06-02 12:56:19 -0500 (Tue, 02 Jun 2009) | 15 lines

Merged revisions 198824 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

........
 r198824 | dvossel | 2009-06-02 12:55:35 -0500 (Tue, 02 Jun 2009) | 8 lines
 
 fixes issue with channels not going down after transfer
 
 Iax2 currently does not support native bridging if the timeoutms value is set.  We check for that in iax2_bridge, but then set timeoutms to 0 by default.  If the timeoutms is not provided it is set to -1. By setting timeoutms to 0 it is processed causing a bridging retry loop.
 
 (closes issue ASTERISK-14220)
 Reported by: oxymoron
 Tested by: dvossel
........

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=198825
By: Digium Subversion (svnbot) 2009-06-02 12:57:00

Repository: asterisk
Revision: 198826

_U  branches/1.6.1/
U   branches/1.6.1/channels/chan_iax2.c

------------------------------------------------------------------------
r198826 | dvossel | 2009-06-02 12:56:59 -0500 (Tue, 02 Jun 2009) | 15 lines

Merged revisions 198824 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

........
 r198824 | dvossel | 2009-06-02 12:55:35 -0500 (Tue, 02 Jun 2009) | 8 lines
 
 fixes issue with channels not going down after transfer
 
 Iax2 currently does not support native bridging if the timeoutms value is set.  We check for that in iax2_bridge, but then set timeoutms to 0 by default.  If the timeoutms is not provided it is set to -1. By setting timeoutms to 0 it is processed causing a bridging retry loop.
 
 (closes issue ASTERISK-14220)
 Reported by: oxymoron
 Tested by: dvossel
........

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=198826
By: Digium Subversion (svnbot) 2009-06-02 12:58:09

Repository: asterisk
Revision: 198827

_U  branches/1.6.2/
U   branches/1.6.2/channels/chan_iax2.c

------------------------------------------------------------------------
r198827 | dvossel | 2009-06-02 12:58:09 -0500 (Tue, 02 Jun 2009) | 15 lines

Merged revisions 198824 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

........
 r198824 | dvossel | 2009-06-02 12:55:35 -0500 (Tue, 02 Jun 2009) | 8 lines
 
 fixes issue with channels not going down after transfer
 
 Iax2 currently does not support native bridging if the timeoutms value is set.  We check for that in iax2_bridge, but then set timeoutms to 0 by default.  If the timeoutms is not provided it is set to -1. By setting timeoutms to 0 it is processed causing a bridging retry loop.
 
 (closes issue ASTERISK-14220)
 Reported by: oxymoron
 Tested by: dvossel
........

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=198827