[asterisk-users] Strange FXS disconnection problem.

[David Bath]

Hey all,

Just as an update, incoming calls are fine.  I have had several long
calls today inbound on the PSTN with no drops.

>From the log it does sort of look like a hangup is being detected.. but
its certainly not correct!

Could anyone help me debug this a little further?

Cheers,

Dave

-----Original Message-----
From: asterisk-users-bounces at lists.digium.com
[mailto:asterisk-users-bounces at lists.digium.com] On Behalf Of David Bath
Sent: 11 October 2006 19:52
To: Asterisk Users Mailing List - Non-Commercial Discussion
Subject: RE: [asterisk-users] Strange FXS disconnection problem.

As further info, here's the tail of the verbose logging (as enabled in
logger.conf).  I have the complete log (but there are lots of irrelevant
SIP transactions for other phones/providers) which I can send if it
becomes helpful.

NB. The mysql server was down for maintenance at the time, so the cdr
errors are expected.


Oct 11 19:46:52 DEBUG[19438]: chan_zap.c:2342 zt_hangup: Hangup:
channel: 2 index = 0, normal = 20, callwait = -1, thirdcall = -1
Oct 11 19:46:52 DEBUG[19438]: chan_zap.c:1435 zt_disable_ec: disabled
echo cancellation on channel 2
Oct 11 19:46:52 DEBUG[19438]: chan_zap.c:2782 zt_setoption: Set option
TDD MODE, value: OFF(0) on Zap/2-1
Oct 11 19:46:52 DEBUG[19438]: chan_zap.c:1372 update_conf: Updated
conferencing on 2, with 0 conference users
    -- Hungup 'Zap/2-1'
Oct 11 19:46:52 DEBUG[19438]: app_dial.c:1635 dial_exec_full: Exiting
with DIALSTATUS=CANCEL.
  == Spawn extension (davesextensions, 517070, 1) exited non-zero on
'SIP/101-09d925a8'
Oct 11 19:46:52 ERROR[19438]: cdr_addon_mysql.c:144 mysql_log:
cdr_mysql: cannot connect to database server localhost.
Oct 11 19:46:52 DEBUG[19438]: cdr_addon_mysql.c:206 mysql_log:
cdr_mysql: inserting a CDR record.
Oct 11 19:46:52 DEBUG[19438]: cdr_addon_mysql.c:222 mysql_log:
cdr_mysql: SQL command as follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,dura
tion,billsec,disposition,amaflags,accountcode) VALUES ('2006-10-11
19:43:52','\"Dave Bath\" <101>','101','517070','davesextensions',
'SIP/101-09d925a8','Zap/2-1','Dial','ZAP/2/17070',180,0,'NO
ANSWER',3,'')
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '"Dave Bath"
<101>'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '101'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '517070'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is
'davesextensions'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is
'SIP/101-09d925a8'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is 'Zap/2-1'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is 'Dial'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is 'ZAP/2/17070'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '2006-10-11
19:43:52'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '(null)'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '2006-10-11
19:46:52'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '180'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '0'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '(null)'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '1160592232.0'
Oct 11 19:46:52 DEBUG[19438]: pbx.c:1522
pbx_substitute_variables_helper_full: Function result is '(null)'
Oct 11 19:46:52 DEBUG[19438]: chan_sip.c:2433 sip_hangup:
update_call_counter(101) - decrement call limit counter

Cheers,

Dave

-----Original Message-----
From: asterisk-users-bounces at lists.digium.com
[mailto:asterisk-users-bounces at lists.digium.com] On Behalf Of David Bath
Sent: 11 October 2006 17:08
To: Asterisk Users Mailing List - Non-Commercial Discussion
Subject: RE: [asterisk-users] Strange FXS disconnection problem.

Hi Tzafrir,

Many thanks for reply.

Busydetect is also disabled.  There's no chance of an actual busy
signal, as it happens exactly 2m 40 seconds (give or take 1s) into an
active call with both parties connected and talking away.

Zapata.conf copied below:

[channels]
signalling=fxs_ks
echocancel=64
echocancelwhenbridged=yes
echotraining=400
cidstart=polarity
hidecallerid=no
callwaiting=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
busydetect=no
callprogress=no
progzone=uk
pulsedial=no
answeronpolarityswitch=yes
musiconhold=default
ringtimeout=1000
userincomingcalledidonzaptransfer=yes
usercallerid=yes
cidsignalling=v23
cidstart=usehist
language=en
rxgain=3
txgain=3
context=bt_pstn
channel => 1

Thanks for your time.

Dave

~

-----Original Message-----
From: asterisk-users-bounces at lists.digium.com
[mailto:asterisk-users-bounces at lists.digium.com] On Behalf Of Tzafrir
Cohen
Sent: 11 October 2006 08:29
To: asterisk-users at lists.digium.com
Subject: Re: [asterisk-users] Strange FXS disconnection problem.

On Tue, Oct 10, 2006 at 11:43:09PM +0100, David Bath wrote:
> Hi All,
> 
>  
> 
> I've tried to find the solution to this, but sadly met with failure.
> I've got an asterisk box with two X100P's in, and both cards have the
> same strange problem.  After 2min 40seconds (always: within +/- 1sec)
> they drop an outbound call.  Inbound calls are not affected... they
stay
> up as long as required.
> 

Do you use busydetect? Any chance that there's a busy tone there?

>  
> 
> I've seen a fair bit of chatter about similar kinds of problems
> sometimes being related to callprogess detecting false hang-ups, so
I've
> made sure this is disabled in Zapata.conf but it seems to have had no
> effect.
> 
>  
> 
> I was running a slightly older version of zaptel/asterisk (1.2.0) and
> I've upgraded to the lastest build, but also with no success.

Details, pleasse:

zapata.conf

logs from a call (set verbose to at least 3, and enable full in
logger.conf).

-- 
Tzafrir Cohen         sip:tzafrir at local.xorcom.com
icq#16849755          iax:tzafrir at local.xorcom.com
+972-50-7952406          jabber:tzafrir at jabber.org
tzafrir.cohen at xorcom.com     http://www.xorcom.com
_______________________________________________
--Bandwidth and Colocation provided by Easynews.com --

asterisk-users mailing list
To UNSUBSCRIBE or update options visit:
   http://lists.digium.com/mailman/listinfo/asterisk-users
_______________________________________________
--Bandwidth and Colocation provided by Easynews.com --

asterisk-users mailing list
To UNSUBSCRIBE or update options visit:
   http://lists.digium.com/mailman/listinfo/asterisk-users
_______________________________________________
--Bandwidth and Colocation provided by Easynews.com --

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