Open Source Training Seminar FreePBX Paid Support

Ticket #703 (closed Bugs: fixed)

Opened 2 years ago

Last modified 2 years ago

Voicemail issue when routing directly to voicemail

Reported by: anonymous Assigned to: RyanCourtnage
Priority: blocker Milestone: 2.1
Component: Core Version: SVN-HEAD
Keywords: Cc:
Confirmation: SVN Revision (if applicable):
Backend Engine: All Backend Engine Version:

Description

I have a voicemail set up for extenshion 200 with a custom away and busy message. If I setup up an inbound route to send the call directly to (core) voicemail 200 the system greeting picks up with Alisons voice and not my custom recorded greeting on voicemail box 200 but if I route the call to the extenshion and just let it go to voicemail afte the alloted time it works fine.

Change History

04/25/06 12:42:53 changed by Bob LaPoint <blapoint@carolina.rr.com>

Sorry I forgot to say who I was

04/25/06 13:16:07 changed by RyanCourtnage

  • owner set to RyanCourtnage.
  • status changed from new to assigned.

Can you please send the asterisk cli output when your dial in on this DID?

Please use the wiki tags {{{ ... }}} around your paste for format it nicely.

04/30/06 14:59:08 changed by Darkearth56

extensions.conf

exten => s-DIRECTDIAL,n,Voicemail(${ARG1}@${VMCONTEXT}) ; Voicemail Busy message

Line should read

exten => s-DIRECTDIAL,n,Voicemail(b${ARG1}@${VMCONTEXT}) ; Voicemail Busy message

05/01/06 10:29:17 changed by RyanCourtnage

Bob, do you have the console output? If there is a bug, I'd like to fix it prior to releasing another beta.

05/01/06 11:49:17 changed by blapoint

Hi Ryan

Here is the log you asked for.

