misdn BCHAN_ERROR

Hi all,

pls help me on this.. i won't be able to receive call from isdn line to the local softphone

can anybody tell me , what is the problem
tq

here is a misdn.log
[code:1]

Tue Jul 15 11:02:01 2008: P[ 1] handle_frm: frm->addr:42000103 frm->prim:3f082
Tue Jul 15 11:02:01 2008: P[ 1] handle_frm: frm->addr:42000103 frm->prim:30582
Tue Jul 15 11:02:01 2008: P[ 1] set_channel: bc->channel:0 channel:1
Tue Jul 15 11:02:01 2008: P[ 1] I IND :NEW_CHANNEL oad:60132265634 dad: pid:3 state:none
Tue Jul 15 11:02:01 2008: P[ 1] --> channel:1 mode:TE cause:16 ocause:16 rad: cad:
Tue Jul 15 11:02:01 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:01 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:01 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:01 2008: P[ 1] --> addr:0 l3id:20002 b_stid:0 layer_id:50010180
Tue Jul 15 11:02:01 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:01 2008: P[ 1] --> bc_state:BCHAN_CLEANED
Tue Jul 15 11:02:01 2008: P[ 1] Chan not existing at the moment bc->l3id:20002 bc:0x8219b94 event:NEW_CHANNEL port:1 channel:1
Tue Jul 15 11:02:01 2008: P[ 1] NO USERUESRINFO
Tue Jul 15 11:02:01 2008: P[ 1] --> found chan (preselected): 1
Tue Jul 15 11:02:01 2008: P[ 1] set_chan_in_stack: 1
Tue Jul 15 11:02:01 2008: P[ 1] setup_bc: with dsp
Tue Jul 15 11:02:01 2008: P[ 1] --> Channel is 1
Tue Jul 15 11:02:01 2008: P[ 1] --> TRANSPARENT Mode
Tue Jul 15 11:02:06 2008: P[ 1] $$$ Set StackIND Err: -1 Success
Tue Jul 15 11:02:06 2008: P[ 1] I IND :BCHAN_ERROR oad:60132265634 dad: pid:3 state:none
Tue Jul 15 11:02:06 2008: P[ 1] --> channel:1 mode:TE cause:16 ocause:16 rad: cad:
Tue Jul 15 11:02:06 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:06 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:06 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:06 2008: P[ 1] --> addr:0 l3id:20002 b_stid:10010100 layer_id:50010180
Tue Jul 15 11:02:06 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:06 2008: P[ 1] --> bc_state:BCHAN_ERROR
Tue Jul 15 11:02:06 2008: P[ 1] Chan not existing at the moment bc->l3id:20002 bc:0x8219b94 event:BCHAN_ERROR port:1 channel:1
Tue Jul 15 11:02:06 2008: P[ 1] I IND :SETUP oad:60132265634 dad: pid:3 state:none
Tue Jul 15 11:02:06 2008: P[ 1] --> channel:1 mode:TE cause:16 ocause:16 rad: cad:
Tue Jul 15 11:02:06 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:06 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:06 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:06 2008: P[ 1] --> addr:0 l3id:20002 b_stid:10010100 layer_id:50010180
Tue Jul 15 11:02:06 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:06 2008: P[ 1] --> bc_state:BCHAN_ERROR
Tue Jul 15 11:02:06 2008: P[ 1] --> Bearer: Speech
Tue Jul 15 11:02:06 2008: P[ 1] --> Codec: Alaw
Tue Jul 15 11:02:06 2008: P[ 1] --> Bearer: Speech
Tue Jul 15 11:02:06 2008: P[ 1] --> Codec: Alaw
Tue Jul 15 11:02:06 2008: P[ 0] --> * NEW CHANNEL dad: oad:60132265634
Tue Jul 15 11:02:06 2008: P[ 1] read_config: Getting Config
Tue Jul 15 11:02:06 2008: P[ 1] --> CTON: Unknown
Tue Jul 15 11:02:06 2008: P[ 1] --> EXPORT_PID: pid:3
Tue Jul 15 11:02:06 2008: P[ 1] --> PRES: Restricted (0)
Tue Jul 15 11:02:06 2008: P[ 1] --> SCREEN: Unscreened (0)
Tue Jul 15 11:02:06 2008: P[ 1] * Queuing chan 0x81f4338
Tue Jul 15 11:02:06 2008: P[ 1] I SEND:SETUP_ACKNOWLEDGE oad:0060132265634 dad: pid:3
Tue Jul 15 11:02:06 2008: P[ 1] --> bc_state:BCHAN_ERROR
Tue Jul 15 11:02:06 2008: P[ 1] --> channel:1 mode:TE cause:16 ocause:16 rad: cad:
Tue Jul 15 11:02:06 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:06 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:06 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:06 2008: P[ 1] --> addr:0 l3id:20002 b_stid:10010100 layer_id:50010180
Tue Jul 15 11:02:06 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:06 2008: P[ 1] GOT SETUP OK
ue Jul 15 11:02:06 2008: P[ 0] Waking up misdn_tasks thread
Tue Jul 15 11:02:06 2008
P[ 1] BCHAN: MGR_DELLAYER|CNF pid:3
Tue Jul 15 11:02:06 2008: P[ 1] Sending msg, prim:30d80 addr:41000104 dinfo:20002
Tue Jul 15 11:02:06 2008: P[ 1] overlap dial task, chan_state: 1
Tue Jul 15 11:02:10 2008: P[ 1] overlap dial task, chan_state: 1
Tue Jul 15 11:02:10 2008: P[ 1] --> None
Tue Jul 15 11:02:10 2008: P[ 1] ph_control: c1:2310 c2:12
Tue Jul 15 11:02:10 2008: P[ 1] I SEND:DISCONNECT oad:0060132265634 dad: pid:0
Tue Jul 15 11:02:10 2008: P[ 0] PH_CONTROL|CONFIRM
Tue Jul 15 11:02:10 2008: P[ 1] --> bc_state:BCHAN_ERROR
Tue Jul 15 11:02:10 2008: P[ 1] --> channel:1 mode:TE cause:16 ocause:1 rad: cad:
Tue Jul 15 11:02:10 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:10 2008
P[ 0] DL_DATA|CONFIRM
Tue Jul 15 11:02:10 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:10 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:10 2008: P[ 1] --> addr:0 l3id:20002 b_stid:10010100 layer_id:50010180
Tue Jul 15 11:02:10 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:10 2008: P[ 1] Sending msg, prim:34580 addr:41000104 dinfo:20002
Tue Jul 15 11:02:10 2008: P[ 1] handle_frm: frm->addr:42000103 frm->prim:34d82
Tue Jul 15 11:02:10 2008: P[ 1] I IND :RELEASE oad:0060132265634 dad: pid:0 state:CLEANING
Tue Jul 15 11:02:10 2008: P[ 1] --> channel:1 mode:TE cause:1 ocause:1 rad: cad:
Tue Jul 15 11:02:10 2008: P[ 1] --> info_dad: onumplan:1 dnumplan: rnumplan: cpnnumplan:0
Tue Jul 15 11:02:10 2008: P[ 1] --> caps:Speech pi:0 keypad: sending_complete:0
Tue Jul 15 11:02:10 2008: P[ 1] --> screen:0 --> pres:0
Tue Jul 15 11:02:10 2008: P[ 1] --> addr:0 l3id:20002 b_stid:10010100 layer_id:50010180
Tue Jul 15 11:02:10 2008: P[ 1] --> facility:Fac_None out_facility:Fac_None
Tue Jul 15 11:02:10 2008: P[ 1] --> bc_state:BCHAN_ERROR
Tue Jul 15 11:02:10 2008: P[ 1] --> hangup
Tue Jul 15 11:02:10 2008: P[ 1] * IND : HANGUP pid:0 ctx:from-isdn dad: oad:0060132265634 State:CLEANING
Tue Jul 15 11:02:10 2008: P[ 1] --> l3id:20002
Tue Jul 15 11:02:10 2008: P[ 1] --> cause:1
Tue Jul 15 11:02:10 2008: P[ 1] --> out_cause:1
Tue Jul 15 11:02:10 2008: P[ 1] --> state:CLEANING
Tue Jul 15 11:02:10 2008: P[ 1] --> Channel: mISDN/1-u1 hanguped new state:CLEANING
Tue Jul 15 11:02:10 2008: P[ 1] handle_frm: frm->addr:42000103 frm->prim:3f182
Tue Jul 15 11:02:10 2008: P[ 1] --> lib: RELEASE_CR Ind with l3id:20002
Tue Jul 15 11:02:10 2008: P[ 1] --> lib: CLEANING UP l3id: 20002
Tue Jul 15 11:02:10 2008: P[ 1] $$$ CLEANUP CALLED pid:0
Tue Jul 15 11:02:10 2008: P[ 1] $$$ Cleaning up bc with stid :10010100 pid:0
Tue Jul 15 11:02:10 2008: P[ 1] --> ec_disable
Tue Jul 15 11:02:10 2008: P[ 1] Sending Control ECHOCAN_OFF
Tue Jul 15 11:02:10 2008: P[ 1] ph_control: c1:2319 c2:0
Tue Jul 15 11:02:10 2008: P[ 1] bchan_deactivate: called but not activated
Tue Jul 15 11:02:10 2008: P[ 1] empty_chan_in_stack: 1
Tue Jul 15 11:02:10 2008: P[ 0] PH_CONTROL|CONFIRM
Tue Jul 15 11:02:10 2008: P[ 0] handle_bchan: BC not found for prim:f2481 with addr:55010180 dinfo:0
Tue Jul 15 11:02:10 2008: P[ 0] MGMT: DELLAYER|CONFIRM Addr: 55010180 !
[/code:1]

