Stabilität: chan_misdn benötigt regelmässig restart ports

[WolfgangA]

Hallo,

von Zeit zu zeit benötigt chan_misdn einen "misdn restart port 1" ... "misdn restart port 4", sonst kann nicht mehr heraus gewählt werden.

Der Effekt für den User ist:
Ca. 8 sec nichts und dann ein busy tone (asterisk congestion)

Normalerweise dauert es keine sec. um ein Frei- oder Besetztzeichen zu bekommen.

Hier die Protokolle: (Fehler - Restart der Ports - Danach)

Fehler:

Tue Apr 10 16:48:17 2007: P[ 0]   --> Group Call group: ISDNPBX
Tue Apr 10 16:48:17 2007: P[ 1]  Group [ISDNPBX] Port [1]
Tue Apr 10 16:48:17 2007: P[ 1]  portup:1
Tue Apr 10 16:48:17 2007: P[ 0]   --> * NEW CHANNEL dad:(null) oad:(null)
Tue Apr 10 16:48:17 2007: P[ 0]  misdn_new: no exten given.
Tue Apr 10 16:48:17 2007: P[ 1]  * Queuing chan 0xb2b9840
Tue Apr 10 16:48:17 2007: P[ 1]   --> TON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]   --> LTON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]   --> CTON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]  $$$ CLEANUP CALLED pid:4782
Tue Apr 10 16:48:17 2007: P[ 0]   --> * NEW CHANNEL dad:21 oad:(null)
Tue Apr 10 16:48:17 2007: P[ 1]  * Queuing chan 0xb2b9840
Tue Apr 10 16:48:17 2007: P[ 1]   --> TON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]   --> LTON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]   --> CTON: Unknown
Tue Apr 10 16:48:17 2007: P[ 1]  * CALL: 1/21
Tue Apr 10 16:48:17 2007: P[ 1]   --> * dad:21 tech:mISDN/1-u5361 ctx:trunk_39
Tue Apr 10 16:48:17 2007: P[ 1]   --> * adding2newbc ext 21
Tue Apr 10 16:48:17 2007: P[ 1]   --> * adding2newbc callerid 82
Tue Apr 10 16:48:17 2007: P[ 1]   --> pres: -1 screen: -1
Tue Apr 10 16:48:17 2007: P[ 1]   --> pres: 0
Tue Apr 10 16:48:17 2007: P[ 1]   --> PRES: Allowed (0x0)
Tue Apr 10 16:48:17 2007: P[ 1]   --> SCREEN: Unscreened (0x0)
Tue Apr 10 16:48:17 2007: P[ 1]  NO OPTS GIVEN
Tue Apr 10 16:48:17 2007: P[ 1]  I SEND:SETUP oad:82 dad:21 pid:4783
Tue Apr 10 16:48:17 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:17 2007: P[ 1]   --> channel:0 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:48:17 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> addr:0 l3id:803dff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:17 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:17 2007: P[ 1]   --> found chan: 1
Tue Apr 10 16:48:17 2007: P[ 1]  set_chan_in_stack: 1
Tue Apr 10 16:48:17 2007: P[ 1]   -->  found channel: 1
Tue Apr 10 16:48:17 2007: P[ 1]   --> new_l3id ff00
 P[ 1]   --> * SEND: State Dialing pid:4783