May 1 14:43:29 DEBUG[2946] chan_sip.c: Stopping retransmission on '39cfc19c57012af30c9862111538e9c0@192.168.1.50' of Request 102: Match Found May 1 14:43:30 DEBUG[13465] chan_zap.c: Exception on 13, channel 4 May 1 14:43:30 DEBUG[13465] chan_zap.c: Got event Ringer Off(11) on channel 4 (index 0) May 1 14:43:30 VERBOSE[13465] logger.c: -- Zap/4-1 is ringing May 1 14:43:31 VERBOSE[13465] logger.c: -- Nobody picked up in 15000 ms May 1 14:43:31 DEBUG[13465] chan_zap.c: Hangup: channel: 4 index = 0, normal = 13, callwait = -1, thirdcall = -1 May 1 14:43:31 DEBUG[13465] chan_zap.c: disabled echo cancellation on channel 4 May 1 14:43:31 DEBUG[13465] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/4-1 May 1 14:43:31 DEBUG[13465] chan_zap.c: Updated conferencing on 4, with 0 conference users May 1 14:43:31 VERBOSE[13465] logger.c: -- Hungup 'Zap/4-1' May 1 14:43:31 DEBUG[13465] chan_sip.c: update_call_counter(200) - decrement call limit counter May 1 14:43:31 DEBUG[13465] chan_sip.c: Acked pending invite 102 May 1 14:43:31 DEBUG[13465] chan_sip.c: Stopping retransmission on '5dca54bd1ee992bf6c7da58226adea1e@192.168.1.50' of Request 102: Match Found May 1 14:43:31 DEBUG[13465] app_dial.c: Exiting with DIALSTATUS=NOANSWER. May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "RingGroupMethod?=") in new stack May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ivr-7|s|1") in new stack May 1 14:43:31 VERBOSE[13465] logger.c: -- Goto (ivr-7,s,1) May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "LOOPCOUNT=0") in new stack May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "DIR-CONTEXT=default") in new stack May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Answer("IAX2/telasip-gw-5", "") in new stack May 1 14:43:31 VERBOSE[13465] logger.c: -- Executing Wait("IAX2/telasip-gw-5", "1") in new stack May 1 14:43:31 DEBUG[2946] chan_sip.c: Stopping retransmission on '5dca54bd1ee992bf6c7da58226adea1e@192.168.1.50' of Request 102: Match Found May 1 14:43:32 DEBUG[2946] chan_sip.c: Stopping retransmission on '5dca54bd1ee992bf6c7da58226adea1e@192.168.1.50' of Request 102: Match Not Found May 1 14:43:32 DEBUG[2947] chan_iax2.c: Ooh, voice format changed to 4 May 1 14:43:32 DEBUG[2946] chan_sip.c: Stopping retransmission on '429f5a2f1a93053e010ff63a58f6397f@192.168.1.50' of Request 102: Match Found May 1 14:43:32 DEBUG[2946] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 May 1 14:43:32 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "TIMEOUT(digit)=3") in new stack May 1 14:43:32 VERBOSE[13465] logger.c: -- Digit timeout set to 3 May 1 14:43:32 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "TIMEOUT(response)=2") in new stack May 1 14:43:32 VERBOSE[13465] logger.c: -- Response timeout set to 2 May 1 14:43:32 VERBOSE[13465] logger.c: -- Executing BackGround?("IAX2/telasip-gw-5", "custom/BobsFindMeRecording") in new stack May 1 14:43:32 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:43:32 VERBOSE[13465] logger.c: -- Playing 'custom/BobsFindMeRecording' (language 'en') May 1 14:43:33 DEBUG[2947] chan_iax2.c: Peer lastms 6, historicms 6, maxms 2000 May 1 14:43:33 DEBUG[2946] chan_sip.c: Stopping retransmission on '670815f46342d9f93e6e2fcd489d175d@192.168.1.50' of Request 102: Match Found May 1 14:43:33 DEBUG[2947] chan_iax2.c: Peer lastms 18, historicms 18, maxms 2000 May 1 14:43:34 DEBUG[2946] chan_sip.c: Stopping retransmission on '1a11ce98186327d00bd0da11353ca690@192.168.1.50' of Request 102: Match Found May 1 14:43:34 DEBUG[2947] chan_iax2.c: Peer lastms 36, historicms 36, maxms 2000 May 1 14:43:34 DEBUG[2946] chan_sip.c: Auto destroying call 'b0e1edf4-aa03fa12-bf8e6bd3@192.168.1.107' May 1 14:43:35 DEBUG[2946] chan_sip.c: Stopping retransmission on '11796ef3340382f86e6218bd6f63ef27@192.168.1.50' of Request 102: Match Found May 1 14:43:35 DEBUG[2946] chan_sip.c: Stopping retransmission on '57ffef8e54fe4f934491be1f0825dde4@192.168.1.50' of Request 102: Match Found May 1 14:43:36 DEBUG[2947] chan_iax2.c: Received iseqno 9 not within window 11->11 May 1 14:43:38 DEBUG[2946] chan_sip.c: Auto destroying call '22390-D1B9-127B-BFA8-65A5C78421ED@192.168.1.106' May 1 14:43:42 DEBUG[2946] chan_sip.c: Auto destroying call '5c1bd84b-46302729-1ab4d98a@192.168.1.107' May 1 14:43:43 DEBUG[2946] chan_sip.c: Stopping retransmission on '0db3850222ec6ec2551a6a691da85814@192.168.1.50' of Request 102: Match Found May 1 14:43:44 DEBUG[2946] chan_sip.c: Auto destroying call 'dbb0d102-344f1aa0-726f71a1@192.168.1.107' May 1 14:43:45 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:45 DEBUG[13465] pbx.c: Oooh, got something to jump out with ('2')! May 1 14:43:45 VERBOSE[13465] logger.c: == CDR updated on IAX2/telasip-gw-5 May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ext-local|*200|1") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (ext-local,*200,1) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "vm|200|DIRECTDIAL") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "user-callerid") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '0' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?report") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Not taking any branch May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '1' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?start") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-user-callerid,s,4) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "REALCALLERIDNUM is 7044775830") in new stack May 1 14:43:45 DEBUG[13465] db.c: Unable to find key '7044775830/user' in family 'DEVICE' May 1 14:43:45 DEBUG[13465] func_db.c: DB: DEVICE/7044775830/user not found in database. May 1 14:43:45 DEBUG[13465] pbx.c: Function result is May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSER=") in new stack May 1 14:43:45 DEBUG[13465] db.c: Unable to find key '/cidname' in family 'AMPUSER' May 1 14:43:45 DEBUG[13465] func_db.c: DB: AMPUSER//cidname not found in database. May 1 14:43:45 DEBUG[13465] pbx.c: Function result is May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSERCIDNAME=") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '1' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?report") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-user-callerid,s,9) May 1 14:43:45 DEBUG[13465] pbx.c: Function result is '"7044775830" <7044775830>' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "Using CallerID "7044775830" <7044775830>") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "s-DIRECTDIAL|1") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-vm,s-DIRECTDIAL,1) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "DIRECTDIAL voicemail") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "get-vmcontext|200") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Function result is 'default' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "VMCONTEXT=default") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '0' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?200:300") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-get-vmcontext,s,300) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing VoiceMail("IAX2/telasip-gw-5", "200@default") in new stack May 1 14:43:45 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:43:45 VERBOSE[13465] logger.c: -- Playing '/var/spool/asterisk/voicemail/default/200/temp' (language 'en') May 1 14:43:56 DEBUG[2947] chan_iax2.c: Received iseqno 14 not within window 16->17 May 1 14:43:56 DEBUG[2947] chan_iax2.c: Received iseqno 15 not within window 17->17 May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 138 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:43:56 VERBOSE[13465] logger.c: -- Playing 'vm-intro' (language 'en') May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 6 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] app.c: Locked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:44:01 VERBOSE[13465] logger.c: -- Playing 'beep' (language 'en') May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 VERBOSE[13465] logger.c: -- Recording the message May 1 14:44:01 DEBUG[13465] app.c: play_and_record: , /var/spool/asterisk/voicemail/default/200/INBOX/msg0000, 'wav49|wav' May 1 14:44:01 DEBUG[13465] app.c: Recording Formats: sfmts=wav49 May 1 14:44:01 VERBOSE[13465] logger.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav49, 0x9a0d998 May 1 14:44:01 VERBOSE[13465] logger.c: -- x=1, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav, 0x9a45d20 May 1 14:44:01 DEBUG[13465] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 14:44:02 DEBUG[2945] chan_zap.c: Message status for 200@default changed from 0 to 1 on 4 May 1 14:44:05 DEBUG[2947] chan_iax2.c: Immediately destroying 5, having received hangup May 1 14:44:05 VERBOSE[13465] logger.c: -- User hung up May 1 14:44:05 DEBUG[13465] app_voicemail.c: Attaching file '/var/spool/asterisk/voicemail/default/200/INBOX/msg0000', format 'WAV', uservm is '2048', global is 2048 May 1 14:44:05 DEBUG[13465] app_voicemail.c: Sent mail to rlapoint@gmail.com with command '/usr/sbin/sendmail -t' May 1 14:44:05 VERBOSE[13465] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' in macro 'vm' May 1 14:44:05 VERBOSE[13465] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' May 1 14:44:05 DEBUG[13465] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. May 1 14:44:05 DEBUG[13465] 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-05-01 14:43:16','\"7044775830\" <7044775830>','7044775830','*200','ext-local', 'IAX2/telasip-gw-5','Zap/4-1','VoiceMail','200@default',49,34,'ANSWERED',3,,'1146508996.137') May 1 14:44:05 DEBUG[13465] chan_iax2.c: We're hanging up IAX2/telasip-gw-5 now... May 1 14:44:05 DEBUG[13465] chan_iax2.c: Really destroying IAX2/telasip-gw-5 now... May 1 14:44:05 VERBOSE[13465] logger.c: -- Hungup 'IAX2/telasip-gw-5' May 1 14:44:07 DEBUG[2946] chan_sip.c: Stopping retransmission on '42c46c2c0e00575630368c6c2a620bf3@192.168.1.50' of Request 102: Match Found May 1 14:44:07 DEBUG[2946] chan_sip.c: Stoppi

