[asterisk-r2] Protocol error. Reason = Invalid CAS

Thu Oct 25 08:06:58 CDT 2012

Buenos dias,

Tengo media trama(15 canales) contratados con Telefónica Argentina, las 
llamadas funcionan bien.

Asterisk: 1.8.11.1
Dahdi: 2.6.0
OpenR2 version: 1.3.2, revision: exportado
Placa digium TE121

Pero aleatoriamente durante el día obtengo alrededor de 10 veces este 
error en el log de asterisk::

[Oct 24 21:53:06] VERBOSE[15083] app_dial.c:     -- Called Dahdi/r0/4429682
[Oct 24 21:53:12] VERBOSE[15083] chan_dahdi.c: MFC/R2 call has been 
accepted on forward channel 6
[Oct 24 21:53:12] VERBOSE[15083] app_dial.c:     -- DAHDI/6-1 is ringing
[Oct 24 21:53:12] VERBOSE[15083] app_dial.c:     -- DAHDI/6-1 is making 
progress passing it to SIP/12720-00000d91
[Oct 24 21:53:25] VERBOSE[15083] chan_dahdi.c: MFC/R2 call has been 
answered on channel 6
[Oct 24 21:53:25] VERBOSE[15083] app_dial.c:     -- DAHDI/6-1 answered 
SIP/12720-00000d91
[Oct 24 21:56:38] VERBOSE[15083] chan_dahdi.c:     -- Hungup 'DAHDI/6-1'
[Oct 24 21:56:38] VERBOSE[15083] pbx.c:   == Spawn extension 
(user-locales, 4429682, 5) exited non-zero on 'SIP/12720-00000d91'
[Oct 24 21:59:29] ERROR[2066] chan_dahdi.c: Chan 6 - Protocol error. 
Reason = Invalid CAS, R2 State = Clear Back After Clear Forward 
Received,MF state = MF Engine Off, MF Group = Forward Group II, CAS = 0x0C
DNIS = 4429682, ANI = 12720, MF = 0x20
[Oct 24 21:59:29] ERROR[2066] chan_dahdi.c: MFC/R2 protocol error on 
chan 6: Invalid CAS
[Oct 24 21:59:32] NOTICE[2066] chan_dahdi.c: Far end unblocked on chan 6

Y acá esta el .call de la llamada:

