[asterisk-r2] Failed calls E1 CANTV

[Rafael Angulo]

Hello everybody,

I have an E1 (CANTV VENEZUELA) configured against an elastix appliance.
Elastix version is 2.3 asterisk version is 1.8.17.0 and dahdi version
is 2.4.1.2. Some calls are failing to go out, writing the "busy/congested"
message  into the log and sending the appropriate message back to the
caller. I'm not sure why the calling is failing, I think all the outgoing
channels in the E1 are getting busy, but when I check the logs, there are
some errors and I'm not sure if is the regular way asterisk log when all
channels in a E1 are busy or there is actually an error in the progress of
the call.

Any help will be appreciated, right now I'm saving the output of the
command mfcr2 show channels executed every two seconds to monitor if all
the channels get busy together at any time during the day. Attached to this
email is the log with the failing calls, this same behavior happens
randomly with many other channels.

[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - Requested to make
call (ANI=2748100, DNIS=04149898199, category=National Subscriber)
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - Call started at Fri
Feb  1 09:50:20 2013 on chan 22 [openr2 version 1.3.1, revision exported]
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - Outgoing call
proceeding: ANI=2748100, DNIS=04149898199, Category=National Subscriber
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Tx >> [SEIZE]
0x00
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Raw Tx >> 0x01
[Feb  1 09:50:20] VERBOSE[12104] app_dial.c:     -- Called
DAHDI/g3/04149898199
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: bits changed in chan 22
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - Bits changed from
0x08 to 0x0C
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Rx << [SEIZE
ACK] 0x0C
[Feb  1 09:50:20] DEBUG[12104] chan_dahdi.c: Chan 22 - DTMF/R2 call
acknowledge!
[Feb  1 09:50:21] DEBUG[12104] chan_dahdi.c: Chan 22 - calling timer 3
(start_dialing_dtmf) callback
[Feb  1 09:50:21] VERBOSE[12104] chan_dahdi.c: Chan 22 - Dialing
04149898199 with DTMF/R2 (tone on = 50, tone off = 100)
[Feb  1 09:50:22] DEBUG[12104] chan_dahdi.c: Chan 22 - Done with DTMF
generation
[Feb  1 09:50:22] DEBUG[12104] chan_dahdi.c: Chan 22 - DTMF R2 call is done
generating DTMF, forcing accept signal
[Feb  1 09:50:22] VERBOSE[12104] chan_dahdi.c: MFC/R2 call has been
accepted on forward channel 22
[Feb  1 09:50:22] VERBOSE[12104] app_dial.c:     -- DAHDI/22-1 is ringing
[Feb  1 09:50:22] DEBUG[12104] chan_dahdi.c: Enqueuing progress frame after
R2 accept in chan 22
[Feb  1 09:50:22] VERBOSE[12104] app_dial.c:     -- DAHDI/22-1 is making
progress passing it to SIP/116-000089f0
[Feb  1 09:50:23] DEBUG[12104] chan_dahdi.c: bits changed in chan 22
[Feb  1 09:50:23] DEBUG[12104] chan_dahdi.c: Chan 22 - Bits changed from
0x0C to 0x08
[Feb  1 09:50:23] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Rx << [0x08] 0x08
[Feb  1 09:50:23] ERROR[12104] chan_dahdi.c: Chan 22 - Protocol error.
Reason = Invalid CAS, R2 State = Seize ACK Received, MF state = MF Engine
Off, MF Group = Forwar$
DNIS = 04149898199, ANI = 2748100, MF = 0x20
[Feb  1 09:50:23] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Tx >> [IDLE] 0x08
[Feb  1 09:50:23] DEBUG[12104] chan_dahdi.c: Chan 22 - CAS Raw Tx >> 0x09
[Feb  1 09:50:23] ERROR[12104] chan_dahdi.c: MFC/R2 protocol error on chan
22: Invalid CAS
[Feb  1 09:50:23] VERBOSE[12104] chan_dahdi.c:     -- Hungup 'DAHDI/22-1'
[Feb  1 09:50:23] VERBOSE[12104] app_dial.c:   == Everyone is
busy/congested at this time (1:0/0/1)
[Feb  1 09:50:23] VERBOSE[12104] pbx.c:     -- Executing
[s at macro-dialout-trunk:20] NoOp("SIP/116-000089f0", "Dial failed for some
reason with DIALSTATUS = CHANUNAVAIL$
[Feb  1 09:50:23] VERBOSE[12104] pbx.c:     -- Executing
[s at macro-dialout-trunk:21] Goto("SIP/116-000089f0", "s-CHANUNAVAIL,1") in
new stack





-- 
Rafael A Angulo R
Director Gerente
Tarma Consultores C.A.
Cel. (58)(414)106.04.73
Ofi. (58)(212)793.49.81
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-r2/attachments/20130201/5785742b/attachment.htm>