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" <sip:2818404949@10.1.16.142>;tag=as27e852f0
To: <sip:7135152830@10.1.16.13>
Contact: <sip:2818404949@10.1.16.142>
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" <sip:2818404949@10.1.16.142>;tag=as27e852f0
To: <sip:7135152830@10.1.16.13>;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" <sip:2818404949@10.1.16.142>;tag=as27e852f0
To: <sip:7135152830@10.1.16.13>;tag=1c293541816
Contact: <sip:2818404949@10.1.16.142>
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