hello,
please give us more details such like your confgs file from misdn and dialplan for that, and please check the misdn show stacks? make sure the connection is created successfully.
regards!
James.zhu

ok.. here is my misdn.conf

[code:1]

[general]
;
; Sets the Path to the misdn-init.conf (for nt_ptp mode checking)
;
misdn_init = /etc/misdn-init.conf

debug = 4
ntdebugflags = 0
ntdebugfile = /var/log/misdn-nt.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 = no
pmp_l1_check = no
reject_cause = 16
need_more_infos = no
nttimeout = no
method = standard
overlapdial = yes
dialplan = 0
localdialplan = 0
cpndialplan = 0
incoming_early_audio = no
nodialtone = no
presentation = -1
screen = -1
jitterbuffer = 4000
jitterbuffer_upper_threshold = 0
hdlc = no
max_incoming = -1
max_outgoing = -1

[outcall]
ports = 1
context = from-isdn

[isdn]
; define your ports, e.g. 1,2 (depends on mISDN-driver loading order)
ports = 1
msns = *

; context where to go to when incoming Call on one of the above ports
context = astctidemo





[/code:1]
my extensions.conf
[code:1]
[general]
static = yes
writeprotect = no
clearglobalvars = no

[globals]
CONSOLE = Console/dsp ; Console interface for demo

