[Asterisk-Users] Some Extensions Remain Busy?

Johnny Stork stork at openenterprise.ca
Fri Apr 28 08:26:35 MST 2006 

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 '
BF9C3AB4-7AC1-45D8-9C9A-D8066495A57F at 192.168.1.10' 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,dura
tion,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:marco.mouta at gmail.com]
   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 < stork at openenterprise.ca> wrote: 


      I have a fairly new, but functional install of A at 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 at H
      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 
      


   


-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20060428/86b58c76/attachment.htm

More information about the asterisk-users mailing list