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: [EMAIL PROTECTED] [mailto:[EMAIL PROTECTED] 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: [EMAIL PROTECTED] [mailto:[EMAIL PROTECTED] 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: [EMAIL PROTECTED] [mailto:[EMAIL PROTECTED] On Behalf Of Tzafrir Cohen Sent: 11 October 2006 08:29 To: asterisk-users@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:[EMAIL PROTECTED] icq#16849755 iax:[EMAIL PROTECTED] +972-50-7952406 jabber:[EMAIL PROTECTED] [EMAIL PROTECTED] 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 _______________________________________________ --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