[from-isdn]
exten => s,1,Answer()
exten => s,2,NoOp("coming calls")
exten => s,3,Dial(SIP/201)

[astctidemo]
;
; We start with what to do when a call first comes in
;
exten => 100,1,Dial(mISDN/g:isdn/1/4000)
exten => 100,2,Hangup()

exten => _2xx,1,Dial(SIP/${EXTEN},20,r)

[/code:1]
[code:1]
buntu*CLI> misdn show stacks
BEGIN STACK_LIST:
* Port 1 Type TE Prot. PTP L2Link UP L1Link:UP Blocked:0 Debug:4

buntu*CLI>misdn show channels
Chan List: (nil)
[/code:1]

hello,
i think the connection should be no problem. please set the verbose higher to see the result from asterisk console and make sure the sip/201 is available. post console message here.
regards!
James.zhu

Hello James, thank you for your reply..I guess this is cause by mISDN driver, improperly loading..when i reboot, unload and load again mISDN driver. no problem, can receive call and call out. maybe conflicting with other driver..
I install on ubuntu 8.04 compile from source..but nevermind now i use trixbox, it much more easier. everything fine..

Thanks again James , you're very helpful...

hello,
i think you have to check the ISDN module from linux. that module is built-in with linux(centos5), sometime, it is conflict with mISDN. please check setup=>service, disable it.
regards!
James.zhu