[asterisk-r2] MFC/R2 protocol error on chan 5: Multi Frequency Cycle Timeout

[Alfredo Andaluz Prado]

This ist entire log from one incomming call

[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Bits changed from 
0x08 to 0x00
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - ABCD Rx << [SEIZE] 
0x00
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - ABCD Tx >> [SEIZE 
ACK] 0x0C
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - ABCD Raw Tx >> 0x0D
[Nov 14 15:22:22] NOTICE[14425] chan_dahdi.c: New MFC/R2 call detected 
on chan 5.
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 2 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 2
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 2, 
expected length: 7
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 2 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 5 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 5
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 25, 
expected length: 7
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 5 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 9 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 9
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 259, 
expected length: 7
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 9 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 8 [ON]
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 8
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 2598, 
expected length: 7
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:22] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 8 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 0 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 0
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 
25980, expected length: 7
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 0 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 0 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 0
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 
259800, expected length: 7
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Requesting next 
DNIS with signal 0x31.
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 0 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 5 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Getting DNIS digit 5
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - DNIS so far: 
2598005, expected length: 7
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - Done getting DNIS!
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Rx << 5 [OFF]
[Nov 14 15:22:23] DEBUG[14425] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Nov 14 15:22:28] DEBUG[14425] chan_dahdi.c: Chan 5 - calling timer callback
[Nov 14 15:22:28] WARNING[14425] chan_dahdi.c: Chan 5 - MF back cycle 
timed out!
[Nov 14 15:22:28] ERROR[14425] chan_dahdi.c: Chan 5 - Protocol error. 
Reason = Multi Frequency Cycle Timeout, R2 State = Seize ACK 
Transmitted, MF state = Category Request Transmitted, MF Group = 
Backward Group A, CAS = 0x00
DNIS = 2598005, ANI = , MF = 0x20
[Nov 14 15:22:28] DEBUG[14425] chan_dahdi.c: Chan 5 - ABCD Tx >> [IDLE] 0x08
[Nov 14 15:22:28] DEBUG[14425] chan_dahdi.c: Chan 5 - ABCD Raw Tx >> 0x09
[Nov 14 15:22:28] ERROR[14425] chan_dahdi.c: MFC/R2 protocol error on 
chan 5: Multi Frequency Cycle Timeout

cnt-r2-gye:~#


Alfredo Andaluz Prado wrote:
> Hi all
>
> try to incomming call from E1 R2 using OpenR2 version: 0.1.1, 
> revision: 98 , the context for testing its simple
>
> [incoming]
> exten => _X.,1,Answer()
> exten => _X.,n,Playback(hello-world)
> exten => _X.,n,Hangup()
>
> the msg ist:
>
> [Nov 14 15:16:00] ERROR[14425] chan_dahdi.c: MFC/R2 protocol error on 
> chan 5: Multi Frequency Cycle Timeout
>
> any idea ?
>