Problem on misdn and b400/b200p

Hi guys,

We have a couple of asterisk box installed with Elastix 1.6.2-7 with misdn 1.1.9.1-0. We found an error, when the asterisk run the dynamic loader, this is the log:

[Mar 17 10:35:27] VERBOSE[13089] logger.c: -- mISDN/2-u3590 is proceeding passing it to SIP/108-000003ff
[Mar 17 10:35:34] VERBOSE[13089] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
[Mar 17 10:35:38] VERBOSE[13168] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log
[Mar 17 10:35:38] VERBOSE[13168] logger.c: Asterisk Dynamic Loader Starting:
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/modules.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/dnsmgr.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/http.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/codecs.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Ping
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Events
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Logoff
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Hangup
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Status
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Setvar
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Getvar
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action GetConfig
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action UpdateConfig
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Redirect
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Atxfer
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Originate
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action Command
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action ExtensionState
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action AbsoluteTimeout
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action MailboxStatus
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action MailboxCount
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action ListCommands
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action UserEvent
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Manager registered action WaitEvent
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/manager.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:38] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Mar 17 10:35:38] VERBOSE[13168] logger.c: Found

Continue loading... and we can see

[Mar 17 10:35:40] VERBOSE[13168] logger.c: == Parsing '/etc/asterisk/misdn.conf': [Mar 17 10:35:40] VERBOSE[13168] logger.c: Found
[Mar 17 10:35:40] WARNING[13168] misdn_config.c: misdn.conf: "l1watcher_timeout=0" (section: general) invalid or out of range. Please edit your misdn.conf and then do a "misdn reload".
[Mar 17 10:35:40] WARNING[13168] misdn_config.c: misdn.conf: "echotraining=no" (section: default) invalid or out of range. Please edit your misdn.conf and then do a "misdn reload".
[Mar 17 10:35:40] VERBOSE[13168] logger.c: == Registered channel type 'mISDN' (Channel driver for mISDN Support (Bri/Pri))
[Mar 17 10:35:40] VERBOSE[13168] logger.c: == Registered application 'misdn_set_opt'
[Mar 17 10:35:40] VERBOSE[13168] logger.c: == Registered application 'misdn_facility'
[Mar 17 10:35:40] VERBOSE[13168] logger.c: == Registered application 'misdn_check_l2l1'
[Mar 17 10:35:40] VERBOSE[13168] logger.c: chan_misdn.so => (Channel driver for mISDN Support (BRI/PRI))

Here, a post the misdn.log. At this time:

Thu Mar 17 10:35:40 2011: P[ 0] -- mISDN Channel Driver Registered --
Thu Mar 17 10:35:40 2011: P[ 2] MGMT: SSTATUS: L1_ACTIVATED
Thu Mar 17 10:35:40 2011: P[ 2] MGMT: SSTATUS: L2_ESTABLISH
Thu Mar 17 10:35:40 2011: P[ 4] MGMT: SSTATUS: L1_ACTIVATED
Thu Mar 17 10:35:40 2011: P[ 4] MGMT: SSTATUS: L2_ESTABLISH
Thu Mar 17 10:35:40 2011: P[ 3] MGMT: SSTATUS: L1_ACTIVATED
Thu Mar 17 10:35:40 2011: P[ 3] MGMT: SSTATUS: L2_ESTABLISH
Thu Mar 17 10:35:40 2011: P[ 1] MGMT: SSTATUS: L1_ACTIVATED
Thu Mar 17 10:35:40 2011: P[ 1] MGMT: SSTATUS: L2_ESTABLISH
Thu Mar 17 10:35:40 2011: P[ 5] MGMT: SSTATUS: L1_DEACTIVATED
Thu Mar 17 10:35:40 2011: P[ 5] MGMT: SSTATUS: L2_RELEASED
Thu Mar 17 10:35:40 2011: P[ 8] MGMT: SSTATUS: L1_DEACTIVATED
Thu Mar 17 10:35:40 2011: P[ 8] MGMT: SSTATUS: L2_RELEASED
Thu Mar 17 10:35:40 2011: P[ 6] MGMT: SSTATUS: L1_DEACTIVATED
Thu Mar 17 10:35:40 2011: P[ 6] MGMT: SSTATUS: L2_RELEASED
Thu Mar 17 10:35:40 2011: P[ 7] MGMT: SSTATUS: L1_DEACTIVATED
Thu Mar 17 10:35:40 2011: P[ 7] MGMT: SSTATUS: L2_RELEASED
Thu Mar 17 10:35:42 2011: P[ 2] --> Didn't find BC so temporarily creating dummy BC (l3id:1102ca) on this port.
Thu Mar 17 10:35:42 2011: P[ 2] --> found chan: 1
Thu Mar 17 10:35:42 2011: P[ 2] --> TRANSPARENT Mode
Thu Mar 17 10:35:42 2011: P[ 2] I IND :CONNECT oad: dad: pid:0 state:none
Thu Mar 17 10:35:42 2011: P[ 2] --> channel:1 mode:TE cause:0 ocause:0 rad: cad:
Thu Mar 17 10:35:42 2011: P[ 2] --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:
Thu Mar 17 10:35:42 2011: P[ 2] --> caps:Speech pi:0 keypad: sending_complete:0
Thu Mar 17 10:35:42 2011: P[ 2] Chan not existing at the moment bc->l3id:1102ca bc:0xb6e4a7e8 event:CONNECT port:2 channel:1
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:20181 with addr:52010201 dinfo:0
Thu Mar 17 10:35:42 2011: P[ 0] received 1k Unhandled Bchannel Messages: prim 20181 len 0 from addr 52010201, dinfo 0 on this port.
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff
Thu Mar 17 10:35:42 2011: P[ 0] handle_bchan: BC not found for prim:120282 with addr:52010201 dinfo:ffffffff

This error don't stop to appears on log and asterisk console, and the asterisk don't work. We have this issue randomly.

We suspect, that happens when the asterisk restart automatically and reload misdn driver when the people is calling.

Any suggestions? We can stop to asterisk to start reload automatically.

Hi,

Please try to enable these two parameters in file /etc/asterisk/misdn.conf

---

jitterbuffer=4000
jitterbuffer_upper_threshold=0

---

This is my misdn.conf

;
; chan_misdns jitterbuffer, default 4000
;
jitterbuffer=4000

;
; change this threshold to enable dejitter functionality
;
jitterbuffer_upper_threshold=0

The jitterbuffer is enable. Another idea? thanks,

Help somebody......


Someone have any ideas?

Thanks,
Pablo

Hi Pablo,

Please provide SSH info to me by email box below . Let me have a check for you.

Meahwhile, give invitation to my Google Talk: This email address is being protected from spambots. You need JavaScript enabled to view it. or Skype: This email address is being protected from spambots. You need JavaScript enabled to view it.

Anyway, I will let you know what I did when problem sovled.