---------- Forwarded message ----------
From: Steven McCann <[EMAIL PROTECTED]>
Date: Mon, Mar 31, 2008 at 7:23 AM
Subject: Calls dropping w. trixbox|
To: Toronto Asterisk UG <[email protected]>
Hello All,
I wonder if anyone would be familiar with a problem I am having. I have some
SIP phones running off an Asterisk server, and the Asterisk server has a
TDM400P with 2 POTS lines coming into it. Every now and then a call will
just drop, and the person on the other end thinks you hung up on them. Some
of the server config is below...
Asterisk server:
-Athlon 1400 MHz
-512mb PC133 SDRAM
-ASUS A7V133 motherboard (VIA chipset)
-20GB 7200rpm drive
-Ultra 550w high-efficiency power supply
-Wildcard TDM400P Rev 1 Board 1
-hooked up to a 1000VA APC battery back up
-both lines are surge protected, and are hooked up to a DSL filter (which
seems to improve the detection of hang-ups)
---
I have tried to have a look at the files /var/log/messages/asterisk/full...
on the server, and looks like some interesting messages there.
A call dropped at about 3:51pm, and the person called back about 15 seconds
later. I think I found the log where it happened...
I think I should still have a warranty with Digium so I can inquire with
them, but if anyone has any ideas they are greatly appreciated!
Here's an output from /var/log/messages/asterisk/full :
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Exception on 19, channel 2
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Got event On hook(1) on channel 2
(index 0)
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: disabled echo cancellation on
channel 2
Mar 26 15:51:07 DEBUG[12888] channel.c: Didn't get a frame from channel:
Zap/2-1
Mar 26 15:51:07 DEBUG[12888] channel.c: Bridge stops bridging channels
Zap/2-1 and SIP/1026-09af1270
Mar 26 15:51:07 DEBUG[12888] chan_sip.c: update_call_counter(1026) -
decrement call limit counter
Mar 26 15:51:07 DEBUG[12888] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Mar 26 15:51:07 DEBUG[12888] cdr_addon_mysql.c: cdr_mysql: inserting a CDR
record.
Mar 26 15:51:07 DEBUG[12888] cdr_addon_mysql.c: cdr_mysql: SQL command as
follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid)
VALUES ('2008-03-26 15:50:51','EXT:','','600','ext-group',
'Zap/2-1','SIP/1026-09af1270','ResetCDR','w',16,13,'ANSWERED',3,'','
1206561049.364')
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: ResetCDR
Mar 26 15:51:07 NOTICE[12888] cdr.c: CDR on channel 'Zap/2-1' not posted
Mar 26 15:51:07 NOTICE[12888] cdr.c: CDR on channel 'Zap/2-1' lacks end
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: NoCDR
Mar 26 15:51:07 DEBUG[12888] pbx.c: Expression result is '1'
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: GotoIf
Mar 26 15:51:07 DEBUG[12888] pbx.c: Expression result is '0'
Mar 26 15:51:07 DEBUG[12888] pbx.c: Not taking any branch
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: GotoIf
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: Noop
Mar 26 15:51:07 DEBUG[12888] db.c: Unable to find key '600/Zap/2-1' in
family 'BLKVM'
Mar 26 15:51:07 DEBUG[12888] app_macro.c: Executed application: DBDel
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Hangup: channel: 2 index = 0,
normal = 19, callwait = -1, thirdcall = -1
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: disabled echo cancellation on
channel 2
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Set option TDD MODE, value: OFF(0)
on Zap/2-1
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Updated conferencing on 2, with 0
conference users
Mar 26 15:51:07 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 103: Match Found
Mar 26 15:51:12 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:12 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:20 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:20 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:21 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:21 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:22 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:22 DEBUG[3005] chan_sip.c: Stopping retransmission on '
[EMAIL PROTECTED]' of Request 102: Match Found
Mar 26 15:51:23 DEBUG[3010] dsp.c: dsp busy pattern set to 0,0
Mar 26 15:51:24 NOTICE[12905] chan_zap.c: Got event 18 (Ring Begin)...
Mar 26 15:51:24 DEBUG[3384] manager.c: Manager received command 'Command'
Mar 26 15:51:24 DEBUG[3384] manager.c: Manager received command 'Command'
Mar 26 15:51:24 DEBUG[3384] manager.c: Manager received command 'Command'
Mar 26 15:51:24 NOTICE[12905] chan_zap.c: Got event 2 (Ring/Answered)...
Mar 26 15:51:25 NOTICE[12905] chan_zap.c: Got event 18 (Ring Begin)...
At the beginning there's:
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Exception on 19, channel 2
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: Got event On hook(1) on channel 2
(index 0)
Mar 26 15:51:07 DEBUG[12888] chan_zap.c: disabled echo cancellation on
channel 2
Mar 26 15:51:07 DEBUG[12888] channel.c: Didn't get a frame from channel:
Zap/2-1
Mar 26 15:51:07 DEBUG[12888] channel.c: Bridge stops bridging channels
Zap/2-1 and SIP/1026-09af1270
--*I think this is when the call dropped*
Then at the end you can see:
Mar 26 15:51:24 NOTICE[12905] chan_zap.c: Got event 2 (Ring/Answered)...
Mar 26 15:51:25 NOTICE[12905] chan_zap.c: Got event 18 (Ring Begin)...
--*I think this is when the person called back, about 17 seconds later*
Thanks!
Steven
