[NAS->Kodi]Stream hängt, kurz Buffern, Ton vorraus, Bild Zeitraffer

stimmenhotel

Schönen guten Tag,

leider weiß Ich überhaupt nicht wo Ich bei meinem Problem ansetzen soll, noch wie Ich etwaige Logs erzeuge oder lesen kann.
Daher wende Ich mich nun an euch.

Zustand:
Ich schaue mit meinem WeTek Hub (LibreElec 8.8.8g) SD und HD Filme/Serien von meiner NAS (Synology DS916+).
Angebunden über Ethernet Cat5e. Freigaben über NFS auf der NAS freigegeben. (Einstellungen im Anhang)
NAS hat aktuelle Firmware (6.1), Dateisystem BTRFS, Synology-Hybrid-Raid.
Datenbank auf der WeTek, MediaInfo liegt auf der NAS.
FritzBox 7390 zwischen den Geräten.
Keine Hintergrundprozesse auf NAS oder Kodi.

Problem:
Starte Ich nun einen Film oder eine Serie (Größe spielt keine Rolle) fängt Kodi erstmal normal an zu spielen, keine Lags, kein Buffern.
Dann, nach unterschiedlichen Spielzeiten (1-10min) fängt das Drama an.
Das Bild und der Ton bleiben stehen, Ich kann Kodi weiter "bedienen", wenn Ich aber auf Pause oder auf Stop drücke, friert auch das UI ein.
Lasse Ich Kodi weiterlaufen, dauert es unterschiedlich lange (1-5min), kurzes Buffern, dann startet der Ton weit entfernt von der Stelle, an der er gestoppt ist und das Bild sprintet (Zeitraffer) dem Ton hinterher, bis es wieder synchron ist.
Das tritt dann immer wieder auf, in unterschiedlichen Zeitabständen...

Das Problem ist mit der Zeit, so fühlte es sich an, schlimmer geworden. LibreElec habe Ich bei dem letzten update (gestern) komplett neu installiert.
AMCodecs deaktiviert habe Ich probehalber auch schon, brachte aber keine Ergebnisse.

Hatte jemand dieses Problem selbst schon mal und kann mir bei dem beheben helfen?
Ich brauche auch, wie oben geschrieben, Hilfe bei der Erstellung der benötigten LOG-Dateien, sollte das nötig sein.
Irgendwelche Lösungsansätze, die Ich testen kann?

Danke

Mfg
Stimmenhotel

don

Wie sind denn deine Audio und Videosettings?
Und woran ist die WeTek angeschlossen?

muetzekoeln

Zitat von Stimmenhotel

FritzBox 7390 zwischen den Geräten

Ich fürchte die Fritzbox ist Dein Problem! Es gibt ein bekanntes Problem der Fritzbox mit bestimmten NAS-Systemen. Entweder Du suchst in den einschlägigen Foren mal oder Du setzt einen richtigen Ethernet-Switch dazwischen und guckst, ob das Problem verschwindet.

bennySB

siehe dazu auch diesen Thread hier:

Fritzbox Netzwerk zu langsam?

Da hat ein Switch auch geholfen oder aber du stellst die Ports an der Fritzbox auf 100MBit ein, aber das wird dann wieder zu wenig für die Streams sein.

stimmenhotel

Zitat von don

Wie sind denn deine Audio und Videosettings?
Und woran ist die WeTek angeschlossen?

Video- und Audiosettings habe Ich nach dem neu installieren nicht weiter angefasst.
Die WeTek hängt per HDMI am FHD-Fernseher, dieser wiederum per Headsphone-Out an meinem Verstärker (Alter 2.0 ohne HDMI).
Ethernet läuft wie vermutet über die Fritzbox.

Video-Settings:
HQ-Scaler ab 20%
amcodecs aktiv
mpeg2 immer beschläunigen
mpeg4 HD und besser
h264 immer

Anzeige:
1920x1080p/60Hz
Anzahl der verwendeten Buffer = 2

Audio:
ALSA: AML-M8Audio, PCM
2.0 Kanäle
Ausgabekonfi: Optimiert
Qualität der Anpassung Hoch
Passthrough: Deaktiviert

Ansonsten mache Ich jetzt erstmal einen Pingtest und schaue ob was verloren geht.
GB-Router müsste Ich erstmal organisieren, dauert dann etwas, bis Ich das ausprobieren kann. (Sofort einen kaufen will Ich nicht )

stimmenhotel

So, Pingtst durchgeführt, keine Probleme.
Iperf hat gute Übertragungsraten (~900Mbits)

Jedoch wohl einige Retr Einträge im Log.

iperf -c 192.168.178.31 -R -P 5 -t 500
Hier mal die Endsumme:

Code

