HFC-S PCI [NT] mit Bristuff-0.4.0-test1

[stinkstiefel]

Ich versuche mich grad an einem Bristuff Update von 0.3.0-PRE-1o auf 0.4.0-test1.
Vermutlich bekomme ich keine Verbindung zwischen Telefon und ISDN-Karte zustande.
Wähle ich eine Nummer, zeigt sich das Telefon davon recht unbeeindruckt und geht kurz darauf auf Störung.
Wo kann ich mit der Fehlerdiagnose ansetzen? Systemkernel ist 2.4.34

Zaptel Version: 1.4.1
Echo Canceller: MG2
Configuration
======================

SPAN 1: CCS/ AMI Build-out: 399-533 feet (DSX-1)

Channel map:

Channel 01: Clear channel (Default) (Slaves: 01)
Channel 02: Clear channel (Default) (Slaves: 02)
Channel 03: D-channel (Default) (Slaves: 03)

3 channels configured.

Changing signalling on channel 1 from Unused to Clear channel
Changing signalling on channel 2 from Unused to Clear channel
Changing signalling on channel 3 from Unused to HDLC with FCS check

kernel: Zapata Telephony Interface Registered on major 196
kernel: Zaptel Version: 1.4.1
kernel: Zaptel Echo Canceller: MG2
kernel: PCI: Enabling device 01:09.0 (0004 -> 0007)
kernel: PCI: Found IRQ 10 for device 01:09.0
kernel: PCI: Sharing IRQ 10 with 00:1f.3
kernel: zaphfc: CCD/Billion/Asuscom 2BD0 configured at mem c68b7000 fifo c3588000(0x3588000) IRQ 10 HZ 100
kernel: zaphfc: Card 0 configured for NT mode
kernel: zaphfc: 1 hfc-pci card(s) in this box.
kernel: Registered tone zone 29 (Germany)

asterisk -vvc bringt mich zur cli vorher sehe ich noch einige Warnings zu translate.c und loader.c über den Schirm fliegen.
Wie bekomme ich es eigentlich hin die Ausgabe von asterisk -vvc in einem leicht lesbaren Format in eine Datei zu schreiben?
"asterisk -vvc >/tmp/test.txt" funktioniert zwar, ist aber nur schwer zu entziffern.

Hat folgende Notiz eventuell etwas mit meinen Problem zu tun?

NOTICE[1175]: codec_zap.c:365 find_transcoders: No Zaptel transcoder support!
codec_zap.so => (Generic Zaptel Transcoder Codec Translator)

 

[Netview]

1) asterisk mit -gccvvv starten
2) codec_zap.so löschen (wird beim bristuff nicht gebraucht - nicht zu verwechseln mit chan_zap.so!).

 

[stinkstiefel]

"asterisk -gccvvv" ändert aber leider an der Ausgabeform nichts, wenn ich diese Ausgabe mit meiner vorherigen vergleiche stelle ich auf den ersten Blick keinen Unterschied fest.

Da ich codec_zap.so ja so wie du schreibst nicht brauche habe ich es jetzt mit noload => codec_zap.so in /etc/asterisk/modules.conf eingetragen.
Nur wie komme ich jetzt der Ursache für das nach wie vor funktionslose Telefon auf die schliche.

Hat sich vielleicht etwas grundlegendes an den relevanten *.conf Dateien geändert? Die habe ich nämlich aus Faulheit erst mal so belassen wie sie waren.

zapate.conf

[channels]  
switchtype=euroisdn 
pridialplan=local 
overlapdial=yes 
signalling=bri_net_ptmp
echocancel=yes
echocancelwhenbridged=yes
echotraining=yes
rxgain=0.0
txgain=0.0
immediate=no 
context=zap_in 
group=1
channel=>1-2

zaptel.conf

loadzone=de
defaultzone=de
span=1,1,3,ccs,ami 
bchan=1-2 
dchan=3

 

[Netview]

Wenn du asterisk mit 'asterisk -gcccvvv' startest müsstest du einige Meldungen durchrauschen sehen. Mit ZOC kann man dann recht bequem zurückblättern (ssh bzw. telnet session).
Alternativ kannst du auch in der logger.conf einige Optionen anschalten und das log nachher analysieren.

 

[stinkstiefel]

Wenn du asterisk mit 'asterisk -gcccvvv' startest müsstest du einige Meldungen durchrauschen sehen.

