[asterisk-r2] Protocol error. Reason = Multi Frequency Cycle Timeout

[Jesus Alexander Nuñez Roman]

I have a Nortel and Asterik connected through a Ethernet E1 Wildcard TE122
with VPMADT032

With this configuration i make a call from Asterisk and everything works
fine. But when I try to call from Nortel to Asterisk I get the following
error:

Protocol error. Reason = Multi Frequency Cycle Timeout, R2 State = Seize 
ACK Transmitted, MF state = Category Request Transmitted, MF Group = 
Backward Group C, CAS = 0x00

I want to know what may be causing this problem and how can I fix it?


Here are the log from the calls:

***log from nortel to asterisk***
chan-31-backward-1-20090227052201.call
[05:22:01:904] [Thread: 3085134736] [Chan 31] - Call started at Fri Feb 
27 05:22:01 2009 on chan 31
[05:22:01:904] [Thread: 3085134736] [Chan 31] - CAS Tx >> [SEIZE ACK] 0x0C
[05:22:01:904] [Thread: 3085134736] [Chan 31] - CAS Raw Tx >> 0x0D
[05:22:05:256] [Thread: 3085134736] [Chan 31] - MF Rx << 6 [ON]
[05:22:05:256] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 0
[05:22:05:256] [Thread: 3085134736] [Chan 31] - Cannot cancel timer 0
[05:22:05:256] [Thread: 3085134736] [Chan 31] - Getting DNIS digit 6
[05:22:05:256] [Thread: 3085134736] [Chan 31] - DNIS so far: 6, expected 
length: 10
[05:22:05:256] [Thread: 3085134736] [Chan 31] - Requesting next DNIS 
with signal 0x31.
[05:22:05:256] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [ON]
[05:22:05:356] [Thread: 3085134736] [Chan 31] - MF Rx << 6 [OFF]
[05:22:05:356] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [OFF]
[05:22:05:776] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [ON]
[05:22:05:776] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 2
[05:22:05:776] [Thread: 3085134736] [Chan 31] - timer id 2 found, 
cancelling it now
[05:22:05:776] [Thread: 3085134736] [Chan 31] - Getting DNIS digit 0
[05:22:05:776] [Thread: 3085134736] [Chan 31] - DNIS so far: 60, 
expected length: 10
[05:22:05:776] [Thread: 3085134736] [Chan 31] - Requesting next DNIS 
with signal 0x31.
[05:22:05:776] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [ON]
[05:22:05:856] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [OFF]
[05:22:05:856] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [OFF]
[05:22:06:096] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [ON]
[05:22:06:096] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 3
[05:22:06:096] [Thread: 3085134736] [Chan 31] - timer id 3 found, 
cancelling it now
[05:22:06:096] [Thread: 3085134736] [Chan 31] - Getting DNIS digit 0
[05:22:06:096] [Thread: 3085134736] [Chan 31] - DNIS so far: 600, 
expected length: 10
[05:22:06:096] [Thread: 3085134736] [Chan 31] - Requesting next DNIS 
with signal 0x31.
[05:22:06:096] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [ON]
[05:22:06:196] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [OFF]
[05:22:06:196] [Thread: 3085134736] [Chan 31] - MF Tx >> 1 [OFF]
[05:22:06:296] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [ON]
[05:22:06:296] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 4
[05:22:06:296] [Thread: 3085134736] [Chan 31] - timer id 4 found, 
cancelling it now
[05:22:06:296] [Thread: 3085134736] [Chan 31] - Getting DNIS digit 0
[05:22:06:296] [Thread: 3085134736] [Chan 31] - DNIS so far: 6000, 
expected length: 10
[05:22:06:296] [Thread: 3085134736] [Chan 31] - User requested us to 
stop getting DNIS!
[05:22:06:296] [Thread: 3085134736] [Chan 31] - MF Tx >> 6 [ON]
[05:22:06:376] [Thread: 3085134736] [Chan 31] - MF Rx << 0 [OFF]
[05:22:06:376] [Thread: 3085134736] [Chan 31] - MF Tx >> 6 [OFF]
[05:22:11:295] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 5
[05:22:11:295] [Thread: 3085134736] [Chan 31] - timer id 5 found, 
cancelling it now
[05:22:11:295] [Thread: 3085134736] [Chan 31] - calling timer callback
[05:22:11:295] [Thread: 3085134736] [Chan 31] - MF back cycle timed out!
[05:22:11:295] [Thread: 3085134736] [Chan 31] - Protocol error. Reason = 
Multi Frequency Cycle Timeout, R2 State = Seize ACK Transmitted, MF 
state = Category Request Transmitted, MF Group = Backward Group C, CAS = 
0x00
DNIS = 6000, ANI = , MF = 0x20
[05:22:11:295] [Thread: 3085134736] [Chan 31] - Attempting to cancel 
timer timer 0
[05:22:11:295] [Thread: 3085134736] [Chan 31] - Cannot cancel timer 0