- - - - - - - - - - - - - - - - - - - - - - - - -
[ ID] Interval           Transfer     Bandwidth
[  4]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[  4]   0.00-227.00 sec  4.97 GBytes   188 Mbits/sec                  receiver
[  6]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[  6]   0.00-227.00 sec  4.98 GBytes   188 Mbits/sec                  receiver
[  8]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[  8]   0.00-227.00 sec  5.02 GBytes   190 Mbits/sec                  receiver
[ 10]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[ 10]   0.00-227.00 sec  4.95 GBytes   187 Mbits/sec                  receiver
[ 12]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[ 12]   0.00-227.00 sec  4.96 GBytes   188 Mbits/sec                  receiver
[SUM]   0.00-227.00 sec  0.00 Bytes  0.00 bits/sec                  sender
[SUM]   0.00-227.00 sec  24.9 GBytes   942 Mbits/sec                  receiver

Alles anzeigen

Fazits aus dem anderen Thread:
Dort ging es um eine FritzBox <-> Switch Kombination, die Probleme macht. FB selbst ist also nicht das Problem, da Ich alles über die FB laufen lasse.
(Ich werde dennoch versuchen einen GB-Switch zu bekommen und provisorisch testen. Kann aber etwas dauern.)

don

Zitat von Stimmenhotel

Ausgabekonfi: Optimiert

Das könntest du noch auf FIX stellen.

stimmenhotel

Zitat von don

Das könntest du noch auf FIX stellen.

Aber das wird es ja sicher auch nicht sein...
Ist Optimiert nicht auch angepasst für meine Konfig? Besonders wenn die Quelle 5.1 nutzt?

Zur Zeit ist es wieder mal sehr sporadisch geworden, mit den Aussetzern ...
Wie logge Ich denn den Playback möglichst präzise? Dann kann Ich bei den nächsten Aussetzern evtl. ein brauchbares Log liefern.

don

Ein [definition=12,3]debug.[definition='1','0']log[/definition][/definition] sollte schon reichen.
Du kannst aber in den Settings auch noch spezifisches Logging aktivieren:

stimmenhotel

Zitat von don

Ein [definition=12,3]debug.[definition='1','0']log[/definition][/definition] sollte schon reichen.
Du kannst aber in den Settings auch noch spezifisches Logging aktivieren:

Welche von den Komponenten sollte aktivieren?
Video, Audio, FFMPEG? Für NFS finde Ich leider keine Komponente.

don

Äh.... stimmt
Habe ich auch noch nicht bemerkt, dass da nur SMB und DAV steht.
Vielleicht fällt NFS auch unter SMB.

stimmenhotel

Ich aktiviere mal die vier Features und schau mal beim nächsten Mal rein.
Sobald es aufgetreten ist neustarten um eine neue Log zu starten, bzw. die alte abzuschließen, oder?

Auszug aus der 01_Kodi.[definition='1','0']log[/definition] zur betreffenden Zeit. Um 22 Uhr (Punkt laut Kodi) fror das Bild ein, bzw habe Ich auf die Uhr geschaut.
Nach dem Fehler direkt Neustart gemacht.
Den Rest der Logs habe Ich durch geschaut, aber nichts zur Uhrzeit passendes gefunden.

Spoiler anzeigen

