Jul 12 23:23:57 DEBUG[2495] chan_sip.c: Auto destroying call '46d3ad3b787b9c0c1845abb4256646da@127.0.0.1'
Jul 12 23:24:14 DEBUG[2495] chan_sip.c: Allocating new SIP dialog for 46d3ad3b787b9c0c1845abb4256646da@127.0.0.1 - REGISTER (No RTP)
Jul 12 23:24:14 DEBUG[2495] chan_sip.c: Scheduled a registration timeout for sip.broadvoice.com id  #207
Jul 12 23:24:14 DEBUG[2495] chan_sip.c: Stopping retransmission on '46d3ad3b787b9c0c1845abb4256646da@127.0.0.1' of Request 140: Match Found
Jul 12 23:24:14 DEBUG[2495] chan_sip.c: Registration successful
Jul 12 23:24:14 DEBUG[2495] chan_sip.c: Cancelling timeout 207
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Allocating new SIP dialog for 370d40d3583f4e3462ac0367b715b705@192.168.2.2 - INVITE (With RTP)
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: * SIP extension value: 4 for call 370d40d3583f4e3462ac0367b715b705@192.168.2.2
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Setting NAT on RTP to 0
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Stopping retransmission on '370d40d3583f4e3462ac0367b715b705@192.168.2.2' of Response 271013683: Match Found
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Setting NAT on RTP to 0
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Checking SIP call limits for device 200
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: build_route: Contact hop: 703-752-1999 <sip:200@192.168.2.2>
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Macro'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '1'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Macro'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '200'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '200'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '703-752-1999'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '"703-752-1999" <200>'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '200'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Macro'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'AGI'
Jul 12 23:24:19 DEBUG[3720] app_queue.c: Device 'SIP/200' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 12 23:24:19 VERBOSE[3719] logger.c:   recordingcheck|20060712-232419|1152761059.6: Outbound recording not enabled
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Macro'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '1'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is ''
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] db.c: Unable to find key '200/emergency_cid' in family 'DEVICE'
Jul 12 23:24:19 DEBUG[3719] func_db.c: DB: DEVICE/200/emergency_cid not found in database.
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is ''
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '1'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '1'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '"Orion Networks" <7039350106>'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is '1'
Jul 12 23:24:19 WARNING[3719] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP o$ 1 >
      ^
Jul 12 23:24:19 WARNING[3719] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'AGI'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Function result is 'SIP/sip.broadvoice.com'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Set'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Expression result is '0'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'GotoIf'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Not taking any branch
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Dial'
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: Setting NAT on RTP to 0
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-14.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable MACRO_DEPTH.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-13.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable custom.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-12.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable OUTNUM.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-11.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-10.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable DIAL_TRUNK.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-9.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable DIAL_NUMBER.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-8.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-7.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable GROUP.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-6.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable MACRO_PRIORITY.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable MACRO_CONTEXT.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable MACRO_EXTEN.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable ARG1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-15.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-13.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-12.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-11.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-7.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable TRUNKOUTCID.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-6.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable EMERGENCYCID.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-5.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable USEROUTCID.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-4.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable DB_RESULT.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-3.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-outbound-callerid-s-1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-5.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable ARG2.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-record-enable-s-5.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-record-enable-s-4.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-record-enable-s-1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-4.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-9.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-8.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-7.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable AMPUSERCIDNAME.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-6.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable AMPUSER.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-5.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-4.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable REALCALLERIDNUM.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-3.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-2.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-user-callerid-s-1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-3.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-macro-dialout-trunk-s-1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable ARG4.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable ARG3.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable STACK-from-internal-7039361212-1.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable SIPCALLID.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable SIPUSERAGENT.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable SIPDOMAIN.
Jul 12 23:24:19 DEBUG[3719] channel.c: Not copying variable SIPURI.
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: Outgoing Call for 7039361212
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '4d7e36aa1ae6f3fb26f0907f386f42c0@192.168.2.$Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Acked pending invite 102
Jul 12 23:24:19 DEBUG[2495] chan_sip.c: Stopping retransmission on '4d7e36aa1ae6f3fb26f0907f386f42c0@192.168.2.7' of Request 102: Match Found
Jul 12 23:24:19 DEBUG[3719] channel.c: Hanging up channel 'SIP/sip.broadvoice.com-ed12'
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: Hangup call SIP/sip.broadvoice.com-ed12, SIP callid 4d7e36aa1ae6f3fb26f0907f386f42c0@192.168.2.7)
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: update_call_counter(7039361212) - decrement call limit counter
Jul 12 23:24:19 DEBUG[3719] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Goto'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'NoOp'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Macro'
Jul 12 23:24:19 DEBUG[3719] pbx.c: Launching 'Playback'
Jul 12 23:24:19 DEBUG[3719] chan_sip.c: sip_answer(SIP/200-93e9)
Jul 12 23:24:19 DEBUG[3719] channel.c: Set channel SIP/200-93e9 to write format gsm
Jul 12 23:24:19 DEBUG[3719] rtp.c: Ooh, format changed from unknown to ulaw
Jul 12 23:24:19 DEBUG[3719] channel.c: Scheduling timer at 160 sample intervals
Jul 12 23:24:19 DEBUG[3723] app_queue.c: Device 'SIP/sip.broadvoice.com' changed to state '1' (Not in use) but we don't care because they're not a m$Jul 12 23:24:19 DEBUG[3724] app_queue.c: Device 'SIP/200' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 12 23:24:20 DEBUG[2495] chan_sip.c: Stopping retransmission on '370d40d3583f4e3462ac0367b715b705@192.168.2.2' of Response 271013684: Match Found
Jul 12 23:24:21 DEBUG[3719] channel.c: Scheduling timer at 0 sample intervals
Jul 12 23:24:21 DEBUG[3719] channel.c: Scheduling timer at 0 sample intervals
Jul 12 23:24:21 DEBUG[3719] channel.c: Set channel SIP/200-93e9 to write format ulaw
Jul 12 23:24:21 DEBUG[3719] pbx.c: Launching 'Playback'
Jul 12 23:24:21 DEBUG[3719] channel.c: Set channel SIP/200-93e9 to write format gsm
Jul 12 23:24:21 DEBUG[3719] channel.c: Scheduling timer at 160 sample intervals
Jul 12 23:24:22 DEBUG[2495] chan_sip.c: Auto destroying call '46d3ad3b787b9c0c1845abb4256646da@127.0.0.1'
Jul 12 23:24:22 DEBUG[3719] channel.c: Scheduling timer at 0 sample intervals
Jul 12 23:24:22 DEBUG[3719] channel.c: Set channel SIP/200-93e9 to write format ulaw
Jul 12 23:24:22 DEBUG[3719] app_macro.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/200-93e9' in macro 'outisbusy'
Jul 12 23:24:22 DEBUG[3719] pbx.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/200-93e9'
Jul 12 23:24:22 DEBUG[3719] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jul 12 23:24:22 DEBUG[3719] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel$Jul 12 23:24:22 DEBUG[3719] channel.c: Hanging up channel 'SIP/200-93e9'
Jul 12 23:24:22 DEBUG[3719] chan_sip.c: Hangup call SIP/200-93e9, SIP callid 370d40d3583f4e3462ac0367b715b705@192.168.2.2)
Jul 12 23:24:22 DEBUG[3719] chan_sip.c: update_call_counter(200) - decrement call limit counter
Jul 12 23:24:22 DEBUG[3725] app_queue.c: Device 'SIP/200' changed to state '1' (Not in use) but we don't care because they're not a member of any qu$