Tue Apr 10 16:48:17 2007: P[ 1]   --> lib: Event_ind:SETUP CONFIRM [NT] : new L3ID  is 803eff00
Tue Apr 10 16:48:17 2007: P[ 1]  I IND :CC_SETUP|CONFIRM: old l3id:ff00 new l3id:803eff00
Tue Apr 10 16:48:17 2007: P[ 1]  I IND :NEW_L3ID oad:82 dad:21 pid:4783 state:CALLING
Tue Apr 10 16:48:17 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:48:17 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> addr:0 l3id:803eff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:17 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:17 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:17 2007: P[ 1]  set_channel: bc->channel:1 channel:1
Tue Apr 10 16:48:17 2007: P[ 1]  We already have a channel (1)
Tue Apr 10 16:48:17 2007: P[ 1]  I IND :PROCEEDING oad:82 dad:21 pid:4783 state:CALLING
Tue Apr 10 16:48:17 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:48:17 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:17 2007: P[ 1]   --> addr:0 l3id:803eff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:17 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:17 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:18 2007: P[ 1]  I IND :ALERTING oad:82 dad:21 pid:4783 state:PROCEEDING
Tue Apr 10 16:48:18 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:48:18 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:48:18 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:18 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:18 2007: P[ 1]   --> addr:0 l3id:803eff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:18 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:18 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:18 2007: P[ 1]   --> We have no inband Data, the other end must create ringing
Tue Apr 10 16:48:20 2007: P[ 1]  I IND :CONNECT oad:82 dad:21 pid:4783 state:ALERTING
Tue Apr 10 16:48:20 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:21
Tue Apr 10 16:48:20 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:4
Tue Apr 10 16:48:20 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> addr:0 l3id:803eff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:20 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:20 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:20 2007: P[ 1]  I SEND:CONNECT_ACKNOWLEDGE  oad:82 dad:21 pid:4783
Tue Apr 10 16:48:20 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:20 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:21
Tue Apr 10 16:48:20 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:4
Tue Apr 10 16:48:20 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> addr:0 l3id:803eff00 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:20 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:20 2007: P[ 1]  setup_bc: with dsp
Tue Apr 10 16:48:20 2007: P[ 1]   --> Channel is 1
Tue Apr 10 16:48:20 2007: P[ 1]   --> TRANSPARENT Mode
Tue Apr 10 16:48:20 2007: P[ 1]  ph_control: c1:2100 c2:0
Tue Apr 10 16:48:20 2007: P[ 1]  ec_enable
Tue Apr 10 16:48:20 2007: P[ 1]  Sending Control ECHOCAN_ON taps:128 training:0
Tue Apr 10 16:48:20 2007: P[ 1]  Taps is 128
Tue Apr 10 16:48:20 2007: P[ 1]   --> None
Tue Apr 10 16:48:20 2007: P[ 1]  BCHAN: bchan ACT Confirm pid:4783
Tue Apr 10 16:48:20 2007: P[ 1]   --> Removing Process Id:0 on this port.
Tue Apr 10 16:48:20 2007: P[ 1]  lib: Event_ind:CC_NEW_CR : very new L3ID  is 803e0000
Tue Apr 10 16:48:20 2007: P[ 1]  I IND :NEW_L3ID oad:82 dad:21 pid:4783 state:CONNECTED
Tue Apr 10 16:48:20 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:21
Tue Apr 10 16:48:20 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:4
Tue Apr 10 16:48:20 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:20 2007: P[ 1]   --> addr:50010102 l3id:803e0000 b_stid:10010100 layer_id:50010180
Tue Apr 10 16:48:20 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:20 2007: P[ 1]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:48:20 2007: P[ 1]  * IND: Got Fixup State:CONNECTED L3id:803e0000
Tue Apr 10 16:48:53 2007: P[ 1]  I IND :DISCONNECT oad:82 dad:21 pid:4783 state:CONNECTED
Tue Apr 10 16:48:53 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:21
Tue Apr 10 16:48:53 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:4
Tue Apr 10 16:48:53 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> addr:50010102 l3id:803e0000 b_stid:10010100 layer_id:50010180
Tue Apr 10 16:48:53 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:53 2007: P[ 1]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:48:53 2007: P[ 1]  $$$ find_holded: --> holded:0 channel:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> org:1 nt:1, inbandavail:0 state:10
Tue Apr 10 16:48:53 2007: P[ 1]   --> queue_hangup
Tue Apr 10 16:48:53 2007: P[ 1]  I SEND:RELEASE oad:82 dad:21 pid:4783
Tue Apr 10 16:48:53 2007: P[ 1]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:48:53 2007: P[ 1]   --> channel:1 mode:NT cause:16 ocause:-1 rad: cad:21
Tue Apr 10 16:48:53 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:4
Tue Apr 10 16:48:53 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> addr:50010102 l3id:803e0000 b_stid:10010100 layer_id:50010180
Tue Apr 10 16:48:53 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:53 2007: P[ 1]  * IND : HANGUP	pid:4783 ctx:DefaultOutgoingRule dad:s oad:21 State:CONNECTED
Tue Apr 10 16:48:53 2007: P[ 1]   --> l3id:803e0000
Tue Apr 10 16:48:53 2007: P[ 1]   --> cause:16
Tue Apr 10 16:48:53 2007: P[ 1]   --> out_cause:16
Tue Apr 10 16:48:53 2007: P[ 1]   --> state:CONNECTED
Tue Apr 10 16:48:53 2007: P[ 1]  ph_control: c1:2310 c2:12
Tue Apr 10 16:48:53 2007: P[ 1]   --> Channel: mISDN/1-u5361 hanguped new state:CLEANING
Tue Apr 10 16:48:53 2007: P[ 1]  empty_chan_in_stack: 1
Tue Apr 10 16:48:53 2007: P[ 1]  $$$ CLEANUP CALLED pid:4783
Tue Apr 10 16:48:53 2007: P[ 1]  $$$ Cleaning up bc with stid :10010100 pid:4783
Tue Apr 10 16:48:53 2007: P[ 1]   --> ec_disable
Tue Apr 10 16:48:53 2007: P[ 1]  Sending Control ECHOCAN_OFF
Tue Apr 10 16:48:53 2007: P[ 1]  ph_control: c1:2319 c2:0
Tue Apr 10 16:48:53 2007: P[ 1]  I IND :RELEASE_COMPLETE oad: dad: pid:4783 state:CLEANING
Tue Apr 10 16:48:53 2007: P[ 1]   --> channel:0 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:48:53 2007: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> screen:0 --> pres:0
Tue Apr 10 16:48:53 2007: P[ 1]   --> addr:50010102 l3id:803e0000 b_stid:0 layer_id:50010180
Tue Apr 10 16:48:53 2007: P[ 1]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:48:53 2007: P[ 1]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:48:53 2007: P[ 1]  ast_hangup already called, so we have no ast ptr anymore in event(RELEASE_COMPLETE)
Tue Apr 10 16:48:53 2007: P[ 1]   --> No need to queue hangup
Tue Apr 10 16:48:53 2007: P[ 1]  Cannot hangup chan, no ast
Tue Apr 10 16:48:53 2007: P[ 1]   --> Faking Realease_cr for 41000101
Tue Apr 10 16:48:53 2007: P[ 1]   --> lib: RELEASE_CR Ind with l3id:803e0000
Tue Apr 10 16:48:53 2007: P[ 1]   --> lib: CLEANING UP l3id: 803e0000
Tue Apr 10 16:48:53 2007: P[ 1]  $$$ CLEANUP CALLED pid:4783
Tue Apr 10 16:48:53 2007: P[ 1]  BCHAN: DeACT Conf pid:4783
Tue Apr 10 16:48:53 2007: P[ 1]  BCHAN: MGR_DELLAYER|CNF pid:4783

