<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>

<META content="MSHTML 6.00.2900.2873" name=GENERATOR></HEAD>
<BODY>
<DIV><SPAN class=274072515-28042006><FONT color=#0000ff size=2>That was clearly 
not the solution...but thanks. </FONT></SPAN></DIV>
<DIV><SPAN class=274072515-28042006><FONT color=#0000ff 
size=2></FONT></SPAN>&nbsp;</DIV>
<DIV><SPAN class=274072515-28042006><FONT color=#0000ff size=2>Below is a log 
entry when the call is made which goes straight to vm</FONT></SPAN></DIV>
<DIV><SPAN class=274072515-28042006><FONT color=#0000ff 
size=2></FONT></SPAN>&nbsp;</DIV>
<DIV><SPAN class=274072515-28042006>&nbsp;Apr 28 07:48:25 VERBOSE[4600] 
logger.c: -- Executing Macro("SIP/103-c10f", "user-callerid") in new 
stack&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing 
DBget("SIP/103-c10f", "AMPUSER=DEVICE/103/user") in new stack&nbsp;<BR>Apr 28 
07:48:25 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, 
key=103/user&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: set 
variable AMPUSER to 103&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- 
Executing DBget("SIP/103-c10f", "AMPUSERCIDNAME=AMPUSER/103/cidname") in new 
stack&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- DBget: 
varname=AMPUSERCIDNAME, family=AMPUSER, key=103/cidname&nbsp;<BR>Apr 28 07:48:25 
VERBOSE[4600] logger.c: -- DBget: set variable AMPUSERCIDNAME to Johnny 
Laptop&nbsp;<BR>Apr 28 07:48:25 DEBUG[4600] pbx.c: Expression result is 
'0'&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing 
GotoIf("SIP/103-c10f", "0?5") in new stack&nbsp;<BR>Apr 28 07:48:25 DEBUG[4600] 
pbx.c: Not taking any branch&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- 
Executing SetCallerID("SIP/103-c10f", ""Johnny Laptop" &lt;103&gt;") in new 
stack&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing 
NoOp("SIP/103-c10f", "Using CallerID "Johnny Laptop" &lt;103&gt;") in new 
stack&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- Executing 
SetVar("SIP/103-c10f", "FROMCONTEXT=exten-vm") in new stack&nbsp;<BR>Apr 28 
07:48:25 VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", 
"record-enable|102|IN") in new stack&nbsp;<BR>Apr 28 07:48:25 DEBUG[4600] pbx.c: 
Function result is '0'&nbsp;<BR>Apr 28 07:48:25 VERBOSE[4600] logger.c: -- 
Executing GotoIf("SIP/103-c10f", "0 &gt; 0?2:4") in new stack&nbsp;<BR>Apr 28 
07:48:25 VERBOSE[4600] logger.c: -- Goto (macro-record-enable,s,4)&nbsp;<BR>Apr 
28 07:48:25 VERBOSE[4600] logger.c: -- Executing AGI("SIP/103-c10f", 
"recordingcheck|20060428-074825|1146235705.88") in new stack&nbsp;<BR>Apr 28 
07:48:25 VERBOSE[4600] logger.c: -- Launched AGI Script 
/var/lib/asterisk/agi-bin/recordingcheck&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: recordingcheck|20060428-074825|1146235705.88: Inbound recording not 
enabled&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- AGI Script 
recordingcheck completed, returning 0&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- Executing NoOp("SIP/103-c10f", "No recording needed") in new 
stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
Macro("SIP/103-c10f", "dial|15|tr|102") in new stack&nbsp;<BR>Apr 28 07:48:26 
DEBUG[4600] pbx.c: Expression result is '0'&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?4:2") in new 
stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Goto 
(macro-dial,s,2)&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] pbx.c: Function result is 
'0'&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] pbx.c: Expression result is 
'0'&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
GotoIf("SIP/103-c10f", "0?5:4") in new stack&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Goto (macro-dial,s,4)&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Executing AGI("SIP/103-c10f", "dialparties.agi") in 
new stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Launched AGI 
Script /var/lib/asterisk/agi-bin/dialparties.agi&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- dialparties.agi: priority = 4&nbsp;<BR>Apr 28 
07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callingani2 = 
0&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: 
accountcode = &nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- 
dialparties.agi: channel = SIP/103-c10f&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- dialparties.agi: callerid = 103&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- dialparties.agi: context = macro-dial&nbsp;<BR>Apr 28 
07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: callington = 0&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: dnid = 102&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: request = 
dialparties.agi&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- 
dialparties.agi: calleridname = Johnny Laptop&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- dialparties.agi: extension = s&nbsp;<BR>Apr 28 
07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: language = en&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: uniqueid = 
1146235705.88&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- 
dialparties.agi: callingpres = 0&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- dialparties.agi: type = SIP&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- dialparties.agi: rdnis = unknown&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- dialparties.agi: callingtns = 0&nbsp;<BR>Apr 28 
07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: enhanced = 0.0&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: Caller ID name and number 
are '103'&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: 
Methodology of ring is 'none'&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: 
-- dialparties.agi: Added extension 102 to extension map&nbsp;<BR>Apr 28 
07:48:26 DEBUG[4600] db.c: Unable to find key '102' in family 'CF'&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: -- dialparties.agi: Extension 102 cf is 
disabled&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: dialparties.agi: 
Extension 102 has do not disturb enabled&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- AGI Script Executing Application: (NoOp) Options: ()&nbsp;<BR>Apr 
28 07:48:26 VERBOSE[4600] logger.c: -- AGI Script dialparties.agi completed, 
returning 0&nbsp;<BR>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&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
SetVar("SIP/103-c10f", "DIALSTATUS=BUSY") in new stack&nbsp;<BR>Apr 28 07:48:26 
WARNING[4600] ast_expr2.y: non-numeric argument&nbsp;<BR>Apr 28 07:48:26 
DEBUG[4600] pbx.c: Expression result is '0'&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Executing GotoIf("SIP/103-c10f", "0?s-BUSY|1") in new 
stack&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] pbx.c: Not taking any 
branch&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] pbx.c: Expression result is 
'0'&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
GotoIf("SIP/103-c10f", "0?s-BUSY|1") in new stack&nbsp;<BR>Apr 28 07:48:26 
DEBUG[4600] pbx.c: Not taking any branch&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] 
logger.c: -- Executing NoOp("SIP/103-c10f", "Sending to Voicemail box 102") in 
new stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
Macro("SIP/103-c10f", "vm|102|BUSY") in new stack&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Executing Macro("SIP/103-c10f", "user-callerid") in 
new stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
DBget("SIP/103-c10f", "AMPUSER=DEVICE/103/user") in new stack&nbsp;<BR>Apr 28 
07:48:26 VERBOSE[4600] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, 
key=103/user&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: set 
variable AMPUSER to 103&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- 
Executing DBget("SIP/103-c10f", "AMPUSERCIDNAME=AMPUSER/103/cidname") in new 
stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- DBget: 
varname=AMPUSERCIDNAME, family=AMPUSER, key=103/cidname&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- DBget: set variable AMPUSERCIDNAME to Johnny 
Laptop&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] pbx.c: Expression result is 
'0'&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
GotoIf("SIP/103-c10f", "0?5") in new stack&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] 
pbx.c: Not taking any branch&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- 
Executing SetCallerID("SIP/103-c10f", ""Johnny Laptop" &lt;103&gt;") in new 
stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
NoOp("SIP/103-c10f", "Using CallerID "Johnny Laptop" &lt;103&gt;") in new 
stack&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Executing 
Goto("SIP/103-c10f", "s-BUSY|1") in new stack&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Goto (macro-vm,s-BUSY,1)&nbsp;<BR>Apr 28 07:48:26 
VERBOSE[4600] logger.c: -- Executing VoiceMail("SIP/103-c10f", "b102") in new 
stack&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] app_voicemail.c: 
/var/spool/asterisk/voicemail/default/102/busy doesn't exist, doing what we 
can&nbsp;<BR>Apr 28 07:48:26 DEBUG[4600] channel.c: Scheduling timer at 160 
sample intervals&nbsp;<BR>Apr 28 07:48:26 VERBOSE[4600] logger.c: -- Playing 
'/var/spool/asterisk/voicemail/default/102/greet' (language 'en')&nbsp;<BR>Apr 
28 07:48:26 DEBUG[2767] chan_sip.c: Stopping retransmission on '<A 
href="mailto:BF9C3AB4-7AC1-45D8-9C9A-D8066495A57F@192.168.1.10" 
target=_new>BF9C3AB4-7AC1-45D8-9C9A-D8066495A57F@192.168.1.10</A>' of Response 
24157: Match Found&nbsp;<BR>Apr 28 07:48:30 DEBUG[4600] channel.c: Scheduling 
timer at 138 sample intervals&nbsp;<BR>Apr 28 07:48:30 DEBUG[4600] channel.c: 
Scheduling timer at 0 sample intervals&nbsp;<BR>Apr 28 07:48:30 DEBUG[4600] 
channel.c: Scheduling timer at 0 sample intervals&nbsp;<BR>Apr 28 07:48:30 
DEBUG[4600] channel.c: Scheduling timer at 160 sample intervals&nbsp;<BR>Apr 28 
07:48:30 VERBOSE[4600] logger.c: -- Playing 'vm-isonphone' (language 
'en')&nbsp;<BR>Apr 28 07:48:30 DEBUG[4600] app_voicemail.c: Hang up during 
prefile playback&nbsp;<BR>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'&nbsp;<BR>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'&nbsp;<BR>Apr 28 07:48:30 VERBOSE[4600] logger.c: == Spawn extension 
(macro-vm, s-BUSY, 1) exited non-zero on 'SIP/103-c10f'&nbsp;<BR>Apr 28 07:48:30 
DEBUG[4600] channel.c: Scheduling timer at 0 sample intervals&nbsp;<BR>Apr 28 
07:48:30 DEBUG[4600] cdr_addon_mysql.c: cdr_mysql: inserting a CDR 
record.&nbsp;<BR>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\" 
&lt;103&gt;','103','102','from-internal', 
'SIP/103-c10f','','VoiceMail','b102',5,4,'ANSWERED',3,'','1146235705.88')&nbsp;<BR>Apr 
28 07:48:30 DEBUG[4600] chan_sip.c: update_call_counter(103) - decrement call 
limit counter&nbsp;<BR>Apr 28 07:50:11 DEBUG[4485] manager.c: Manager received 
command 'Command'&nbsp;<BR>Apr 28 07:50:11 DEBUG[4485] manager.c: Manager 
received command 'Command'&nbsp;</SPAN></DIV>
<BLOCKQUOTE dir=ltr 
style="PADDING-LEFT: 5px; MARGIN-LEFT: 5px; BORDER-LEFT: #0000ff 2px solid; MARGIN-RIGHT: 0px">
  <DIV class=OutlookMessageHeader dir=ltr align=left><FONT face=Tahoma 
  size=2>-----Original Message-----<BR><B>From:</B> Marco Mouta 
  [mailto:marco.mouta@gmail.com]<BR><B>Sent:</B> Friday, April 28, 2006 7:45 
  AM<BR><B>To:</B> Asterisk Users Mailing List - Non-Commercial 
  Discussion<BR><B>Subject:</B> Re: [Asterisk-Users] Some Extensions Remain 
  Busy?<BR><BR></FONT></DIV>
  <P>You must activate call waiting for those extensions, this way you will get 
  correctly voicemail busy and unavailable.<BR><BR>From the sip extension dial 
  *70<BR><BR><BR><BR>
  <DIV><SPAN class=gmail_quote>On 4/28/06, <B class=gmail_sendername>Johnny 
  Stork</B> &lt;<A 
  href="mailto:stork@openenterprise.ca">stork@openenterprise.ca</A>&gt; 
  wrote:</SPAN>
  <BLOCKQUOTE class=gmail_quote 
  style="PADDING-LEFT: 1ex; MARGIN: 0pt 0pt 0pt 0.8ex; BORDER-LEFT: rgb(204,204,204) 1px solid"><BR>I 
    have a fairly new, but functional install of A@H 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 A@H it keeps 
    coming up as being busy..."......so and so is On The Phone".<BR><BR>This 
    just started for some reason and all other phones seem 
    fine?<BR>_______________________________________________<BR>--Bandwidth and 
    Colocation provided by <A href="http://Easynews.com">Easynews.com</A> 
    --<BR><BR>Asterisk-Users mailing list<BR>To UNSUBSCRIBE or update options 
    visit:<BR>&nbsp;&nbsp; <A 
    href="http://lists.digium.com/mailman/listinfo/asterisk-users">http://lists.digium.com/mailman/listinfo/asterisk-users 
    </A><BR></BLOCKQUOTE></DIV><BR>
  <P></P></BLOCKQUOTE></BODY></HTML>