[asterisk-r2] [r2 dropped calls]

Mon Jul 18 15:54:18 CDT 2011

Hello all there.

We're having trouble with calls being dropped on our asterisk box and we
can't find out why.

I'm working with asterisk 1.6.2.0 + DAHDI 2.4.1.2 + OpenR2 1.2.0, with a
quad E1 Redfone.  We work 2 E1s with our provider in Cali Colombia and 2 E1s
against an old legacy PBX (Siemens EMS 601).

With our provider everything is ok, calls are set up with no problem at all.

The problem is on incoming calls, they are dropped suddenly after they are
established.  Even the time after the calls are dropped is totally random,
so there nothing there that could help us to find out what's going on.  We
have placed an IP Phone to receive some calls and everything is fine, so the
problem is between our asterisk and  Siemens PBX.  Checking the mfcr2 logs,
after the call is established appears a message saying "Bits changed from
0x04 to 0x08" and the call is dropped.  This happens with only one call.

Sometimes, after the call is established another incoming call generates an
error message saying "Invalid CAS", after this message, all the calls in
that span are dropped.

We have tried with all the mfcr2 variants and is the same thing or worse
with someones.  The PBX programmer say they are working with br variant, but
with this variant we can't make outbound calls and incoming calls are
dropped too.  He says that he can't see what's happening in the siemens PBX
because he doesn't have the appropriate software for tracing, so we're
stucked because we can't see any log on the legacy PBX.

These are my configuration files:

****************************************************************chan_dahdi.conf*********************************************************************************************
[channels]
language=es
context=default
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
;faxdetect=no
busydetect=no
callprogress=no
relaxdtmf=yes
rxgain=0.0
txgain=0.0
callgroup=1
pickupgroup=1
;
signalling=mfcr2
mfcr2_variant=itu  ;AR ;CN ;CZ ;PH ;MX ;EC ;VE ;BR ;co ;ar     ;br
;  mfcr2_immediate_accept=yes
mfcr2_max_ani=20
mfcr2_max_dnis=20
mfcr2_category=national_subscriber
mfcr2_get_ani_first=no
mfcr2_advanced_protocol_file=/etc/asterisk/openr2.conf
;  mfcr2_metering_pulse_timeout=0
;  mfcr2_charge_calls=yes
;  mfcr2_mfback_timeout=3000
; The next three params are for debuging, you can disable when in production
mfcr2_call_files=yes
mfcr2_logdir=/var/log/embratel
mfcr2_logging=all

group=0
context=planta
channel => 1-15
channel => 17-31
channel => 32-46
channel => 48-62

group=1
context=entrante
channel => 63-77
channel => 79-93
channel => 94-108
channel => 110-124
***************************************************************chan_dahdi.conf******************************************************************************************

***************************************************************openr2.conf***********************************************************************************************
mf_g1_tones.no_more_dnis_available=X
***************************************************************openr2.conf***********************************************************************************************

And this is the log of a dropped call made from the IP phone to an extension
in the Siemens PBX.

***************************************************************chan-1-forward-0-20110718154508.call*************************************************************