Ja, genau die, die ich mit -vvc auch gesehen habe.
Mit PuTTY kann ich leider nicht bis zum Anfang zurückblättern, deswegen wollte ich es ja in eine Datei umleiten, egal vielleicht kann man das auch einstellen, nicht so wichtig.
"logger.conf" war ein schöner Hinweis, ich habe mal für messages das volle Programm aktiviert. (messages => notice,warning,error,debug)

Direkt nach dem starten des Asterisk bekomme ich so:

[Apr 18 16:11:16] NOTICE[2977] cdr.c: CDR simple logging enabled.
[Apr 18 16:11:16] NOTICE[2977] loader.c: 150 modules will be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidname.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_enumlookup.so': /usr/lib/asterisk/modules/app_enumlookup.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidnum.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_cut.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_eval.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_groupcount.so': /usr/lib/asterisk/modules/app_groupcount.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_txtcidname.so': /usr/lib/asterisk/modules/app_txtcidname.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_math.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setrdnis.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_md5.so': /usr/lib/asterisk/modules/app_md5.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_callingpres.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_capiNoES.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'format_pcm_alaw.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'format_au.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'pbx_functions.so' did not register itself during load
[Apr 18 16:11:16] NOTICE[2977] res_smdi.c: Unable to load config smdi.conf: SMDI disabled
[Apr 18 16:11:16] WARNING[2977] res_smdi.c: No SMDI interfaces are available to listen on, not starting SDMI listener.
[Apr 18 16:11:16] WARNING[2977] chan_sip.c: tos value at line 9 is deprecated.  See doc/ip-tos.txt for more information.
[Apr 18 16:11:16] WARNING[2977] chan_sip.c: insecure=very at line 10 is deprecated; use insecure=port,invite instead
[Apr 18 16:11:16] WARNING[2977] acl.c: TOS value lowdelay is deprecated. Please see doc/ip-tos.txt for more information.
[Apr 18 16:11:16] WARNING[2977] acl.c: TOS value lowdelay is deprecated. Please see doc/ip-tos.txt for more information.
[Apr 18 16:11:16] WARNING[2977] acl.c: TOS value lowdelay is deprecated. Please see doc/ip-tos.txt for more information.
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Failed to read gains: Invalid argument
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Failed to read gains: Invalid argument
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Updated conferencing on 1, with 0 conference users
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Failed to read gains: Invalid argument
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Failed to read gains: Invalid argument
[Apr 18 16:11:16] DEBUG[2977] chan_zap.c: Updated conferencing on 2, with 0 conference users
[Apr 18 16:11:16] DEBUG[2977] pbx_dundi.c: Seeding global EID '00:e0:7d:ed:10:06' from 'eth0'
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: Starting AEL load process.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: calculated config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: parsed config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: checked config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: compiled config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: merged config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] NOTICE[2977] pbx_ael.c: AEL load process: verified config file name '/etc/asterisk/extensions.ael'.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidname.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidname.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_enumlookup.so': /usr/lib/asterisk/modules/app_enumlookup.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_enumlookup.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidnum.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setcidnum.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_cut.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_cut.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_eval.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_eval.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_groupcount.so': /usr/lib/asterisk/modules/app_groupcount.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_groupcount.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_txtcidname.so': /usr/lib/asterisk/modules/app_txtcidname.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_txtcidname.so' could not be loaded.
[Apr 18 16:11:16] NOTICE[2987] chan_sip.c: Peer 'sipgate_blabla' is now Reachable. (188ms / 2000ms)
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_math.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_math.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setrdnis.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_setrdnis.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Error loading module 'app_md5.so': /usr/lib/asterisk/modules/app_md5.so: undefined symbol: option_priority_jumping
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_md5.so' could not be loaded.
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_callingpres.so' did not register itself during load
[Apr 18 16:11:16] WARNING[2977] loader.c: Module 'app_callingpres.so' could not be loaded.
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'app_capiNoES.so' did not register itself during load
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'app_capiNoES.so' could not be loaded.
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] translate.c: plc_samples 160 format 6
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'format_pcm_alaw.so' did not register itself during load
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'format_pcm_alaw.so' could not be loaded.
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'format_au.so' did not register itself during load
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'format_au.so' could not be loaded.
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'pbx_functions.so' did not register itself during load
[Apr 18 16:11:17] WARNING[2977] loader.c: Module 'pbx_functions.so' could not be loaded.
[Apr 18 16:11:17] ERROR[2977] app_amd.c: Configuration file amd.conf missing.
[Apr 18 16:11:17] WARNING[2977] app_followme.c: No follow me config file (followme.conf), so no follow me

