Probleme nach Festplatten-Überfüllung

ChrisK

Hallo zusammen,

bei mir läuft KODI auf eine kleinen HTPC mit Kodibuntu drauf (dafür gibt's schon Haue, oder?).
Kodi läuft eigentlich durchgängig.

Gestern mache ich den Fernseher an und Kodi läuft auf englisch. Auch die Tastentöne sind aktiviert, scheint also auf Default zurückgegangen zu sein.
Nach ein bisschen Analyse habe ich festgestellt, dass die Festplatte wo Betriebssystem und Kodi drauf ist, voll war. Hab dann Platz geschaffen, Kodi neu gestartet und war froh, dass alles wieder normal aussah. War es aber leider nicht.

Live-TV (über Sat-Karte und tvheadend) und mkv-Dateien auf dem NAS wurden mit Ton aber ohne Bild abgespielt.
Bin dann hier gelandet und habe testweise VAAPI deaktiviert. Das Bild war danach wieder da. Im Thread dort ist zu lesen, dass das nur ein Workaround ist und deswegen habe ich wie dort empfohlen mesa aktualisiert. Leider hilft das nicht weiter, bei aktiviertem VAAPI ist das Bild weiterhin schwarz.
Hinzu kommt, dass Kodi bei der Auswahl eines TV-Senders ein paar Mal komplett abgestürzt ist. Das ist früher nie passiert. Bei einem von drei Versuchen klappt es mit deaktiviertem VAAPI auch mit dem Live-TV.

Ich habe das Debugging mal aktiviert und das Log beim Absturz raus kopiert:

Code

07:34:27.669 T:140369832425216   DEBUG: ### [Qlock] - Delaying 3 secs
07:34:29.970 T:140371338680320   DEBUG: Previous line repeats 1 times.
07:34:29.970 T:140371338680320   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
07:34:29.970 T:140371338680320   DEBUG: OnKey: return (0xf00d) pressed, action is Select
07:34:29.970 T:140371338680320   DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x0000, modifier: 0x0
07:34:29.974 T:140371338680320   DEBUG: Loading settings for pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr)
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
07:34:29.974 T:140371338680320   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
07:34:29.974 T:140371338680320   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
07:34:29.976 T:140371338680320   DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
07:34:29.976 T:140371338680320  NOTICE: VideoPlayer: Opening: pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr
07:34:29.976 T:140371338680320 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:34:29.976 T:140371338680320   DEBUG: LinuxRendererGL: Cleaning up GL resources
07:34:29.976 T:140371338680320   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
07:34:29.976 T:140368981202688   DEBUG: Thread VideoPlayer start, auto delete: false
07:34:29.976 T:140368981202688  NOTICE: Creating InputStream
07:34:29.976 T:140368981202688   DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Das Erste HD'
07:34:29.976 T:140368981202688   DEBUG: opening live stream for channel 'Das Erste HD'
07:34:29.976 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux open
07:34:29.976 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscribe to 1509039286
07:34:29.977 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully subscribed to channel id 1509039286, subscription id 1
07:34:29.977 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux open
07:34:29.977 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux subscribe to 1308390525
07:34:30.476 T:140371338680320   DEBUG: ------ Window Init (DialogBusy.xml) ------
07:34:30.478 T:140371338680320   DEBUG: UPower: Received an unknown signal DeviceChanged
07:34:30.633 T:140369309886208   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux unhandled subscription message [subscriptionGrace]
07:34:30.633 T:140368981202688   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully subscribed to channel id 1308390525, subscription id 2
07:34:30.633 T:140368981202688   DEBUG: CDVDInputStreamPVRManager - Open - playback has started on filename pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr
07:34:30.634 T:140368981202688   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr
07:34:30.634 T:140368981202688  NOTICE: Creating Demuxer
07:34:30.634 T:140368981202688   DEBUG: OnPlayBackStarted: play state was 1, starting 1
07:34:30.634 T:140368981202688   DEBUG: CVideoPlayer::SetCaching - caching state 2
07:34:30.634 T:140368981202688   DEBUG: CDVDClock::SetSpeedAdjust - adjusted:0.000000
07:34:30.637 T:140371338680320   DEBUG: PlayFile: OpenFile succeed, play state 2
07:34:30.637 T:140371338680320   DEBUG: OnPlayBackStarted: play state was 2, starting 0
07:34:30.652 T:140369714992896   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/Alle KanÃ¤le/pvr.hts_1509039286.pvr)
07:34:30.652 T:140370909554432   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
07:34:30.652 T:140370909554432   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
07:34:30.709 T:140369824032512   DEBUG: Skin Helper Service --> Kodi_Monitor: sender xbmc - method: Player.OnPlay  - data: {"item":{"channeltype":"tv","id":410,"title":"Das Erste HD","type":"channel"},"player":{"playerid":1,"speed":1}}
07:34:30.786 T:140371338680320   DEBUG: ------ Window Deinit (DialogBusy.xml) ------

