[asterisk-r2] openR2 drop calls

Fri Apr 17 14:47:04 CDT 2009

Hi all,

I'm Brazilian and I study English yet and forgive my worst English. So, I have tested openR2 and my received calls is dropping. In error log, I have the following:

[11:52:05:755] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [ON]

[11:52:05:755] [Thread: 3070852016] [Chan 29] - scheduled timer id 14 (mf_back_cycle)

[11:52:05:815] [Thread: 3070852016] [Chan 29] - MF Rx << F [OFF]

[11:52:05:815] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [OFF]

[11:52:05:895] [Thread: 3070852016] [Chan 29] - MF Rx << 3 [ON]

[11:52:05:895] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 14

[11:52:05:895] [Thread: 3070852016] [Chan 29] - timer id 14 found, cancelling it now

[11:52:05:895] [Thread: 3070852016] [Chan 29] - Getting DNIS digit 3

[11:52:05:895] [Thread: 3070852016] [Chan 29] - DNIS so far: 73, expected length: 20

[11:52:05:895] [Thread: 3070852016] [Chan 29] - Requesting next DNIS with signal 0x31.

[11:52:05:895] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [ON]

[11:52:05:895] [Thread: 3070852016] [Chan 29] - scheduled timer id 15 (mf_back_cycle)

[11:52:05:975] [Thread: 3070852016] [Chan 29] - MF Rx << 3 [OFF]

[11:52:05:975] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [OFF]

[11:52:06:035] [Thread: 3070852016] [Chan 29] - MF Rx << 2 [ON]

[11:52:06:035] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 15

[11:52:06:035] [Thread: 3070852016] [Chan 29] - timer id 15 found, cancelling it now

[11:52:06:035] [Thread: 3070852016] [Chan 29] - Getting DNIS digit 2

[11:52:06:035] [Thread: 3070852016] [Chan 29] - DNIS so far: 732, expected length: 20

[11:52:06:035] [Thread: 3070852016] [Chan 29] - Requesting next DNIS with signal 0x31.

[11:52:06:035] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [ON]

[11:52:06:035] [Thread: 3070852016] [Chan 29] - scheduled timer id 16 (mf_back_cycle)

[11:52:06:115] [Thread: 3070852016] [Chan 29] - MF Rx << 2 [OFF]

[11:52:06:115] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [OFF]

[11:52:06:175] [Thread: 3070852016] [Chan 29] - MF Rx << 0 [ON]

[11:52:06:175] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 16

[11:52:06:175] [Thread: 3070852016] [Chan 29] - timer id 16 found, cancelling it now

[11:52:06:175] [Thread: 3070852016] [Chan 29] - Getting DNIS digit 0

[11:52:06:175] [Thread: 3070852016] [Chan 29] - DNIS so far: 7320, expected length: 20

[11:52:06:175] [Thread: 3070852016] [Chan 29] - User requested us to stop getting DNIS!

[11:52:06:175] [Thread: 3070852016] [Chan 29] - Requesting change to Group II with signal 0x33

[11:52:06:175] [Thread: 3070852016] [Chan 29] - MF Tx >> 3 [ON]

[11:52:06:175] [Thread: 3070852016] [Chan 29] - scheduled timer id 17 (mf_back_cycle)

[11:52:06:255] [Thread: 3070852016] [Chan 29] - MF Rx << 0 [OFF]

[11:52:06:255] [Thread: 3070852016] [Chan 29] - MF Tx >> 3 [OFF]

[11:52:06:315] [Thread: 3070852016] [Chan 29] - MF Rx << 1 [ON]

[11:52:06:315] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 17

[11:52:06:315] [Thread: 3070852016] [Chan 29] - timer id 17 found, cancelling it now

[11:52:06:315] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [ON]

[11:52:06:315] [Thread: 3070852016] [Chan 29] - scheduled timer id 18 (mf_back_cycle)

[11:52:06:395] [Thread: 3070852016] [Chan 29] - MF Rx << 1 [OFF]

[11:52:06:395] [Thread: 3070852016] [Chan 29] - MF Tx >> 1 [OFF]

[11:52:06:395] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 18

[11:52:06:395] [Thread: 3070852016] [Chan 29] - timer id 18 found, cancelling it now

[11:52:06:395] [Thread: 3070852016] [Chan 29] - scheduled timer id 19 (r2_answer_delay)

[11:52:06:555] [Thread: 3070852016] [Chan 29] - Attempting to cancel timer timer 19