21:23:53.586 T:4116307968 NOTICE: VideoPlayer: OnResetDisplay received
21:59:32.149 T:3904893856 NOTICE: CVideoPlayerAudio::Process - stream stalled
22:00:07.291 T:4116307968 NOTICE: CVideoPlayer::CloseFile()
22:00:07.291 T:4116307968 NOTICE: VideoPlayer: waiting for threads to exit
22:00:07.298 T:4028621728 NOTICE: CVideoPlayer::OnExit()
22:00:07.298 T:4028621728 NOTICE: Closing stream player 1
22:00:07.298 T:4028621728 NOTICE: Waiting for audio thread to exit
22:00:07.300 T:3904893856 NOTICE: thread end: CVideoPlayerAudio::OnExit()
22:00:07.300 T:4028621728 NOTICE: Closing audio device
22:00:07.310 T:4028621728 NOTICE: Deleting audio codec
22:00:07.310 T:4028621728 NOTICE: Closing stream player 2
22:00:07.310 T:4028621728 NOTICE: waiting for video thread to exit
22:00:07.315 T:3730420640 NOTICE: thread end: video_thread
22:00:07.317 T:4028621728 NOTICE: deleting video codec
22:00:07.329 T:4028621728 NOTICE: Closing stream player 3
22:00:07.348 T:4116307968 NOTICE: VideoPlayer: finished waiting
22:00:07.382 T:4116307968 NOTICE: VideoPlayer: OnLostDisplay received
22:00:07.382 T:4116307968 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
22:00:07.382 T:4116307968 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
22:00:07.668 T:4028621728 ERROR: GetDirectory - Error getting
22:00:08.590 T:3730420640 NOTICE: WakeOnAccess [192.168.178.32] trigged by accessing : nfs://192.168.178.32/volume1/05_Filme/Izo - The world can never be changed (2004)/poster.jpg
22:00:08.619 T:3730420640 NOTICE: WakeOnAccess success exit, server already running
22:00:08.869 T:4116307968 NOTICE: VideoPlayer: OnResetDisplay received
22:00:08.869 T:4116307968 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
22:00:08.869 T:4116307968 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
22:00:08.897 T:4116307968 NOTICE: CVideoPlayer::CloseFile()
22:00:08.897 T:4116307968 NOTICE: VideoPlayer: waiting for threads to exit
22:00:08.897 T:4116307968 NOTICE: VideoPlayer: finished waiting
22:00:08.897 T:4116307968 NOTICE: CVideoPlayer::CloseFile()
22:00:08.897 T:4116307968 NOTICE: VideoPlayer: waiting for threads to exit
22:00:08.897 T:4116307968 NOTICE: VideoPlayer: finished waiting
22:00:09.232 T:3904893856 ERROR: Open - Error, could not open file nfs://192.168.178.32/volume1/05_Filme/Ein Ufo, dachte sie (2011)/Ein Ufo, dachte sie.avi
22:00:19.287 T:4116307968 NOTICE: Storing total System Uptime
22:00:19.296 T:4116307968 NOTICE: Saving settings
22:00:19.315 T:4116307968 NOTICE: Saving skin settings
22:00:19.317 T:4116307968 NOTICE: stop all
22:00:19.317 T:4116307968 NOTICE: stop player
22:00:19.317 T:4116307968 NOTICE: ES: Stopping event server
22:00:19.450 T:3873858464 NOTICE: ES: UDP Event server stopped
22:00:20.076 T:4116307968 NOTICE: stopping upnp
22:00:20.231 T:4116307968 NOTICE: stopping zeroconf publishing
22:00:20.245 T:4116307968 NOTICE: CWebServer[8080]: Stopped
22:00:20.246 T:4116307968 NOTICE: stop dvd detect media
22:00:20.937 T:4116307968 NOTICE: clean cached files!
22:00:21.311 T:4116307968 NOTICE: closing down remote control service
22:00:21.320 T:4116307968 NOTICE: stopped
22:00:21.534 T:4116307968 NOTICE: destroy
22:00:21.534 T:4116307968 NOTICE: unload skin
22:00:21.631 T:4116307968 WARNING: Cleanup: Having to cleanup texture lists/focus.png
22:00:21.660 T:4116307968 NOTICE: unload sections
22:00:21.725 T:4116307968 NOTICE: special://profile/ is mapped to: special://masterprofile/
22:00:21.729 T:4116307968 NOTICE: application stopped...

Uhm warte, muss Ich echt den [definition=12,7]Debug-Log[/definition] Screen-Overlay aktivieren?
Logt Kodi nicht auch so?
Hab noch mal in der Wiki nachgelesen, dort wird die Log über den Uploader oder über temp/[definition=9,2]kodi.[definition='1','0']log[/definition][/definition] gefunden. Meine Log kommt aber aus dem von außen sichtbaren Netzwerkpfad, ist das die selbe Log?

EDIT:
Hab das [definition=12,5][definition='1','3']Debug[/definition] Logging[/definition] nun mit der [definition='2','1']advancedsettings[/definition].xml aktiviert:
<[definition='2','1']advancedsettings[/definition]> <loglevel>1</loglevel></[definition='2','1']advancedsettings[/definition]>Warte nun auf den nächsten Aussetzter.

don

Error, could not open file nfs://192.168.178.32/volume1/05_Filme/Ein Ufo, dachte sie (2011)/Ein Ufo, dachte sie.avi
Das ist schon seltsam, wenn der Film sonst läuft. Ich verwende generell keine Satzzeichen in Dateinamen und auch keine Klammern etc.
Aber wenn der Film startet, dann sollte das keine Rolle spielen.

stimmenhotel

Geht ja nicht mal um den Film, weiß auch nicht warum Kodi da versucht auf zwei Filme zuzugreifen.
Hatte eben wieder einen Freeze, konnte leider nicht den Log uploaden...
Beim öffnen per Log Viewer abgestürzt und nach dem Neustart mit Log Uploader den neuen hochgeladen (Einstellungen -> Old Log) ...

don

Da wir bisher nur halbe logs hier haben, bitte 1x ein komplettes inkl Fehler.
Konkret fällt mir aber auch nichts ein.

stimmenhotel