05/01/06 12:13:24 changed by blapoint

{{{May 1 14:43:44 DEBUG[2946] chan_sip.c: Auto destroying call 'dbb0d102-344f1aa0-726f71a1@192.168.1.107' May 1 14:43:45 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:45 DEBUG[13465] pbx.c: Oooh, got something to jump out with ('2')! May 1 14:43:45 VERBOSE[13465] logger.c: == CDR updated on IAX2/telasip-gw-5 May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ext-local|*200|1") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (ext-local,*200,1) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "vm|200|DIRECTDIAL") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "user-callerid") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '0' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?report") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Not taking any branch May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '1' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?start") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-user-callerid,s,4) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "REALCALLERIDNUM is 7044775830") in new stack May 1 14:43:45 DEBUG[13465] db.c: Unable to find key '7044775830/user' in family 'DEVICE' May 1 14:43:45 DEBUG[13465] func_db.c: DB: DEVICE/7044775830/user not found in database. May 1 14:43:45 DEBUG[13465] pbx.c: Function result is May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSER=") in new stack May 1 14:43:45 DEBUG[13465] db.c: Unable to find key '/cidname' in family 'AMPUSER' May 1 14:43:45 DEBUG[13465] func_db.c: DB: AMPUSER//cidname not found in database. May 1 14:43:45 DEBUG[13465] pbx.c: Function result is May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSERCIDNAME=") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '1' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?report") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-user-callerid,s,9) May 1 14:43:45 DEBUG[13465] pbx.c: Function result is '"7044775830" <7044775830>' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "Using CallerID "7044775830" <7044775830>") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "s-DIRECTDIAL|1") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-vm,s-DIRECTDIAL,1) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "DIRECTDIAL voicemail") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "get-vmcontext|200") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Function result is 'default' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing Set("IAX2/telasip-gw-5", "VMCONTEXT=default") in new stack May 1 14:43:45 DEBUG[13465] pbx.c: Expression result is '0' May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?200:300") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Goto (macro-get-vmcontext,s,300) May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "") in new stack May 1 14:43:45 VERBOSE[13465] logger.c: -- Executing VoiceMail("IAX2/telasip-gw-5", "200@default") in new stack May 1 14:43:45 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:43:45 VERBOSE[13465] logger.c: -- Playing '/var/spool/asterisk/voicemail/default/200/temp' (language 'en') May 1 14:43:56 DEBUG[2947] chan_iax2.c: Received iseqno 14 not within window 16->17 May 1 14:43:56 DEBUG[2947] chan_iax2.c: Received iseqno 15 not within window 17->17 May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 138 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:43:56 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:43:56 VERBOSE[13465] logger.c: -- Playing 'vm-intro' (language 'en') May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 6 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] app.c: Locked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 160 sample intervals May 1 14:44:01 VERBOSE[13465] logger.c: -- Playing 'beep' (language 'en') May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 DEBUG[13465] channel.c: Scheduling timer at 0 sample intervals May 1 14:44:01 VERBOSE[13465] logger.c: -- Recording the message May 1 14:44:01 DEBUG[13465] app.c: play_and_record: , /var/spool/asterisk/voicemail/default/200/INBOX/msg0000, 'wav49|wav' May 1 14:44:01 DEBUG[13465] app.c: Recording Formats: sfmts=wav49 May 1 14:44:01 VERBOSE[13465] logger.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav49, 0x9a0d998 May 1 14:44:01 VERBOSE[13465] logger.c: -- x=1, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav, 0x9a45d20 May 1 14:44:01 DEBUG[13465] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 14:44:02 DEBUG[2945] chan_zap.c: Message status for 200@default changed from 0 to 1 on 4 May 1 14:44:05 DEBUG[2947] chan_iax2.c: Immediately destroying 5, having received hangup May 1 14:44:05 VERBOSE[13465] logger.c: -- User hung up May 1 14:44:05 DEBUG[13465] app_voicemail.c: Attaching file '/var/spool/asterisk/voicemail/default/200/INBOX/msg0000', format 'WAV', uservm is '2048', global is 2048 May 1 14:44:05 DEBUG[13465] app_voicemail.c: Sent mail to rlapoint@gmail.com with command '/usr/sbin/sendmail -t' May 1 14:44:05 VERBOSE[13465] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' in macro 'vm' May 1 14:44:05 VERBOSE[13465] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' May 1 14:44:05 DEBUG[13465] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. May 1 14:44:05 DEBUG[13465] 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-05-01 14:43:16','\"7044775830\" <7044775830>','7044775830','*200','ext-local', 'IAX2/telasip-gw-5','Zap/4-1','VoiceMail','200@default',49,34,'ANSWERED',3,,'1146508996.137') May 1 14:44:05 DEBUG[13465] chan_iax2.c: We're hanging up IAX2/telasip-gw-5 now... May 1 14:44:05 DEBUG[13465] chan_iax2.c: Really destroying IAX2/telasip-gw-5 now... May 1 14:44:05 VERBOSE[13465] logger.c: -- Hungup 'IAX2/telasip-gw-5' May 1 14:44:07 DEBUG[2946] chan_sip.c: Stopping retransmission on '42c46c2c0e00575630368c6c2a620bf3@192.168.1.50' of Request 102: Match Found May 1 14:44:07 DEBUG[2946] chan_sip.c: Stopping retransmission on '42c46c2c0e00575630368c6c2a620bf3@192.168.1.50' of Request 102: Match Found}}}

05/01/06 12:24:17 changed by RyanCourtnage

  • status changed from assigned to closed.
  • resolution set to worksforme.

This is not a bug, but a usage question.

Here's what I see:

-- Executing VoiceMail("IAX2/telasip-gw-5", "200@default") in new stack 
-- Playing '/var/spool/asterisk/voicemail/default/200/temp' (language 'en')

You have a temporary greeting set for this voicemail box. You need to remove it (using the voicemail application).

05/01/06 12:31:13 changed by blapoint

  • status changed from closed to reopened.
  • resolution deleted.

Hi Bentley

I removed the temp greeting file and now it play allisons voice saying please leave you message. Should'nt it play either my busy or unavailable message. Here is the log from that test.

{{{May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "FROM_DID=7042154399") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "FAX_RX=disabled") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ext-findmefollow|200|1") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Goto (ext-findmefollow,200,1) May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "user-callerid|") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Expression result is '0' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?report") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Not taking any branch May 1 15:27:23 DEBUG[13813] pbx.c: Expression result is '0' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?start") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Not taking any branch May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '7044775830' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "REALCALLERIDNUM=7044775830") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "REALCALLERIDNUM is 7044775830") in new stack May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '7044775830/user' in family 'DEVICE' May 1 15:27:23 DEBUG[13813] func_db.c: DB: DEVICE/7044775830/user not found in database. May 1 15:27:23 DEBUG[13813] pbx.c: Function result is May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSER=") in new stack May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '/cidname' in family 'AMPUSER' May 1 15:27:23 DEBUG[13813] func_db.c: DB: AMPUSER//cidname not found in database. May 1 15:27:23 DEBUG[13813] pbx.c: Function result is May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSERCIDNAME=") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Expression result is '1' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?report") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Goto (macro-user-callerid,s,9) May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '"7044775830" <7044775830>' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "Using CallerID "7044775830" <7044775830>") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '0' May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '7044775830' May 1 15:27:23 WARNING[13813] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected TOK_NE, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input: != May 1 15:27:23 WARNING[13813] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source. May 1 15:27:23 DEBUG[13813] pbx.c: Expression result is '0' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?NEWPREFIX") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Not taking any branch May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '0' May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '7044775830' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "CALLERID(name)=7044775830") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "RGPREFIX=") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '7044775830' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "CALLERID(name)=7044775830") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "RecordMethod?=Group") in new stack