Tue Apr 10 16:50:04 2007: P[ 4]  set_channel: bc->channel:0 channel:255
Tue Apr 10 16:50:04 2007: P[ 4]   --> found chan: 1
Tue Apr 10 16:50:04 2007: P[ 4]  set_chan_in_stack: 1
Tue Apr 10 16:50:04 2007: P[ 4]  I IND :SETUP oad:928 dad:1529 pid:4784 state:none
Tue Apr 10 16:50:04 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:04 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:04 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:04 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:04 2007: P[ 4]   --> Bearer: Speech
Tue Apr 10 16:50:04 2007: P[ 4]   --> Codec: Alaw
Tue Apr 10 16:50:04 2007: P[ 0]   --> * NEW CHANNEL dad:1529 oad:928
Tue Apr 10 16:50:04 2007: P[ 4]   --> CTON: Unknown
Tue Apr 10 16:50:04 2007: P[ 4]   --> EXPORT_PID: pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> PRES: Restricted (0)
Tue Apr 10 16:50:04 2007: P[ 4]   --> SCREEN: Unscreened (0)
Tue Apr 10 16:50:04 2007: P[ 4]  * Queuing chan 0xb3ea7c28
Tue Apr 10 16:50:04 2007: P[ 4]  I SEND:SETUP_ACKNOWLEDGE oad:928 dad:1529 pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:04 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:04 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:04 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:04 2007: P[ 4]  setup_bc: with dsp
Tue Apr 10 16:50:04 2007: P[ 4]   --> Channel is 1
Tue Apr 10 16:50:04 2007: P[ 4]   --> TRANSPARENT Mode
Tue Apr 10 16:50:04 2007: P[ 4]  BCHAN: bchan ACT Confirm pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> * IND :	proceeding pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]  I SEND:PROCEEDING oad:928 dad:1529 pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:04 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:04 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:04 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:04 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:04 2007: P[ 4]  * IND :	ringing pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> * IND :	ringing pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]  I SEND:ALERTING oad:928 dad:1529 pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:04 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:04 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:04 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:04 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:04 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:04 2007: P[ 4]   --> * SEND: State Ring pid:4784
Tue Apr 10 16:50:04 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:50:07 2007: P[ 4]   --> * IND :	-1! (stop indication) pid:4784
Tue Apr 10 16:50:07 2007: P[ 4]   --> None
Tue Apr 10 16:50:07 2007: P[ 4]  * ANSWER:
Tue Apr 10 16:50:07 2007: P[ 4]   --> Connection is without BF encryption
Tue Apr 10 16:50:07 2007: P[ 4]   --> None
Tue Apr 10 16:50:07 2007: P[ 4]   --> empty cad using dad
Tue Apr 10 16:50:07 2007: P[ 4]  I SEND:CONNECT oad:928 dad:1529 pid:4784
Tue Apr 10 16:50:07 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:07 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:1529
Tue Apr 10 16:50:07 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:07 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:07 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:07 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:07 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:07 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:07 2007: P[ 4]  ph_control: c1:2100 c2:0
Tue Apr 10 16:50:07 2007: P[ 4]  ec_enable
Tue Apr 10 16:50:07 2007: P[ 4]  Sending Control ECHOCAN_ON taps:128 training:0
Tue Apr 10 16:50:07 2007: P[ 4]  Taps is 128
Tue Apr 10 16:50:12 2007: P[ 4]  empty_chan_in_stack: 1
Tue Apr 10 16:50:12 2007: P[ 4]  $$$ CLEANUP CALLED pid:4784
Tue Apr 10 16:50:12 2007: P[ 4]  $$$ Cleaning up bc with stid :10010400 pid:4784
Tue Apr 10 16:50:12 2007: P[ 4]   --> ec_disable
Tue Apr 10 16:50:12 2007: P[ 4]  Sending Control ECHOCAN_OFF
Tue Apr 10 16:50:12 2007: P[ 4]  ph_control: c1:2319 c2:0
Tue Apr 10 16:50:12 2007: P[ 4]  I IND :RELEASE_COMPLETE oad: dad: pid:4784 state:CONNECTED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:0 mode:NT cause:102 ocause:16 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:12 2007: P[ 4]   --> queue_hangup
Tue Apr 10 16:50:12 2007: P[ 4]  * RELEASING CHANNEL pid:4784 ctx:macro-did dad:s oad:928 state: CONNECTED
Tue Apr 10 16:50:12 2007: P[ 4]   --> * State Down
Tue Apr 10 16:50:12 2007: P[ 4]   --> Setting AST State to down
Tue Apr 10 16:50:12 2007: P[ 4]   --> Faking Realease_cr for 41000401
Tue Apr 10 16:50:12 2007: P[ 4]   --> lib: RELEASE_CR Ind with l3id:10000
Tue Apr 10 16:50:12 2007: P[ 4]   --> lib: CLEANING UP l3id: 10000
Tue Apr 10 16:50:12 2007: P[ 4]  $$$ CLEANUP CALLED pid:4784
Tue Apr 10 16:50:12 2007: P[ 4]  BCHAN: DeACT Conf pid:4784
Tue Apr 10 16:50:12 2007: P[ 4]  BCHAN: MGR_DELLAYER|CNF pid:4784
Tue Apr 10 16:50:12 2007: P[ 4]  set_channel: bc->channel:0 channel:255
Tue Apr 10 16:50:12 2007: P[ 4]   --> found chan: 1
Tue Apr 10 16:50:12 2007: P[ 4]  set_chan_in_stack: 1
Tue Apr 10 16:50:12 2007: P[ 4]  I IND :SETUP oad:928 dad:1529 pid:4785 state:none
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:12 2007: P[ 4]   --> Bearer: Speech
Tue Apr 10 16:50:12 2007: P[ 4]   --> Codec: Alaw
Tue Apr 10 16:50:12 2007: P[ 0]   --> * NEW CHANNEL dad:1529 oad:928
Tue Apr 10 16:50:12 2007: P[ 4]   --> CTON: Unknown
Tue Apr 10 16:50:12 2007: P[ 4]   --> EXPORT_PID: pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> PRES: Restricted (0)
Tue Apr 10 16:50:12 2007: P[ 4]   --> SCREEN: Unscreened (0)
Tue Apr 10 16:50:12 2007: P[ 4]  * Queuing chan 0xb3ea7c28
Tue Apr 10 16:50:12 2007: P[ 4]  I SEND:SETUP_ACKNOWLEDGE oad:928 dad:1529 pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]  setup_bc: with dsp
Tue Apr 10 16:50:12 2007: P[ 4]   --> Channel is 1
Tue Apr 10 16:50:12 2007: P[ 4]   --> TRANSPARENT Mode
Tue Apr 10 16:50:12 2007: P[ 4]  BCHAN: bchan ACT Confirm pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> * IND :	proceeding pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]  I SEND:PROCEEDING oad:928 dad:1529 pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:12 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:50:12 2007: P[ 4]  * IND :	busy pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]  I SEND:DISCONNECT oad:928 dad:1529 pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:17 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:50:20 2007: P[ 4]  empty_chan_in_stack: 1
Tue Apr 10 16:50:20 2007: P[ 4]  $$$ CLEANUP CALLED pid:4785
Tue Apr 10 16:50:20 2007: P[ 4]  $$$ Cleaning up bc with stid :10010400 pid:4785
Tue Apr 10 16:50:20 2007: P[ 4]   --> ec_disable
Tue Apr 10 16:50:20 2007: P[ 4]  Sending Control ECHOCAN_OFF
Tue Apr 10 16:50:20 2007: P[ 4]  ph_control: c1:2319 c2:0
Tue Apr 10 16:50:20 2007: P[ 4]  I IND :RELEASE_COMPLETE oad: dad: pid:4785 state:DIALING
Tue Apr 10 16:50:20 2007: P[ 4]   --> channel:0 mode:NT cause:102 ocause:16 rad: cad:
Tue Apr 10 16:50:20 2007: P[ 4]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:50:20 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:20 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:20 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:50:20 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:20 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:20 2007: P[ 4]   --> queue_hangup
Tue Apr 10 16:50:20 2007: P[ 4]  * RELEASING CHANNEL pid:4785 ctx:trunk_39 dad:1529 oad:928 state: DIALING
Tue Apr 10 16:50:20 2007: P[ 4]   --> * State Down
Tue Apr 10 16:50:20 2007: P[ 4]   --> Setting AST State to down
Tue Apr 10 16:50:20 2007: P[ 4]   --> Faking Realease_cr for 41000401
Tue Apr 10 16:50:20 2007: P[ 4]   --> lib: RELEASE_CR Ind with l3id:10000
Tue Apr 10 16:50:20 2007: P[ 4]   --> lib: CLEANING UP l3id: 10000
Tue Apr 10 16:50:20 2007: P[ 4]  $$$ CLEANUP CALLED pid:4785
Tue Apr 10 16:50:20 2007: P[ 4]  BCHAN: MGR_DELLAYER|CNF pid:4785
Tue Apr 10 16:50:20 2007: P[ 3]  set_channel: bc->channel:0 channel:255
Tue Apr 10 16:50:20 2007: P[ 3]   --> found chan: 1
Tue Apr 10 16:50:20 2007: P[ 3]  set_chan_in_stack: 1
Tue Apr 10 16:50:20 2007: P[ 3]  I IND :SETUP oad:928 dad:1529 pid:4786 state:none
Tue Apr 10 16:50:20 2007: P[ 3]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:20 2007: P[ 3]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010380
Tue Apr 10 16:50:20 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:20 2007: P[ 3]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:20 2007: P[ 3]   --> Bearer: Speech
Tue Apr 10 16:50:20 2007: P[ 3]   --> Codec: Alaw
Tue Apr 10 16:50:20 2007: P[ 0]   --> * NEW CHANNEL dad:1529 oad:928
Tue Apr 10 16:50:20 2007: P[ 3]   --> CTON: Unknown
Tue Apr 10 16:50:20 2007: P[ 3]   --> EXPORT_PID: pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]   --> PRES: Restricted (0)
Tue Apr 10 16:50:20 2007: P[ 3]   --> SCREEN: Unscreened (0)
Tue Apr 10 16:50:20 2007: P[ 3]  * Queuing chan 0xb3ea7c28
Tue Apr 10 16:50:20 2007: P[ 3]  I SEND:SETUP_ACKNOWLEDGE oad:928 dad:1529 pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:20 2007: P[ 3]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:20 2007: P[ 3]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010380
Tue Apr 10 16:50:20 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:20 2007: P[ 3]  setup_bc: with dsp
Tue Apr 10 16:50:20 2007: P[ 3]   --> Channel is 1
Tue Apr 10 16:50:20 2007: P[ 3]   --> TRANSPARENT Mode
Tue Apr 10 16:50:20 2007: P[ 3]  BCHAN: bchan ACT Confirm pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]   --> * IND :	proceeding pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]  I SEND:PROCEEDING oad:928 dad:1529 pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:20 2007: P[ 3]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:20 2007: P[ 3]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> addr:50010302 l3id:10000 b_stid:10010300 layer_id:50010380
Tue Apr 10 16:50:20 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:20 2007: P[ 3]  $$$ bc already upsetted stid :10010300 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:20 2007: P[ 3]  misdn_write: zero write
Tue Apr 10 16:50:20 2007: P[ 3]  * IND :	busy pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]  I SEND:DISCONNECT oad:928 dad:1529 pid:4786
Tue Apr 10 16:50:20 2007: P[ 3]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:20 2007: P[ 3]   --> channel:1 mode:NT cause:16 ocause:17 rad: cad:
Tue Apr 10 16:50:20 2007: P[ 3]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:20 2007: P[ 3]   --> addr:50010302 l3id:10000 b_stid:10010300 layer_id:50010380
Tue Apr 10 16:50:20 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:20 2007: P[ 3]  misdn_write: zero write
Tue Apr 10 16:50:23 2007: P[ 3]  empty_chan_in_stack: 1
Tue Apr 10 16:50:23 2007: P[ 3]  $$$ CLEANUP CALLED pid:4786
Tue Apr 10 16:50:23 2007: P[ 3]  $$$ Cleaning up bc with stid :10010300 pid:4786
Tue Apr 10 16:50:23 2007: P[ 3]   --> ec_disable
Tue Apr 10 16:50:23 2007: P[ 3]  Sending Control ECHOCAN_OFF
Tue Apr 10 16:50:23 2007: P[ 3]  ph_control: c1:2319 c2:0
Tue Apr 10 16:50:23 2007: P[ 3]  I IND :RELEASE oad: dad: pid:4786 state:DIALING
Tue Apr 10 16:50:23 2007: P[ 3]   --> channel:0 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:23 2007: P[ 3]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> addr:50010302 l3id:10000 b_stid:0 layer_id:50010380
Tue Apr 10 16:50:23 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:23 2007: P[ 3]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:23 2007: P[ 3]   --> queue_hangup
Tue Apr 10 16:50:23 2007: P[ 3]  * RELEASING CHANNEL pid:4786 ctx:trunk_39 dad:1529 oad:928 state: DIALING
Tue Apr 10 16:50:23 2007: P[ 3]   --> * State Down
Tue Apr 10 16:50:23 2007: P[ 3]   --> Setting AST State to down
Tue Apr 10 16:50:23 2007: P[ 3]  I SEND:RELEASE_COMPLETE oad: dad: pid:4786
Tue Apr 10 16:50:23 2007: P[ 3]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:50:23 2007: P[ 3]   --> channel:0 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:23 2007: P[ 3]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> screen:0 --> pres:0
Tue Apr 10 16:50:23 2007: P[ 3]   --> addr:50010302 l3id:10000 b_stid:0 layer_id:50010380
Tue Apr 10 16:50:23 2007: P[ 3]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:23 2007: P[ 3]  BCHAN: DeACT Conf pid:4786
Tue Apr 10 16:50:23 2007: P[ 3]   --> Faking Realease_cr for 41000301
Tue Apr 10 16:50:23 2007: P[ 3]  BCHAN: MGR_DELLAYER|CNF pid:4786
Tue Apr 10 16:50:23 2007: P[ 3]   --> lib: RELEASE_CR Ind with l3id:10000
Tue Apr 10 16:50:23 2007: P[ 3]   --> lib: CLEANING UP l3id: 10000
Tue Apr 10 16:50:23 2007: P[ 3]  $$$ CLEANUP CALLED pid:0