Alles anzeigen

Aus meiner Sicht nichts Auffälliges am Ende, aber ich hab von Kodi-Logs nicht wirklich Ahnung.

Hat einer ne Idee, was ich prüfen/aktualisieren könnte?

Vielen Dank schonmal!

don

Zitat von ChrisK

bei aktiviertem VAAPI ist das Bild weiterhin schwarz.

Wenn es nicht benötigt wird, dann einfach aus lassen.

Hast du beim Abspielen schon probiert, de-interlace zu ändern?

Ursache und Wirkung sind mir hier auch nicht klar.
Kodi wurde zurückgesetzt. Kann vorkommen. Aber die Treiber für die Grafik/Video-Ecke sollten unberührt bleiben.
Steht evtl etwas verdächtiges in der settings.xml von Kodi?

ChrisK

Danke Dir für die Antwort!

Zitat von don

Wenn es nicht benötigt wird, dann einfach aus lassen.

Da ich diese Option nie bewusst aktiviert habe und auch garnicht weiß wofür sie ist, wüsste ich nicht wofür ich die brauchen sollte. Bleibt auch erstmal aus.

Zitat

Hast du beim Abspielen schon probiert, de-interlace zu ändern?

Ne, werde ich aber heute Abend mal machen.

Zitat

Ursache und Wirkung sind mir hier auch nicht klar.
Kodi wurde zurückgesetzt. Kann vorkommen. Aber die Treiber für die Grafik/Video-Ecke sollten unberührt bleiben.
Steht evtl etwas verdächtiges in der settings.xml von Kodi?

Ich verstehe den Zusammenhang auch nicht, aber anscheinend hilft das Deaktivieren der Option.
Meinst Du die "[definition='2','1']advancedsettings[/definition].xml"? Die sieht normal aus, eine settings.xml habe ich im userdata-Ordner nicht.

Gestern lief Kodi ohne Absturz durch. Entweder er hat sich gefangen oder das Problem zieht er beim Start mit wenn es Auftritt.

PvD

Die älteren Kodi-Versionen haben noch eine guisettings.xml. Wenn diese nicht (vollständig) geschrieben werden kann und danach defekt ist, erzeugt Kodi nach dem Start eine default-Datei, in der quasi die zurückgesetzen Werte bzw. der Anfangszustand drin steht.

VAAPI brauchst Du für die Ausgabe per Intel-GPU, VDPAU ist zuständig für die Ausgabe per nVidia-GPU. Nur eins von beiden sollte aktiv sein.

ChrisK

Zitat von BJ1

VAAPI brauchst Du für die Ausgabe per Intel-GPU, VDPAU ist zuständig für die Ausgabe per nVidia-GPU. Nur eins von beiden sollte aktiv sein.

Ich habe da ein ASRock B85M-ITX drin, d.h. das wird eine Intel-GPU sein und VAAPI sollte(?) aktiviert werden?

PvD

Das ist Intel (LGA1150). Die Ausgabe erfolgt per CPU (bzw. der integrierten GPU). Also eigentlich VAAPI. VDPAU musst Du deaktivieren.

ChrisK

Zitat von BJ1

Das ist Intel (LGA1150). Die Ausgabe erfolgt per CPU (bzw. der integrierten GPU). Also eigentlich VAAPI. VDPAU musst Du deaktivieren.

Danke, klappt nur leider nicht:

- VAAPI an, VDPAU aus: schwarzes Bild
- VAAPI an, VDPAU an: schwarzes Bild
- VAAPI aus, VDPAU an: korrektes Bild
- VAAPI aus, VDPAU aus: korrektes Bild

don

Ja, weil das TV-Signal nicht mehr (bei dir) per HW decodiert werden kann.
Das könnte mit deinterlace zusammenhängen.

