That was clearly not the solution...but thanks.
 
Below is a log entry when the call is made which goes straight to vm
 
 Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "user-callerid") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing DBget("SIP/103-c10f", "AMPUSER=DEVICE/103/user") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, key=103/user 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: set variable AMPUSER to 103 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing DBget("SIP/103-c10f", "AMPUSERCIDNAME=AMPUSER/103/cidname") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=103/cidname 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: set variable AMPUSERCIDNAME to Johnny Laptop 
Apr 28 07:48:25 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?5") in new stack 
Apr 28 07:48:25 DEBUG[4600] pbx.c: Not taking any branch 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing SetCallerID("SIP/103-c10f", ""Johnny Laptop" <103>") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing NoOp("SIP/103-c10f", "Using CallerID "Johnny Laptop" <103>") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing SetVar("SIP/103-c10f", "FROMCONTEXT=exten-vm") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "record-enable|102|IN") in new stack 
Apr 28 07:48:25 DEBUG[4600] pbx.c: Function result is '0' 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0 > 0?2:4") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Goto (macro-record-enable,s,4) 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing AGI("SIP/103-c10f", "recordingcheck|20060428-074825|1146235705.88") in new stack 
Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck 
Apr 28 07:48:26 VERBOSE[4600] logger.c: recordingcheck|20060428-074825|1146235705.88: Inbound recording not enabled 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- AGI Script recordingcheck completed, returning 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing NoOp("SIP/103-c10f", "No recording needed") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "dial|15|tr|102") in new stack 
Apr 28 07:48:26 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?4:2") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Goto (macro-dial,s,2) 
Apr 28 07:48:26 DEBUG[4600] pbx.c: Function result is '0' 
Apr 28 07:48:26 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?5:4") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Goto (macro-dial,s,4) 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing AGI("SIP/103-c10f", "dialparties.agi") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: priority = 4 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callingani2 = 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: accountcode =  
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: channel = SIP/103-c10f 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callerid = 103 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: context = macro-dial 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callington = 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: dnid = 102 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: request = dialparties.agi 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: calleridname = Johnny Laptop 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: extension = s 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: language = en 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: uniqueid = 1146235705.88 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callingpres = 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: type = SIP 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: rdnis = unknown 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callingtns = 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: enhanced = 0.0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: Caller ID name and number are '103' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: Methodology of ring is 'none' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: Added extension 102 to extension map 
Apr 28 07:48:26 DEBUG[4600] db.c: Unable to find key '102' in family 'CF' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: Extension 102 cf is disabled 
Apr 28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: Extension 102 has do not disturb enabled 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- AGI Script Executing Application: (NoOp) Options: () 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- AGI Script dialparties.agi completed, returning 0 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing NoOp("SIP/103-c10f", "Returned from dialparties with no extensions to call") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing SetVar("SIP/103-c10f", "DIALSTATUS=BUSY") in new stack 
Apr 28 07:48:26 WARNING[4600] ast_expr2.y: non-numeric argument 
Apr 28 07:48:26 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?s-BUSY|1") in new stack 
Apr 28 07:48:26 DEBUG[4600] pbx.c: Not taking any branch 
Apr 28 07:48:26 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?s-BUSY|1") in new stack 
Apr 28 07:48:26 DEBUG[4600] pbx.c: Not taking any branch 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing NoOp("SIP/103-c10f", "Sending to Voicemail box 102") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "vm|102|BUSY") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "user-callerid") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing DBget("SIP/103-c10f", "AMPUSER=DEVICE/103/user") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, key=103/user 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: set variable AMPUSER to 103 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing DBget("SIP/103-c10f", "AMPUSERCIDNAME=AMPUSER/103/cidname") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=103/cidname 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: set variable AMPUSERCIDNAME to Johnny Laptop 
Apr 28 07:48:26 DEBUG[4600] pbx.c: _expression_ result is '0' 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?5") in new stack 
Apr 28 07:48:26 DEBUG[4600] pbx.c: Not taking any branch 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing SetCallerID("SIP/103-c10f", ""Johnny Laptop" <103>") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing NoOp("SIP/103-c10f", "Using CallerID "Johnny Laptop" <103>") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing Goto("SIP/103-c10f", "s-BUSY|1") in new stack 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Goto (macro-vm,s-BUSY,1) 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing VoiceMail("SIP/103-c10f", "b102") in new stack 
Apr 28 07:48:26 DEBUG[4600] app_voicemail.c: /var/spool/asterisk/voicemail/default/102/busy doesn't exist, doing what we can 
Apr 28 07:48:26 DEBUG[4600] channel.c: Scheduling timer at 160 sample intervals 
Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Playing '/var/spool/asterisk/voicemail/default/102/greet' (language 'en') 
Apr 28 07:48:26 DEBUG[2767] chan_sip.c: Stopping retransmission on '[EMAIL PROTECTED]' of Response 24157: Match Found 
Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling timer at 138 sample intervals 
Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling timer at 0 sample intervals 
Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling timer at 0 sample intervals 
Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling timer at 160 sample intervals 
Apr 28 07:48:30 VERBOSE[4600] logger.c: -- Playing 'vm-isonphone' (language 'en') 
Apr 28 07:48:30 DEBUG[4600] app_voicemail.c: Hang up during prefile playback 
Apr 28 07:48:30 VERBOSE[4600] logger.c: == Spawn extension (macro-vm, s-BUSY, 1) exited non-zero on 'SIP/103-c10f' in macro 'vm' 
Apr 28 07:48:30 VERBOSE[4600] logger.c: == Spawn extension (macro-vm, s-BUSY, 1) exited non-zero on 'SIP/103-c10f' in macro 'exten-vm' 
Apr 28 07:48:30 VERBOSE[4600] logger.c: == Spawn extension (macro-vm, s-BUSY, 1) exited non-zero on 'SIP/103-c10f' 
Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling timer at 0 sample intervals 
Apr 28 07:48:30 DEBUG[4600] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. 
Apr 28 07:48:30 DEBUG[4600] 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-04-28 07:48:25','\"Johnny Laptop\" <103>','103','102','from-internal', 'SIP/103-c10f','','VoiceMail','b102',5,4,'ANSWERED',3,'','1146235705.88') 
Apr 28 07:48:30 DEBUG[4600] chan_sip.c: update_call_counter(103) - decrement call limit counter 
Apr 28 07:50:11 DEBUG[4485] manager.c: Manager received command 'Command' 
Apr 28 07:50:11 DEBUG[4485] manager.c: Manager received command 'Command' 
-----Original Message-----
From: Marco Mouta [mailto:[EMAIL PROTECTED]
Sent: Friday, April 28, 2006 7:45 AM
To: Asterisk Users Mailing List - Non-Commercial Discussion
Subject: Re: [Asterisk-Users] Some Extensions Remain Busy?

You must activate call waiting for those extensions, this way you will get correctly voicemail busy and unavailable.

From the sip extension dial *70



On 4/28/06, Johnny Stork <[EMAIL PROTECTED]> wrote:

I have a fairly new, but functional install of [EMAIL PROTECTED] 2.7 with a TDM400 (1 FXS) and T101P (1 FXO) hardware. For some reason the analog phone connected to the FXS port and one SIP softphone goes straight to the voicemail indicating "....Is On the Phone" although it is NOT off the hook. I can use the phone and call out like normal. The one SIP softphone also does this, in spite of shutting down the computer and restarting, or even restarting [EMAIL PROTECTED] it keeps coming up as being busy..."......so and so is On The Phone".

This just started for some reason and all other phones seem fine?
_______________________________________________
--Bandwidth and Colocation provided by Easynews.com --

Asterisk-Users mailing list
To UNSUBSCRIBE or update options visit:
   http://lists.digium.com/mailman/listinfo/asterisk-users

_______________________________________________
--Bandwidth and Colocation provided by Easynews.com --

Asterisk-Users mailing list
To UNSUBSCRIBE or update options visit:
   http://lists.digium.com/mailman/listinfo/asterisk-users

Reply via email to