Ich glaube, dass Problem liegt hier:
"Tue Apr 10 16:50:12 2007: P[ 4] * IND : busy pid:4785"
Der Teilnehmer ist definitiv nicht busy.

Tue Apr 10 16:50:12 2007: P[ 4]  I SEND:PROCEEDING oad:928 dad:1529 pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:50:12 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:50:12 2007: P[ 4]  * IND :	busy pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]  I SEND:DISCONNECT oad:928 dad:1529 pid:4785
Tue Apr 10 16:50:12 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:50:12 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:17 rad: cad:
Tue Apr 10 16:50:12 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:50:12 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:50:12 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:50:12 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:50:20 2007: P[ 4]  empty_chan_in_stack: 1
Tue Apr 10 16:50:20 2007: P[ 4]  $$$ CLEANUP CALLED pid:4785

 

[WolfgangA]

Restart der Ports:

Tue Apr 10 16:50:52 2007: P[ 1]  Restarting this port.
Tue Apr 10 16:50:52 2007: P[ 1]  Stack:0x8faa748
Tue Apr 10 16:50:52 2007: P[ 1]  empty_chan_in_stack: 1
Tue Apr 10 16:50:52 2007: P[ 1]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:52 2007: P[ 1]  empty_chan_in_stack: 2
Tue Apr 10 16:50:52 2007: P[ 1]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:52 2007: P[ 1]  *HOLDER: find 8046ff01
Tue Apr 10 16:50:52 2007: P[ 1]  *HOLDER: find nothing
Tue Apr 10 16:50:52 2007: P[ 1]   --> Faking Realease_cr for 41000101
Tue Apr 10 16:50:52 2007: P[ 1]   --> Didn't found BC so temporarly creating dummy BC (l3id:8046ff01) on this port.
Tue Apr 10 16:50:52 2007: P[ 1]   --> Removing Process Id:1 on this port.
Tue Apr 10 16:50:52 2007: P[ 1]   --> lib: RELEASE_CR Ind with l3id:8046ff01
Tue Apr 10 16:50:52 2007: P[ 1]  *HOLDER: find 8046ff01
Tue Apr 10 16:50:52 2007: P[ 1]  *HOLDER: find nothing
Tue Apr 10 16:50:52 2007: P[ 1]   --> Didn't found BC so temporarly creating dummy BC (l3id:8046ff01) on this port.
Tue Apr 10 16:50:52 2007: P[ 1]   --> lib: CLEANING UP l3id: 8046ff01
Tue Apr 10 16:50:52 2007: P[ 1]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:52 2007: P[ 1]  L1: PH L1Link Up!
Tue Apr 10 16:50:52 2007: P[ 1]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:52 2007: P[ 1]  L1: PH L1Link Up!
Tue Apr 10 16:50:52 2007: P[ 1]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:52 2007: P[ 1]  % GOT L2 Activate Info.
Tue Apr 10 16:50:54 2007: P[ 2]  Restarting this port.
Tue Apr 10 16:50:54 2007: P[ 2]  Stack:0x8fb8348
Tue Apr 10 16:50:54 2007: P[ 2]  empty_chan_in_stack: 1
Tue Apr 10 16:50:54 2007: P[ 2]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:54 2007: P[ 2]  empty_chan_in_stack: 2
Tue Apr 10 16:50:54 2007: P[ 2]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:54 2007: P[ 2]  L1: PH L1Link Up!
Tue Apr 10 16:50:54 2007: P[ 2]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:54 2007: P[ 2]  L1: PH L1Link Up!
Tue Apr 10 16:50:54 2007: P[ 2]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:54 2007: P[ 2]  % GOT L2 Activate Info.
Tue Apr 10 16:50:55 2007: P[ 3]  Restarting this port.
Tue Apr 10 16:50:55 2007: P[ 3]  Stack:0x8fc5658
Tue Apr 10 16:50:55 2007: P[ 3]  empty_chan_in_stack: 1
Tue Apr 10 16:50:55 2007: P[ 3]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:55 2007: P[ 3]  empty_chan_in_stack: 2
Tue Apr 10 16:50:55 2007: P[ 3]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:55 2007: P[ 3]  L1: PH L1Link Up!
Tue Apr 10 16:50:55 2007: P[ 3]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:55 2007: P[ 3]  % GOT L2 Activate Info.
Tue Apr 10 16:50:57 2007: P[ 4]  Restarting this port.
Tue Apr 10 16:50:57 2007: P[ 4]  Stack:0x8fd2a30
Tue Apr 10 16:50:57 2007: P[ 4]  empty_chan_in_stack: 1
Tue Apr 10 16:50:57 2007: P[ 4]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:57 2007: P[ 4]  empty_chan_in_stack: 2
Tue Apr 10 16:50:57 2007: P[ 4]  $$$ CLEANUP CALLED pid:0
Tue Apr 10 16:50:57 2007: P[ 4]  L1: PH L1Link Up!
Tue Apr 10 16:50:57 2007: P[ 4]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:57 2007: P[ 4]  L1: PH L1Link Up!
Tue Apr 10 16:50:57 2007: P[ 4]  MGMT: SSTATUS: L1_ACTIVATED 
Tue Apr 10 16:50:57 2007: P[ 4]  % GOT L2 Activate Info.

 

