Firmware wird nur gelegentlich geladen

[Boris]

Moin zusammen,


ich möchte folgendes Verhalten schildern und bitte um Statements:

Umgebung: Stretch auf Thinkpad T520, DVB-T2-Stick Astrometa mit dual
Receiver (RT2832 und MN88473)

Ziel: DVB-T2-Empfang mit dem Stick

Ich habe eine gute Konfiguration von tv-headend und habe vor ein paar
Tagen mit dem System Nachrichten gesehen - läuft also prinzipiell.
Zu diesem Zeitpunkt wurde der Stick korrekt eingebunden und die dafür
bereitgestellte Firmware geladen:

Auszug aus syslog an diesem guten Tag:

Jul 25 19:36:02 nl-tp-boris kernel: [ 81.289140] mn88473 10-0018:
downloading firmware from file 'dvb-demod-mn88473-01.fw'
Jul 25 19:36:03 nl-tp-boris kernel: [ 82.043067] mn88473 10-0018:
firmware: direct-loading firmware dvb-demod-mn88473-01.fw

Das ist aber leider der Ausnahmefall. Üblicherweise wird der Stick zwar
erkannt, aber die Firmware für den Panasonic MN88473 wird nicht geladen.
Entsprechend kann auch w_scan, tv-headend oder irgendein anderes Tool
den Chip nicht ansprechen.

Was ist zu tun? Bugreport?

Dank und Grüße aus Dänemark,

Boris

syslog beim Einstöpseln:

Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.709692] usb 2-1.1: new
high-speed USB device number 4 using ehci-pci
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.828400] usb 2-1.1: New USB
device found, idVendor=15f4, idProduct=0131
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.828405] usb 2-1.1: New USB
device strings: Mfr=1, Product=2, SerialNumber=0
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.828408] usb 2-1.1: Product: dvbt2
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.828410] usb 2-1.1:
Manufacturer: astrometadvbt2
Jul 29 12:10:38 nl-tp-boris mtp-probe: checking bus 2, device 4:
"/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1"
Jul 29 12:10:38 nl-tp-boris mtp-probe: bus: 2, device: 4 was not an MTP
device
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.868731] usb 2-1.1:
dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.945073] usb 2-1.1:
dvb_usb_v2: will pass the complete MPEG2 transport stream to the
software demuxer
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.945094] DVB: registering new
adapter (Astrometa DVB-T2)
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.952256] i2c i2c-10: Added
multiplexed i2c bus 11
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.952259] rtl2832 10-0010:
Realtek RTL2832 successfully attached
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.959594] mn88473 10-0018:
Panasonic MN88473 successfully identified
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.959602] usb 2-1.1: DVB:
registering adapter 0 frontend 0 (Realtek RTL2832 (DVB-T))...
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.959660] usb 2-1.1: DVB:
registering adapter 0 frontend 1 (Panasonic MN88473)...
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.961380] r820t 11-003a:
creating new instance
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.968584] r820t 11-003a: Rafael
Micro r820t successfully identified
Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.968597] r820t 11-003a:
attaching existing instance
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.973728] r820t 11-003a: Rafael
Micro r820t successfully identified
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.975957] rtl2832_sdr
rtl2832_sdr.1.auto: Registered as swradio0
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.975959] rtl2832_sdr
rtl2832_sdr.1.auto: Realtek RTL2832 SDR attached
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.975961] rtl2832_sdr
rtl2832_sdr.1.auto: SDR API is still slightly experimental and
functionality changes may follow
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.982731] Registered IR keymap
rc-empty
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.982897] input: Astrometa
DVB-T2 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1/rc/rc0/input21
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.982987] rc rc0: Astrometa
DVB-T2 as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1/rc/rc0
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.983262] usb 2-1.1:
dvb_usb_v2: schedule remote query interval to 200 msecs
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.984856] lirc_dev: IR Remote
Control driver registered, major 243
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.985778] rc rc0: lirc_dev:
driver ir-lirc-codec (dvb_usb_rtl28xxu) registered at minor = 0
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.985779] IR LIRC bridge
handler initialized
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.996310] usb 2-1.1:
dvb_usb_v2: 'Astrometa DVB-T2' successfully initialized and connected
Jul 29 12:10:39 nl-tp-boris kernel: [ 1461.996402] usbcore: registered
new interface driver dvb_usb_rtl28xxu
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
config/udev: Adding input device Astrometa DVB-T2 (/dev/input/event20)
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Astrometa DVB-T2: Applying InputClass "libinput keyboard catchall"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
Using input driver 'libinput' for 'Astrometa DVB-T2'
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
systemd-logind: got fd for /dev/input/event20 13:84 fd 55 paused 0
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Astrometa DVB-T2: always reports core events
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Option "Device" "/dev/input/event20"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Option "_source" "server/udev"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
input device 'Astrometa DVB-T2', /dev/input/event20 is tagged by udev
as: Keyboard
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
input device 'Astrometa DVB-T2', /dev/input/event20 is a keyboard
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Option "config_info"
"udev:/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1/rc/rc0/input21/event20"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
XINPUT: Adding extended input device "Astrometa DVB-T2" (type: KEYBOARD,
id 17)
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Option "xkb_model" "pc105"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (**)
Option "xkb_layout" "de"
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (WW)
Option "xkb_variant" requires a string value
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (WW)
Option "xkb_options" requires a string value
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
input device 'Astrometa DVB-T2', /dev/input/event20 is tagged by udev
as: Keyboard
Jul 29 12:10:39 nl-tp-boris /usr/lib/gdm3/gdm-x-session[1316]: (II)
input device 'Astrometa DVB-T2', /dev/input/event20 is a keyboard
Jul 29 12:10:43 nl-tp-boris tvheadend[713]: linuxdvb: adapter added
/dev/dvb/adapter0
Jul 29 12:10:43 nl-tp-boris kernel: [ 1466.527577] usb 2-1.1: DVB:
adapter 0 frontend 0 frequency 0 out of range (174000000..862000000)

