Jul 7 18:14:12 VERBOSE[14236] logger.c: Asterisk Event Logger restarted Jul 7 18:14:12 VERBOSE[14236] logger.c: Asterisk Queue Logger restarted Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Allocating new SIP dialog for 2defbd2e-f e6623bf@192.168.7.74 - INVITE (With RTP) Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Setting NAT on RTP to 0 Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Stopping retransmission on '2defbd2e-fe6 623bf@192.168.7.74' of Response 101: Match Found Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Setting NAT on RTP to 0 Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Checking SIP call limits for device 105 Jul 7 18:14:20 DEBUG[2623] chan_sip.c: build_route: Contact hop: "Operations" < sip:105@192.168.7.74:5060> Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Macro' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '1' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Macro' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '105' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '105' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '105' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is 'Operations' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '"Operations" <105>' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '105' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Macro' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'AGI' Jul 7 18:14:20 DEBUG[14238] app_queue.c: Device 'SIP/105' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 7 18:14:20 VERBOSE[14237] logger.c: recordingcheck|20060707-181420|115228 5260.27: Outbound recording not enabled Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Macro' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '1' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] db.c: Unable to find key '105/emergency_cid' in fam ily 'DEVICE' Jul 7 18:14:20 DEBUG[14237] func_db.c: DB: DEVICE/105/emergency_cid not found i n database. Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '1' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '1' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '1' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '"Operations" <105>' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is '1' Jul 7 18:14:20 WARNING[14237] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; In put: 1 > ^ Jul 7 18:14:20 WARNING[14237] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source. Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'AGI' Jul 7 18:14:20 VERBOSE[14237] logger.c: fixlocalprefix: Could not parse /etc/ asterisk/localprefixes.conf Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Function result is 'ZAP/g0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Set' Jul 7 18:14:20 DEBUG[14237] pbx.c: Expression result is '0' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'GotoIf' Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Not taking any branch Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Dial' Jul 7 18:14:20 NOTICE[14237] app_dial.c: Unable to create channel of type 'ZAP' (cause 0 - Unknown) Jul 7 18:14:20 DEBUG[14237] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL. Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Goto' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'NoOp' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Macro' Jul 7 18:14:20 DEBUG[14237] pbx.c: Launching 'Playback' Jul 7 18:14:20 DEBUG[14237] chan_sip.c: sip_answer(SIP/105-07e5) Jul 7 18:14:20 DEBUG[14237] channel.c: Set channel SIP/105-07e5 to write format gsm Jul 7 18:14:20 DEBUG[14237] rtp.c: Ooh, format changed from unknown to ulaw Jul 7 18:14:20 DEBUG[14237] channel.c: Scheduling timer at 160 sample intervals Jul 7 18:14:20 DEBUG[2623] chan_sip.c: Stopping retransmission on '2defbd2e-fe6 623bf@192.168.7.74' of Response 102: Match Found Jul 7 18:14:20 DEBUG[14241] app_queue.c: Device 'SIP/105' changed to state '2' (In use) but we don't care because they're not a member of any queue. Jul 7 18:14:22 DEBUG[14237] channel.c: Scheduling timer at 0 sample intervals Jul 7 18:14:22 DEBUG[14237] channel.c: Scheduling timer at 0 sample intervals Jul 7 18:14:22 DEBUG[14237] channel.c: Set channel SIP/105-07e5 to write format ulaw Jul 7 18:14:22 DEBUG[14237] pbx.c: Launching 'Playback' Jul 7 18:14:22 DEBUG[14237] channel.c: Set channel SIP/105-07e5 to write format gsm Jul 7 18:14:22 DEBUG[14237] channel.c: Scheduling timer at 160 sample intervals Jul 7 18:14:24 DEBUG[14237] channel.c: Scheduling timer at 0 sample intervals Jul 7 18:14:24 DEBUG[14237] channel.c: Set channel SIP/105-07e5 to write format ulaw Jul 7 18:14:24 DEBUG[14237] app_macro.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/105-07e5' in macro 'outisbusy' Jul 7 18:14:24 DEBUG[14237] pbx.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/105-07e5' Jul 7 18:14:24 DEBUG[14237] cdr_addon_mysql.c: cdr_mysql: inserting a CDR recor d. Jul 7 18:14:24 DEBUG[14237] 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 ('2006 -07-07 18:14:20','\"Operations\" <105>','105','9807007','from-internal', 'SIP/10 5-07e5','','Playback','pls-try-call-later',4,4,'ANSWERED',3,'','1152285260.27') Jul 7 18:14:24 DEBUG[14237] channel.c: Hanging up channel 'SIP/105-07e5' Jul 7 18:14:24 DEBUG[14237] chan_sip.c: Hangup call SIP/105-07e5, SIP callid 2d efbd2e-fe6623bf@192.168.7.74) Jul 7 18:14:24 DEBUG[14237] chan_sip.c: update_call_counter(105) - decrement ca ll limit counter Jul 7 18:14:24 DEBUG[14237] channel.c: Scheduling timer at 0 sample intervals Jul 7 18:14:24 DEBUG[14237] channel.c: Set channel SIP/105-07e5 to write format ulaw Jul 7 18:14:24 DEBUG[14237] app_macro.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/105-07e5' in macro 'outisbusy' Jul 7 18:14:24 DEBUG[14237] pbx.c: Spawn extension (macro-outisbusy,s,2) exited non-zero on 'SIP/105-07e5' Jul 7 18:14:24 DEBUG[14237] cdr_addon_mysql.c: cdr_mysql: inserting a CDR recor d. Jul 7 18:14:24 DEBUG[14237] 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 ('2006 -07-07 18:14:20','\"Operations\" <105>','105','9807007','from-internal', 'SIP/10 5-07e5','','Playback','pls-try-call-later',4,4,'ANSWERED',3,'','1152285260.27') Jul 7 18:14:24 DEBUG[14237] channel.c: Hanging up channel 'SIP/105-07e5' Jul 7 18:14:24 DEBUG[14237] chan_sip.c: Hangup call SIP/105-07e5, SIP callid 2d efbd2e-fe6623bf@192.168.7.74) Jul 7 18:14:24 DEBUG[14237] chan_sip.c: update_call_counter(105) - decrement ca ll limit counter Jul 7 18:14:24 DEBUG[14242] app_queue.c: Device 'SIP/105' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Jul 7 18:15:59 DEBUG[3185] manager.c: Manager received command 'Command' Jul 7 18:15:59 DEBUG[3185] manager.c: Manager received command 'Command'