[Home]

Summary:ASTERISK-12665: Multiple hangups on IAX trunk between two asterisk servers
Reporter:Arkadiusz Malka (yarns)Labels:
Date Opened:2008-08-30 11:25:35Date Closed:2011-06-07 14:02:40
Priority:MajorRegression?No
Status:Closed/CompleteComponents:Channels/chan_iax2
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I have two asterisk servers connected via IAX trunk.
Many calls (30% -40%) got hangup while calling via trunk:

-- Executing [s@macro-gsmCall:11] Dial("SIP/192.168.3.102-1e47dcc0", "IAX2/gsm/601877245") in new stack
   -- Called gsm/601877245
Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: NEW    
  Timestamp: 00015ms  SCall: 04232  DCall: 00000 [192.168.2.11:4569]
VERSION         : 2
  CALLED NUMBER   : 601877245
  CODEC_PREFS     : (alaw)
  CALLING NUMBER  : 223857300
  CALLING PRESNTN : 0
  CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME    : 192.168.3.102
LANGUAGE        : en
  FORMAT          : 8
CAPABILITY      : 65407
ADSICPE         : 2
DATE TIME       : 2008-08-30  18:14:38



Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: ACCEPT
Timestamp: 00003ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
  FORMAT          : 8

-- Call accepted by 192.168.2.11 (format alaw)
-- Format for call is alaw

Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: ACK    
Timestamp: 00003ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 001 Type: VOICE   Subclass: 8
  Timestamp: 00127ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: ACK    
  Timestamp: 00127ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 002 Type: CONTROL Subclass: PROCDNG
  Timestamp: 00245ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 002 Type: IAX     Subclass: ACK    
  Timestamp: 00245ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: VOICE   Subclass: 8
  Timestamp: 00246ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
-- IAX2/gsm-4232 is proceeding passing it to SIP/192.168.3.102-1e47dcc0

Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX     Subclass: ACK    
  Timestamp: 00246ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING
  Timestamp: 07147ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 004 Type: IAX     Subclass: ACK    
  Timestamp: 07147ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]
-- IAX2/gsm-4232 is ringing

Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 002 Type: IAX     Subclass: LAGRQ  
Timestamp: 09981ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 005 Type: IAX     Subclass: LAGRP  
  Timestamp: 09981ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 003 Type: IAX     Subclass: ACK    
Timestamp: 09981ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: LAGRQ  
Timestamp: 10150ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: LAGRP  
Timestamp: 10150ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[-01] -- OSeqno: 004 ISeqno: 006 Type: IAX     Subclass: ACK    
Timestamp: 10150ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 004 Type: IAX     Subclass: LAGRQ  
  Timestamp: 19983ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 007 Type: IAX     Subclass: LAGRP  
  Timestamp: 19983ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 005 Type: IAX     Subclass: ACK    
  Timestamp: 19983ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 007 Type: IAX     Subclass: LAGRQ  
Timestamp: 20170ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 006 Type: IAX     Subclass: LAGRP  
Timestamp: 20170ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 008 Type: IAX     Subclass: ACK    
Timestamp: 20170ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 006 Type: IAX     Subclass: PING  
Timestamp: 20979ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 009 Type: IAX     Subclass: PONG  
  Timestamp: 20979ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]
  RR_JITTER       : 0
  RR_LOSS         : 0
  RR_PKTS         : 1
  RR_DELAY        : 40
  RR_DROPPED      : 0
  RR_OUTOFORDER   : 0


Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 007 Type: IAX     Subclass: ACK    
Timestamp: 20979ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 009 Type: IAX     Subclass: PING  
Timestamp: 21170ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 008 Type: IAX     Subclass: PONG  
Timestamp: 21170ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
  RR_JITTER       : 9
  RR_LOSS         : 0
RR_PKTS         : 1053
  RR_DELAY        : 64
  RR_DROPPED      : 0
  RR_OUTOFORDER   : 0


Tx-Frame Retry[-01] -- OSeqno: 008 ISeqno: 010 Type: IAX     Subclass: ACK    
Timestamp: 21170ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 008 Type: IAX     Subclass: LAGRQ  
  Timestamp: 29978ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 011 Type: IAX     Subclass: LAGRP  
Timestamp: 29978ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 009 Type: IAX     Subclass: ACK    
Timestamp: 29978ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 011 Type: IAX     Subclass: LAGRQ  
Timestamp: 30190ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 010 Type: IAX     Subclass: LAGRP  
Timestamp: 30190ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[-01] -- OSeqno: 010 ISeqno: 012 Type: IAX     Subclass: ACK    
Timestamp: 30190ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 012 ISeqno: 010 Type: IAX     Subclass: LAGRQ  
Timestamp: 39977ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 013 Type: IAX     Subclass: LAGRP  
Timestamp: 39977ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 013 ISeqno: 011 Type: IAX     Subclass: ACK    
Timestamp: 39977ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 013 Type: IAX     Subclass: LAGRQ  
Timestamp: 40230ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 013 ISeqno: 012 Type: IAX     Subclass: LAGRP  
Timestamp: 40230ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 014 Type: IAX     Subclass: ACK    
Timestamp: 40230ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 014 ISeqno: 012 Type: IAX     Subclass: PING  
Timestamp: 41977ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 015 Type: IAX     Subclass: PONG  
Timestamp: 41977ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]
  RR_JITTER       : 0
RR_LOSS         : 0
  RR_PKTS         : 1
  RR_DELAY        : 40
  RR_DROPPED      : 0
RR_OUTOFORDER   : 0



Rx-Frame Retry[ No] -- OSeqno: 015 ISeqno: 013 Type: IAX     Subclass: ACK    
Timestamp: 41977ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[000] -- OSeqno: 013 ISeqno: 015 Type: IAX     Subclass: PING  
Timestamp: 42230ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 015 ISeqno: 014 Type: IAX     Subclass: PONG  
Timestamp: 42230ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
  RR_JITTER       : 9
  RR_LOSS         : 0
  RR_PKTS         : 2106
  RR_DELAY        : 61
RR_DROPPED      : 0
  RR_OUTOFORDER   : 0


Tx-Frame Retry[-01] -- OSeqno: 014 ISeqno: 016 Type: IAX     Subclass: ACK    
Timestamp: 42230ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 016 ISeqno: 014 Type: CONTROL Subclass: (255?)
Timestamp: 47354ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]

Tx-Frame Retry[-01] -- OSeqno: 014 ISeqno: 017 Type: IAX     Subclass: ACK    
Timestamp: 47354ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]

Rx-Frame Retry[ No] -- OSeqno: 017 ISeqno: 014 Type: IAX     Subclass: HANGUP
Timestamp: 47357ms  SCall: 14775  DCall: 04232 [192.168.2.11:4569]
  CAUSE CODE      : 0

-- IAX2/gsm-4232 stopped sounds

Tx-Frame Retry[-01] -- OSeqno: 014 ISeqno: 018 Type: IAX     Subclass: ACK    
Timestamp: 47357ms  SCall: 04232  DCall: 14775 [192.168.2.11:4569]
-- Hungup 'IAX2/gsm-4232'

 == Everyone is busy/congested at this time (1:0/0/1)

If someone need additional infromation i can provide it.
Comments:By: Arkadiusz Malka (yarns) 2008-09-01 08:28:03

Upgrade to 1.4.19.2 solved rpoblem... please close this bug report.