ChrisK

Zitat von don

Ja, weil das TV-Signal nicht mehr (bei dir) per HW decodiert werden kann.
Das könnte mit deinterlace zusammenhängen.

Nachdem ich endlich herausgefunden habe, wo ich deinterlace aktiviere/deaktiviere (ganz schön versteckt für einen Laien, aber logisch da es ja pro Video eingestellt wird) habe ich VDPAU ausgeschaltet und VAAPI eingeschaltet, dann einen TV-Sender gestartet und in den Einstellungen Deintelace auf inaktiv gestellt -> schwarzes Bild

Dann wollte ich Deinterlace wieder aktivieren (also darauf zurücksetzen wo es war) -> Bild ist da.
Jetzt dachte ich, vielleicht hat das aus- und wieder einschalten geholfen. Beim nächsten Sender stürzte aber Kodi direkt ab. Neustart, TV-Sender ausgewählt -> direkt wieder Absturz.
Dann noch ein Neustart, VDPAU und VAAPI deaktiviert und jetzt geht es wieder.

Ich halte jetzt mal die Finger still, Hauptsache es läuft jetzt

ChrisK

Leider fällt das Finger stillhalten nicht so leicht, da dieser Punkt noch regelmäßig stört:

Zitat von ChrisK

Hinzu kommt, dass Kodi bei der Auswahl eines TV-Senders ein paar Mal komplett abgestürzt ist. Das ist früher nie passiert. Bei einem von drei Versuchen klappt es mit deaktiviertem VAAPI auch mit dem Live-TV.

Das passiert immer wieder mal, normalerweise nach längerem Stillstand (also KODI ist gestartet, es läuft aber weder Live-TV noch sonst irgendwas).
Dann stürzt KODI beim Versuch einen TV-Sender zu starten nach einer Sekunde komplett ab. Ich kann KODI neu starten und die ersten 2-3 Mal kommt es immer wieder zu einem Absturz (egal ob ich länger warte nach dem KODI-Start oder direkt einen TV-Sender starte).

Ich habe versucht in die KODI-Logs zu schauen und auch in die Kodibuntu-Logs, sehe dort aber (mit meinem nicht vorhanden Linux-Wissen) nichts auffälliges.
Welche Logs wären denn für die Analyse interessant? Dann könnte ich die mal rauskopieren.

ChrisK

Da der Crash beim TV-Start immer öfter vorkommt, versuche ich mal den kodilog zu verstehen.

Dieser Fehler hat vermutlich nichts mit dem Crash zu tun, aber je weniger Fehler im Log desto besser, oder?

Code

