Problem on elastix 1.6 with misdn and b400/b200p

Discussion in 'General' started by alfil2k, Mar 17, 2011.

  1. alfil2k

    Joined:
    Aug 17, 2009
    Messages:
    25
    Likes Received:
    0
    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:

    Code:
    [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

    Code:
    [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:

    Code:
    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.
     

Share This Page