IAX2: VNAK loop

Hi,

Has anybody come across the VANK loop ion IAX. The debug is attached below. Thanks in advance.

This usually happens after someone answers the call.

Regards,
Walter

voip-01*CLI> iax2 set debug
IAX2 Debugging Enabled
– Accepting call from ‘59996305317’ to ‘6313045’ on channel 0/31, span 2
– Executing [6313045@second-e1-config:1] Set(“Zap/62-1”, “DB(lastcall/6313045=59996305317)”) in new stack
– Executing [6313045@second-e1-config:2] Macro(“Zap/62-1”, “dial-iax2-line|6313045|6313045”) in new stack
– Executing [s@macro-dial-iax2-line:1] Set(“Zap/62-1”, “call_forward=”) in new stack
– Executing [s@macro-dial-iax2-line:2] GotoIf(“Zap/62-1”, “0?3:5”) in new stack
– Goto (macro-dial-iax2-line,s,5)
– Executing [s@macro-dial-iax2-line:5] Dial(“Zap/62-1”, “IAX2/6313045|20”) in new stack
– Called 6313045
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00013ms SCall: 00655 DCall: 00000 [10.10.40.51:4569]
VERSION : 2
CALLED NUMBER : s
CODEC_PREFS : (alaw|g729|gsm)
CALLING NUMBER : 59996305317
CALLING PRESNTN : 3
CALLING TYPEOFN : 17
CALLING TRANSIT : 0
CALLING NAME :
LANGUAGE : en
USERNAME : 6313045
FORMAT : 8
CAPABILITY : 266
ADSICPE : 2
DATE TIME : 2010-01-09 02:00:54

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00013ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: VNAK
Timestamp: 01049ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
FORMAT : 8

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

Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 001 Type: VOICE Subclass: 8
Timestamp: 01060ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01060ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Rx-Frame Retry[Yes] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
FORMAT : 8

Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[Yes] – OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
– IAX2/6313045-655 is ringing
Rx-Frame Retry[Yes] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
FORMAT : 8

Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[Yes] – OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[Yes] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACCEPT
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
FORMAT : 8

Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[Yes] – OSeqno: 001 ISeqno: 001 Type: CONTROL Subclass: RINGING
Timestamp: 01000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 01000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 002 Type: CONTROL Subclass: ANSWER
Timestamp: 04000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 04000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 04000ms SCall: 25766 DCall: 00655 [10.10.40.51:4569]
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 04000ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04225ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04255ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04270ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04290ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04310ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04330ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04350ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04370ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04390ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04410ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04430ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04450ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04475ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04495ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04523ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04535ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04555ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04575ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04595ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04615ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04635ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04655ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04675ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04695ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: VNAK
Timestamp: 04723ms SCall: 00655 DCall: 25766 [10.10.40.51:4569]

IAX2 Debugging Disabled
– Hungup ‘IAX2/6313045-655’
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial-iax2-line:6] Goto(“Zap/62-1”, “s-CHANUNAVAIL|1”) in new stack
– Goto (macro-dial-iax2-line,s-CHANUNAVAIL,1)
== Auto fallthrough, channel ‘Zap/62-1’ status is ‘CHANUNAVAIL’
– Channel 0/31, span 2 got hangup request, cause 16
– Hungup ‘Zap/62-1’