[Home]

Summary:ASTERISK-11419: external lines connected with message !! Got Busy in Connected State !?!
Reporter:Fons van der Beek (fvdb)Labels:
Date Opened:2008-02-11 16:48:08.000-0600Date Closed:2009-07-13 09:56:06
Priority:MajorRegression?No
Status:Closed/CompleteComponents:Channels/chan_misdn
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I am using asterisk 1.4.17 together with misdn,  once in a while:

-when a call was put on hold
-the operator tries to call a internal party for transfering the call
-the internal party doesn't answer the phone
-the operator wants to get the external line back again by putting the call "off hold"

And then the external line is disconnected.
an exact log of events is recorded and is given in the additional information

This happens a few times a day, i am not sure if this is the right place to post this error, but it troubles me for a lang while. I first thought the telephone was the cause of this problem (linksys 962). I've chosen the Digium Wildcard B410P ISDN BRI as my ISDN interface, the reason was that i thought it would seamless integrate into asterisk (digium digium) therefor i hope that this answer is also answered in this asterisk thread.

in the asterisk user section some-one else also has this issue


****** ADDITIONAL INFORMATION ******

[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Executing [0475426426@incomming:1] Answer("mISDN/5-u299", "") in new stack
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Executing [0475426426@incomming:2] LookupCIDName("mISDN/5-u299", "") in new stack
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Changed Caller*ID name to some-one,Firstname
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Executing [0475426426@incomming:3] GotoIf("mISDN/5-u299", "0?7") in new stack
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Executing [0475426426@incomming:4] Devstate("mISDN/5-u299", "100|1") in new stack
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Executing [0475426426@incomming:5] Dial("mISDN/5-u299", "SIP/201&SIP/240|45|twk") in new stack
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Called 201
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- Called 240
[2008-02-11 16:02:27] VERBOSE[6543] logger.c:  Extension Changed 240 new state Ringing for Notify User 201
[2008-02-11 16:02:27] VERBOSE[22205] logger.c:     -- SIP/201-08758540 is ringing
[2008-02-11 16:02:28] VERBOSE[22205] logger.c:     -- SIP/240-b630ef30 is ringing
[2008-02-11 16:02:33] VERBOSE[22205] logger.c:     -- SIP/201-08758540 answered mISDN/5-u299
[2008-02-11 16:02:33] VERBOSE[6543] logger.c:  Extension Changed 240 new state Idle for Notify User 201
[2008-02-11 16:02:40] VERBOSE[22205] logger.c:     -- Started music on hold, class 'default', on mISDN/5-u299
[2008-02-11 16:02:42] VERBOSE[22215] logger.c:     -- Executing [211@default:1] Dial("SIP/201-0872f2c0", "SIP/211|20|tWwTkK") in new stack
[2008-02-11 16:02:42] VERBOSE[6543] logger.c:  Extension Changed 211 new state Ringing for Notify User 201
[2008-02-11 16:02:42] VERBOSE[22215] logger.c:     -- Called 211
[2008-02-11 16:02:42] VERBOSE[22215] logger.c:     -- SIP/211-0874d9a0 is ringing
[2008-02-11 16:02:55] VERBOSE[6545] logger.c:     -- Stopped music on hold on mISDN/5-u299
[2008-02-11 16:02:55] VERBOSE[22205] logger.c:   == Spawn extension (incomming, 0475426426, 5) exited non-zero on 'SIP/201-0872f2c0<ZOMBIE>'
[2008-02-11 16:03:00] VERBOSE[22217] logger.c:     -- Executing [211@default:1] Dial("SIP/201-08775598", "SIP/211|20|tWwTkK") in new stack
[2008-02-11 16:03:00] VERBOSE[22217] logger.c:     -- Called 211
[2008-02-11 16:03:00] VERBOSE[22217] logger.c:     -- SIP/211-08779510 is ringing
[2008-02-11 16:03:02] VERBOSE[22215] logger.c:     -- Nobody picked up in 20000 ms
[2008-02-11 16:03:02] VERBOSE[22215] logger.c:     -- Executing [211@default:2] Busy("mISDN/5-u299", "") in new stack
[2008-02-11 16:03:02] WARNING[22215] chan_misdn.c:  --> !! Got Busy in Connected State !?! ast:mISDN/5-u299
[2008-02-11 16:03:03] VERBOSE[6543] logger.c:  Extension Changed 211 new state Idle for Notify User 201
[2008-02-11 16:03:03] VERBOSE[22217] logger.c:   == Spawn extension (default, 211, 1) exited non-zero on 'SIP/201-08775598'

/etc/misdn-init.conf
card=1,0x4
te_ptp=1,2,3
te_ptp=4

/etc/asterisk/misdn.conf
[general]
misdn_init=/etc/misdn-init.conf
debug=0
ntdebugflags=0
ntdebugfile=/var/log/misdn-nt.log
tracefile=/var/log/asterisk/misdn.log
bridging=no
stop_tone_after_first_digit=yes
append_digits2exten=yes
dynamic_crypt=no
crypt_prefix=**
crypt_keys=test,muh
[default]
context=misdn
language=en
musicclass=default
senddtmf=yes
far_alerting=no
allowed_bearers=all
nationalprefix=0
internationalprefix=00
rxgain=0
txgain=0
te_choose_channel=yes
pmp_l1_check=no
reject_cause=16
need_more_infos=no
nttimeout=no
dialplan=0
localdialplan=0
cpndialplan=0
early_bconnect=yes
nodialtone=no
callgroup=1
pickupgroup=1
presentation=-1
screen=-1
echocancel=128
jitterbuffer=4000
jitterbuffer_upper_threshold=0
hdlc=yes
max_outgoing=-1
[extern]
ports=1,2,3
context=incomming
msns=*

[extern1]
ports=4
context=incomming
msns=*
Comments:By: Fons van der Beek (fvdb) 2008-02-13 05:04:29.000-0600

another simulair problem occurred
Extension Changed 203 new state Idle for Notify User 201
 == Spawn extension (default, 702, 1) exited non-zero on 'SIP/201-b6308bc0'
Extension Changed 237 new state InUse for Notify User 201
   -- Executing [0354354@default:1] misdn_check_l2l1("SIP/237-b6304668", "g:extern|2") in new stack
   -- Executing [0354354@default:2] Dial("SIP/237-b6304668", "mISDN/1/354354|30|WKT") in new stack
   -- Called 1/354354
   -- mISDN/1-u89 is proceeding passing it to SIP/237-b6304668
   -- mISDN/1-u90 is ringing
   -- mISDN/1-u90 answered SIP/237-b6304668
Extension Changed 203 new state InUse for Notify User 201
   -- Executing [0352630@default:1] misdn_check_l2l1("SIP/203-b6305bf8", "g:extern|2") in new stack
   -- Executing [0352630@default:2] Dial("SIP/203-b6305bf8", "mISDN/1/352630|30|WKT") in new stack
   -- Called 1/352630
   -- mISDN/2-u93 is proceeding passing it to SIP/203-b6305bf8
   -- mISDN/2-u94 is ringing
   -- mISDN/2-u94 answered SIP/203-b6305bf8
   -- Executing [0475528520@incomming:1] Answer("mISDN/4-u95", "") in new stack
   -- Executing [0475528520@incomming:2] misdn_check_l2l1("mISDN/4-u95", "g:extern1|2") in new stack
   -- Executing [0475528520@incomming:3] Dial("mISDN/4-u95", "mISDN/4/0475531157") in new stack
   -- Called 4/0475531157
   -- mISDN/7-u98 is proceeding passing it to mISDN/4-u95
   -- mISDN/7-u99 is ringing
   -- mISDN/7-u99 answered mISDN/4-u95
   -- Executing [0475528520@incomming:1] Answer("mISDN/6-u100", "") in new stack
   -- Executing [0475528520@incomming:2] misdn_check_l2l1("mISDN/6-u100", "g:extern1|2") in new stack
   -- Executing [0475528520@incomming:3] Dial("mISDN/6-u100", "mISDN/4/0475531157") in new stack
   -- Called 4/0475531157
   -- mISDN/8-u103 is proceeding passing it to mISDN/6-u100
   -- mISDN/8-u103 is busy
 == Everyone is busy/congested at this time (1:1/0/0)
 == Auto fallthrough, channel 'mISDN/6-u100' status is 'BUSY'
[2008-02-13 12:03:43] WARNING[17978]: chan_misdn.c:5650 chan_misdn_log:  --> !! Got Busy in Connected State !?! ast:mISDN/6-u100
P[ 4]  --> Didn't find BC so temporarly creating dummy BC (l3id:ffff0001) on this port.
[2008-02-13 12:04:03] WARNING[13710]: chan_misdn.c:5650 chan_misdn_log: Got EVENT_FACILITY but we don't have a ch!
 == Spawn extension (default, 0354354, 2) exited non-zero on 'SIP/237-b6304668'
Extension Changed 237 new state Idle for Notify User 201
By: Richard Mudgett (rmudgett) 2009-07-02 16:47:24

In both of the cases provided, the incoming mISDN call was answered with the Answer application.  This put the channel in the connected state.  Then the subsequent Dial got a busy endpoint.  It is valid for a connected channel to get the busy indication at this point.  The warning message can be safely ignored and I will remove it.
By: Digium Subversion (svnbot) 2009-07-02 16:59:44

Repository: asterisk
Revision: 204834

U   branches/1.4/channels/chan_misdn.c

------------------------------------------------------------------------
r204834 | rmudgett | 2009-07-02 16:59:44 -0500 (Thu, 02 Jul 2009) | 10 lines

Removed confusing warning message "Got Busy in Connected State"

If an incoming mISDN call is answered with the Answer application and a
subsequent Dial gets a busy endpoint then it is valid for that already
connected channel to get the busy indication.  Asterisk will play the busy
tones until the dialplan plays something else or hangs up the call.

(closes issue ASTERISK-11419)
Reported by: fvdb

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=204834
By: Digium Subversion (svnbot) 2009-07-02 17:01:29

Repository: asterisk
Revision: 204835

_U  trunk/
U   trunk/channels/chan_misdn.c

------------------------------------------------------------------------
r204835 | rmudgett | 2009-07-02 17:01:29 -0500 (Thu, 02 Jul 2009) | 17 lines

Merged revisions 204834 via svnmerge from
https://origsvn.digium.com/svn/asterisk/branches/1.4

........
 r204834 | rmudgett | 2009-07-02 16:59:43 -0500 (Thu, 02 Jul 2009) | 10 lines
 
 Removed confusing warning message "Got Busy in Connected State"
 
 If an incoming mISDN call is answered with the Answer application and a
 subsequent Dial gets a busy endpoint then it is valid for that already
 connected channel to get the busy indication.  Asterisk will play the busy
 tones until the dialplan plays something else or hangs up the call.
 
 (closes issue ASTERISK-11419)
 Reported by: fvdb
........

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=204835
By: Digium Subversion (svnbot) 2009-07-02 17:03:26

Repository: asterisk
Revision: 204836

_U  branches/1.6.0/
U   branches/1.6.0/channels/chan_misdn.c

------------------------------------------------------------------------
r204836 | rmudgett | 2009-07-02 17:03:26 -0500 (Thu, 02 Jul 2009) | 24 lines

Merged revisions 204835 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

................
 r204835 | rmudgett | 2009-07-02 17:01:28 -0500 (Thu, 02 Jul 2009) | 17 lines
 
 Merged revisions 204834 via svnmerge from
 https://origsvn.digium.com/svn/asterisk/branches/1.4
 
 ........
   r204834 | rmudgett | 2009-07-02 16:59:43 -0500 (Thu, 02 Jul 2009) | 10 lines
   
   Removed confusing warning message "Got Busy in Connected State"
   
   If an incoming mISDN call is answered with the Answer application and a
   subsequent Dial gets a busy endpoint then it is valid for that already
   connected channel to get the busy indication.  Asterisk will play the busy
   tones until the dialplan plays something else or hangs up the call.
   
   (closes issue ASTERISK-11419)
   Reported by: fvdb
 ........
................

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=204836
By: Digium Subversion (svnbot) 2009-07-02 17:05:07

Repository: asterisk
Revision: 204837

_U  branches/1.6.1/
U   branches/1.6.1/channels/chan_misdn.c

------------------------------------------------------------------------
r204837 | rmudgett | 2009-07-02 17:05:07 -0500 (Thu, 02 Jul 2009) | 24 lines

Merged revisions 204835 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

................
 r204835 | rmudgett | 2009-07-02 17:01:28 -0500 (Thu, 02 Jul 2009) | 17 lines
 
 Merged revisions 204834 via svnmerge from
 https://origsvn.digium.com/svn/asterisk/branches/1.4
 
 ........
   r204834 | rmudgett | 2009-07-02 16:59:43 -0500 (Thu, 02 Jul 2009) | 10 lines
   
   Removed confusing warning message "Got Busy in Connected State"
   
   If an incoming mISDN call is answered with the Answer application and a
   subsequent Dial gets a busy endpoint then it is valid for that already
   connected channel to get the busy indication.  Asterisk will play the busy
   tones until the dialplan plays something else or hangs up the call.
   
   (closes issue ASTERISK-11419)
   Reported by: fvdb
 ........
................

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=204837
By: Digium Subversion (svnbot) 2009-07-02 17:06:22

Repository: asterisk
Revision: 204838

_U  branches/1.6.2/
U   branches/1.6.2/channels/chan_misdn.c

------------------------------------------------------------------------
r204838 | rmudgett | 2009-07-02 17:06:21 -0500 (Thu, 02 Jul 2009) | 24 lines

Merged revisions 204835 via svnmerge from
https://origsvn.digium.com/svn/asterisk/trunk

................
 r204835 | rmudgett | 2009-07-02 17:01:28 -0500 (Thu, 02 Jul 2009) | 17 lines
 
 Merged revisions 204834 via svnmerge from
 https://origsvn.digium.com/svn/asterisk/branches/1.4
 
 ........
   r204834 | rmudgett | 2009-07-02 16:59:43 -0500 (Thu, 02 Jul 2009) | 10 lines
   
   Removed confusing warning message "Got Busy in Connected State"
   
   If an incoming mISDN call is answered with the Answer application and a
   subsequent Dial gets a busy endpoint then it is valid for that already
   connected channel to get the busy indication.  Asterisk will play the busy
   tones until the dialplan plays something else or hangs up the call.
   
   (closes issue ASTERISK-11419)
   Reported by: fvdb
 ........
................

------------------------------------------------------------------------

http://svn.digium.com/view/asterisk?view=rev&revision=204838