[11:52:06:555] [Thread: 3070852016] [Chan 29] - timer id 19 found, cancelling it now

[11:52:06:555] [Thread: 3070852016] [Chan 29] - calling timer 19 (r2_answer_delay) callback

[11:52:06:558] [Thread: 3069574064] [Chan 29] - CAS Tx >> [ANSWER] 0x04

[11:52:06:558] [Thread: 3069574064] [Chan 29] - CAS Raw Tx >> 0x05

[11:52:38:619] [Thread: 3069574064] [Chan 29] - Bits changed from 0x00 to 0x08

[11:52:38:619] [Thread: 3069574064] [Chan 29] - CAS Rx << [CLEAR FORWARD] 0x08

[11:52:38:619] [Thread: 3069574064] [Chan 29] - Far end disconnected. Reason: Normal Clearing

[11:52:38:633] [Thread: 3069574064] [Chan 29] - Call ended

[11:52:38:633] [Thread: 3069574064] [Chan 29] - Attempting to cancel timer timer 0

[11:52:38:633] [Thread: 3069574064] [Chan 29] - Cannot cancel timer 0

My configuration is that: AsteriskR2 à Asteriskchannels

I receive the call in AsteriskR2 and I send this call for Asteriskchannels through IAX2. The Asteriskchannels look the specific channel and send the call for the correct user.

I think who I don't have problems with IAX2.. IAX2 don't drop my call, see the asterisk log:

[Apr 16 11:52:04] NOTICE[2772]: chan_dahdi.c:1023 dahdi_r2_on_call_init: New MFC/R2 call detected on chan 29.

[Apr 16 11:52:06] NOTICE[2772]: chan_dahdi.c:1090 dahdi_r2_on_call_offered: MFC/R2 call offered on chan 29. ANI = 11XXXXXXXX, DNIS = XXXX, Category = National Subscriber

[Apr 16 11:52:06] NOTICE[2772]: chan_dahdi.c:1153 dahdi_r2_on_call_accepted: MFC/R2 call has been accepted on chan 29

    -- Executing [XXXX at e1:1] Set("Zap/29-1", "CALLERID(num)=11XXXXXXXX") in new stack

    -- Executing [XXXX at e1:2] Dial("Zap/29-1", "IAX2/astpstnr2:XXXXXXX at nnnn.mmmm.dddd.zzzz/XXXX") in new stack

    -- Called astpstnr2: :XXXXXXX at nnnn.mmmm.dddd.zzzz/XXXX

    -- Call accepted by nnnn.mmmm.dddd.zzzz (format ulaw)

    -- Format for call is ulaw

    -- IAX2/ nnnn.mmmm.dddd.zzzz:4569-14305 answered Zap/29-1

After

[Apr 16 11:52:38] NOTICE[2939]: chan_dahdi.c:1288 dahdi_r2_write_log: Chan 29 - Far end disconnected. Reason: Normal Clearing

[Apr 16 11:52:38] NOTICE[2939]: chan_dahdi.c:1223 dahdi_r2_on_call_disconnect: MFC/R2 call disconnected on chan 29

    -- Hungup 'IAX2/ nnnn.mmmm.dddd.zzzz:4569-14305'

  == Spawn extension (e1, XXXX, 2) exited non-zero on 'Zap/29-1'

[Apr 16 11:52:38] NOTICE[2939]: chan_dahdi.c:1142 dahdi_r2_on_call_end: MFC/R2 call end on chan 29

    -- Hungup 'Zap/29-1'

My zaptel conf

span=1,1,0,cas,hdb3

cas=1-15:1101

dchan=16

cas=17-31:1101

loadzone = br

defaultzone= br

My Zapata.conf ( E1 Telefonica)

[channels]

usecallerid=yes

callwaiting=yes

usecallingpres=yes

callwaitingcallerid=yes

threewaycalling=yes

transfer=yes

cancallforward=yes

callreturn=yes

echocancel=yes

echotrainning=yes

echocancelwhenbridged=yes

signalling=mfcr2

mfcr2_variant=br

mfcr2_get_ani_first=yes

mfcr2_max_ani=20

mfcr2_max_dnis=20

mfcr2_category=national_subscriber

mfcr2_logdir=openr2

mfcr2_call_files=yes

mfcr2_logging=all

mfcr2_metering_pulse_timeout=500

;

group=1

callerid=asreceived

context=e1            

channel => 1-15,17-31

Thanks,

Rubens Almeida

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-r2/attachments/20090417/987dadc1/attachment.htm 