Neben den Meldungen zu Modulen die ich wohl grösstenteils eh nicht brauchen werde ist aber vielleicht das DEBUG zu chan_zap interessant oder?
Nur leider kann ich damit nichts anfangen.

 

[Netview]

Die Meldungen sind nichts aussergewöhnliches und lassen keine Rückschluss zu weshalb deine ISDN-Telefone auf Störung gehen.

Im übrigen ist der zaphfc-Treiber vom bristuff-0.4.0 identisch mit dem bristuff-0.3.0-PRE-1y-e (per md5-checksum geprüft). Insofern dürfte wahrscheinlich auch zaphfc aus der 0.3.0-PRE-1o mit asterisk 1.4.x laufen!

 

[stinkstiefel]

Die Meldungen sind nichts aussergewöhnliches und lassen keine Rückschluss zu weshalb deine ISDN-Telefone auf Störung gehen.

Okay, wo dann weitersuchen?

Was mich irgend wie irretiert ist, dass sich eigentlich bezüglich zaptel und zaphfc alles so verhält wie sonst, nur halt eben nicht funktioniert.

zap show status zeigt mir für IRQ 0 obwohl ich da etwas anderes erwartet hätte. Allerdings weiss ich nicht wie es in der alten Version aussieht.

Description                              Alarms     IRQ        bpviol     CRC4
HFC-S PCI A ISDN card 0 [NT] layer 1 A OK         0          0          0

Auch Ausgaben von zap show channels, cat /proc/zaptel/* und lsmod sehen gut aus.

Wenn ich die Sipgatenummer anrufe und dabei die Debugmeldungen auf der cli betrachte
bringt mich das leider auch nicht weiter.

    -- Executing [blabla@sip_in:1] Dial("SIP/blabla", "Zap/g1/NR vom ISDN-Telefon") in new stack
    -- Requested transfer capability: 0x00 - SPEECH
    -- Called g1/NR vom ISDN-Telefon
    -- Channel 0/1, span 1 got hangup, cause 18
    -- Hungup 'Zap/1-1'
  == Everyone is busy/congested at this time (1:0/0/1)
  == Auto fallthrough, channel 'SIP/blabla' status is 'CHANUNAVAIL'

busy ist das Telefon definitiv nicht
congested = die Verbindung verstopft kommt schon eher hin. Ein Hinweis um den Korken zu lösen wäre jetzt nicht schlecht.

Nach dem ich jetzt seit 18:00 eine CPU Auslastung von 98% beobachte,
was sich nach dem beenden des asterisk wieder normalisiert, werde ich wohl auf die nächste Version warten.

 

[clan]

Ist die ISDN Schicht-2 aktiv? 'bri debug span x' und evtl. 'bri intense debug span x' (für passende x) sollten darüber Auskunft geben. Vor allem kannst du da auch sehen, welche Rufnummer der Treiber in das ISDN-Setup setzt und ob das Busy vom Treiber oder von der VSt kommt.

 

[stinkstiefel]

@clan da ich wieder die lauffähige Version installiert habe kann ich das im Moment leider nicht überprüfen.

Ich habe aber beim vergleichen noch etwas gefunden. Die Zeile mit "master mode"

kernel: Zapata Telephony Interface Registered on major 196
kernel: Zaptel Version: 1.2.10 Echo Canceller: MG2
kernel: zaphfc: jitterbuffer size: 1
kernel: PCI: Enabling device 01:09.0 (0000 -> 0003)
kernel: PCI: Found IRQ 10 for device 01:09.0
kernel: PCI: Sharing IRQ 10 with 00:1f.3
kernel: zaphfc: CCD/Billion/Asuscom 2BD0 configured at mem 0xc73ab000 fifo 0xc0cd0000(0xcd0000) IRQ 10 HZ 100
kernel: zaphfc: Card 0 configured for NT mode
kernel: zaphfc: Card 0 configured for master mode
kernel: zaphfc: 1 hfc-pci card(s) in this box.
kernel: Registered tone zone 29 (Germany)

ist mit Zaptel 1.4.1 nicht im log. Nun wäre halt interessant zu wissen ob die Zeile vorhanden sein muss.
0.3.0-PRE-1o zeigt mir für "zap show status" übriegens auch IRQ 0, also ist das wohl eher unbedenklich.