[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