[15:45:08:004] [Thread: 3044408208] [Chan 1] - M - Call started at Mon Jul
18 15:45:08 2011 on chan 1 [openr2 version 1.2.0, revision (release)]
[15:45:08:004] [Thread: 3044408208] [Chan 1] - M - CAS Tx >> [SEIZE] 0x00
[15:45:08:004] [Thread: 3044408208] [Chan 1] - M - CAS Raw Tx >> 0x01
[15:45:08:004] [Thread: 3044408208] [Chan 1] - M - scheduled timer id 2
(r2_seize)
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - Bits changed from 0x08 to
0x0C
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - CAS Rx << [SEIZE ACK]
0x0C
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 2
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - timer id 2 found,
cancelling it now
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - Sending DNIS digit 4
[15:45:08:047] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 4 [ON]
[15:45:08:183] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [ON]
[15:45:08:183] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:08:183] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:08:183] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 4 [OFF]
[15:45:08:323] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [OFF]
[15:45:08:323] [Thread: 3044408208] [Chan 1] - M - Sending DNIS digit 7
[15:45:08:323] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 7 [ON]
[15:45:08:323] [Thread: 3044408208] [Chan 1] - M - Group A DNIS request
handled
[15:45:08:463] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [ON]
[15:45:08:463] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:08:463] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:08:463] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 7 [OFF]
[15:45:08:603] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [OFF]
[15:45:08:603] [Thread: 3044408208] [Chan 1] - M - Sending DNIS digit 6
[15:45:08:603] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 6 [ON]
[15:45:08:603] [Thread: 3044408208] [Chan 1] - M - Group A DNIS request
handled
[15:45:08:743] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [ON]
[15:45:08:743] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:08:743] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:08:743] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 6 [OFF]
[15:45:08:863] [Thread: 3044408208] [Chan 1] - M - MF Rx << 1 [OFF]
[15:45:08:863] [Thread: 3044408208] [Chan 1] - M - Sending DNIS digit 2
[15:45:08:863] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 2 [ON]
[15:45:08:863] [Thread: 3044408208] [Chan 1] - M - Group A DNIS request
handled
[15:45:09:003] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:09:003] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:09:003] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:09:003] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 2 [OFF]
[15:45:09:143] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:09:143] [Thread: 3044408208] [Chan 1] - M - Sending category National
Subscriber
[15:45:09:143] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 1 [ON]
[15:45:09:263] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:09:263] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:09:263] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:09:263] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 1 [OFF]
[15:45:09:383] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:09:383] [Thread: 3044408208] [Chan 1] - M - Sending ANI digit 5
[15:45:09:383] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 5 [ON]
[15:45:09:523] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:09:523] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:09:523] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:09:523] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 5 [OFF]
[15:45:09:643] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:09:643] [Thread: 3044408208] [Chan 1] - M - Sending ANI digit 0
[15:45:09:643] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [ON]
[15:45:09:763] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:09:763] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:09:763] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:09:763] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [OFF]
[15:45:09:883] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:09:883] [Thread: 3044408208] [Chan 1] - M - Sending ANI digit 0
[15:45:09:883] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [ON]
[15:45:10:023] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:10:023] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:10:023] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:10:023] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [OFF]
[15:45:10:123] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:10:123] [Thread: 3044408208] [Chan 1] - M - Sending ANI digit 0
[15:45:10:123] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [ON]
[15:45:10:243] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [ON]
[15:45:10:243] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:10:243] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:10:243] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 0 [OFF]
[15:45:10:383] [Thread: 3044408208] [Chan 1] - M - MF Rx << 5 [OFF]
[15:45:10:383] [Thread: 3044408208] [Chan 1] - M - Sending more ANI
unavailable
[15:45:10:383] [Thread: 3044408208] [Chan 1] - M - MF Tx >> F [ON]
[15:45:10:523] [Thread: 3044408208] [Chan 1] - M - MF Rx << 3 [ON]
[15:45:10:523] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:10:523] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:10:523] [Thread: 3044408208] [Chan 1] - M - MF Tx >> F [OFF]
[15:45:10:643] [Thread: 3044408208] [Chan 1] - M - MF Rx << 3 [OFF]
[15:45:10:643] [Thread: 3044408208] [Chan 1] - M - Sending category National
Subscriber
[15:45:10:643] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 1 [ON]
[15:45:10:763] [Thread: 3044408208] [Chan 1] - M - MF Rx << 6 [ON]
[15:45:10:763] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:10:763] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:10:763] [Thread: 3044408208] [Chan 1] - M - MF Tx >> 1 [OFF]
[15:45:10:903] [Thread: 3044408208] [Chan 1] - M - MF Rx << 6 [OFF]
[15:45:10:903] [Thread: 3044408208] [Chan 1] - M - scheduled timer id 3
(r2_answer)
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - Bits changed from 0x0C to
0x04
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - CAS Rx << [ANSWER] 0x04
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 3
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - timer id 3 found,
cancelling it now
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:11:702] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:26:842] [Thread: 3044408208] [Chan 1] - M - Bits changed from 0x04 to
0x08
[15:45:26:842] [Thread: 3044408208] [Chan 1] - M - CAS Rx << [CLEAR FORWARD]
0x08
[15:45:26:842] [Thread: 3044408208] [Chan 1] - M - Far end disconnected.
Reason: Normal Clearing
[15:45:26:844] [Thread: 3044408208] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:26:844] [Thread: 3044408208] [Chan 1] - M - Cannot cancel timer 0
[15:45:26:844] [Thread: 3044408208] [Chan 1] - M - CAS Tx >> [CLEAR FORWARD]
0x08
[15:45:26:844] [Thread: 3044408208] [Chan 1] - M - CAS Raw Tx >> 0x09
[15:45:26:859] [Thread: 3057417104] [Chan 1] - M - Bits changed from 0x08 to
0x0C
[15:45:26:859] [Thread: 3057417104] [Chan 1] - M - CAS Rx << [CLEAR BACK]
0x0C
[15:45:27:534] [Thread: 3057417104] [Chan 1] - M - Bits changed from 0x0C to
0x08
[15:45:27:534] [Thread: 3057417104] [Chan 1] - M - CAS Rx << [IDLE] 0x08
[15:45:27:534] [Thread: 3057417104] [Chan 1] - M - Call ended
[15:45:27:534] [Thread: 3057417104] [Chan 1] - M - Attempting to cancel
timer timer 0
[15:45:27:534] [Thread: 3057417104] [Chan 1] - M - Cannot cancel timer 0

************************************************chan-1-forward-0-20110718154508.call********************************************************************************

We have also used the "timers.cas_persistence_check=3000" parameter and the
calls are dropped too.

Thanks in advance for any help on this.
-- 
Juan Pablo Villarreal
Ingeniero Electrónico
email: jvillarreal at opengroupsa.com
Santiago de Cali
Colombia

*
<http://www.opengroupsa.com/>*
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.digium.com/pipermail/asterisk-r2/attachments/20110718/56a7dc3d/attachment.htm>