[asterisk-r2] getting a dial tone when dialing on e1r2

Tue May 19 23:22:08 CDT 2009

Has anyone experienced this before? Whenever we dialout using the E1R2
trunk, all we get is a dial tone after sending the number to dial on the
Asterisk server. It's as if it's waiting for another set of numbers to be
dialed.

Here's the output from the Asterisk CLI:

    -- Executing Dial("SIP/100-1adcfa70", "zap/g0/7016872||tTo") in new
stack
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1334 zt_r2_get_channel_category: no
MFC/R2 category specified for chan Zap/1-1, using default National
Subscriber
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Attempting to make call (ANI=8989525, DNIS=7016872, category=National
Subscriber)
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - CAS
Tx >> [SEIZE] 0x00
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - CAS
Raw Tx >> 0x01
    -- Called g0/7016872
May 14 04:33:15 DEBUG[6635]: channel.c:775 channel_find_locked: Avoiding
initial deadlock for 'Zap/1-1'
May 14 04:33:15 DEBUG[6654]: chan_zap.c:4969 __zt_exception: Exception on
16, channel 1
May 14 04:33:15 DEBUG[6654]: chan_zap.c:4147 zt_handle_event: Got event Bits
Changed(13) on channel 1 (index 0)
May 14 04:33:15 DEBUG[6654]: chan_zap.c:4184 zt_handle_event: bits changed
in chan 1
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - Bits
changed from 0x08 to 0x0C
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - CAS
Rx << [SEIZE ACK] 0x0C
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending DNIS digit 7
May 14 04:33:15 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 7 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 7 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending category National Subscriber
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 1 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 1 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 8
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 8 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 8 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 9
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 9 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 9 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 8
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 8 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 8 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 9
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 9 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 9 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 5
May 14 04:33:16 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 5 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 5 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 2
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 2 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 2 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending ANI digit 5
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 5 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 5 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 5 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending more ANI unavailable
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> F [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 1 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> F [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 1 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending DNIS digit 0
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 0 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - Group
A DNIS request handled
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 1 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 0 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 1 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 -
Sending DNIS digit 1
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 1 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - Group
A DNIS request handled
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 6 [ON]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Tx
>> 1 [OFF]
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - MF Rx
<< 6 [OFF]
May 14 04:33:17 NOTICE[6654]: chan_zap.c:1486 zt_r2_on_call_accepted: MFC/R2
call has been accepted on chan 1
May 14 04:33:17 DEBUG[6654]: chan_zap.c:1986 zt_enable_ec: Enabled echo
cancellation on channel 1
May 14 04:33:17 NOTICE[6654]: chan_zap.c:1502 zt_r2_on_call_accepted: Call
accepted on forward channel 1
    -- Zap/1-1 is ringing
May 14 04:33:49 DEBUG[6654]: chan_zap.c:2958 zt_hangup: Hangup: channel: 1
index = 0, normal = 16, callwait = -1, thirdcall = -1
May 14 04:33:49 DEBUG[6654]: chan_zap.c:3139 zt_hangup: disconnecting MFC/R2
call on chan 1
May 14 04:33:49 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - CAS
Tx >> [CLEAR FORWARD] 0x08
May 14 04:33:49 DEBUG[6654]: chan_zap.c:1602 zt_r2_write_log: Chan 1 - CAS
Raw Tx >> 0x09
May 14 04:33:49 DEBUG[6654]: chan_zap.c:2018 zt_disable_ec: disabled echo
cancellation on channel 1
May 14 04:33:49 DEBUG[6654]: chan_zap.c:3397 zt_setoption: Set option TDD
MODE, value: OFF(0) on Zap/1-1
May 14 04:33:49 DEBUG[6654]: chan_zap.c:1955 update_conf: Updated
conferencing on 1, with 0 conference users
    -- Hungup 'Zap/1-1'

Here are some details on our setup:

Asterisk SVN-moy-mfcr2-1.2-r178065M
OpenR2 version: 1.1.0
Zaptel 1.2.27
PLDT E1R2 line (Philippines)
Digium TE405P

Here's the contents of zaptel.conf:

span=1,1,0,cas,hdb3
cas=1-15:1101
cas=17-31:1101
dchan=16

Here's zapata.conf:

[trunkgroups]

[channels]
usecallerid=yes
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=yes

signalling=mfcr2
mfcr2_variant=ph
;mfcr2_advance_protocol_file=/etc/asterisk/r2proto.conf
mfcr2_get_ani_first=no
mfcr2_max_ani=12
mfcr2_max_dnis=18
mfcr2_category=national_subscriber
mfcr2_logdir=span1
mfcr2_logging=all
mfcr2_immediate_accept=yes
;mfcr2_call_files=yes

context=default
group=0
callgroup=0
pickupgroup=0
channel => 1-15
channel => 17-31

Any inputs or insights are greatly appreciated.

-- 
Regards,

Demian A. Biscocho
CTO
vNOW Inc.
Unit 420 Suite 4/F Burgundy Empire Tower
ADB Avenue Ortigas Center Pasig City 1605
Web: http://www.vnowinc.com
PH office: 63 27016872

Lead Developer
VicidialNOW open source project
http://www.vicidialnow.org

Linux User 121825 (http://counter.li.org)
US mobile: 1 2062036815
PH mobile: 63 9172491537
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-r2/attachments/20090520/08de31c9/attachment.htm 