****log from asterisk to nortel****

chan-1-forward-1-20090227052042.call
[05:20:42:882] [Thread: 3077835664] [Chan 1] - Call started at Fri Feb 
27 05:20:42 2009 on chan 1
[05:20:42:882] [Thread: 3077835664] [Chan 1] - CAS Tx >> [SEIZE] 0x00
[05:20:42:882] [Thread: 3077835664] [Chan 1] - CAS Raw Tx >> 0x01
[05:20:43:131] [Thread: 3077835664] [Chan 1] - Bits changed from 0x08 to 
0x0C
[05:20:43:131] [Thread: 3077835664] [Chan 1] - CAS Rx << [SEIZE ACK] 0x0C
[05:20:43:131] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 2
[05:20:43:131] [Thread: 3077835664] [Chan 1] - timer id 2 found, 
cancelling it now
[05:20:43:131] [Thread: 3077835664] [Chan 1] - Sending DNIS digit 5
[05:20:43:131] [Thread: 3077835664] [Chan 1] - MF Tx >> 5 [ON]
[05:20:43:241] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:43:241] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:43:241] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:43:241] [Thread: 3077835664] [Chan 1] - MF Tx >> 5 [OFF]
[05:20:43:341] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:43:341] [Thread: 3077835664] [Chan 1] - Sending DNIS digit 3
[05:20:43:341] [Thread: 3077835664] [Chan 1] - MF Tx >> 3 [ON]
[05:20:43:341] [Thread: 3077835664] [Chan 1] - Group A DNIS request handled
[05:20:43:421] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:43:421] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:43:421] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:43:421] [Thread: 3077835664] [Chan 1] - MF Tx >> 3 [OFF]
[05:20:43:521] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:43:521] [Thread: 3077835664] [Chan 1] - Sending DNIS digit 7
[05:20:43:521] [Thread: 3077835664] [Chan 1] - MF Tx >> 7 [ON]
[05:20:43:521] [Thread: 3077835664] [Chan 1] - Group A DNIS request handled
[05:20:43:621] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:43:621] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:43:621] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:43:621] [Thread: 3077835664] [Chan 1] - MF Tx >> 7 [OFF]
[05:20:43:721] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:43:721] [Thread: 3077835664] [Chan 1] - Sending DNIS digit 6
[05:20:43:721] [Thread: 3077835664] [Chan 1] - MF Tx >> 6 [ON]
[05:20:43:721] [Thread: 3077835664] [Chan 1] - Group A DNIS request handled
[05:20:43:821] [Thread: 3077835664] [Chan 1] - MF Rx << 6 [ON]
[05:20:43:821] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:43:821] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:43:821] [Thread: 3077835664] [Chan 1] - MF Tx >> 6 [OFF]
[05:20:43:921] [Thread: 3077835664] [Chan 1] - MF Rx << 6 [OFF]
[05:20:43:921] [Thread: 3077835664] [Chan 1] - Sending category National 
Priority Subscriber
[05:20:43:921] [Thread: 3077835664] [Chan 1] - MF Tx >> 2 [ON]
[05:20:44:021] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:44:021] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:44:021] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:44:021] [Thread: 3077835664] [Chan 1] - MF Tx >> 2 [OFF]
[05:20:44:121] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:44:121] [Thread: 3077835664] [Chan 1] - Sending ANI digit 6
[05:20:44:121] [Thread: 3077835664] [Chan 1] - MF Tx >> 6 [ON]
[05:20:44:221] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:44:221] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:44:221] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:44:221] [Thread: 3077835664] [Chan 1] - MF Tx >> 6 [OFF]
[05:20:44:321] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:44:321] [Thread: 3077835664] [Chan 1] - Sending ANI digit 0
[05:20:44:321] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [ON]
[05:20:44:461] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:44:461] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:44:461] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:44:461] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [OFF]
[05:20:44:561] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:44:561] [Thread: 3077835664] [Chan 1] - Sending ANI digit 0
[05:20:44:561] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [ON]
[05:20:44:641] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:44:641] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:44:641] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:44:641] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [OFF]
[05:20:44:721] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:44:721] [Thread: 3077835664] [Chan 1] - Sending ANI digit 0
[05:20:44:721] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [ON]
[05:20:44:801] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:44:801] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:44:801] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:44:801] [Thread: 3077835664] [Chan 1] - MF Tx >> 0 [OFF]
[05:20:44:901] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:44:901] [Thread: 3077835664] [Chan 1] - Sending more ANI unavailable
[05:20:44:901] [Thread: 3077835664] [Chan 1] - MF Tx >> F [ON]
[05:20:45:001] [Thread: 3077835664] [Chan 1] - MF Rx << 3 [ON]
[05:20:45:001] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:45:001] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:45:001] [Thread: 3077835664] [Chan 1] - MF Tx >> F [OFF]
[05:20:45:101] [Thread: 3077835664] [Chan 1] - MF Rx << 3 [OFF]
[05:20:45:101] [Thread: 3077835664] [Chan 1] - Sending category National 
Priority Subscriber
[05:20:45:101] [Thread: 3077835664] [Chan 1] - MF Tx >> 2 [ON]
[05:20:45:201] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [ON]
[05:20:45:201] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:45:201] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:45:201] [Thread: 3077835664] [Chan 1] - MF Tx >> 2 [OFF]
[05:20:45:301] [Thread: 3077835664] [Chan 1] - MF Rx << 1 [OFF]
[05:20:52:410] [Thread: 3077835664] [Chan 1] - Bits changed from 0x0C to 
0x04
[05:20:52:410] [Thread: 3077835664] [Chan 1] - CAS Rx << [ANSWER] 0x04
[05:20:52:410] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 3
[05:20:52:410] [Thread: 3077835664] [Chan 1] - timer id 3 found, 
cancelling it now
[05:20:52:410] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:52:410] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:54:521] [Thread: 3077835664] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:54:521] [Thread: 3077835664] [Chan 1] - Cannot cancel timer 0
[05:20:54:521] [Thread: 3077835664] [Chan 1] - CAS Tx >> [CLEAR FORWARD] 
0x08
[05:20:54:521] [Thread: 3077835664] [Chan 1] - CAS Raw Tx >> 0x09
[05:20:55:098] [Thread: 3085380496] [Chan 1] - Bits changed from 0x04 to 
0x08
[05:20:55:098] [Thread: 3085380496] [Chan 1] - CAS Rx << [IDLE] 0x08
[05:20:55:098] [Thread: 3085380496] [Chan 1] - Call ended
[05:20:55:098] [Thread: 3085380496] [Chan 1] - Attempting to cancel 
timer timer 0
[05:20:55:098] [Thread: 3085380496] [Chan 1] - Cannot cancel timer 0

Por favor considera tu responsabilidad ambiental antes de imprimir este documento.
Please consider your environmental responsibility before printing this document.