interner Port bricht weg

wurtzel

Neuer User
Mitglied seit
7 Mrz 2006
Beiträge
49
Punkte für Reaktionen
0
Punkte
0
Hallo

ich habe folgendes Problem bei mir hängt sich regelmäßig der NTport auf.
Einkommende Rufe kommen beim * noch an können aber nicht an Anlage(M26) weiter gestellt werden.
SIP Telefone funktionieren normal können aber keine Nummern die auf die Anlage(M26) geschaltet sind erreichen.
Das problem lässt sich nur mit einem neustart von Asterisk beheben.


misdn.conf
Code:
[general]
debug=1
;method=standard
append_digits2exten=yes
bridging=yes


[default]
context=default
language=de
nationalprefix=0
internationalprefix=00
rxgain=0
txgain=0
dialplan=0

[TePort]
ports=1ptp
msns=*
context=from-extern

[NTPort]
ports=2ptp
context=from-intern
msns=*
extenions.conf
Code:
[general]
static=yes
writeprotect=no

[local]
include => internphones
exten => 10,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 11,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 20,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 21,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 30,1,macro,extern|SIP|${EXTEN}|90|25
exten => 40,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 41,1,macro,extern|SIP|${EXTEN}|90|25
exten => 50,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25
exten => 60,1,macro,extern|misdn/g:NTPort|${EXTEN}|90|25

;exten => _Z[01],1,Dial(SIP/${EXTEN},20)
;exten => _Z[01],hint,Dial(SIP/${EXTEN},20)
;exten => _Z[01],2,VoiceMail(u${EXTEN})
;exten => _Z[01],102,VoiveMail(b${EXTEN})
exten => 3000,1,VoiceMailMain(${CALLERIDNUM})



[default]
include => local
include => to-extern

[from-extern]
include => local
exten => 266520,1,Goto(local,10,1)
exten => 26652,1,Goto(local,10,1)
exten => 266521,1,Goto(local,10,1)
exten => 266525,1,Goto(local,50,1)
exten => 266524,1,Goto(local,40,1)
exten => 266522,1,Goto(local,21,1)

exten => _X.,1,NoOp(${EXTEN} extern)
exten => _0.,1,Set(CALLERID(number)=037126652${CALLERID(number)})
exten => _0.,2,Dial(misdn/g:TePort/${EXTEN:1})
exten => _26652Z[01],1,Goto(local,${EXTEN:-2},1)


[from-intern]
exten => _X.,1,waitfordigits(1500)
exten => _X.,2,NoOp(${EXTEN} intern ${CALLERID} )
exten => _XX,3,Goto(local,,1)
exten => _XX.,3,Dial(misdn/g:TePort/${EXTEN})

;include => default

[to-extern]
exten => _X.,1,NoOp(${EXTEN} extern)
exten => _0.,1,Set(CALLERID(number)=037126652${CALLERID(number)})
exten => _0.,2,Dial(misdn/g:TePort/${EXTEN:1})


[internphones]
;switch => Realtime/internphones@


[macro-extern]
; ARG1 = Protokol
; ARG2 = <intern-Nr>
; ARG3 = Sekunden <intern-Nr>
; ARG4 = Sekunden $vermittlung

exten => s,1,NoOp(Macro: Anruf von Extern)
exten => s,n,Dial,${ARG1}/${ARG2}|${ARG3}|Ttr
exten => s,n,Goto,s-${DIALSTATUS}|1

; Besetzt
exten => s-BUSY,1,Dial,SIP/30|25|Ttr
exten => s-BUSY,1,voicemail,b${ARG2}
exten => s-BUSY,n,Hangup

; Gassenbesetzt
; exten => s-CONGESTION,1,Dial,${vermittlung}|${ARG4}|Ttr
exten => s-CONGESTION,1,voicemail,b${ARG2}
exten => s-CONGESTION,n,Hangup

; Nichtannahme
; exten => s-NOANSWER,1,Dial,${vermittlung}|${ARG4}|Ttr
;exten => s-NOANSWER,1,voicemail,u${ARG2}
exten => s-NOANSWER,n,Hangup