May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "record-enableGroup") in new stack May 1 15:27:23 DEBUG[13813] pbx.c: Function result is '0' May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0 > 0?2:4") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Goto (macro-record-enable,s,4) May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing AGI("IAX2/telasip-gw-5", "recordingcheck|20060501-152723|1146511643.150") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '/recording' in family 'AMPUSER' May 1 15:27:23 VERBOSE[13813] logger.c: -- AGI Script recordingcheck completed, returning 0 May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "No recording needed") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "RingGroupMethod?=ringall") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "dial|15|trwW|200-208") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing AGI("IAX2/telasip-gw-5", "dialparties.agi") in new stack May 1 15:27:23 VERBOSE[13813] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi May 1 15:27:23 VERBOSE[13813] logger.c: dialparties.agi: Starting New Dialparties.agi May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: priority is 1 May 1 15:27:23 VERBOSE[13813] logger.c: dialparties.agi: Caller ID name is '7044775830' number is '7044775830' May 1 15:27:23 VERBOSE[13813] logger.c: dialparties.agi: Methodology of ring is 'ringall' May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Added extension 200 to extension map May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Added extension 208 to extension map May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '200' in family 'CF' May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Extension 200 cf is disabled May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '208' in family 'CF' May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Extension 208 cf is disabled May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '200' in family 'DND' May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Extension 200 do not disturb is disabled May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '208' in family 'DND' May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Extension 208 do not disturb is disabled May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '200' in family 'CW' May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '200' in family 'CFB' May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '200' in family 'CFU' May 1 15:27:23 DEBUG[13817] manager.c: Manager received command 'login' May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager.conf': May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager.conf': Found May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 1 15:27:23 VERBOSE[13817] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found May 1 15:27:23 WARNING[13817] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf May 1 15:27:23 DEBUG[13817] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer May 1 15:27:23 DEBUG[13817] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer May 1 15:27:23 DEBUG[13817] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 May 1 15:27:23 DEBUG[13817] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 May 1 15:27:23 VERBOSE[13817] logger.c: == Manager 'admin' logged on from 127.0.0.1 May 1 15:27:23 DEBUG[13817] manager.c: Manager received command 'ExtensionState?' May 1 15:27:23 DEBUG[13817] manager.c: Manager received command 'Logoff' May 1 15:27:23 VERBOSE[13817] logger.c: == Manager 'admin' logged off from 127.0.0.1 May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 200 May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: DbSet? CALLTRACE/200 to 7044775830 May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '208' in family 'CW' May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '208' in family 'CFB' May 1 15:27:23 DEBUG[13813] db.c: Unable to find key '208' in family 'CFU' May 1 15:27:23 DEBUG[13818] manager.c: Manager received command 'login' May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager.conf': May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager.conf': Found May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 1 15:27:23 VERBOSE[13818] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found May 1 15:27:23 WARNING[13818] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf May 1 15:27:23 DEBUG[13818] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer May 1 15:27:23 DEBUG[13818] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer May 1 15:27:23 DEBUG[13818] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 May 1 15:27:23 DEBUG[13818] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 May 1 15:27:23 VERBOSE[13818] logger.c: == Manager 'admin' logged on from 127.0.0.1 May 1 15:27:23 DEBUG[13818] manager.c: Manager received command 'ExtensionState?' May 1 15:27:23 DEBUG[13818] manager.c: Manager received command 'Logoff' May 1 15:27:23 VERBOSE[13818] logger.c: == Manager 'admin' logged off from 127.0.0.1 May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 208 May 1 15:27:23 VERBOSE[13813] logger.c: -- dialparties.agi: DbSet? CALLTRACE/208 to 7044775830 May 1 15:27:23 VERBOSE[13813] logger.c: -- AGI Script dialparties.agi completed, returning 0 May 1 15:27:23 VERBOSE[13813] logger.c: -- Executing Dial("IAX2/telasip-gw-5", "SIP/200&ZAP/4|15|trwW") in new stack May 1 15:27:23 DEBUG[13813] chan_sip.c: Setting NAT on RTP to 0 May 1 15:27:23 DEBUG[13813] chan_sip.c: Outgoing Call for 200 May 1 15:27:23 VERBOSE[13813] logger.c: -- Called 200 May 1 15:27:23 VERBOSE[13813] logger.c: -- Called 4 May 1 15:27:23 VERBOSE[13813] logger.c: -- Zap/4-1 is ringing May 1 15:27:23 DEBUG[2946] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3bc701e4420d1dfc4387f41e27116846@192.168.1.50' Request 102: Found May 1 15:27:24 DEBUG[2946] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3bc701e4420d1dfc4387f41e27116846@192.168.1.50' Request 102: Found May 1 15:27:24 VERBOSE[13813] logger.c: -- SIP/200-eca6 is ringing May 1 15:27:25 DEBUG[13813] chan_zap.c: Exception on 13, channel 4 May 1 15:27:25 DEBUG[13813] chan_zap.c: Got event Ringer Off(11) on channel 4 (index 0) May 1 15:27:25 VERBOSE[13813] logger.c: -- Zap/4-1 is ringing May 1 15:27:29 DEBUG[13813] chan_zap.c: Exception on 13, channel 4 May 1 15:27:29 DEBUG[13813] chan_zap.c: Got event Ringer On(10) on channel 4 (index 0) May 1 15:27:31 DEBUG[13813] chan_zap.c: Exception on 13, channel 4 May 1 15:27:31 DEBUG[13813] chan_zap.c: Got event Ringer Off(11) on channel 4 (index 0) May 1 15:27:31 VERBOSE[13813] logger.c: -- Zap/4-1 is ringing May 1 15:27:35 DEBUG[13813] chan_zap.c: Exception on 13, channel 4 May 1 15:27:35 DEBUG[13813] chan_zap.c: Got event Ringer On(10) on channel 4 (index 0) May 1 15:27:36 DEBUG[2947] chan_iax2.c: Peer lastms 6, historicms 6, maxms 2000 May 1 15:27:36 DEBUG[2946] chan_sip.c: Stopping retransmission on '5afd9b267c0a050813801031144e713e@192.168.1.50' of Request 102: Match Found May 1 15:27:37 DEBUG[2947] chan_iax2.c: Peer lastms 20, historicms 20, maxms 2000 May 1 15:27:37 DEBUG[13813] chan_zap.c: Exception on 13, channel 4 May 1 15:27:37 DEBUG[13813] chan_zap.c: Got event Ringer Off(11) on channel 4 (index 0) May 1 15:27:37 VERBOSE[13813] logger.c: -- Zap/4-1 is ringing May 1 15:27:37 DEBUG[2947] chan_iax2.c: Peer lastms 37, historicms 37, maxms 2000 May 1 15:27:39 DEBUG[2946] chan_sip.c: Stopping retransmission on '57728d0750104cad3b625bc5235203b4@192.168.1.50' of Request 102: Match Found May 1 15:27:39 DEBUG[2946] chan_sip.c: Stopping retransmission on '478c7fd6627bf19f7ce6db885ebc378c@192.168.1.50' of Request 102: Match Found May 1 15:27:39 VERBOSE[13813] logger.c: -- Nobody picked up in 15000 ms May 1 15:27:39 DEBUG[13813] chan_zap.c: Hangup: channel: 4 index = 0, normal = 13, callwait = -1, thirdcall = -1 May 1 15:27:39 DEBUG[13813] chan_zap.c: disabled echo cancellation on channel 4 May 1 15:27:39 DEBUG[13813] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/4-1 May 1 15:27:39 DEBUG[13813] chan_zap.c: Updated conferencing on 4, with 0 conference users May 1 15:27:39 VERBOSE[13813] logger.c: -- Hungup 'Zap/4-1' May 1 15:27:39 DEBUG[13813] chan_sip.c: update_call_counter(200) - decrement call limit counter May 1 15:27:39 DEBUG[13813] chan_sip.c: Acked pending invite 102 May 1 15:27:39 DEBUG[13813] chan_sip.c: Stopping retransmission on '3bc701e4420d1dfc4387f41e27116846@192.168.1.50' of Request 102: Match Found May 1 15:27:39 DEBUG[13813] app_dial.c: Exiting with DIALSTATUS=NOANSWER. May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "RingGroupMethod?=") in new stack May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ivr-7|s|1") in new stack May 1 15:27:39 VERBOSE[13813] logger.c: -- Goto (ivr-7,s,1) May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "LOOPCOUNT=0") in new stack May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "DIR-CONTEXT=default") in new stack May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Answer("IAX2/telasip-gw-5", "") in new stack May 1 15:27:39 VERBOSE[13813] logger.c: -- Executing Wait("IAX2/telasip-gw-5", "1") in new stack May 1 15:27:39 DEBUG[2946] chan_sip.c: Stopping retransmission on '3bc701e4420d1dfc4387f41e27116846@192.168.1.50' of Request 102: Match Found May 1 15:27:39 DEBUG[2946] chan_sip.c: Stopping retransmission on '3bc701e4420d1dfc4387f41e27116846@192.168.1.50' of Request 102: Match Not Found May 1 15:27:39 DEBUG[2947] chan_iax2.c: Ooh, voice format changed to 4 May 1 15:27:40 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "TIMEOUT(digit)=3") in new stack May 1 15:27:40 VERBOSE[13813] logger.c: -- Digit timeout set to 3 May 1 15:27:40 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "TIMEOUT(response)=2") in new stack May 1 15:27:40 VERBOSE[13813] logger.c: -- Response timeout set to 2 May 1 15:27:40 VERBOSE[13813] logger.c: -- Executing BackGround?("IAX2/telasip-gw-5", "custom/BobsFindMeRecording") in new stack May 1 15:27:40 DEBUG[13813] channel.c: Scheduling timer at 160 sample intervals May 1 15:27:40 VERBOSE[13813] logger.c: -- Playing 'custom/BobsFindMeRecording' (language 'en') May 1 15:27:43 DEBUG[2947] chan_iax2.c: Received VNAK: resending outstanding frames May 1 15:27:43 DEBUG[2947] chan_iax2.c: Received VNAK: resending outstanding frames May 1 15:27:43 DEBUG[2947] chan_iax2.c: Received VNAK: resending outstanding frames May 1 15:27:46 DEBUG[2946] chan_sip.c: Stopping retransmission on '7b36d808535c7baf0cff29016d1091f5@192.168.1.50' of Request 102: Match Found May 1 15:27:49 DEBUG[13813] channel.c: Scheduling timer at 0 sample intervals May 1 15:27:49 DEBUG[13813] pbx.c: Oooh, got something to jump out with ('2')! May 1 15:27:49 VERBOSE[13813] logger.c: == CDR updated on IAX2/telasip-gw-5 May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "ext-local|*200|1") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Goto (ext-local,*200,1) May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "vm|200|DIRECTDIAL") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "user-callerid") in new stack May 1 15:27:49 DEBUG[13813] pbx.c: Expression result is '0' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?report") in new stack May 1 15:27:49 DEBUG[13813] pbx.c: Not taking any branch May 1 15:27:49 DEBUG[13813] pbx.c: Expression result is '1' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?start") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Goto (macro-user-callerid,s,4) May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "REALCALLERIDNUM is 7044775830") in new stack May 1 15:27:49 DEBUG[13813] db.c: Unable to find key '7044775830/user' in family 'DEVICE' May 1 15:27:49 DEBUG[13813] func_db.c: DB: DEVICE/7044775830/user not found in database. May 1 15:27:49 DEBUG[13813] pbx.c: Function result is May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSER=") in new stack May 1 15:27:49 DEBUG[13813] db.c: Unable to find key '/cidname' in family 'AMPUSER' May 1 15:27:49 DEBUG[13813] func_db.c: DB: AMPUSER//cidname not found in database. May 1 15:27:49 DEBUG[13813] pbx.c: Function result is May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "AMPUSERCIDNAME=") in new stack May 1 15:27:49 DEBUG[13813] pbx.c: Expression result is '1' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "1?report") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Goto (macro-user-callerid,s,9) May 1 15:27:49 DEBUG[13813] pbx.c: Function result is '"7044775830" <7044775830>' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "Using CallerID "7044775830" <7044775830>") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Goto("IAX2/telasip-gw-5", "s-DIRECTDIAL|1") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Goto (macro-vm,s-DIRECTDIAL,1) May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "DIRECTDIAL voicemail") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Macro("IAX2/telasip-gw-5", "get-vmcontext|200") in new stack May 1 15:27:49 DEBUG[13813] pbx.c: Function result is 'default' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing Set("IAX2/telasip-gw-5", "VMCONTEXT=default") in new stack May 1 15:27:49 DEBUG[13813] pbx.c: Expression result is '0' May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing GotoIf?("IAX2/telasip-gw-5", "0?200:300") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Goto (macro-get-vmcontext,s,300) May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing NoOp?("IAX2/telasip-gw-5", "") in new stack May 1 15:27:49 VERBOSE[13813] logger.c: -- Executing VoiceMail("IAX2/telasip-gw-5", "200@default") in new stack May 1 15:27:49 DEBUG[13813] channel.c: Scheduling timer at 160 sample intervals May 1 15:27:49 VERBOSE[13813] logger.c: -- Playing 'vm-intro' (language 'en') May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 6 sample intervals May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 0 sample intervals May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 0 sample intervals May 1 15:27:54 DEBUG[13813] app.c: Locked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 160 sample intervals May 1 15:27:54 VERBOSE[13813] logger.c: -- Playing 'beep' (language 'en') May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 0 sample intervals May 1 15:27:54 DEBUG[13813] channel.c: Scheduling timer at 0 sample intervals May 1 15:27:54 VERBOSE[13813] logger.c: -- Recording the message May 1 15:27:54 DEBUG[13813] app.c: play_and_record: , /var/spool/asterisk/voicemail/default/200/INBOX/msg0000, 'wav49|wav' May 1 15:27:54 DEBUG[13813] app.c: Recording Formats: sfmts=wav49 May 1 15:27:54 VERBOSE[13813] logger.c: -- x=0, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav49, 0x9a51050 May 1 15:27:54 VERBOSE[13813] logger.c: -- x=1, open writing: /var/spool/asterisk/voicemail/default/200/INBOX/msg0000 format: wav, 0x9a47fc0 May 1 15:27:54 DEBUG[13813] app.c: Unlocked path '/var/spool/asterisk/voicemail/default/200/INBOX' May 1 15:27:55 DEBUG[2945] chan_zap.c: Message status for 200@default changed from 0 to 1 on 4 May 1 15:27:59 DEBUG[2947] chan_iax2.c: Immediately destroying 5, having received hangup May 1 15:27:59 VERBOSE[13813] logger.c: -- User hung up May 1 15:27:59 DEBUG[13813] app_voicemail.c: Attaching file '/var/spool/asterisk/voicemail/default/200/INBOX/msg0000', format 'WAV', uservm is '2048', global is 2048 May 1 15:27:59 DEBUG[13813] app_voicemail.c: Sent mail to rlapoint@gmail.com with command '/usr/sbin/sendmail -t' May 1 15:27:59 VERBOSE[13813] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' in macro 'vm' May 1 15:27:59 VERBOSE[13813] logger.c: == Spawn extension (macro-vm, s-DIRECTDIAL, 3) exited non-zero on 'IAX2/telasip-gw-5' May 1 15:27:59 DEBUG[13813] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. May 1 15:27:59 DEBUG[13813] 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-05-01 15:27:23','\"7044775830\" <7044775830>','7044775830','*200','ext-local', 'IAX2/telasip-gw-5','Zap/4-1','VoiceMail','200@default',36,20,'ANSWERED',3,,'1146511643.150') May 1 15:27:59 DEBUG[13813] chan_iax2.c: We're hanging up IAX2/telasip-gw-5 now... May 1 15:27:59 DEBUG[13813] chan_iax2.c: Really destroying IAX2/telasip-gw-5 now... May 1 15:27:59 VERBOSE[13813] logger.c: -- Hungup 'IAX2/telasip-gw-5' May 1 15:28:03 DEBUG[2946] chan_sip.c: Stopping retransmission on '6db2ae981996efb2128362f016d75920@192.168.1.50' of Request 102: Match Found May 1 15:28:03 DEBUG[2946] chan_sip.c: Stopping re}}}

