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

Fri Oct 26 06:58:15 CDT 2012

Diego

Buen día, te comento que no es claro cual es tu problema en el trace de la llamada, indica que se genero la llamada desde el interno 12720 (Asterisk)  al numero  4429682 este contesta la llamada y luego de un tiempo el interno 12720, libera la comunicación, lo que si es de observar que el centro frontal a posterior de que el interno 12720 corta la comunicación y se hace el envió de la señalización de liberación este del estado se contestación envía una liberación forzada, debiendo de ser IDLE.
No hay razón alguna de que puedas hacer algo de tu lado, ya sea cambiando balunes conectores y demás, si te puede servir que en la pruebas tantos vos como el centro frontal hagan trace de los eventos y comparen entre si.
Saludos

Mc GRATH Ricardo
E-Mail mcgrathr at mail2web.com
________________________________________
From: asterisk-r2-bounces at lists.digium.com [asterisk-r2-bounces at lists.digium.com] On Behalf Of Diego G. Perez [diegoperez891 at gmail.com]
Sent: 25 October 2012 10:06
To: asterisk-r2 at lists.digium.com
Subject: [asterisk-r2] Protocol error. Reason = Invalid CAS

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

--
_____________________________________________________________________
-- Bandwidth and Colocation Provided by http://www.api-digital.com --

asterisk-r2 mailing list
To UNSUBSCRIBE or update options visit:
   http://lists.digium.com/mailman/listinfo/asterisk-r2