; SIP-Telefon nicht angemeldet
; exten => s-CHANUNAVAIL,1,Dial,${vermittlung}|${ARG4}|Ttr
exten => s-CHANUNAVAIL,1,voicemail,u${ARG2}
exten => s-CHANUNAVAIL,n,Hangup
CLI
Code:
P[ 2] I IND :TIMEOUT oad:0336045096 dad:50
Jun 30 12:31:55 WARNING[5199]: chan_misdn.c:3019 cb_events: Chan not existing at the moment bc->l3id:8006ff00 bc:0x8198ed4 event:TIMEOUT port:2 channel:1
P[ 2] I IND :CLEAN_UP oad: dad:
    -- Playing 'digits/0' (language 'de')
    -- Playing 'vm-isunavail' (language 'de')
    -- Playing 'vm-intro' (language 'de')
Jun 30 12:32:03 DEBUG[5848]: app.c:1175 ast_lock_path: Locked path '/var/spool/asterisk/voicemail/default/50/INBOX'
Jun 30 12:32:03 DEBUG[5848]: app.c:1187 ast_unlock_path: Unlocked path '/var/spool/asterisk/voicemail/default/50/INBOX'
    -- Playing 'beep' (language 'de')
    -- Recording the message
Jun 30 12:32:03 DEBUG[5848]: app.c:569 ast_play_and_record_full: play_and_record: <None>, /var/spool/asterisk/voicemail/default/50/tmp/Z96RgI, 'wav49|gsm|wav'Jun 30 12:32:03 DEBUG[5848]: app.c:586 ast_play_and_record_full: Recording Formats: sfmts=wav49
    -- x=0, open writing:  /var/spool/asterisk/voicemail/default/50/tmp/Z96RgI format: wav49, 0x82ad1d0
    -- x=1, open writing:  /var/spool/asterisk/voicemail/default/50/tmp/Z96RgI format: gsm, 0x81f1b18
    -- x=2, open writing:  /var/spool/asterisk/voicemail/default/50/tmp/Z96RgI format: wav, 0x836cff0
P[ 1] * IND : Indication [18] from s-CHANUNAVAIL
Jun 30 12:32:03 WARNING[5848]: chan_misdn.c:1865 misdn_indication:  --> * Unknown Indication:18 pid:22
    coding=0 location=2 progress=8
P[ 1] I IND :DISCONNECT oad:0336045096 dad:2665250
P[ 1] I SEND:RELEASE oad:0336045096 dad:2665250
weitere Warnung
Code:
chan_misdn.c 3033 cb_events:No Ast or No private Pointer in Event(34:TIMEOUT)
nach dieser warnung hab ich mehrfach festgestellt das der NTPort sich verabschiedet hat
 
Zuletzt bearbeitet:
Dast Problem ist bekannt, ich arbeite im Moment daran es zu lösen.
 
wenn noch hilfreiche informationen gebraucht werden helf ich gern
 
Es wär sehr hilfreich wenn du einen misdn trace mit debuglevel 4 parallel laufen lässt. Benutze bitte auch die rc17, da habe ich schonmal ein problem gelöst in der Richtung.

wenn das nochmal passiert sende mir bitte den trace. (misdn.conf => debug=4)
 
jetzt mit rc17 läuft es bis jetzt stabil und ich hoffe das bleibt so.

debug ist aber vorsichtshalber auf 4 gesaetzt.
 
Leider immer noch interne BUS-Probleme

Hi,
gestern habe ich unsere Maschine auf Kernel 2.6.17.1 upgedated (ohne profiling!) und konnte dann endlich auch von rc4 auf rc17 gehen.
Bisher ist asterisk noch nicht wieder abgestürzt. allerdings war heute morgen wieder kein Telefonat möglich, der Bus war mal wieder tot.

Im Gegensatz zur vorherigen Version konnte ich allerdings auf der Asterisk-Console diesmal Meldungen sehen wenn ein Telefonhörer abgenommen wurde, kam aber gleich ein Besetztzeichen.
Das war vorher noch "töter" :) da gabs keinerlei Messages.

Habe jetzt mal debug auf 4 gesetzt, auf was soll ich achten?

bridging ist auf yes.


