<div>Here is a log from a recent call made out on a ZAP channel from a SIP phone inside my network.</div>
<div> </div>
<div>For some reason, CDR is billing time even though the "busy tone" was detected.</div>
<div>It's also logging the call as ANSWERED.</div>
<div> </div>
<div>Is this normal behavior? Seems a little odd to me.</div>
<div> </div>
<div>I have this as the first 3 lines of my zapata.conf</div>
<div> </div>
<div>[channels]</div>
<div>busydetect=1<br>busycount=3<br> </div>
<div> </div>
<div>CVS HEAD updated late Thursday night AEST...</div>
<div> </div>
<div> </div>
<div> -- Executing Macro("SIP/125-c304", "enum-call|localcall|<DESTPSTN>") in new stack<br> -- Executing SetCallerID("SIP/125-c304", "<MyPSTN>") in new stack<br> -- Executing EnumLookup("SIP/125-c304", "<MyPSTN>") in new stack
<br>Jul 15 10:55:26 DEBUG[18721]: enum.c:363 ast_get_enum: No such number found: <DESTPSTN>.e164.org (Success)<br> -- Executing Goto("SIP/125-c304", "localcall|<DESTPSTN>|1") in new stack
<br> -- Goto (localcall,<DESTPSTN>,1)<br> == Channel 'SIP/125-c304' jumping out of macro 'enum-call'<br> -- Executing StripMSD("SIP/125-c304", "3") in new stack<br> -- Executing Dial("SIP/125-c304", "ZAP/4/<DESTPSTN>|60|t") in new stack
<br>Jul 15 10:55:26 DEBUG[18721]: chan_zap.c:1785 zt_call: Dialing <DESTPSTN></div>
<div>Jul 15 10:55:26 DEBUG[18721]: chan_zap.c:1846 zt_call: Deferring dialing...<br> -- Called 4/<DESTPSTN><br>Jul 15 10:55:27 DEBUG[18721]: chan_zap.c:4083 __zt_exception: Exception on 14, channel 4<br>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)
</div>
<p>Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:4083 __zt_exception: Exception on 14, channel 4<br>Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:3309 zt_handle_event: Got event Dial Complete(9) on channel 4 (index 0)<br>Jul 15 10:55:29 DEBUG[18721]: chan_zap.c:1364 zt_enable_ec: Enabled echo cancellation on channel 4
<br> -- Zap/4-1 answered SIP/125-c304<br>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<br>Jul 15 10:55:34 DEBUG[18721]: channel.c:2959 ast_generic_bridge: Got a FRAME_CONTROL (5) frame on channel Zap/4-1
<br>Jul 15 10:55:34 DEBUG[18721]: channel.c:3213 ast_channel_bridge: Bridge stops bridging channels SIP/125-c304 and Zap/4-1<br>Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:2179 zt_hangup: Hangup: channel: 4 index = 0, normal = 14, callwait = -1, thirdcall = -1
<br>Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:1396 zt_disable_ec: disabled echo cancellation on channel 4<br>Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:2588 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/4-1<br>Jul 15 10:55:34 DEBUG[18721]: chan_zap.c:1333 update_conf: Updated conferencing on 4, with 0 conference users
<br> -- Hungup 'Zap/4-1'<br>Jul 15 10:55:34 DEBUG[18721]: app_dial.c:1657 dial_exec_full: Exiting with DIALSTATUS=ANSWER.<br> == Spawn extension (localcall, <DestinationPSTNNo>, 2) exited non-zero on 'SIP/125-c304'
<br>Jul 15 10:55:34 DEBUG[18721]: cdr_addon_mysql.c:179 mysql_log: cdr_mysql: inserting a CDR record.<br>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,'')
</p><br><br>-- <br>regards,<br><br>Mark P. Edwards<br>FWD: 667917<br>