[WolfgangA]

Nach Restart der Ports - Jetzt gehts wieder:

ue Apr 10 16:51:18 2007: P[ 4]  set_channel: bc->channel:0 channel:255
Tue Apr 10 16:51:18 2007: P[ 4]   --> found chan: 1
Tue Apr 10 16:51:18 2007: P[ 4]  set_chan_in_stack: 1
Tue Apr 10 16:51:18 2007: P[ 4]  I IND :SETUP oad:928 dad:1529 pid:4787 state:none
Tue Apr 10 16:51:18 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:51:18 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:51:18 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:18 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:51:18 2007: P[ 4]   --> Bearer: Speech
Tue Apr 10 16:51:18 2007: P[ 4]   --> Codec: Alaw
Tue Apr 10 16:51:18 2007: P[ 0]   --> * NEW CHANNEL dad:1529 oad:928
Tue Apr 10 16:51:18 2007: P[ 4]   --> CTON: Unknown
Tue Apr 10 16:51:18 2007: P[ 4]   --> EXPORT_PID: pid:4787
Tue Apr 10 16:51:18 2007: P[ 4]   --> PRES: Restricted (0)
Tue Apr 10 16:51:18 2007: P[ 4]   --> SCREEN: Unscreened (0)
Tue Apr 10 16:51:18 2007: P[ 4]  * Queuing chan 0xb40dbca8
Tue Apr 10 16:51:18 2007: P[ 4]  I SEND:SETUP_ACKNOWLEDGE oad:928 dad:1529 pid:4787
Tue Apr 10 16:51:18 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:51:18 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:51:18 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:18 2007: P[ 4]   --> addr:0 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:51:18 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:18 2007: P[ 4]  setup_bc: with dsp
Tue Apr 10 16:51:18 2007: P[ 4]   --> Channel is 1
Tue Apr 10 16:51:18 2007: P[ 4]   --> TRANSPARENT Mode
Tue Apr 10 16:51:18 2007: P[ 4]  BCHAN: bchan ACT Confirm pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]   --> * IND :	proceeding pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]  I SEND:PROCEEDING oad:928 dad:1529 pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:51:19 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:51:19 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:51:19 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:19 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:51:19 2007: P[ 4]  * IND :	ringing pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]   --> * IND :	ringing pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]  I SEND:ALERTING oad:928 dad:1529 pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:51:19 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:51:19 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:19 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:51:19 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:19 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
 P[ 4]   --> * SEND: State Ring pid:4787
