/var/log/asterisk/full - last 2000 lines
Jun 13 08:48:38 DEBUG[2365] chan_iax2.c: Allocate call number
Jun 13 08:48:38 DEBUG[2365] chan_iax2.c: Registration created on call 2
Jun 13 08:48:53 DEBUG[11925] manager.c: Manager received command 'Command'
Jun 13 08:48:53 DEBUG[11925] manager.c: Manager received command 'Command'
Jun 13 08:48:58 DEBUG[2393] chan_sip.c: Allocating new SIP dialog for F7pOktAiodO203SO@192.168.1.7 - REGISTER (No RTP)
Jun 13 08:48:58 DEBUG[11937] app_queue.c: Device 'SIP/1000' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jun 13 08:48:59 DEBUG[2393] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
Jun 13 08:48:59 DEBUG[2393] chan_sip.c: Stopping retransmission on '2c130497286c9e5b7687a05c55b0a84f@192.168.1.8' of Request 102: Match Found
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: Allocating new SIP dialog for bRoHWcq0ABuQKoly@192.168.1.7 - INVITE (With RTP)
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: * SIP extension value: 1 for call bRoHWcq0ABuQKoly@192.168.1.7
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: Setting NAT on RTP to 0
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: Stopping retransmission on 'bRoHWcq0ABuQKoly@192.168.1.7' of Response 1: Match Found
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: Setting NAT on RTP to 0
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: Checking SIP call limits for device 1000
Jun 13 08:49:04 DEBUG[2393] chan_sip.c: build_route: Contact hop:
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Macro'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Macro("SIP/1000-e207", "dialout-trunk|2|9166383494||") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "DIAL_TRUNK=2") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "DIAL_NUMBER=9166383494") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 DEBUG[11939] app_queue.c: Device 'SIP/1000' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "ROUTE_PASSWD=") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '1'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "1?noauth") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "GROUP()=OUT_2") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Macro'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Macro("SIP/1000-e207", "user-callerid") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?report") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?start") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '1000'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "REALCALLERIDNUM=1000") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "REALCALLERIDNUM is 1000") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '1000'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "AMPUSER=1000") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is 'Richard Neese'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "AMPUSERCIDNAME=Richard Neese") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?report") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "CALLERID(all)=Richard Neese <1000>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '"Richard Neese" <1000>'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "Using CallerID "Richard Neese" <1000>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '1000'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Macro'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Macro("SIP/1000-e207", "record-enable|1000|OUT") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0 > 0?2:4") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-record-enable,s,4)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'AGI'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing AGI("SIP/1000-e207", "recordingcheck|20060613-084904|1150210144.14") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jun 13 08:49:04 VERBOSE[11938] logger.c: recordingcheck|20060613-084904|1150210144.14: Outbound recording not enabled
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- AGI Script recordingcheck completed, returning 0
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "No recording needed") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Macro'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Macro("SIP/1000-e207", "outbound-callerid|2") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '1'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "1?start") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-outbound-callerid,s,3)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "REALCALLERIDNUM is 1000") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '"Richard Neese" <3036298343>'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "USEROUTCID="Richard Neese" <3036298343>") in new stack
Jun 13 08:49:04 DEBUG[11938] db.c: Unable to find key '1000/emergency_cid' in family 'DEVICE'
Jun 13 08:49:04 DEBUG[11938] func_db.c: DB: DEVICE/1000/emergency_cid not found in database.
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is ''
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "EMERGENCYCID=") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "TRUNKOUTCID=""<3036298343>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '1'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "1?trunkcid") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?usercid") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "CALLERID(all)=""<3036298343>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?report") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "CALLERID(all)="Richard Neese" <3036298343>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is '"Richard Neese" <3036298343>'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "CallerID set to "Richard Neese" <3036298343>") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '1'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "1?nomax") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'AGI'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing AGI("SIP/1000-e207", "fixlocalprefix") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jun 13 08:49:04 VERBOSE[11938] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "OUTNUM=9166383494") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Function result is 'IAX2/teliax'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Set'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Set("SIP/1000-e207", "custom=IAX2/teliax") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Expression result is '0'
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'GotoIf'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing GotoIf("SIP/1000-e207", "0?customtrunk") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Not taking any branch
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Dial'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Dial("SIP/1000-e207", "IAX2/teliax/9166383494|120|r") in new stack
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-16.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable MACRO_DEPTH.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-15.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable custom.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-14.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable OUTNUM.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-13.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-12.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-10.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable MACRO_PRIORITY.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable MACRO_CONTEXT.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable MACRO_EXTEN.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable ARG1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-15.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-14.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-13.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-12.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-11.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-7.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable TRUNKOUTCID.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-6.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable EMERGENCYCID.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-5.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable USEROUTCID.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-4.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable DB_RESULT.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-3.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-outbound-callerid-s-1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-9.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable ARG2.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-record-enable-s-5.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-record-enable-s-4.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-record-enable-s-1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-8.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-9.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-8.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-7.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable AMPUSERCIDNAME.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-6.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable AMPUSER.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-5.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-4.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable REALCALLERIDNUM.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-3.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-2.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-user-callerid-s-1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-7.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable GROUP.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-6.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-4.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable ROUTE_PASSWD.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-3.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable DIAL_NUMBER.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-2.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable DIAL_TRUNK.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-macro-dialout-trunk-s-1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable ARG4.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable ARG3.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable STACK-from-internal-9166383494-1.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable SIPCALLID.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable SIPUSERAGENT.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable SIPDOMAIN.
Jun 13 08:49:04 DEBUG[11938] channel.c: Not copying variable SIPURI.
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Called teliax/9166383494
Jun 13 08:49:04 DEBUG[11942] app_queue.c: Device 'IAX2/teliax' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
Jun 13 08:49:04 WARNING[2365] chan_iax2.c: Call rejected by 207.174.202.3: No such context/extension
Jun 13 08:49:04 DEBUG[2365] chan_iax2.c: Immediately destroying 1, having received reject
Jun 13 08:49:04 DEBUG[11938] channel.c: Hanging up channel 'IAX2/teliax-1'
Jun 13 08:49:04 DEBUG[11938] chan_iax2.c: We're hanging up IAX2/teliax-1 now...
Jun 13 08:49:04 DEBUG[11938] chan_iax2.c: Really destroying IAX2/teliax-1 now...
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Hungup 'IAX2/teliax-1'
Jun 13 08:49:04 VERBOSE[11938] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
Jun 13 08:49:04 DEBUG[11938] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Goto'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Goto("SIP/1000-e207", "s-CHANUNAVAIL|1") in new stack
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'NoOp'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing NoOp("SIP/1000-e207", "Dial failed due to CHANUNAVAIL") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Macro'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Macro("SIP/1000-e207", "outisbusy|") in new stack
Jun 13 08:49:04 DEBUG[11938] pbx.c: Launching 'Playback'
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Executing Playback("SIP/1000-e207", "all-circuits-busy-now") in new stack
Jun 13 08:49:04 DEBUG[11938] chan_sip.c: sip_answer(SIP/1000-e207)
Jun 13 08:49:04 DEBUG[11938] channel.c: Set channel SIP/1000-e207 to write format gsm
Jun 13 08:49:04 DEBUG[11938] rtp.c: Ooh, format changed from unknown to ulaw
Jun 13 08:49:04 DEBUG[11938] channel.c: Scheduling timer at 160 sample intervals
Jun 13 08:49:04 VERBOSE[11938] logger.c: -- Playing 'all-circuits-busy-now' (language 'en')
Jun 13 08:49:04 DEBUG[11943] app_queue.c: Device 'IAX2/teliax' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jun 13 08:49:04 DEBUG[11944] app_queue.c: Device 'SIP/1000' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jun 13 08:49:05 DEBUG[2393] chan_sip.c: Stopping retransmission on 'bRoHWcq0ABuQKoly@192.168.1.7' of Response 2: Match Found
Jun 13 08:49:06 DEBUG[11938] channel.c: Scheduling timer at 0 sample intervals
Jun 13 08:49:06 DEBUG[11938] channel.c: Set channel SIP/1000-e207 to write format ulaw
Jun 13 08:49:06 DEBUG[11938] app_macro.c: Spawn extension (macro-outisbusy,s,1) exited non-zero on 'SIP/1000-e207' in macro 'outisbusy'
Jun 13 08:49:06 DEBUG[11938] pbx.c: Spawn extension (macro-outisbusy,s,1) exited non-zero on 'SIP/1000-e207'
Jun 13 08:49:06 DEBUG[11938] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jun 13 08:49:06 DEBUG[11938] 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-13 08:49:04','\"Richard Neese\" <3036298343>','3036298343','9166383494','from-internal', 'SIP/1000-e207','IAX2/teliax-1','Playback','all-circuits-busy-now',2,2,'ANSWERED',3,'','1150210144.14')
Jun 13 08:49:06 DEBUG[11938] channel.c: Hanging up channel 'SIP/1000-e207'
Jun 13 08:49:06 DEBUG[11938] chan_sip.c: Hangup call SIP/1000-e207, SIP callid bRoHWcq0ABuQKoly@192.168.1.7)
Jun 13 08:49:06 DEBUG[11938] chan_sip.c: update_call_counter(1000) - decrement call limit counter
Jun 13 08:49:06 DEBUG[11945] app_queue.c: Device 'SIP/1000' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jun 13 08:49:13 DEBUG[2393] chan_sip.c: Auto destroying call 'F7pOktAiodO203SO@192.168.1.7'
Jun 13 08:49:13 DEBUG[2393] chan_sip.c: Auto destroying call 'F7pOktAiodO203SO@192.168.1.7'