13:22:23.355 T:140235044579072 WARNING: script.module.metadatautils --> Traceback (most recent call last):
                                              File "/home/user/.kodi/addons/script.module.metadatautils/lib/helpers/utils.py", line 114, in get_json
                                                response = requests.get(url, params=params, timeout=20)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/api.py", line 72, in get
                                                return request('get', url, params=params, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/api.py", line 58, in request
                                                return session.request(method=method, url=url, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 508, in request
                                                resp = self.send(prep, **send_kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 640, in send
                                                history = [resp for resp in gen] if allow_redirects else []
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 218, in resolve_redirects
                                                **adapter_kwargs
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 618, in send
                                                r = adapter.send(request, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/adapters.py", line 506, in send
                                                raise SSLError(e, request=request)
                                            SSLError: HTTPSConnectionPool(host='www.thelogodb.com', port=443): Max retries exceeded with url: /api/json/v1/3241/tvchannel.php?s=WDR+HD+K%C3%B6ln (Caused by SSLError(SSLError(1, '_ssl.c:510: error:14077410:SSL routines:SSL23_GET_SERVER_HELLO:sslv3 alert handshake failure'),))
13:22:23.356 T:140235044579072   ERROR: script.module.metadatautils --> ERROR in helpers.utils ! --> HTTPSConnectionPool(host='www.thelogodb.com', port=443): Max retries exceeded with url: /api/json/v1/3241/tvchannel.php?s=WDR+HD+K%C3%B6ln (Caused by SSLError(SSLError(1, '_ssl.c:510: error:14077410:SSL routines:SSL23_GET_SERVER_HELLO:sslv3 alert handshake failure'),))
13:22:31.422 T:140235044579072 WARNING: script.module.metadatautils --> Traceback (most recent call last):
                                              File "/home/user/.kodi/addons/script.module.metadatautils/lib/helpers/utils.py", line 114, in get_json
                                                response = requests.get(url, params=params, timeout=20)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/api.py", line 72, in get
                                                return request('get', url, params=params, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/api.py", line 58, in request
                                                return session.request(method=method, url=url, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 508, in request
                                                resp = self.send(prep, **send_kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 640, in send
                                                history = [resp for resp in gen] if allow_redirects else []
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 218, in resolve_redirects
                                                **adapter_kwargs
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/sessions.py", line 618, in send
                                                r = adapter.send(request, **kwargs)
                                              File "/home/user/.kodi/addons/script.module.requests/lib/requests/adapters.py", line 506, in send
                                                raise SSLError(e, request=request)
                                            SSLError: HTTPSConnectionPool(host='www.thelogodb.com', port=443): Max retries exceeded with url: /api/json/v1/3241/tvchannel.php?s=wdr+k%C3%B6ln (Caused by SSLError(SSLError(1, '_ssl.c:510: error:14077410:SSL routines:SSL23_GET_SERVER_HELLO:sslv3 alert handshake failure'),))
13:22:31.422 T:140235044579072   ERROR: script.module.metadatautils --> ERROR in helpers.utils ! --> HTTPSConnectionPool(host='www.thelogodb.com', port=443): Max retries exceeded with url: /api/json/v1/3241/tvchannel.php?s=wdr+k%C3%B6ln (Caused by SSLError(SSLError(1, '_ssl.c:510: error:14077410:SSL routines:SSL23_GET_SERVER_HELLO:sslv3 alert handshake failure'),))

Alles anzeigen

Davon gibt es jede Menge im Log. Woran kann das liegen?

Wenn jemand eine Idee für das eigentliche Problem, dann natürlich gerne her damit. Auch Hinweise in welchen Logs ich gucken sollte sind herzlich willkommen.

don

Also der Fehler ist egal. Für mich steht da nur, dass das Modul keine verschlüsselte Verbindung zu thelogdb aufbauen kann.
Aber, was interessant daran ist: es wird probiert ein Logo (wdr) online zu beziehen. Sollte es daran liegen?
Wenn kein Logo vorhanden ist, sollte es einfach ignoriert werden.
Das ist jetzt aber auch nur ein "nach dem Strohhalm greifen".
Wahrscheinlicher ist irgendeine Energieoption in Ubuntu, da es anscheinend zeitabhängig ist.

ChrisK

Danke Dir @don!

Zitat von don

Wahrscheinlicher ist irgendeine Energieoption in Ubuntu, da es anscheinend zeitabhängig ist.

Ich habe jetzt mal testweise Live-TV über Nacht laufen lassen und am nächsten lief alles ok. Auch ein Senderwechsel war kein Problem.
Wenn Live-TV mal für ein paar Stunden nicht läuft (ich würde so 6-8 Stunden schätzen), dann ist das Standard-Prozedere:
- Sender auswählen
- Absturz
- Neustart Kodi
- Sender auswählen
- Absturz
- Neustart Kodi
- Sender auswählen
- Absturz
- Neustart Kodi
- Sender auswählen
- geht

Also spätestens beim dritten Mal geht es dann.
Nur leider überhaupt keine Anhaltspunkte in Log-Dateien, die ich so finden konnte.

Das Logo muss er wegen mir nicht suchen. Das müsste ich dann aber wahrscheinlich in TVHeadend abstellen. Ich gucke mal, dass ich dort was finde.

don

@CvH
Klingt das nicht irgendwie nach einem Treiber-Unload?
Dagegen spricht, dass es dann irgendwann doch wieder geht.

ChrisK

Kleines Update:
Ich habe mittlerweile auf der gleichen Platte parallel Ubuntu 18.04 installiert.
FHEM und TVHeadend ist soweit installiert und läuft. Kodi auch und beim Abspielen gibt es auch bisher keine Abstürze mehr.

Das wird jetzt mal ne Weile so laufen und wenn ich das Gefühl habe, dass mir nichts mehr fehlt, gucke ich dass ich die erste Partition mit Kodibuntu leere/lösche/verkleinere, was auch immer möglich ist.

Probleme nach Festplatten-Überfüllung

Jetzt mitmachen!

Benutzer online in diesem Thema