[Asterisk-Users] Zap channel billing on busy tone!

[Mark Edwards]

Here is a log from a recent call made out on a ZAP channel from a SIP phone 
inside my network.
 For some reason, CDR is billing time even though the "busy tone" was 
detected.
It's also logging the call as ANSWERED.
 Is this normal behavior? Seems a little odd to me.
 I have this as the first 3 lines of my zapata.conf
 [channels]
busydetect=1
busycount=3
  CVS HEAD updated late Thursday night AEST...
   -- Executing Macro("SIP/125-c304", "enum-call|localcall|<DESTPSTN>") in 
new stack
-- Executing SetCallerID("SIP/125-c304", "<MyPSTN>") in new stack
-- Executing EnumLookup("SIP/125-c304", "<MyPSTN>") in new stack
Jul 15 10:55:26 DEBUG[18721]: enum.c:363 ast_get_enum: No such number found: 
<DESTPSTN>.e164.org (Success)
-- Executing Goto("SIP/125-c304", "localcall|<DESTPSTN>|1") in new stack
-- Goto (localcall,<DESTPSTN>,1)
== Channel 'SIP/125-c304' jumping out of macro 'enum-call'
-- Executing StripMSD("SIP/125-c304", "3") in new stack
-- Executing Dial("SIP/125-c304", "ZAP/4/<DESTPSTN>|60|t") in new stack
Jul 15 10:55:26 DEBUG[18721]: chan_zap.c:1785 zt_call: Dialing <DESTPSTN>
Jul 15 10:55:26 DEBUG[18721]: chan_zap.c:1846 zt_call: Deferring dialing...
-- Called 4/<DESTPSTN>
Jul 15 10:55:27 DEBUG[18721]: chan_zap.c:4083 __zt_exception: Exception on 
14, channel 4
Jul 15 10:55:27 DEBUG[18721]: chan_zap.c:3309 zt_handle_event: Got event 
Hook Transition Complete(12) on channel 4 (index 0)

Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:4083 __zt_exception: Exception on 
14, channel 4
Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:3309 zt_handle_event: Got event 
Dial Complete(9) on channel 4 (index 0)
Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:1364 zt_enable_ec: Enabled echo 
cancellation on channel 4
-- Zap/4-1 answered SIP/125-c304
Jul 15 10:55:34 DEBUG[18721]: dsp.c:1396 ast_dsp_process: Requesting Hangup 
because the busy tone was detected on channel Zap/4-1
Jul 15 10:55:34 DEBUG[18721]: channel.c:2959 ast_generic_bridge: Got a 
FRAME_CONTROL (5) frame on channel Zap/4-1
Jul 15 10:55:34 DEBUG[18721]: channel.c:3213 ast_channel_bridge: Bridge 
stops bridging channels SIP/125-c304 and Zap/4-1
Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:2179 zt_hangup: Hangup: channel: 4 
index = 0, normal = 14, callwait = -1, thirdcall = -1
Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:1396 zt_disable_ec: disabled echo 
cancellation on channel 4
Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:2588 zt_setoption: Set option TDD 
MODE, value: OFF(0) on Zap/4-1
Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:1333 update_conf: Updated 
conferencing on 4, with 0 conference users
-- Hungup 'Zap/4-1'
Jul 15 10:55:34 DEBUG[18721]: app_dial.c:1657 dial_exec_full: Exiting with 
DIALSTATUS=ANSWER.
== Spawn extension (localcall, <DestinationPSTNNo>, 2) exited non-zero on 
'SIP/125-c304'
Jul 15 10:55:34 DEBUG[18721]: cdr_addon_mysql.c:179 mysql_log: cdr_mysql: 
inserting a CDR record.
Jul 15 10:55:34 DEBUG[18721]: cdr_addon_mysql.c:198 mysql_log: cdr_mysql: 
SQL command as follows: INSERT INTO cdr 
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode)
VALUES ('2005-07-15 
10:55:26','<MyPSTN>','<MyPSTN>',<DestinationPSTNNo>,'localcall', 
'SIP/125-c304','Zap/4-1','Dial','ZAP/4/<DestinationPSTNNo>|60|t',8,5,'ANSWERED',3,'')


-- 
regards,

Mark P. Edwards
FWD: 667917
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20050714/24b114de/attachment.htm