[21:53:06:796] [Thread: 140321340434176] [Chan 6] - Call started at Wed 
Oct 24 21:53:06 2012 on chan 6 [openr2 version 1.3.2, revision exportado]
[21:53:06:796] [Thread: 140321340434176] [Chan 6] - Outgoing call 
proceeding: ANI=12720, DNIS=4429682, Category=National Subscriber
[21:53:06:796] [Thread: 140321340434176] [Chan 6] - CAS Tx >> [SEIZE] 0x00
[21:53:06:796] [Thread: 140321340434176] [Chan 6] - CAS Raw Tx >> 0x01
[21:53:06:796] [Thread: 140321340434176] [Chan 6] - scheduled timer id 2 
(r2_seize)
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - Bits changed from 
0x08 to 0x0C
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - CAS Rx << [SEIZE 
ACK] 0x0C
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 2
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - timer id 2 found, 
cancelling it now
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - MFC/R2 call acknowledge!
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 4
[21:53:07:002] [Thread: 140321340434176] [Chan 6] - MF Tx >> 4 [ON]
[21:53:07:095] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:095] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:095] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:095] [Thread: 140321340434176] [Chan 6] - MF Tx >> 4 [OFF]
[21:53:07:155] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:155] [Thread: 140321340434176] [Chan 6] - Sending category 
National Subscriber
[21:53:07:155] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [ON]
[21:53:07:235] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:235] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:235] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:235] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [OFF]
[21:53:07:295] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:295] [Thread: 140321340434176] [Chan 6] - Sending ANI digit 1
[21:53:07:295] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [ON]
[21:53:07:395] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:395] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:395] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:395] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [OFF]
[21:53:07:475] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:475] [Thread: 140321340434176] [Chan 6] - Sending ANI digit 2
[21:53:07:475] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [ON]
[21:53:07:555] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:555] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:555] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:555] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [OFF]
[21:53:07:615] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:615] [Thread: 140321340434176] [Chan 6] - Sending ANI digit 7
[21:53:07:615] [Thread: 140321340434176] [Chan 6] - MF Tx >> 7 [ON]
[21:53:07:695] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:695] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:695] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:695] [Thread: 140321340434176] [Chan 6] - MF Tx >> 7 [OFF]
[21:53:07:775] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:775] [Thread: 140321340434176] [Chan 6] - Sending ANI digit 2
[21:53:07:775] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [ON]
[21:53:07:854] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:854] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:854] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:854] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [OFF]
[21:53:07:915] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:07:915] [Thread: 140321340434176] [Chan 6] - Sending ANI digit 0
[21:53:07:915] [Thread: 140321340434176] [Chan 6] - MF Tx >> 0 [ON]
[21:53:07:995] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [ON]
[21:53:07:995] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:07:995] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:07:995] [Thread: 140321340434176] [Chan 6] - MF Tx >> 0 [OFF]
[21:53:08:055] [Thread: 140321340434176] [Chan 6] - MF Rx << 5 [OFF]
[21:53:08:055] [Thread: 140321340434176] [Chan 6] - Sending more ANI 
unavailable
[21:53:08:055] [Thread: 140321340434176] [Chan 6] - MF Tx >> C [ON]
[21:53:08:155] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:155] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:155] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:155] [Thread: 140321340434176] [Chan 6] - MF Tx >> C [OFF]
[21:53:08:215] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:08:215] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 4
[21:53:08:215] [Thread: 140321340434176] [Chan 6] - MF Tx >> 4 [ON]
[21:53:08:215] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:08:315] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:315] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:315] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:315] [Thread: 140321340434176] [Chan 6] - MF Tx >> 4 [OFF]
[21:53:08:375] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:08:375] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 2
[21:53:08:375] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [ON]
[21:53:08:375] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:08:475] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:475] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:475] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:475] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [OFF]
[21:53:08:555] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:08:555] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 9
[21:53:08:555] [Thread: 140321340434176] [Chan 6] - MF Tx >> 9 [ON]
[21:53:08:555] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:08:635] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:635] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:635] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:635] [Thread: 140321340434176] [Chan 6] - MF Tx >> 9 [OFF]
[21:53:08:695] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:08:695] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 6
[21:53:08:695] [Thread: 140321340434176] [Chan 6] - MF Tx >> 6 [ON]
[21:53:08:695] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:08:775] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:775] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:775] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:775] [Thread: 140321340434176] [Chan 6] - MF Tx >> 6 [OFF]
[21:53:08:835] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:08:835] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 8
[21:53:08:835] [Thread: 140321340434176] [Chan 6] - MF Tx >> 8 [ON]
[21:53:08:835] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:08:975] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:08:975] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:08:975] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:08:975] [Thread: 140321340434176] [Chan 6] - MF Tx >> 8 [OFF]
[21:53:09:054] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:09:054] [Thread: 140321340434176] [Chan 6] - Sending DNIS digit 2
[21:53:09:054] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [ON]
[21:53:09:054] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:09:374] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [ON]
[21:53:09:374] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:09:374] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:09:374] [Thread: 140321340434176] [Chan 6] - MF Tx >> 2 [OFF]
[21:53:09:435] [Thread: 140321340434176] [Chan 6] - MF Rx << 1 [OFF]
[21:53:09:435] [Thread: 140321340434176] [Chan 6] - No more DNIS. Doing 
nothing, waiting for timeout.
[21:53:09:435] [Thread: 140321340434176] [Chan 6] - scheduled timer id 3 
(mf_fwd_safety)
[21:53:09:435] [Thread: 140321340434176] [Chan 6] - Group A DNIS request 
handled
[21:53:12:315] [Thread: 140321340434176] [Chan 6] - MF Rx << 3 [ON]
[21:53:12:315] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 3
[21:53:12:315] [Thread: 140321340434176] [Chan 6] - timer id 3 found, 
cancelling it now
[21:53:12:435] [Thread: 140321340434176] [Chan 6] - MF Rx << 3 [OFF]
[21:53:12:435] [Thread: 140321340434176] [Chan 6] - Sending category 
National Subscriber
[21:53:12:435] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [ON]
[21:53:12:515] [Thread: 140321340434176] [Chan 6] - MF Rx << 6 [ON]
[21:53:12:515] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:12:515] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:53:12:515] [Thread: 140321340434176] [Chan 6] - MF Tx >> 1 [OFF]
[21:53:12:595] [Thread: 140321340434176] [Chan 6] - MF Rx << 6 [OFF]
[21:53:12:595] [Thread: 140321340434176] [Chan 6] - scheduled timer id 4 
(r2_answer)
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - Bits changed from 
0x0C to 0x04
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - CAS Rx << [ANSWER] 0x04
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 4
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - timer id 4 found, 
cancelling it now
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:53:25:091] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:56:38:607] [Thread: 140321340434176] [Chan 6] - Attempting to cancel 
timer timer 0
[21:56:38:607] [Thread: 140321340434176] [Chan 6] - Cannot cancel timer 0
[21:56:38:607] [Thread: 140321340434176] [Chan 6] - CAS Tx >> [CLEAR 
FORWARD] 0x08
[21:56:38:607] [Thread: 140321340434176] [Chan 6] - CAS Raw Tx >> 0x09
[21:56:38:960] [Thread: 140321482045184] [Chan 6] - Bits changed from 
0x04 to 0x00
[21:56:38:960] [Thread: 140321482045184] [Chan 6] - CAS Rx << [FORCED 
RELEASE] 0x00
[21:59:29:138] [Thread: 140321482045184] [Chan 6] - Bits changed from 
0x00 to 0x0C
[21:59:29:138] [Thread: 140321482045184] [Chan 6] - CAS Rx << [0x0C] 0x0C
[21:59:29:138] [Thread: 140321482045184] [Chan 6] - Protocol error. 
Reason = Invalid CAS, R2 State = Clear Back After Clear Forward 
Received, MF state = MF Engine Off, MF Group = Forwar$
DNIS = 4429682, ANI = 12720, MF = 0x20
[21:59:29:138] [Thread: 140321482045184] [Chan 6] - Attempting to cancel 
timer timer 0
[21:59:29:138] [Thread: 140321482045184] [Chan 6] - Cannot cancel timer 0

Cambié los BALUN y conectores pero sigue fallando.

Muchas Gracias!

Saludos

Diego