Tue Apr 10 16:51:19 2007: P[ 4]  misdn_write: zero write
Tue Apr 10 16:51:22 2007: P[ 4]   --> * IND :	-1! (stop indication) pid:4787
Tue Apr 10 16:51:22 2007: P[ 4]   --> None
Tue Apr 10 16:51:22 2007: P[ 4]  * ANSWER:
Tue Apr 10 16:51:22 2007: P[ 4]   --> Connection is without BF encryption
Tue Apr 10 16:51:22 2007: P[ 4]   --> None
Tue Apr 10 16:51:22 2007: P[ 4]   --> empty cad using dad
Tue Apr 10 16:51:22 2007: P[ 4]  I SEND:CONNECT oad:928 dad:1529 pid:4787
Tue Apr 10 16:51:22 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:51:22 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:1529
Tue Apr 10 16:51:22 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:22 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:22 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:22 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:51:22 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:22 2007: P[ 4]  $$$ bc already upsetted stid :10010400 (state:BCHAN_ACTIVATED)
Tue Apr 10 16:51:22 2007: P[ 4]  ph_control: c1:2100 c2:0
Tue Apr 10 16:51:22 2007: P[ 4]  ec_enable
Tue Apr 10 16:51:22 2007: P[ 4]  Sending Control ECHOCAN_ON taps:128 training:0
Tue Apr 10 16:51:22 2007: P[ 4]  Taps is 128
Tue Apr 10 16:51:24 2007: P[ 4]  I IND :DISCONNECT oad:928 dad:1529 pid:4787 state:CONNECTED
Tue Apr 10 16:51:24 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:16 rad: cad:1529
Tue Apr 10 16:51:24 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:51:24 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:24 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:51:24 2007: P[ 4]  $$$ find_holded: --> holded:0 channel:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> org:2 nt:1, inbandavail:0 state:10
Tue Apr 10 16:51:24 2007: P[ 4]   --> queue_hangup
Tue Apr 10 16:51:24 2007: P[ 4]  I SEND:RELEASE oad:928 dad:1529 pid:4787
Tue Apr 10 16:51:24 2007: P[ 4]   --> bc_state:BCHAN_ACTIVATED
Tue Apr 10 16:51:24 2007: P[ 4]   --> channel:1 mode:NT cause:16 ocause:-1 rad: cad:1529
Tue Apr 10 16:51:24 2007: P[ 4]   --> info_dad: onumplan:4 dnumplan:0 rnumplan:  cpnnumplan:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:10010400 layer_id:50010480
Tue Apr 10 16:51:24 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:24 2007: P[ 4]  * IND : HANGUP	pid:4787 ctx:macro-did dad:s oad:928 State:CONNECTED
Tue Apr 10 16:51:24 2007: P[ 4]   --> l3id:10000
Tue Apr 10 16:51:24 2007: P[ 4]   --> cause:16
Tue Apr 10 16:51:24 2007: P[ 4]   --> out_cause:16
Tue Apr 10 16:51:24 2007: P[ 4]   --> state:CONNECTED
Tue Apr 10 16:51:24 2007: P[ 4]  ph_control: c1:2310 c2:12
Tue Apr 10 16:51:24 2007: P[ 4]   --> Channel: mISDN/4-1 hanguped new state:CLEANING
Tue Apr 10 16:51:24 2007: P[ 4]  empty_chan_in_stack: 1
Tue Apr 10 16:51:24 2007: P[ 4]  $$$ CLEANUP CALLED pid:4787
Tue Apr 10 16:51:24 2007: P[ 4]  $$$ Cleaning up bc with stid :10010400 pid:4787
Tue Apr 10 16:51:24 2007: P[ 4]   --> ec_disable
Tue Apr 10 16:51:24 2007: P[ 4]  Sending Control ECHOCAN_OFF
Tue Apr 10 16:51:24 2007: P[ 4]  ph_control: c1:2319 c2:0
Tue Apr 10 16:51:24 2007: P[ 4]  I IND :RELEASE_COMPLETE oad: dad: pid:4787 state:CLEANING
Tue Apr 10 16:51:24 2007: P[ 4]   --> channel:0 mode:NT cause:16 ocause:16 rad: cad:
Tue Apr 10 16:51:24 2007: P[ 4]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> caps:Speech pi:0 keypad: sending_complete:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> screen:0 --> pres:0
Tue Apr 10 16:51:24 2007: P[ 4]   --> addr:50010402 l3id:10000 b_stid:0 layer_id:50010480
Tue Apr 10 16:51:24 2007: P[ 4]   --> facility:FAC_NONE out_facility:FAC_NONE
Tue Apr 10 16:51:24 2007: P[ 4]   --> bc_state:BCHAN_CLEANED
Tue Apr 10 16:51:24 2007: P[ 4]  ast_hangup already called, so we have no ast ptr anymore in event(RELEASE_COMPLETE)
Tue Apr 10 16:51:24 2007: P[ 4]   --> No need to queue hangup
Tue Apr 10 16:51:24 2007: P[ 4]  Cannot hangup chan, no ast
Tue Apr 10 16:51:24 2007: P[ 4]   --> Faking Realease_cr for 41000401
Tue Apr 10 16:51:24 2007: P[ 4]   --> lib: RELEASE_CR Ind with l3id:10000
Tue Apr 10 16:51:24 2007: P[ 4]   --> lib: CLEANING UP l3id: 10000
Tue Apr 10 16:51:24 2007: P[ 4]  $$$ CLEANUP CALLED pid:4787
Tue Apr 10 16:51:24 2007: P[ 4]  BCHAN: DeACT Conf pid:4787
Tue Apr 10 16:51:24 2007: P[ 4]  BCHAN: MGR_DELLAYER|CNF pid:4787

 