Nachtrag:
Ich habe jetzt den rc18 drauf, dabei kommt es auch zu diesen Busproblemen. Früher sah man dann auf der Asterisk-Console nichts, jetzt kommen beim Abnehmen des Hörers einige Meldungen, allerdings hört man sofort ein Besetztzeichen.
Im misdn-trace kommt dabei:
Code:
Thu Jul  6 17:23:32 2006: P[ 1]  find_free_chan: req_chan:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> found chan: 1
Thu Jul  6 17:23:32 2006: P[ 1]  I IND :SETUP oad:0 dad: pid:1 state:none
Thu Jul  6 17:23:32 2006: P[ 1]   --> mode:NT cause:16 ocause:16 rad: cad:
Thu Jul  6 17:23:32 2006: P[ 1]   --> info_dad: onumplan:0 dnumplan:  rnumplan:  cpnnumplan:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> channel:1 caps:Speech pi:0 keypad: sending_complete:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> bc_state:BCHAN_CLEANED
Thu Jul  6 17:23:32 2006: P[ 1]   --> Bearer: Speech
Thu Jul  6 17:23:32 2006: P[ 1]   --> Codec: Alaw
Thu Jul  6 17:23:32 2006: P[ 0]   --> * NEW CHANNEL dad: oad:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> CTON: Unknown
Thu Jul  6 17:23:32 2006: P[ 1]  EXPORT_PID: pid:1
Thu Jul  6 17:23:32 2006: P[ 1]  I SEND:SETUP_ACKNOWLEDGE oad:0 dad: pid:1
Thu Jul  6 17:23:32 2006: P[ 1]   --> bc_state:BCHAN_CLEANED
Thu Jul  6 17:23:32 2006: P[ 1]   --> mode:NT cause:16 ocause:16 rad: cad:
Thu Jul  6 17:23:32 2006: P[ 1]   --> info_dad: onumplan:0 dnumplan:  rnumplan:  cpnnumplan:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> channel:1 caps:Speech pi:0 keypad: sending_complete:0
Thu Jul  6 17:23:32 2006: P[ 1]   --> TRANSPARENT Mode
Thu Jul  6 17:23:32 2006: P[ 1]  BCHAN: bchan ACT Confirm pid:1
Thu Jul  6 17:23:34 2006: P[ 1]  I IND :DISCONNECT oad:0 dad: pid:1 state:WAITING4DIGS
Thu Jul  6 17:23:34 2006: P[ 1]   --> mode:NT cause:16 ocause:16 rad: cad:
Thu Jul  6 17:23:34 2006: P[ 1]   --> info_dad: onumplan:0 dnumplan:  rnumplan:  cpnnumplan:0
Thu Jul  6 17:23:34 2006: P[ 1]   --> channel:1 caps:Speech pi:0 keypad: sending_complete:0
Thu Jul  6 17:23:34 2006: P[ 1]   --> bc_state:BCHAN_ACTIVATED
Thu Jul  6 17:23:34 2006: P[ 1]  release_chan: bc with l3id: 240000
Thu Jul  6 17:23:34 2006: P[ 1]  * RELEASING CHANNEL pid:1 ctx:externs dad: oad:0 state: WAITING4DIGS
Thu Jul  6 17:23:34 2006: P[ 1]  I SEND:RELEASE oad:0 dad: pid:1
Thu Jul  6 17:23:34 2006: P[ 1]   --> bc_state:BCHAN_ACTIVATED
Thu Jul  6 17:23:34 2006: P[ 1]   --> mode:NT cause:16 ocause:-1 rad: cad:
Thu Jul  6 17:23:34 2006: P[ 1]   --> info_dad: onumplan:0 dnumplan:  rnumplan:  cpnnumplan:0
Thu Jul  6 17:23:34 2006: P[ 1]   --> channel:1 caps:Speech pi:0 keypad: sending_complete:0
Thu Jul  6 17:23:34 2006: P[ 1]  empty_chan_in_stack: 1
Thu Jul  6 17:23:34 2006: P[ 1]  $$$ Cleaning up bc with stid :10010100 pid:1
Thu Jul  6 17:23:34 2006: P[ 1]  I IND :CLEAN_UP oad: dad: pid:1 state:none
Thu Jul  6 17:23:34 2006: P[ 1]   --> mode:NT cause:16 ocause:16 rad: cad:
Thu Jul  6 17:23:34 2006: P[ 1]   --> info_dad: onumplan:0 dnumplan:0 rnumplan:0 cpnnumplan:0
Thu Jul  6 17:23:34 2006: P[ 1]   --> channel:0 caps:Speech pi:0 keypad: sending_complete:0
Thu Jul  6 17:23:34 2006: P[ 1]   --> bc_state:BCHAN_CLEANED

