Jun 16 11:05:11 DEBUG[2752] chan_sip.c: Setting NAT on RTP to 0
Jun 16 11:05:11 DEBUG[2752] chan_sip.c: Stopping retransmission on 'bd998a9c-7713f18@10.19.25.16' of Response 101: Match Found
Jun 16 11:05:11 DEBUG[2752] chan_sip.c: Setting NAT on RTP to 0
Jun 16 11:05:11 DEBUG[2752] chan_sip.c: Checking SIP call limits for device 1
Jun 16 11:05:11 DEBUG[2752] chan_sip.c: build_route: Contact hop: gabriel <sip:1@10.19.25.16:5060>
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "dialout-trunk|2|19789870175||") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "DIAL_TRUNK=2") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "DIAL_NUMBER=19789870175") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "ROUTE_PASSWD=") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?noauth") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "GROUP()=OUT_2") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "user-callerid") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?report") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?start") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "REALCALLERIDNUM=1") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "AMPUSER=1") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is 'gabriel'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "AMPUSERCIDNAME=gabriel") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?report") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "CALLERID(all)=gabriel <1>") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "Using CallerID "gabriel" <1>") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "record-enable|1|OUT") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '0'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0 > 0?2:4") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-record-enable,s,4)
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing AGI("SIP/1-09b7", "recordingcheck|20060616-110511|1150470311.25") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jun 16 11:05:11 VERBOSE[31355] logger.c: recordingcheck|20060616-110511|1150470311.25: Outbound recording not enabled
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- AGI Script recordingcheck completed, returning 0
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "No recording needed") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "outbound-callerid|2") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?start") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "REALCALLERIDNUM=1") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is ''
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "USEROUTCID=") in new stack
Jun 16 11:05:11 DEBUG[31355] db.c: Unable to find key '1/emergency_cid' in family 'DEVICE'
Jun 16 11:05:11 DEBUG[31355] func_db.c: DB: DEVICE/1/emergency_cid not found in database.
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is ''
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "EMERGENCYCID=") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "TRUNKOUTCID=") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?trunkcid") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?usercid") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,13)
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?report") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jun 16 11:05:11 DEBUG[31355] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "CallerID set to "gabriel" <1>") in new stack
Jun 16 11:05:11 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?nomax") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Executing AGI("SIP/1-09b7", "fixlocalprefix") in new stack
Jun 16 11:05:11 VERBOSE[31355] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 16 11:05:12 VERBOSE[31355] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "OUTNUM=19789870175") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is 'IAX2/VoipJet'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "custom=IAX2/VoipJet") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?customtrunk") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Dial("SIP/1-09b7", "IAX2/VoipJet/19789870175|120|r") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Called VoipJet/19789870175
Jun 16 11:05:12 VERBOSE[2759] logger.c: -- Call accepted by 64.34.45.100 (format ulaw)
Jun 16 11:05:12 VERBOSE[2759] logger.c: -- Format for call is ulaw
Jun 16 11:05:12 DEBUG[2759] chan_iax2.c: Immediately destroying 1, having received hangup
Jun 16 11:05:12 DEBUG[31355] chan_iax2.c: We're hanging up IAX2/VoipJet-1 now...
Jun 16 11:05:12 DEBUG[31355] chan_iax2.c: Really destroying IAX2/VoipJet-1 now...
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Hungup 'IAX2/VoipJet-1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Jun 16 11:05:12 DEBUG[31355] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Goto("SIP/1-09b7", "s-CHANUNAVAIL|1") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "Dial failed due to CHANUNAVAIL") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "dialout-trunk|2|19789870175||") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "DIAL_TRUNK=2") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "DIAL_NUMBER=19789870175") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "ROUTE_PASSWD=") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?noauth") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "GROUP()=OUT_2") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "user-callerid") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?report") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?start") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "REALCALLERIDNUM=1") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "AMPUSER=1") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is 'gabriel'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "AMPUSERCIDNAME=gabriel") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?report") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "CALLERID(all)=gabriel <1>") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "Using CallerID "gabriel" <1>") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "record-enable|1|OUT") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0 > 0?2:4") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-record-enable,s,4)
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing AGI("SIP/1-09b7", "recordingcheck|20060616-110512|1150470311.25") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jun 16 11:05:12 VERBOSE[31355] logger.c: recordingcheck|20060616-110512|1150470311.25: Outbound recording not enabled
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- AGI Script recordingcheck completed, returning 0
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "No recording needed") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "outbound-callerid|2") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?start") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "REALCALLERIDNUM=1") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "REALCALLERIDNUM is 1") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is ''
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "USEROUTCID=") in new stack
Jun 16 11:05:12 DEBUG[31355] db.c: Unable to find key '1/emergency_cid' in family 'DEVICE'
Jun 16 11:05:12 DEBUG[31355] func_db.c: DB: DEVICE/1/emergency_cid not found in database.
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is ''
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "EMERGENCYCID=") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "TRUNKOUTCID=") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?trunkcid") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?usercid") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,13)
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?report") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is '"gabriel" <1>'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "CallerID set to "gabriel" <1>") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '1'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "1?nomax") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing AGI("SIP/1-09b7", "fixlocalprefix") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 16 11:05:12 VERBOSE[31355] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "OUTNUM=19789870175") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Function result is 'IAX2/VoipJet'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Set("SIP/1-09b7", "custom=IAX2/VoipJet") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Expression result is '0'
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing GotoIf("SIP/1-09b7", "0?customtrunk") in new stack
Jun 16 11:05:12 DEBUG[31355] pbx.c: Not taking any branch
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Dial("SIP/1-09b7", "IAX2/VoipJet/19789870175|120|r") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Called VoipJet/19789870175
Jun 16 11:05:12 VERBOSE[2759] logger.c: -- Call accepted by 64.34.45.100 (format ulaw)
Jun 16 11:05:12 VERBOSE[2759] logger.c: -- Format for call is ulaw
Jun 16 11:05:12 DEBUG[2759] chan_iax2.c: Immediately destroying 3, having received hangup
Jun 16 11:05:12 DEBUG[31355] chan_iax2.c: We're hanging up IAX2/VoipJet-3 now...
Jun 16 11:05:12 DEBUG[31355] chan_iax2.c: Really destroying IAX2/VoipJet-3 now...
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Hungup 'IAX2/VoipJet-3'
Jun 16 11:05:12 VERBOSE[31355] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Jun 16 11:05:12 DEBUG[31355] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Goto("SIP/1-09b7", "s-CHANUNAVAIL|1") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing NoOp("SIP/1-09b7", "Dial failed due to CHANUNAVAIL") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "outisbusy|") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Executing Playback("SIP/1-09b7", "all-circuits-busy-now") in new stack
Jun 16 11:05:12 VERBOSE[31355] logger.c: -- Playing 'all-circuits-busy-now' (language 'en')
Jun 16 11:05:12 DEBUG[2752] chan_sip.c: Stopping retransmission on 'bd998a9c-7713f18@10.19.25.16' of Response 102: Match Found
Jun 16 11:05:14 VERBOSE[31355] logger.c: -- Executing Playback("SIP/1-09b7", "pls-try-call-later") in new stack
Jun 16 11:05:14 VERBOSE[31355] logger.c: -- Playing 'pls-try-call-later' (language 'en')
Jun 16 11:05:16 VERBOSE[31355] logger.c: -- Executing Macro("SIP/1-09b7", "hangupcall") in new stack
Jun 16 11:05:16 VERBOSE[31355] logger.c: -- Executing ResetCDR("SIP/1-09b7", "w") in new stack
Jun 16 11:05:16 DEBUG[31355] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jun 16 11:05:16 DEBUG[31355] 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 ('2006-06-16 11:05:11','\"gabriel\" <1>','1','19789870175','from-internal', 'SIP/1-09b7','IAX2/VoipJet-3','ResetCDR','w',5,4,'ANSWERED',3,'','1150470311.25')
Jun 16 11:05:16 VERBOSE[31355] logger.c: -- Executing NoCDR("SIP/1-09b7", "") in new stack
Jun 16 11:05:16 WARNING[31355] cdr.c: CDR on channel 'SIP/1-09b7' not posted
Jun 16 11:05:16 WARNING[31355] cdr.c: CDR on channel 'SIP/1-09b7' lacks end
Jun 16 11:05:16 VERBOSE[31355] logger.c: -- Executing Wait("SIP/1-09b7", "5") in new stack
Jun 16 11:05:17 VERBOSE[31355] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-09b7' in macro 'hangupcall'
Jun 16 11:05:17 VERBOSE[31355] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-09b7' in macro 'outisbusy'
Jun 16 11:05:17 VERBOSE[31355] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/1-09b7'
Jun 16 11:05:17 DEBUG[31355] chan_sip.c: update_call_counter(1) - decrement call limit counter