[WolfgangA]

Hat vllt. jemand dazu eine Idee?

 

[pluto70]

pmp_l1_check=no in der misdn.conf schon probiert?

 

[WolfgangA]

Nein, da misdn show stacks im Fehlerfall L1=Up zeigt.
Jedoch habe ich die option für Layer 2 aktiviert.

 

[sterkel]

Probier doch mal eine aktuellere chan_misdn-Version aus:
http://www.beronet.com/downloads/

 

[onkel_fritz]

von Zeit zu zeit benötigt chan_misdn einen "misdn restart port 1" ... "misdn restart port 4", sonst kann nicht mehr heraus gewählt werden.

ja das kenne ich auch; habe aber immer einen komplette
entfernung der kernel-module durchführen
müssen, um das port wieder zum laufen zu bewegen
(=> auch asterisk-restart war zuwenig)

das war (oder besser ist) auf einem NT-mode port

wie ist das bei dir (NT oder TE)?

 

[WolfgangA]

Bei mir ist auch ein NT Port (bzw. 4 NT Ports)

Präventiv "zwischendurch" einen Restart durchzuführen macht IMHO keinen Sinn, da ich bisher nicht feststellen konnte weshalb der Fehler auftritt.
Es gibt auch keine "Messmöglichkeit", ausser dass man feststellt das mISDN Ports frei sind, aber nicht benutzt werden.

Daher überlege ich mittlerweile ein Makro im Dialplan zu hinterlegen, welches bei "chanunavil" prüft, ob gerade keine Gespräche aktiv sind und dann einen Restart von Asterisk und den Treibern initiert.

Das ist natürlich ein übler Hack.

Wentlich besser wäre es, wenn man im Protokoll / Log sehen könnte, dass der/ein Fehler auftritt und dies als Trigger für einen Restart nutzt.
(Natürlich ist es das Optimum wenn kein Fehler auftritt, aber das wird wohl noch etwas dauern).

Insgesamt ist es seht Schade, dass diese Instabilität von mISDN / chan_misdn Asterisk als TK-Analage nur bedingt einsatzfähig macht. D.h. typischerweise laufen Telefonanlagen 24x7x365 ohne nennenswerte Störungen. Hardware schliesse ich aus (ist ein neuer DELL Poweredge Server, der sonst keine Auffälligkeiten zeigt und zusätzlich im Monitoring ist (Temp., Lüfter, Platten, ECC-Ram-Fehler, CPU-Last usw.))

Zur Zeit überlegen wir daher mISDN zur Seite zu legen und alternativ externe SIP<->ISDN Gateways einzusetzen, in der Hoffnung dass solche Gateways stabil(er) sind. Aber hier es wohl erst der praktische Einsatz zeigen.

Gruss

Wolfgang

 

[onkel_fritz]

Das ist natürlich ein übler Hack.

Wentlich besser wäre es, wenn man im Protokoll / Log sehen könnte, dass der/ein Fehler auftritt und dies als Trigger für einen Restart nutzt.
(Natürlich ist es das Optimum wenn kein Fehler auftritt, aber das wird wohl noch etwas dauern).

ja ist es

ich weiß ja nicht ob wir beide wirklich das selbe problem haben; aber bei mir ist es so,
dass nach dieser zeit die telefone selbst ja nicht mehr wegwählen können;
interessanterweiße fällt immer zuerst ein NO-NAME produkt aus; die siemens-gigaset
halten ein paar stunden länger durch: wenn man jedoch
zu dem zeitpunkt, wo das noname nicht mehr geht
beim siemens einen power-reset macht, funktioniert es auch nicht mehr;

ich denke hier ist irgendwie die d-kanal komm. gestört; für meinen geschmack
wär es am sinnvollsten, hier mitsniffen zu können (so wie visdn das mal konnte)
da würde man sehr schnell die änderungen erkennen können...

habe mir leider noch nicht genug zeit verschaffen zu können, um einen
zusätzlichen visdn-sniffer zu basteln, den ich dann im fall des falles am S0
mitlaufen lasse; da bei mir das system ja im produktiveinsatz ist, werden
periodisch die module entfernt und neugeladen ... das ist aber auch keine lösung

 

[WolfgangA]

Bringt es bei Dir "etwas" die Module periodisch zu restarten?
Ich habe den Eindruck eher nicht. D.h. Wenn das System läuft und ich präventiv restarte, wird das System nicht stabiler.

 

[onkel_fritz]

Bringt es bei Dir "etwas" die Module periodisch zu restarten?
Ich habe den Eindruck eher nicht. D.h. Wenn das System läuft und ich präventiv restarte, wird das System nicht stabiler.

ja bringt es mir; seit ich das so habe, habe ich keine serviceeinschränkungen bis auf den restart selbst
den führe ich aber um 0300 durch -> sprich für mich kein problem

verstehe aber nicht, was du mit stabiler meinst; ich würde jetzt nicht mal mehr feststellen, wenn die
ursache/probleme inzwischen weg wäre(n);

für mich liegt das problem bei misdn (kernel teil) und nicht chan_misdn -> ich werde mal die neue
1.2beta versuchen (müssen)

 

[mabo]

Hallo,
gibt es schon neue Erfahrungen ?
Ich habe genau das selbe Problem !

serveria:~# asterisk -r
Asterisk 1.4.4, Copyright (C) 1999 - 2006 Digium, Inc. and others.
Created by Mark Spencer <[email protected]>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk 1.4.4 currently running on serveria (pid = 2662)
Verbosity is at least 5
serveria*CLI>

serveria*CLI> misdn show config
Misdn General-Config:
 -> misdn_init: /etc/misdn-init.conf -> debug: 0
 -> tracefile: /var/log/asterisk/misdn.log -> bridging: no
 -> stop_tone_after_first_digit: yes -> append_digits2exten: yes
 -> dynamic_crypt: no                -> crypt_prefix:
 -> crypt_keys:                      -> ntdebugflags: 0
 -> ntdebugfile: /var/log/misdn-nt.log

