Jul 25 22:35:06 DEBUG[1077] app_queue.c: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 25 22:35:06 DEBUG[2496] chan_sip.c: Stopping retransmission on '5dac1286258e591b66d009ba1810719f@sip1.voipbuster.com' of Request 102: Match Not Found
Jul 25 22:35:06 DEBUG[2496] chan_sip.c: Got response on call that is already terminated: 5dac1286258e591b66d009ba1810719f@sip1.voipbuster.com (ignoring)
Jul 25 22:35:06 DEBUG[2496] chan_sip.c: Stopping retransmission on 'f442064c2d516c15MzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI.' of Response 2: Match Found
Jul 25 22:35:08 DEBUG[1066] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:08 DEBUG[1066] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:08 DEBUG[1066] channel.c: Set channel SIP/111-c795 to write format ulaw
Jul 25 22:35:08 DEBUG[1066] pbx.c: Launching 'Playback'
Jul 25 22:35:08 DEBUG[1066] channel.c: Set channel SIP/111-c795 to write format gsm
Jul 25 22:35:08 DEBUG[1066] channel.c: Scheduling timer at 160 sample intervals
Jul 25 22:35:09 DEBUG[1066] rtp.c: Got RTCP report of 152 bytes
Jul 25 22:35:10 DEBUG[1066] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:10 DEBUG[1066] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:10 DEBUG[1066] channel.c: Set channel SIP/111-c795 to write format ulaw
Jul 25 22:35:10 DEBUG[1066] pbx.c: Launching 'Macro'
Jul 25 22:35:10 DEBUG[1066] pbx.c: Launching 'ResetCDR'
Jul 25 22:35:10 DEBUG[1066] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jul 25 22:35:10 DEBUG[1066] 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-07-25 22:35:05','\"111\" <111>','111','00612642124','from-internal', 'SIP/111-c795','SIP/Voipbuster-1d23','ResetCDR','w',5,4,'ANSWERED',3,'','1153859705.54')
Jul 25 22:35:10 DEBUG[1066] pbx.c: Launching 'NoCDR'
Jul 25 22:35:10 WARNING[1066] cdr.c: CDR on channel 'SIP/111-c795' not posted
Jul 25 22:35:10 WARNING[1066] cdr.c: CDR on channel 'SIP/111-c795' lacks end
Jul 25 22:35:10 DEBUG[1066] pbx.c: Launching 'Wait'
Jul 25 22:35:13 DEBUG[1066] rtp.c: Got RTCP report of 152 bytes
Jul 25 22:35:15 DEBUG[1066] pbx.c: Launching 'Hangup'
Jul 25 22:35:15 DEBUG[1066] app_macro.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/111-c795' in macro 'hangupcall'
Jul 25 22:35:15 DEBUG[1066] app_macro.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/111-c795' in macro 'outisbusy'
Jul 25 22:35:15 DEBUG[1066] pbx.c: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'SIP/111-c795'
Jul 25 22:35:15 DEBUG[1066] channel.c: Hanging up channel 'SIP/111-c795'
Jul 25 22:35:15 DEBUG[1066] chan_sip.c: Hangup call SIP/111-c795, SIP callid f442064c2d516c15MzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI.)
Jul 25 22:35:15 DEBUG[1066] chan_sip.c: update_call_counter(111) - decrement call limit counter
Jul 25 22:35:15 DEBUG[1082] app_queue.c: Device 'SIP/111' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jul 25 22:35:16 DEBUG[2496] chan_sip.c: Stopping retransmission on 'f442064c2d516c15MzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI.' of Request 102: Match Found
Jul 25 22:35:20 DEBUG[2496] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
Jul 25 22:35:20 DEBUG[2496] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
Jul 25 22:35:20 DEBUG[2496] chan_sip.c: Stopping retransmission on '641c27e43c135c355a04da1a2b106dcb@192.168.1.4' of Request 102: Match Found
Jul 25 22:35:20 DEBUG[2496] chan_sip.c: Stopping retransmission on '414adc0a511b94dc0388a8f23be0e656@192.168.1.4' of Request 102: Match Found
Jul 25 22:35:22 DEBUG[2496] chan_sip.c: Auto destroying call '5b768bdc39795ef45d5f83b50468f3aa@127.0.0.1'
Jul 25 22:35:26 DEBUG[848] manager.c: Manager received command 'Command'
Jul 25 22:35:26 DEBUG[848] manager.c: Manager received command 'Command'
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Allocating new SIP dialog for fe002d15914bb10eMzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI. - INVITE (With RTP)
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Setting NAT on RTP to 0
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Stopping retransmission on 'fe002d15914bb10eMzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI.' of Response 1: Match Found
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Setting NAT on RTP to 0
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Checking SIP call limits for device 111
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: build_route: Contact hop:
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Macro'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '1'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Macro'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Not taking any branch
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Not taking any branch
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '111'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '111'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '111'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Not taking any branch
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '"111" <111>'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '111'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Macro'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'AGI'
Jul 25 22:35:49 DEBUG[1096] app_queue.c: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 25 22:35:49 VERBOSE[1095] logger.c: recordingcheck|20060725-223549|1153859749.56: Outbound recording not enabled
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Macro'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '1'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is ''
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] db.c: Unable to find key '111/emergency_cid' in family 'DEVICE'
Jul 25 22:35:49 DEBUG[1095] func_db.c: DB: DEVICE/111/emergency_cid not found in database.
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is ''
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '1'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '1'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '1'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '"111" <111>'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is '1'
Jul 25 22:35:49 WARNING[1095] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input:
1 >
^
Jul 25 22:35:49 WARNING[1095] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Not taking any branch
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'AGI'
Jul 25 22:35:49 VERBOSE[1095] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Function result is 'SIP/Voipbuster'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Set'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Expression result is '0'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'GotoIf'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Not taking any branch
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Dial'
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: Setting NAT on RTP to 524288
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-14.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable MACRO_DEPTH.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-13.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable custom.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-12.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable OUTNUM.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-11.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-10.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable DIAL_TRUNK.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-9.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable DIAL_NUMBER.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-8.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-7.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable GROUP.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-6.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable MACRO_PRIORITY.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable MACRO_CONTEXT.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable MACRO_EXTEN.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable ARG1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-15.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-13.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-11.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-7.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable TRUNKOUTCID.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-6.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable EMERGENCYCID.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-5.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable USEROUTCID.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-4.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable DB_RESULT.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-3.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-outbound-callerid-s-1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-5.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable ARG2.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-record-enable-s-5.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-record-enable-s-4.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-record-enable-s-1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-4.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-9.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-8.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-7.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable AMPUSERCIDNAME.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-6.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable AMPUSER.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-5.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-4.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable REALCALLERIDNUM.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-3.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-2.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-user-callerid-s-1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-3.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-macro-dialout-trunk-s-1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable ARG4.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable ARG3.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable STACK-from-internal-00612642124-1.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable SIPCALLID.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable SIPUSERAGENT.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable SIPDOMAIN.
Jul 25 22:35:49 DEBUG[1095] channel.c: Not copying variable SIPURI.
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: Outgoing Call for 0612642124
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Acked pending invite 102
Jul 25 22:35:49 DEBUG[2496] chan_sip.c: Stopping retransmission on '4485285d3b687a43632d23db57c3e616@sip1.voipbuster.com' of Request 102: Match Found
Jul 25 22:35:49 NOTICE[2496] chan_sip.c: Failed to authenticate on INVITE to '"111" ;tag=as129cc795'
Jul 25 22:35:49 DEBUG[1095] channel.c: Hanging up channel 'SIP/Voipbuster-644d'
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: Hangup call SIP/Voipbuster-644d, SIP callid 4485285d3b687a43632d23db57c3e616@sip1.voipbuster.com)
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: update_call_counter(0612642124) - decrement call limit counter
Jul 25 22:35:49 DEBUG[1095] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Goto'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'NoOp'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Macro'
Jul 25 22:35:49 DEBUG[1095] pbx.c: Launching 'Playback'
Jul 25 22:35:49 DEBUG[1095] chan_sip.c: sip_answer(SIP/111-7641)
Jul 25 22:35:49 DEBUG[1095] channel.c: Set channel SIP/111-7641 to write format gsm
Jul 25 22:35:49 DEBUG[1095] rtp.c: Ooh, format changed from unknown to ulaw
Jul 25 22:35:49 DEBUG[1095] channel.c: Scheduling timer at 160 sample intervals
Jul 25 22:35:49 DEBUG[1095] rtp.c: Got RTCP report of 132 bytes
Jul 25 22:35:49 DEBUG[1099] app_queue.c: Device 'SIP/Voipbuster' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
Jul 25 22:35:49 DEBUG[1100] app_queue.c: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 25 22:35:50 DEBUG[2496] chan_sip.c: Stopping retransmission on 'fe002d15914bb10eMzAxNWNhNGYyNzBiNjkzOGIyN2M5NTg2MzE3NGE4ZGI.' of Response 2: Match Found
Jul 25 22:35:51 DEBUG[1095] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:51 DEBUG[1095] channel.c: Scheduling timer at 0 sample intervals
Jul 25 22:35:51 DEBUG[1095] channel.c: Set channel SIP/111-7641 to write format ulaw
Jul 25 22:35:51 DEBUG[1095] pbx.c: Launching 'Playback'
Jul 25 22:35:51 DEBUG[1095] channel.c: Set channel SIP/111-7641 to write format gsm
Jul 25 22:35:51 DEBUG[1095] channel.c: Scheduling timer at 160 sample intervals
Jul 25 22:35:51 DEBUG[1095] channel.c: Scheduling timer at 160 sample intervals