Im syslog habe ich neben den vielen skb... noch folgende Meldungen:
Code:
 mISDN dss1 fromup without proc pr=35a80 dinfo(30001)
 
Zuletzt bearbeitet:
So ich habe leider den fehler gemacht und ein onlineupdate bei meiner suse 10 gemacht.

kernel 2.6.13-15.8-default -> kernel 2.6.13-15.10-default
seit dem stürtzt der gesamte rechner ab sobalt der NTPort benutzt wird.

/var/log/warn
Code:
Jul  7 11:35:15 linuxa64 asterisk[6621]: WARNING[6639]: channel.c:2221 in ast_write: Thread 1090108336 Blocking 'mISDN/2-2', already blocked by thread 109260
6896 in procedure ast_waitfor_nandfds
Jul  7 11:35:15 linuxa64 asterisk[6621]: WARNING[6639]: channel.c:2221 in ast_write: Thread 1090108336 Blocking 'mISDN/2-2', already blocked by thread 109260
6896 in procedure ast_waitfor_nandfds
Jul  7 11:35:15 linuxa64 asterisk[6621]: WARNING[6639]: channel.c:2221 in ast_write: Thread 1090108336 Blocking 'mISDN/2-2', already blocked by thread 109260
6896 in procedure ast_waitfor_nandfds
Jul  7 11:35:29 linuxa64 asterisk[6621]: WARNING[6625]: channel.c:787 in channel_find_locked: Avoided initial deadlock for '0x815b208', 10 retries!
Jul  7 11:44:24 linuxa64 kernel: channel_senddata: next_skb exist ERROR (skb->len=128 next_skb->len=128)
Jul  7 11:44:24 linuxa64 kernel: channel_senddata: next_skb exist ERROR (skb->len=128 next_skb->len=128)
Jul  7 11:44:24 linuxa64 kernel: channel_senddata: next_skb exist ERROR (skb->len=128 next_skb->len=128)
Jul  7 11:44:34 linuxa64 asterisk[6621]: WARNING[6639]: channel.c:656 in ast_queue_frame: Unable to write to alert pipe on 320etype/subclass 4/1 (qlen = 0):
Bad file descriptor!
Jul  7 11:44:45 linuxa64 asterisk[6621]: WARNING[6639]: channel.c:2221 in ast_write: Thread 1090108336 Blocking 'mISDN/2-1', already blocked by thread 109287
3136 in procedure ast_waitfor_nandfds

Code:
Misdn General-Config:
 -> Version: chan_misdn-0.3.1-rc17
 -> misdn_init: /etc/misdn-init.conf -> debug: 4
 -> tracefile: /var/log/asterisk/misdn.log -> bridging: yes
 -> 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: TePort                     -> allowed_bearers: all
 -> far_alerting: no                 -> rxgain: 0
 -> txgain: 0                        -> te_choose_channel: no
 -> pmp_l1_check: yes                -> hdlc: no
 -> context: from-extern             -> language: de
 -> musicclass: default              -> callerid:
 -> method: standard                 -> dialplan: 0
 -> localdialplan: 0                 -> cpndialplan: 0
 -> nationalprefix: 0                -> internationalprefix: 00
 -> presentation: -1                 -> screen: -1
 -> always_immediate: no             -> immediate: no
 -> senddtmf: no                     -> hold_allowed: no
 -> early_bconnect: yes              -> incoming_early_audio: no
 -> echocancel: 0                    -> echocancelwhenbridged: no
 -> echotraining: 0                  -> need_more_infos: no
 -> jitterbuffer: 4000               -> jitterbuffer_upper_threshold: 0
 -> callgroup:                       -> pickupgroup:
 -> msns: *                          -> ptp: yes


[PORT 2]
 -> name: NTPort                     -> allowed_bearers: all
 -> far_alerting: no                 -> rxgain: 0
 -> txgain: 0                        -> te_choose_channel: no
 -> pmp_l1_check: yes                -> hdlc: no
 -> context: from-intern             -> language: de
 -> musicclass: default              -> callerid:
 -> method: standard                 -> dialplan: 0
 -> localdialplan: 0                 -> cpndialplan: 0
 -> nationalprefix: 0                -> internationalprefix: 00
 -> presentation: -1                 -> screen: -1
 -> always_immediate: no             -> immediate: no
 -> senddtmf: no                     -> hold_allowed: no
 -> early_bconnect: yes              -> incoming_early_audio: no
 -> echocancel: 0                    -> echocancelwhenbridged: no
 -> echotraining: 0                  -> need_more_infos: no
 -> jitterbuffer: 4000               -> jitterbuffer_upper_threshold: 0
 -> callgroup:                       -> pickupgroup:
 -> msns: *                          -> ptp: yes