Ich versuche es auch weiter... Blöd nur, wenn Kodi nicht so will wie Ich
Nächstes mal lade Ich den Log direkt hoch und schaue dann rein.
Kann man nicht auch über Netzwerk die Logs aus dem per Netzwerk freigegebenen Log Ordner raus ziehen? Und evtl. mehrere alte Logs zulassen?

don

Kopieren kannst du die jederzeit über das Netzwerk.
Aber erstellt wird immer nur das neue und das old.

stimmenhotel

So, heute habe Ich mal wieder versucht eine kleine Doku zu schauen.
Markante Stellen: 22:09 und 22:12
Aber eigentlich alle 2 Minuten. War seit Neuinstallation das schlimmste Verhalten.
Extra noch mal nachgeschaut, die Lib wird nicht nach dem starten aktualisiert.
Auf der Nas laufen auch keine weiteren Dienste.

Warum in der Log nun Einträge von 23:xx auftauchen kann Ich nicht verstehen.
Die Uhr auf der Wetek Box lief stetig richtig.
Es ist jetzt 22:17, keine 10 Minuten her, dass Ich das Log gezogen habe ...

Hier die 01_Kodi.[definition='1','0']log[/definition] als Rar-Archiv: https://drive.google.com/open?id=0Bz0f7…ODZmWXZUS291dWM
Gepackt 17MB, sost 316MB.

MediaInfo zum Film:

Spoiler anzeigen

Allgemein
Vollständiger Name : \\nas\08_Dokumentationen\Thorium, Atomkraft ohne Risiko (2016)\Thorium, Atomkraft ohne Risiko.mp4
Format : MPEG-4
Format-Profil : Base Media / Version 2
Codec-ID : mp42 (isom/mp42)
Dateigröße : 813 MiB
Dauer : 1 h 37 min
Modus der Gesamtbitrate : variabel
Gesamte Bitrate : 1 167 kb/s
Kodierungs-Datum : UTC 2016-09-25 07:10:44
Tagging-Datum : UTC 2016-09-25 07:10:44
gsst : 0
gstd : 5842465

Video
ID : 1
Format : AVC
Format/Info : Advanced Video Codec
Format-Profil : Main@L3.1
Format-Einstellungen für CABAC : Ja
Format-Einstellungen für ReFrames : 3 frames
Codec-ID : avc1
Codec-ID/Info : Advanced Video Coding
Dauer : 1 h 37 min
Bitrate : 1 038 kb/s
Breite : 1 280 Pixel
Höhe : 720 Pixel
Bildseitenverhältnis : 16:9
Modus der Bildwiederholungsrate : konstant
Bildwiederholungsrate : 25,000 FPS
ColorSpace : YUV
ChromaSubsampling/String : 4:2:0
BitDepth/String : 8 bits
Scantyp : progressiv
Bits/(Pixel*Frame) : 0.045
Stream-Größe : 723 MiB (89%)
Titel : ISO Media file produced by Google Inc.
Kodierungs-Datum : UTC 2016-09-25 07:10:44
Tagging-Datum : UTC 2016-09-25 07:10:44
colour_range : Limited
colour_primaries : BT.709
transfer_characteristics : BT.709
matrix_coefficients : BT.709

Audio
ID : 2
Format : AAC
Format/Info : Advanced Audio Codec
Format-Profil : LC
Codec-ID : 40
Dauer : 1 h 37 min
Bitraten-Modus : variabel
Bitrate : 126 kb/s
Kanäle : 2 Kanäle
Kanal-Positionen : Front: L R
Samplingrate : 44,1 kHz
Bildwiederholungsrate : 43,066 FPS (1024 spf)
Stream-Größe : 87,5 MiB (11%)
Titel : ISO Media file produced by Google Inc.
Kodierungs-Datum : UTC 2016-09-25 07:10:44
Tagging-Datum : UTC 2016-09-25 07:10:44

SkyBird1980

Stattlicher [definition='1','0']log[/definition]... O.o kannst du mal neu starten, den Fehler produzieren und DANN das Log posten? *g*

stimmenhotel

War gerade gestartet.
Habe jedoch auf anraten hin spezifische Komponenten mit aktiviert. (Video, Audio, FFMPEG)
Was mich jetzt aber wundert ...
Nachdem Ich die Logs gezogen habe, meinen Post geschrieben habe und noch etwas anderes gemacht habe ...
Läuft der Film jetzt reibungslos. (Ohne Neustart)

So richtig reproduzieren kann Ich den Fehler nicht.
Es tritt oft sporadisch auf.
Achja, wenn man vom Teufel spricht ... Jetzt gerade schon wieder ...

[NAS->Kodi]Stream hängt, kurz Buffern, Ton vorraus, Bild Zeitraffer

Jetzt mitmachen!

Benutzer online in diesem Thema