[bo...@cation.de]

Moin Liste,

hat niemand eine Idee dazu? Oder ist mein Vorhaben zu profan?

Wie funktioniert die Mimik mit dem Nachladen der Firmware? Woran erkennt
der Kern, dass Firmware geladen werden muss?

Dank und Viele Grüße,


Boris

[bo...@cation.de]

Moin Andreas,

vielen Dank für Deine Antwort!

Wir reden von verschiedenen Dingen, fürchte ich! Die Firmware muss das
System zuerst nachladen, wenn die Hardware erkannt wird, m.E. unabhängig
davon, ob die Module geladen werden oder nicht. Die Mods braucht man
natürlich auch, aber erst im nächsten Schritt.....

Viele Grüße,


Boris

Am 2017-08-03 12:03, schrieb A. Söldner:
> Moin Boris, hallo Liste,
>
> bin da jetzt nicht wirklich firm, aber ..
>
> lsmod listet die geladenen Treiber
> insmod <Modulname> lädt den Treiber
>
>
> Wie es sich automatisieren lässt weiß ich auch nicht.
>
> Gruß
> Andreas
>
> ------ Originalnachricht ------
> Von: bo...@cation.de
> An: "Debian Mailingliste" <debian-us...@lists.debian.org>
> Gesendet: 03.08.2017 11:57:57
> Betreff: Re: Firmware wird nur gelegentlich geladen

[Sascha Reißner]

Hallo Boris,

in deinem Log steht:

Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.868731] usb 2-1.1: dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state

Die Worte 'in warm state' bedeuten, daß die Firmware schon geladen ist.
Wenn noch keine Firmware geladen ist, steht an der Stelle 'in cold
state'.

'warm state' liegt nach einem Reboot vor, da dabei der USB weiter mit
Strom versorgt wird und die Geräte den RAM nicht leeren.
Nur bei einem Kaltstart (und auch nur wenn die USB-Buchsen nicht mit
Standby-Strom versorgt werden) ist der RAM von solchen Geräten leer.
Du kannst diesen Zustand erzwingen, indem du das Gerät abziehst und nach
ein paar Sekunden wieder ansteckst. Dann sollte im Log zuerst die
Meldung 'cold state' auftauchen und das versucht wird die Firmware zu
laden. Nach dem laden der Firmware sollte die selbe Meldung nochmal
kommen, nur diesmal mit 'warm state'.

--
mfG Sascha

GPG key: http://pgp.mit.edu:11371/pks/lookup?op=get&search=0x92BBDD596C5FDC0F
GPG fingerprint: 5A68 F665 44E6 0E50 001C 75A6 92BB DD59 6C5F DC0F

Zu Dorlar einem Dorf an der Lahn nicht weit von Gießen haben fast alle
Leute rote Haare.
-- Georg Christoph Lichtenberg

[Boris]

Moin Sascha,

vielen Dank für Deinen Beitrag! Sorry für meine lange Reaktionszeit - es
war halt Urlaub....

Am 07.08.2017 um 03:49 schrieb Sascha Reißner:
> Hallo Boris,
>
> in deinem Log steht:
>
> Jul 29 12:10:38 nl-tp-boris kernel: [ 1461.868731] usb 2-1.1: dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state
>
> Die Worte 'in warm state' bedeuten, daß die Firmware schon geladen ist.
> Wenn noch keine Firmware geladen ist, steht an der Stelle 'in cold
> state'.
>
> 'warm state' liegt nach einem Reboot vor, da dabei der USB weiter mit
> Strom versorgt wird und die Geräte den RAM nicht leeren.
> Nur bei einem Kaltstart (und auch nur wenn die USB-Buchsen nicht mit
> Standby-Strom versorgt werden) ist der RAM von solchen Geräten leer.
> Du kannst diesen Zustand erzwingen, indem du das Gerät abziehst und nach
> ein paar Sekunden wieder ansteckst. Dann sollte im Log zuerst die
> Meldung 'cold state' auftauchen und das versucht wird die Firmware zu
> laden. Nach dem laden der Firmware sollte die selbe Meldung nochmal
> kommen, nur diesmal mit 'warm state'.
>