05/01/06 12:39:46 changed by RyanCourtnage

If you have an unavailable message for 200 it will play it, yes.

05/01/06 12:42:34 changed by blapoint

but if I dont have a temp greeting should'nt it play either my busy or unavaialbe message depending on the phone state?

05/01/06 13:11:07 changed by RyanCourtnage

It will play your unavailable message if you have one recorded. Direct to VM is not currently designed to play your busy message.

05/01/06 13:18:38 changed by blapoint

Well then I thing there is a bug then becuase I do have an unav message recorded and it does not play it. The only announcement played back to the caller is please leave a message at the sound of the beep and this is a system recording.

05/01/06 13:30:52 changed by RyanCourtnage

This may be a bug in your version of Asterisk, as the dialplan is simply calling:

-- Executing VoiceMail("IAX2/telasip-gw-5", "200@default")

If 200 has an unavailable message, the Voicemail application is responsible for playing it.

Can anyone else duplicate this?

05/01/06 13:37:50 changed by anonymous

DIRECTDIAL doesn't do b or u - I fixed the comment in r1775 (+/- 1 if you're clicking on this in trac).

05/01/06 13:38:50 changed by RobThomas

Previous message was from me --Rob

05/01/06 13:48:11 changed by RyanCourtnage

  • status changed from reopened to closed.
  • resolution set to fixed.

oh yeah, go ahead and make me look bad! :-D Sorry, I thought 'u' used by voicemail as the default, I was wrong.

05/05/06 10:37:19 changed by RyanCourtnage

This case is alive again in ticket #743

06/03/06 09:10:17 changed by jenesys

The problem is a caller from the outside is hearing the system default instead of my unavail message. When I change it using the general setting to play my unavail for direct dial calls it also changes it for outside callers. The thing is, I am using a ring group with the no answer destination being the voicemail for extension 201.

01/08/07 11:46:42 changed by

  • milestone deleted.

Milestone 2.1 deleted

01/08/07 13:26:11 changed by vgster

  • milestone set to 2.1.
Donate



Support
Download
Develop
Forums
News
Documentation
Paid Support
About

Paid Ads