[PORT 1]
 -> name: ptmp_te_1                  -> allowed_bearers: all
 -> far_alerting: no                 -> rxgain: 0
 -> txgain: -1                       -> te_choose_channel: no
 -> pmp_l1_check: no                 -> reject_cause: 21
 -> block_on_alarm: no               -> hdlc: no
 -> context: isdn_in                 -> language: de
 -> musicclass: default              -> callerid:
 -> method: standard                 -> dialplan: 0
 -> localdialplan: 0                 -> cpndialplan: 0
 -> nationalprefix: 0                -> internationalprefix: 00
 -> presentation: -1                 -> screen: -1
 -> always_immediate: no             -> nodialtone: no
 -> immediate: no                    -> senddtmf: no
 -> hold_allowed: no                 -> early_bconnect: yes
 -> incoming_early_audio: no         -> echocancel: 128
 -> need_more_infos: no              -> noautorespond_on_setup: no
 -> nttimeout: no                    -> jitterbuffer: 4000
 -> jitterbuffer_upper_threshold: 0  -> callgroup:
 -> pickupgroup:                     -> max_incoming: -1
 -> max_outgoing: -1                 -> l1watcher_timeout: 0
 -> overlapdial: 0                   -> msns: *
 -> faxdetect: no                    -> faxdetect_context:
 -> faxdetect_timeout: 5             -> ptp: no


[PORT 2]
 -> name: ptmp_nt_2                  -> allowed_bearers: all
 -> far_alerting: no                 -> rxgain: 0
 -> txgain: -1                       -> te_choose_channel: no
 -> pmp_l1_check: no                 -> reject_cause: 21
 -> block_on_alarm: no               -> hdlc: no
 -> context: anlage                  -> language: de
 -> musicclass: default              -> callerid:
 -> method: standard                 -> dialplan: 0
 -> localdialplan: 0                 -> cpndialplan: 0
 -> nationalprefix: 0                -> internationalprefix: 00
 -> presentation: -1                 -> screen: -1
 -> always_immediate: no             -> nodialtone: no
 -> immediate: no                    -> senddtmf: no
 -> hold_allowed: no                 -> early_bconnect: yes
 -> incoming_early_audio: no         -> echocancel: 128
 -> need_more_infos: no              -> noautorespond_on_setup: no
 -> nttimeout: no                    -> jitterbuffer: 4000
 -> jitterbuffer_upper_threshold: 0  -> callgroup:
 -> pickupgroup:                     -> max_incoming: -1
 -> max_outgoing: -1                 -> l1watcher_timeout: 0
 -> overlapdial: 0                   -> msns: *
 -> faxdetect: no                    -> faxdetect_context:
 -> faxdetect_timeout: 5             -> ptp: no

serveria*CLI>

serveria:~# dmesg | grep "ISDN Stack core"
Modular ISDN Stack core version (1_1_2) revision ($Revision: 1.40 $)
Modular ISDN Stack core version (1_1_2) revision ($Revision: 1.40 $)

Gruß mabo

 

[WolfgangA]

Von meiner Seite aus gibt es hierzu nichts Neues.
Nach Deiner Signatur scheint das Problem auch in den "aktuellen" Versionen von mISDN und chan_misdn weiter zu bestehen.

Es hat sich niemand bezgl. der vom mir geposteten ausführlichen Debug Logs gemeldet. (Hatte die Hoffnung ein mISDN bzw. chan_misdn Entwickler liest mit).

Vielleicht ist mISDN tatsächlich noch nicht für den produktiven Einsatz geeignet, zumindest nicht im NT Modus bzw. mit > 1 S0 Port.

 

[jackfritt]

Also aktuell ist asterisk 1.2.18 mISDN 1.1.3 und chan_misdn aus asterisk 1.2.18 (Ich meine ist rc30)

Ich muss hier bei mir keine restarts durchführen und hier wird alles über misdn gemacht. Kann allerdings sein das es an meiner Portkonfig liegt. Ist nur der interne Port auf NT eingestellt. Den musste ich allerdings auch noch nie resetten.

Gruss,


Jörg

 

[crich]

WolfgangA:

ausgehend von deinem Trace ist deine Analyse schon richtig gewesen und das Problem wird sein, das hier ein Hangup erzeugt wird, wo gar keines zu erwarten ist.

Ich habe für solch eine Situation einen Fix in chan_misdn eingebaut, dieser ist im aktuellen asterisk 1.2 bzw. 1.4 branch enthalten und wird beim nächsten Asterisk release enthalten sein.

Ich finde es nur komisch, dass das ne Weile funktioniert und dann plötzlich nicht mehr..

 

[WolfgangA]

WolfgangA:
...
Ich habe für solch eine Situation einen Fix in chan_misdn eingebaut, dieser ist im aktuellen asterisk 1.2 bzw. 1.4 branch enthalten und wird beim nächsten Asterisk release enthalten sein.
...

Danke für die Rückmeldung. Ein paar Fragen dazu:
Wie kann man erkennen, ob dieser Fix in chan_misdn enthalten ist?
Gibt es ein Versionsnummer für diese chan_misdn version?
Wie funktioniert der Fix?

Ich finde es nur komisch, dass das ne Weile funktioniert und dann plötzlich nicht mehr..

Finde ich auch seltsam. Aber es ist genau so.

Danke

Wolfgang

 

[crich]

Es ist relativ schwer zu erkenen ob eine Version den Fix für dein Problem hat oder nicht.

Ich schreibe zwar in jeder RC von chan_misdn eine Mail an die misdn-asterisk ML mit den major Fixes, aber das bedeutet nicht immer das jemand aussenstehendes diese Nachricht zu seinem Problem zuordnen kann. Das geiche gilt für die commit Log Nachrichten die ich in das asterisk subversion repository schreibe wenn ich eine Änderung an chan_misdn mache.

Das beste ist eigentlich wenn "echte" Probleme, also keine Konfigurationsprobleme, in unserem bugtracker auf bugs.beronet.com oder bugs.digium.com eingetragen werden. Dann entsteht zu einem Bug nämlich eine Nr. diese Nr. packe ich eigentlich immer in das svn log bzw. in die Mailinliste wenn der entsprechende bug gefixt ist.

Ausserdem schliesse ich dann natürlich den Bug im Bugtracker, und Leute die einen Bug "watchen" werden dann benachrichtigt.

 

[WolfgangA]

Danke.
Thema Bugtracker:
Ich habe mehrfach versucht ein Konto auf bugs.beronet.com zu erstellen.
Entweder passiert nichts oder ich bekommen eine Fehlermeldung.
Jetzt aktuell z.b.:

Warning: Cannot modify header information - headers already sent by (output started at /var/kunden/webs/beroadmin/beromambo/bugs/core/html_api.php:139) in /var/kunden/webs/beroadmin/beromambo/bugs/core/gpc_api.php on line 220

An wen kann ich mich wenden, um ein Konto zu registrieren?

 

[crich]

macht ein Kollege von mir, sollte demnächst wieder laufen. Notfalls geht immer bugs.digium.com