Jepp, vielleicht ist hier sogar die Ursache zu suchen.....

Der Stick war nun rund zwei Wochen in der Tasche, also nicht
angeschlossen. Heute morgen habe ich den Schleppi gestartet und das Ding
angestöpselt. Wieder finde ich im syslog:

Aug 17 08:43:09 nl-tp-boris kernel: [ 1979.219696] usb 2-1.1:

dvb_usb_v2: found a 'Astrometa DVB-T2' in warm state

Entsprechend wird die Firmware nicht geladen (es gibt keinen
Log-Eintrag) und das Teil funktioniert auch nicht - wird von tv-headend
nicht gefunden.

Geht also womöglich bei der Analyse des warm/cold state etwas schief?
Kann man das Laden der Firmware auch im warm state erzwingen?

Mmmhhh. Irgendwo habe ich gelesen, dass die Firmware von dem
Treibermodul mit geladen wird, wenn das eingebunden wird. Also habe ich
dieses Modul nach dem Abziehen des Sticks mit rmmod mn88473 entfernt und
den Stick wieder angesteckt - wieder warm state ....

Warum war das Modul eigentlich noch geladen, obwohl im Log steht "Aug 17
09:25:04 nl-tp-boris kernel: dvb_usb_v2: 'Astrometa DVB-T2:2-1.1'
successfully deinitialized and disconnected"?

Dank und Grüße,

Boris

[Uwe Kerstan]

* Boris [17-08-2017 09:41]:

> Entsprechend wird die Firmware nicht geladen (es gibt keinen
> Log-Eintrag) und das Teil funktioniert auch nicht - wird von tv-headend
> nicht gefunden.
>
> Geht also womöglich bei der Analyse des warm/cold state etwas schief?
> Kann man das Laden der Firmware auch im warm state erzwingen?

Moin Boris,

ich habe immer mit einem kleinen Skript dvb-reload gearbeitet,
weil ich auch Probleme mit laden der Firmware hatte.

#!/bin/bash
echo "Reload modules for DVB frontend ..."
LOGGER='/usr/bin/logger'
if [ -x $LOGGER ]; then
$LOGGER "reload dvb_usb_dibusb_mc for dvb re-init"
fi
MODUL='dvb_usb_dibusb_mc'
rmmod -v $MODUL
modprobe -v $MODUL
ls -lR /dev/dvb
echo "Done."
exit 0

Das Modul wird sicher nicht auf dein Teil passen, das ist noch DVB-T
erste Generation. DVB-T2 kommt hier lokal erst ab Frühjahr 2019.

Gruß Uwe

[Boris]

Moin Uwe,
hallo Liste,

vielen Dank für Deine Idee!

Das fragliche Modul ist wohl mn88473 .
Es wird beim Einstecken des Sticks (immer warm state, kein Laden der
Firmware) eingebunden und in /dev/dvb existieren dann beide frontends:

/dev/dvb/adapter0:
insgesamt 0
crw-rw----+ 1 root video 212, 0 Aug 28 12:47 demux0
crw-rw----+ 1 root video 212, 1 Aug 28 12:47 dvr0
crw-rw----+ 1 root video 212, 3 Aug 28 12:47 frontend0
crw-rw----+ 1 root video 212, 4 Aug 28 12:47 frontend1
crw-rw----+ 1 root video 212, 2 Aug 28 12:47 net0

Allerdings funktioniert das Frontend1 nicht (tv-headend findet nur den
Realtek-Chip RTL2832).

Manuell entladen kann ich das Modul nicht:

# rmmod -v mn88473
rmmod: ERROR: Module mn88473 is in use

Es wird auch beim Abziehen des Sticks nicht entladen. Dann klappt
allerdings das rmmod.

Stecke ich wieder dran, wird der ganze Vorgang wiederholt (warm-state
ohne Firmware-Nachladen, beide Frontends in /etc/dvb, ....)

Den funktionierenden Zustand habe ich bislang nicht wieder herstellen
können.... Glücklicherweise habe ich eine Zeugin! ;-)

Grüße,


Boris

[Martin Steigerwald]

Boris - 28.08.17, 12:58:

> Manuell entladen kann ich das Modul nicht:
>
> # rmmod -v mn88473
> rmmod: ERROR: Module mn88473 is in use
>
> Es wird auch beim Abziehen des Sticks nicht entladen. Dann klappt
> allerdings das rmmod.

- lsmod und schauen welches andere Modul es auch braucht
- ggf. Stick vorher entfernen.

Mit DVB-Sticks selbst kenne ich mich nicht aus.

--
Martin