Good point. I did not think about that (newbie).
The SIP phones are on 2 PCs, there are BOL SIPPhones.
The users are not logged into the phones.
My goal is to make asterisk answer the call after N seconds.
Currently it answers the call on the 2nd ring.
How can I do this?
Here is a section from the “/var/log/asterisk/full” file:
Jul 19 02:49:43 VERBOSE[6779] logger.c: Asterisk Event Logger restarted
Jul 19 02:49:43 VERBOSE[6779] logger.c: Asterisk Queue Logger restarted
Jul 19 02:51:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:51:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:53:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:53:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:55:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:55:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:57:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:57:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:59:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 02:59:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:01:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:01:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:03:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:03:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:05:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:05:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:07:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:07:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:09:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:09:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:11:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:11:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:13:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:13:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:15:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:15:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:17:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:17:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:19:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:19:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:21:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:21:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:23:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:23:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:25:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:25:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:27:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:27:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:28:24 DEBUG[2868] chan_zap.c: Monitor doohicky got event Ring Begin on channel 1
Jul 19 03:28:25 DEBUG[2868] chan_zap.c: Monitor doohicky got event Ring/Answered on channel 1
Jul 19 03:28:25 DEBUG[8610] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 19 03:28:30 NOTICE[8609] chan_zap.c: Got event 18 (Ring Begin)...
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Expression result is '1'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Function result is 's'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Expression result is '1'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Function result is '1'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'Answer'
Jul 19 03:28:30 DEBUG[8609] chan_zap.c: Took Zap/1-1 off hook
Jul 19 03:28:30 DEBUG[8609] chan_zap.c: Enabled echo cancellation on channel 1
Jul 19 03:28:30 DEBUG[8609] chan_zap.c: Engaged echo training on channel 1
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'PlayTones'
Jul 19 03:28:30 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format slin
Jul 19 03:28:30 DEBUG[8609] channel.c: Scheduling timer at 160 sample intervals
Jul 19 03:28:30 DEBUG[8609] pbx.c: Launching 'NVFaxDetect'
Jul 19 03:28:30 DEBUG[8609] app_nv_faxdetect.c: Preparing detect of fax (waitdur=4ms, sildur=1000ms, mindur=100ms, maxdur=-1ms)
Jul 19 03:28:30 DEBUG[8609] channel.c: Set channel Zap/1-1 to read format slin
Jul 19 03:28:30 DEBUG[8611] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Jul 19 03:28:30 DEBUG[8609] app_nv_faxdetect.c: Start of voice token!
Jul 19 03:28:30 DEBUG[8609] app_nv_faxdetect.c: Found unqualified token of 0 ms
Jul 19 03:28:30 DEBUG[8609] app_nv_faxdetect.c: Start of voice token!
Jul 19 03:28:35 DEBUG[8609] channel.c: Set channel Zap/1-1 to read format ulaw
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Goto'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Not taking any branch
Jul 19 03:28:35 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Not taking any branch
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '/user' in family 'DEVICE'
Jul 19 03:28:35 DEBUG[8609] func_db.c: DB: DEVICE//user not found in database.
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '/cidname' in family 'AMPUSER'
Jul 19 03:28:35 DEBUG[8609] func_db.c: DB: AMPUSER//cidname not found in database.
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Expression result is '1'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is '"" <>'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Not taking any branch
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Function result is '0'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'AGI'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:35 DEBUG[8609] pbx.c: Launching 'AGI'
Jul 19 03:28:35 VERBOSE[8609] logger.c: dialparties.agi: Starting New Dialparties.agi
Jul 19 03:28:35 VERBOSE[8609] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
Jul 19 03:28:35 VERBOSE[8609] logger.c: dialparties.agi: Methodology of ring is 'ringall'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '928' in family 'CF'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '414' in family 'CF'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '928' in family 'DND'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '414' in family 'DND'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '928' in family 'CW'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '928' in family 'CFB'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '928' in family 'CFU'
Jul 19 03:28:35 DEBUG[8614] manager.c: Manager received command 'login'
Jul 19 03:28:35 DEBUG[8614] config.c: Parsing /etc/asterisk/manager.conf
Jul 19 03:28:35 DEBUG[8614] config.c: Parsing /etc/asterisk/manager_custom.conf
Jul 19 03:28:35 WARNING[8614] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf
Jul 19 03:28:35 DEBUG[8614] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Jul 19 03:28:35 DEBUG[8614] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Jul 19 03:28:35 DEBUG[8614] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Jul 19 03:28:35 DEBUG[8614] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Jul 19 03:28:35 DEBUG[8614] manager.c: Manager received command 'ExtensionState'
Jul 19 03:28:35 DEBUG[8614] manager.c: Manager received command 'Logoff'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '414' in family 'CW'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '414' in family 'CFB'
Jul 19 03:28:35 DEBUG[8609] db.c: Unable to find key '414' in family 'CFU'
Jul 19 03:28:36 DEBUG[8615] manager.c: Manager received command 'login'
Jul 19 03:28:36 DEBUG[8615] config.c: Parsing /etc/asterisk/manager.conf
Jul 19 03:28:36 DEBUG[8615] config.c: Parsing /etc/asterisk/manager_custom.conf
Jul 19 03:28:36 WARNING[8615] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf
Jul 19 03:28:36 DEBUG[8615] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Jul 19 03:28:36 DEBUG[8615] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Jul 19 03:28:36 DEBUG[8615] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Jul 19 03:28:36 DEBUG[8615] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Jul 19 03:28:36 DEBUG[8615] manager.c: Manager received command 'ExtensionState'
Jul 19 03:28:36 DEBUG[8615] manager.c: Manager received command 'Logoff'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Dial'
Jul 19 03:28:36 DEBUG[8609] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jul 19 03:28:36 NOTICE[8609] app_dial.c: Unable to create channel of type 'SIP' (cause 3 - No route to destination)
Jul 19 03:28:36 DEBUG[8609] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jul 19 03:28:36 NOTICE[8609] app_dial.c: Unable to create channel of type 'SIP' (cause 3 - No route to destination)
Jul 19 03:28:36 DEBUG[8609] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Goto'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Not taking any branch
Jul 19 03:28:36 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Not taking any branch
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:36 DEBUG[8609] db.c: Unable to find key '/user' in family 'DEVICE'
Jul 19 03:28:36 DEBUG[8609] func_db.c: DB: DEVICE//user not found in database.
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] db.c: Unable to find key '/cidname' in family 'AMPUSER'
Jul 19 03:28:36 DEBUG[8609] func_db.c: DB: AMPUSER//cidname not found in database.
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Expression result is '1'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is '"" <>'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is ''
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Goto'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Macro'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Function result is 'default'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'Set'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Expression result is '0'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'GotoIf'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'NoOp'
Jul 19 03:28:36 DEBUG[8609] pbx.c: Launching 'VoiceMail'
Jul 19 03:28:36 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format ulaw
Jul 19 03:28:36 DEBUG[8609] channel.c: Scheduling timer at 0 sample intervals
Jul 19 03:28:36 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format ulaw
Jul 19 03:28:36 DEBUG[8609] channel.c: Scheduling timer at 160 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 6 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 0 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 0 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format ulaw
Jul 19 03:28:41 DEBUG[8609] app.c: Locked path '/var/spool/asterisk/voicemail/default/98765/INBOX'
Jul 19 03:28:41 DEBUG[8609] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/98765/INBOX'
Jul 19 03:28:41 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format ulaw
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 160 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 0 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Scheduling timer at 0 sample intervals
Jul 19 03:28:41 DEBUG[8609] channel.c: Set channel Zap/1-1 to write format ulaw
Jul 19 03:28:41 DEBUG[8609] app.c: play_and_record: <None>, /var/spool/asterisk/voicemail/default/98765/tmp/WViy2a, 'wav49|wav'
Jul 19 03:28:41 DEBUG[8609] app.c: Recording Formats: sfmts=wav49
Jul 19 03:28:41 VERBOSE[8609] logger.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/98765/tmp/WViy2a format: wav49, 0x9aa0930
Jul 19 03:28:41 VERBOSE[8609] logger.c: -- x=1, open writing: /var/spool/asterisk/voicemail/default/98765/tmp/WViy2a format: wav, 0x9aa0c08
Jul 19 03:28:41 DEBUG[8609] channel.c: Set channel Zap/1-1 to read format slin
Jul 19 03:28:41 DEBUG[8609] chan_zap.c: Requested indication 18 on channel Zap/1-1
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: Exception on 18, channel 1
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: Got event On hook(1) on channel 1 (index 0)
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: disabled echo cancellation on channel 1
Jul 19 03:28:42 DEBUG[8609] channel.c: Set channel Zap/1-1 to read format ulaw
Jul 19 03:28:42 DEBUG[8609] app_macro.c: Spawn extension (macro-vm,s-DIRECTDIAL,3) exited non-zero on 'Zap/1-1' in macro 'vm'
Jul 19 03:28:42 DEBUG[8609] pbx.c: Spawn extension (macro-vm,s-DIRECTDIAL,3) exited non-zero on 'Zap/1-1'
Jul 19 03:28:42 DEBUG[8609] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jul 19 03:28:42 DEBUG[8609] 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-19 03:28:30','','','*98765','ext-local', 'Zap/1-1','','VoiceMail','98765@default|',12,12,'ANSWERED',3,'','1153304905.2')
Jul 19 03:28:42 DEBUG[8609] channel.c: Hanging up channel 'Zap/1-1'
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: zt_hangup(Zap/1-1)
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: Hangup: channel: 1 index = 0, normal = 18, callwait = -1, thirdcall = -1
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: disabled echo cancellation on channel 1
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Jul 19 03:28:42 DEBUG[8609] chan_zap.c: Updated conferencing on 1, with 0 conference users
Jul 19 03:28:42 DEBUG[8616] app_queue.c: Device 'Zap/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
Jul 19 03:29:03 DEBUG[2916] manager.c: Manager received command 'Command'
Jul 19 03:29:03 DEBUG[2916] manager.c: Manager received command 'Command'