im anhang alle wichtigen asterisk und misdn logs
 

Anhänge

  • asterisk-logs.zip
    28.5 KB · Aufrufe: 0
Sterkel:

komisch, eigentlich sehen die Logs prima aus. Was steht denn in der /var/log/asterisk/messages bzw. full zu der Zeit?
 
wurtzel:

sieht so aus als ob du einen 64bit kernel einsetzt. Kann sein das das zum Absturz führt.. hier gibts schonmal ein paar Bugs.
 
ich werde das jetzt mal unter debian einrichten und testen mal sehn was da passiert.
 
crich schrieb:
Sterkel:

komisch, eigentlich sehen die Logs prima aus. Was steht denn in der /var/log/asterisk/messages bzw. full zu der Zeit?

Hi Crich,
ich bin jetzt auf rc19 gewechselt. Das läuft bisher stabil, sogar übers Wochenende ist es nicht eingepennt. Es gibt manchmal diese Merkwürdigkeiten, ohne das der * neu gestartet wurde oder jemand an den Kabeln rumgespielt hat (angeblich :noidea:).
Freitag abend war das letzte Mal das es aufgetreten ist.

Code:
Fri Jul  7 19:10:30 2006: P[ 1]  % GOT L2 DeActivate Info.
Fri Jul  7 19:10:37 2006: P[ 0]  -- mISDN Channel Driver Registred -- (BE AWARE THIS DRIVER IS EXPERIMENTAL!)
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: Short status dinfo 1000001
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: SSTATUS: L1_ACTIVATED
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: Short status dinfo 1000001
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: SSTATUS: L1_ACTIVATED
Fri Jul  7 19:10:37 2006: P[ 1]  L1: PH L1Link Up!
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: Short status dinfo 1000001
Fri Jul  7 19:10:37 2006: P[ 1]  MGMT: SSTATUS: L1_ACTIVATED
Fri Jul  7 19:10:37 2006: P[ 1]  L1: PH L1Link Up!
Fri Jul  7 19:10:37 2006: P[ 2]  MGMT: Short status dinfo 1000001
Fri Jul  7 19:10:37 2006: P[ 2]  MGMT: SSTATUS: L1_ACTIVATED
Fri Jul  7 19:10:37 2006: P[ 2]  MGMT: Short status dinfo 2000001
Fri Jul  7 19:10:37 2006: P[ 2]  MGMT: SSTATUS: L2_ESTABLISH
 
hm.. sehr untypisch für einen PtP Anschluss.. Ich habe übrigens heute rc20 rausgebracht, da hab ich noch ein paar Probleme bezüglich NT Ports gelöst. Es gibt da noch ein problem, wenn das weg ist gibts die 0.3.1 !
 
crich schrieb:
heute rc20 rausgebracht

Is drauf! Die releases kommen so oft, da kann man gar nicht die Dauerstabilität testen :)
 
das war der plan, damit verdecke ich die Langzeitinstabilität ! ;-)
 
crich schrieb:
das war der plan, damit verdecke ich die Langzeitinstabilität ! ;-)
Also die rc20 hatte nur eine Kurzzeitstabilität, gestern gab es zwei Hänger, bei einem konnte ich asterisk nicht beenden (auch nicht mit kill -9). Bus war blockiert.

Diese seltsame Meldung war im Log:
Code:
Jul 11 18:29:38 WARNING[9216] chan_misdn.c:  5.94159E-313OT L2 DeActivate Info.
Jul 11 18:30:15 WARNING[9216] chan_misdn.c:  4.4114E+252OT L2 DeActivate Info.
Jul 11 18:33:02 NOTICE[10859] pbx_spool.c: Call failed to go through, reason 1
Jul 11 18:33:33 WARNING[7341] channel.c: Avoided initial deadlock for '0x81f08b0', 10 retries!
Musste erstmal wieder auf rc19 gehen, das ist eine gute Auslese.
 
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.