Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "dialout-trunk|2|7135152830||") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "DIAL_TRUNK=2") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "_NODEST=") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "DIAL_NUMBER=7135152830") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "ROUTE_PASSWD=") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "DIAL_TRUNK_OPTIONS=tr") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?noauth") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-dialout-trunk,s,8) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "GROUP()=OUT_2") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "user-callerid|SKIPTTL") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is 'device' Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '8000' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "user-callerid: device 8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Noop Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?report") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?start") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '8000' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "REALCALLERIDNUM=8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "REALCALLERIDNUM is 8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: NoOp Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '8000' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "AMPUSER=8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is 'Sean' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "AMPUSERCIDNAME=Sean") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?report") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "CALLERID(all)=Sean <8000>") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '8000' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "REALCALLERIDNUM=8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "TTL: ARG1: SKIPTTL") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Noop Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?continue") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-user-callerid,s,21) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '"Sean" <8000>' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "Using CallerID "Sean" <8000>") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: NoOp Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Macro Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '8000' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "record-enable|8000|OUT") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '0' Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?2:4") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-record-enable,s,4) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '20071009-104603' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing DeadAGI("SIP/8000-0932 1510", "recordingcheck|20071009-104603|1191944763.91") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Launched AGI Script /var/lib/ast erisk/agi-bin/recordingcheck Oct 9 10:46:03 VERBOSE[17598] logger.c: recordingcheck|20071009-104603|119194 4763.91: Outbound recording not enabled Oct 9 10:46:03 VERBOSE[17598] logger.c: -- AGI Script recordingcheck comple ted, returning 0 Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: DeadAGI Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "No recording needed") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Noop Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Macro Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?skipoutcid") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "DIAL_TRUNK_OPTIONS=") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "outbound-callerid|2") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?start") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-outbound-callerid,s, 3) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "REALCALLERIDNUM is 8000") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: NoOp Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?normcid") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-outbound-callerid,s, 9) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '2818404949' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "USEROUTCID=2818404949") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] db.c: Unable to find key '8000/emergency_cid' in fa mily 'DEVICE' Oct 9 10:46:03 DEBUG[17598] func_db.c: DB: DEVICE/8000/emergency_cid not found in database. Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "EMERGENCYCID=") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "TRUNKOUTCID=2818404949") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?trunkcid") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-outbound-callerid,s, 16) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?usercid") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "CALLERID(all)=2818404949") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?report") in new stack Oct 9 10:46:03 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "CALLERID(all)=2818404949") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '' Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?report:hidecid") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-outbound-callerid,s, 22) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 DEBUG[17598] pbx.c: Function result is '"" <2818404949>' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "CallerID set to "" <2818404949>") in new stack Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: NoOp Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: Macro Oct 9 10:46:03 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?nomax") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Goto (macro-dialout-trunk,s,16) Oct 9 10:46:03 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Executing DeadAGI("SIP/8000-0932 1510", "fixlocalprefix") in new stack Oct 9 10:46:03 VERBOSE[17598] logger.c: -- Launched AGI Script /var/lib/ast erisk/agi-bin/fixlocalprefix Oct 9 10:46:04 VERBOSE[17598] logger.c: > fixlocalprefix: Using pattern NXXNXXXXXX Oct 9 10:46:04 VERBOSE[17598] logger.c: == fixlocalprefix: Dialpattern NXXNX XXXXX matched. 7135152830 -> 7135152830 Oct 9 10:46:04 VERBOSE[17598] logger.c: -- AGI Script fixlocalprefix comple ted, returning 0 Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: DeadAGI Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "OUTNUM=7135152830") in new stack Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:04 DEBUG[17598] pbx.c: Function result is 'SIP/mediant' Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Set("SIP/8000-09321510 ", "custom=SIP/mediant") in new stack Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: Set Oct 9 10:46:04 DEBUG[17598] pbx.c: Expression result is '0' Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "0?customtrunk") in new stack Oct 9 10:46:04 DEBUG[17598] pbx.c: Not taking any branch Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Dial("SIP/8000-0932151 0", "SIP/mediant/7135152830|300|") in new stack Oct 9 10:46:04 DEBUG[17598] chan_sip.c: Setting NAT on RTP to 0 Oct 9 10:46:04 DEBUG[17598] chan_sip.c: Outgoing Call for 7135152830 Oct 9 10:46:04 VERBOSE[17598] logger.c: We're at 10.1.16.142 port 17358 Oct 9 10:46:04 VERBOSE[17598] logger.c: Adding codec 0x4 (ulaw) to SDP Oct 9 10:46:04 VERBOSE[17598] logger.c: Adding codec 0x100 (g729) to SDP Oct 9 10:46:04 VERBOSE[17598] logger.c: Adding codec 0x8 (alaw) to SDP Oct 9 10:46:04 VERBOSE[17598] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Oct 9 10:46:04 VERBOSE[17598] logger.c: 13 headers, 13 lines Oct 9 10:46:04 VERBOSE[17598] logger.c: Reliably Transmitting (no NAT) to 10.1. 16.13:5060: INVITE sip:7135152830@10.1.16.13 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK00750875;rport From: "2818404949" ;tag=as27e852f0 To: Contact: Call-ID: 7040efb56ae0afe96389019468d0cee0@10.1.16.142 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Tue, 09 Oct 2007 15:46:04 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 283 v=0 o=root 2930 2930 IN IP4 10.1.16.142 s=session c=IN IP4 10.1.16.142 t=0 0 m=audio 17358 RTP/AVP 0 18 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Called mediant/7135152830 Oct 9 10:46:04 WARNING[17598] channel.c: No path to translate from SIP/mediant- 09326a50(256) to SIP/8000-09321510(4) Oct 9 10:46:04 VERBOSE[2945] logger.c: <-- SIP read from 10.1.16.13:5060: SIP/2.0 404 Not Found Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK00750875;rport From: "2818404949" ;tag=as27e852f0 To: ;tag=1c293541816 Call-ID: 7040efb56ae0afe96389019468d0cee0@10.1.16.142 CSeq: 102 INVITE Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE, UPDATE Server: Audiocodes-Sip-Gateway-Mediant 1000/v.5.00A.024 Reason: Q.850 ;cause=3 Content-Length: 0 Oct 9 10:46:04 VERBOSE[2945] logger.c: --- (11 headers 0 lines) --- Oct 9 10:46:04 DEBUG[2945] chan_sip.c: Acked pending invite 102 Oct 9 10:46:04 DEBUG[2945] chan_sip.c: Stopping retransmission on '7040efb56ae0 afe96389019468d0cee0@10.1.16.142' of Request 102: Match Found Oct 9 10:46:04 VERBOSE[2945] logger.c: Transmitting (no NAT) to 10.1.16.13:5060 : ACK sip:7135152830@10.1.16.13 SIP/2.0 Via: SIP/2.0/UDP 10.1.16.142:5060;branch=z9hG4bK00750875;rport From: "2818404949" ;tag=as27e852f0 To: ;tag=1c293541816 Contact: Call-ID: 7040efb56ae0afe96389019468d0cee0@10.1.16.142 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- Oct 9 10:46:04 VERBOSE[17598] logger.c: -- SIP/mediant-09326a50 is circuit- busy Oct 9 10:46:04 DEBUG[17598] chan_sip.c: update_call_counter(7135152830) - decre ment call limit counter Oct 9 10:46:04 VERBOSE[17598] logger.c: == Everyone is busy/congested at this time (1:0/1/0) Oct 9 10:46:04 DEBUG[17598] app_dial.c: Exiting with DIALSTATUS=CONGESTION. Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: Dial Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Goto("SIP/8000-0932151 0", "s-CONGESTION|1") in new stack Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Goto (macro-dialout-trunk,s-CONG ESTION,1) Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: Goto Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing NoOp("SIP/8000-0932151 0", "Dial failed due to CONGESTION - failing through to other trunks") in new st ack Oct 9 10:46:04 DEBUG[17598] app_macro.c: Executed application: NoOp Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "outisbusy|") in new stack Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Executing Playback("SIP/8000-093 21510", "all-circuits-busy-now|noanswer") in new stack Oct 9 10:46:04 DEBUG[17598] channel.c: Scheduling timer at 160 sample intervals Oct 9 10:46:04 VERBOSE[17598] logger.c: -- Playing 'all-circuits-busy-now' (language 'en') Oct 9 10:46:05 VERBOSE[2945] logger.c: Destroying call '7040efb56ae0afe96389019 468d0cee0@10.1.16.142' Oct 9 10:46:05 DEBUG[17598] channel.c: Scheduling timer at 0 sample intervals Oct 9 10:46:05 DEBUG[17598] channel.c: Scheduling timer at 0 sample intervals Oct 9 10:46:05 DEBUG[17598] app_macro.c: Executed application: Playback Oct 9 10:46:05 VERBOSE[17598] logger.c: -- Executing Playback("SIP/8000-093 21510", "pls-try-call-later|noanswer") in new stack Oct 9 10:46:05 DEBUG[17598] channel.c: Scheduling timer at 160 sample intervals Oct 9 10:46:05 VERBOSE[17598] logger.c: -- Playing 'pls-try-call-later' (la nguage 'en') Oct 9 10:46:08 DEBUG[17598] channel.c: Scheduling timer at 0 sample intervals Oct 9 10:46:08 DEBUG[17598] channel.c: Scheduling timer at 0 sample intervals Oct 9 10:46:08 DEBUG[17598] app_macro.c: Executed application: Playback Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing Macro("SIP/8000-093215 10", "hangupcall") in new stack Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing ResetCDR("SIP/8000-093 21510", "w") in new stack Oct 9 10:46:08 DEBUG[17598] cdr_addon_mysql.c: cdr_mysql: inserting a CDR recor d. Oct 9 10:46:08 DEBUG[17598] cdr_addon_mysql.c: cdr_mysql: SQL command as follow s: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,la stdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2007 -10-09 10:46:03','2818404949','2818404949','7135152830','from-internal', 'SIP/80 00-09321510','SIP/mediant-09326a50','ResetCDR','w',5,0,'FAILED',3,'','1191944763 .91') Oct 9 10:46:08 DEBUG[17598] app_macro.c: Executed application: ResetCDR Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing NoCDR("SIP/8000-093215 10", "") in new stack Oct 9 10:46:08 NOTICE[17598] cdr.c: CDR on channel 'SIP/8000-09321510' not post ed Oct 9 10:46:08 NOTICE[17598] cdr.c: CDR on channel 'SIP/8000-09321510' lacks en d Oct 9 10:46:08 DEBUG[17598] app_macro.c: Executed application: NoCDR Oct 9 10:46:08 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?skiprg") in new stack Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Goto (macro-hangupcall,s,6) Oct 9 10:46:08 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:08 DEBUG[17598] pbx.c: Expression result is '1' Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing GotoIf("SIP/8000-09321 510", "1?theend") in new stack Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Goto (macro-hangupcall,s,9) Oct 9 10:46:08 DEBUG[17598] app_macro.c: Executed application: GotoIf Oct 9 10:46:08 VERBOSE[17598] logger.c: -- Executing Wait("SIP/8000-0932151 0", "5") in new stack Oct 9 10:46:08 VERBOSE[17598] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/8000-09321510' in macro 'hangupcall' Oct 9 10:46:08 VERBOSE[17598] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/8000-09321510' in macro 'outisbusy' Oct 9 10:46:08 VERBOSE[17598] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/8000-09321510' Oct 9 10:46:08 DEBUG[17598] chan_sip.c: update_call_counter(8000) - decrement c all limit counter