Plex Server (PMS) stürzt bei Zugriff über plex.tv ab

The World is Flat Deutsch - German
1

Hallo zusammen,
ich hatte früher einen Speedport (Telekom) und später eine EasyBox (Vodafone). Seit kurzem besitze ich nun eine Fritzbox 7490. Seit ich die Fritzbox in Betrieb habe, stürzt mein Plex Server ab, sobald über plex.tv darauf zugegriffen wird.

Kurios ist nun aber, dass der Absturz nur dann erfolgt, wenn die Webseite (plex.tv) außerhalb des heimischen Netzwerks aufgerufen wird. Innerhalb des lokalen Netzwerks arbeitet das System (Plex Server und plex.tv) ohne Probleme zusammen.

Ich habe testweise einen zweiten Server, auf einem iMac in Betrieb genommen und das selbe Problem dort feststellen können. Ich bekomme den Server erst nach mehreren, manuellen Starts des Servers wieder zum laufen. Stürzt er dann mal nicht ab, läuft er ohne Probleme. Das Verhalten habe ich aber erst, seit die Fritzbox in Betrieb ist.

Ich habe die Server App gelöscht und neu geladen, aber auch das half nicht.

Hat jemand schon mal ein ähnliches Problem gehabt oder vielleicht eine Lösung für mein Problem?
Die Fritzbox ist so konfiguriert, dass der Mac mini seine Ports selbständig, per UPnP an der Fritzbox freigeben kann. Außerdem wurde der Port für den Plex Server, nur zur Sicherheit, manuell vorgegeben.
Das Routing scheint ja auch ohne Probleme zu funktionieren, da ja der Stream, wenn er mal läuft, sauber funktioniert.
Auch das DNS-Rebind Problem wurde durch einen Eintrag in der Fritz-Konfig beseitigt. -> Ist ja auch kein Problem, da zuhause der Aufruf von plex.tv ohne Probleme funktioniert.

Hoffe sehr, einer von euch hat ne Idee.

Anbei die Konfig:
Plex Media Server: Version 1.11.1.4768

FritzBox Portweiterleitung

qitlren7udxt.png1031×125 31 KB

Plex Server
v0rzzng7i929.png1143×515 440 KB

Plex Server Test
2eusgnvis0jh.png1148×528 452 KB

2

UDP wird nicht benötigt für die Portweiterleitung.

Wie äußert sich der Serverabsturz?

3

Ich habe mich mit TeamViewer auf meine Maschine gewählt, um das zu prüfen.
Ich kann beobachten, dass der Server sofort beendet wird (Plex Icon verschwindet) sobald ich mich auf plex.tv eingeloggt habe und die Seit den ersten Kontakt zu den Mediatheken aufnimmt.

Das einzige was ich momentan in der Konsole finden konnte war dieser Abschnitt, der erscheint, in der Sekunde , in der der Server abstürzt.

! Feb 08, 2018 18:31:33.104 [0x700010f51000] DEBUG - Reading 148 bytes in the body, code is 200
! Feb 08, 2018 18:31:33.104 [0x700010f51000] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 50186.
! Feb 08, 2018 18:31:33.104 [0x700010f51000] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
! Feb 08, 2018 18:31:33.105 [0x700010f51000] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
! Feb 08, 2018 18:31:33.105 [0x700010f51000] DEBUG - HTTP requesting GET http://127.0.0.1:50186/:/plugins/com.plexapp.agents.imdb/prefs
! Feb 08, 2018 18:31:33.142 [0x700010f51000] DEBUG - HTTP 200 response from GET http://127.0.0.1:50186/:/plugins/com.plexapp.agents.imdb/prefs
! Feb 08, 2018 18:31:33.142 [0x700010f51000] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2340 bytes of content.
! Feb 08, 2018 18:31:33.142 [0x700010f51000] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 271c34f34911b73c772db8b0e317c86137a76dd3
! Feb 08, 2018 18:31:33.143 [0x700010f51000] DEBUG - IVA: Downloading trailer lists.
! Feb 08, 2018 18:31:33.833 [0x700010f51000] DEBUG - IVA: Downloaded 66 trailers for source 1.
! Feb 08, 2018 18:31:34.497 [0x700010f51000] DEBUG - IVA: Downloaded 81 trailers for source 2.
! Feb 08, 2018 18:31:40.053 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:31:40.054 [0x700010180000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:31:47.544 [0x70000fe6e000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:31:50.063 [0x70000fe6e000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:31:50.064 [0x700010180000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:31:50.818 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:31:50.818 [0x70001007a000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:00.064 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:00.065 [0x7000100fd000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:32:00.819 [0x70000fe6e000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:08.502 [0x700010180000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:08.535 [0x7000100fd000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:09.801 [0x700010180000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:09.833 [0x7000100fd000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:10.072 [0x70001007a000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:10.072 [0x700010180000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:32:10.827 [0x70001007a000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:17.552 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:20.081 [0x70001007a000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:20.082 [0x7000100fd000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:32:20.194 [0x700010180000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:20.821 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:20.827 [0x70001007a000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:21.143 [0x7000100fd000] ERROR - Couldn’t accept new TLS connection: Invalid argument
! Feb 08, 2018 18:32:28.476 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:1093 in time, dropping connection.
! Feb 08, 2018 18:32:28.568 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:39393 in time, dropping connection.
! Feb 08, 2018 18:32:28.601 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54303 in time, dropping connection.
! Feb 08, 2018 18:32:28.634 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54304 in time, dropping connection.
! Feb 08, 2018 18:32:28.666 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54305 in time, dropping connection.
! Feb 08, 2018 18:32:28.711 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:1085 in time, dropping connection.
! Feb 08, 2018 18:32:28.781 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54308 in time, dropping connection.
! Feb 08, 2018 18:32:28.804 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54309 in time, dropping connection.
! Feb 08, 2018 18:32:29.568 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54335 in time, dropping connection.
! Feb 08, 2018 18:32:29.601 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54336 in time, dropping connection.
! Feb 08, 2018 18:32:29.629 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:8041 in time, dropping connection.
! Feb 08, 2018 18:32:29.663 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:63674 in time, dropping connection.
! Feb 08, 2018 18:32:29.691 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:27403 in time, dropping connection.
! Feb 08, 2018 18:32:29.732 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:52732 in time, dropping connection.
! Feb 08, 2018 18:32:29.763 [0x700010180000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:54341 in time, dropping connection.
! Feb 08, 2018 18:32:29.862 [0x7000100fd000] VERBOSE - We didn’t receive any data from ::ffff:62.xxx.xxx.xx1:40765 in time, dropping connection.
! Feb 08, 2018 18:32:30.090 [0x70000fdeb000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:30.092 [0x700010180000] VERBOSE - WebSocket: processed 1 frame(s)
! Feb 08, 2018 18:32:30.830 [0x70000fe6e000] DEBUG - Thread: Running async task started by thread 0x70000fc62000.
! Feb 08, 2018 18:32:38.856 [0x700010180000] DEBUG - Job running: ‘/Applications/Plex Media Server.app/Contents/MacOS/CrashUploader’ ‘–directory=/Users/christian/Library/Application Support/Plex Media Server/Crash Reports/1.11.1.4768-338ac2b75’ ‘–version=1.11.1.4768-338ac2b75’ ‘–platform=darwin-x86_64 (10.12.6)’ ‘–serverUuid=503882xxxxxxxxxxxxxxxxxxxxxxxe58f28b18’ ‘–userId=rxxxxxxxxxx@xxx.de’ ‘–url=https://crashreport.plexapp.com’
! Feb 08, 2018 18:32:38.859 [0x700010180000] VERBOSE - Jobs: Starting child process with pid 20559

4

Bitte nicht die ‘Ausführlichen’ Logs aktivieren. Die ersäufen nur die wirklich wichtigen Informationen mit Nebensächlichkeiten.

Hast du inzwischen das UDP Protokoll bei den Weiterleitungen rausgenommen?

Einstellungen - Server - Netzwerk - IPv6 = deaktivieren

Einstellungen - Server - DLNA = deaktivieren

Danach Server neu starten

Läuft auf der Fritzbox ein Antivirus-Check?

Welcher Internet-Provider wird benutzt?

5

Und ich dachte mir, das erweiterte Log würde helfen :#
Ich habe nun die UDP Weiterleitung für den Port 32400 gelöscht.
Die IPv6 Unterstützung deaktiviert.
Der DLNA Dienst von Plex ist eh deaktiviert, da ich Plex Home nutze.

Im Logbuch finde ich nur diese beiden Zeilen, die in der Sekunde erscheinen, wenn der Server aus geht.
Ich sitze hier (Remote) hinter einer Palo Alto Firewall mit deep packet inspection und SSL-Intercept. Bisher hat es allerdings ohne Probleme funktioniert und ein Bekannter neben mir hat keine Probleme mit seinem Plex.

Feb 08, 2018 19:22:10.970 [0x700010413000] ERROR - Couldn’t accept new TLS connection: Invalid argument
Feb 08, 2018 19:22:15.505 [0x700010390000] ERROR - Exception getting remote address: remote_endpoint: Invalid argument

Auf der Fritzbox läuft kein Antivirus.
Der ISP ist Vodafone - VDSL 50

Der Fehler tritt nach den Konfigänderungen immer noch auf.

6

@cHr15714N said:
Ich sitze hier (Remote) hinter einer Palo Alto Firewall mit deep packet inspection und SSL-Intercept. Bisher hat es allerdings ohne Probleme funktioniert und ein Bekannter neben mir hat keine Probleme mit seinem Plex.

Dann ist der Fall klar. SSL-intercept decodiert die verschlüsselten Pakete, inspiziert sie und encodiert sie dann wieder mit dem ‘intermediate’ Zertifikat des Firewall Herstellers.
Damit ist das Paket ungültig, da die Absenderadresse und das Zertifikat nicht zusammen passen.
Bei Plex wird striktes Zertifikat-Pinning betrieben.

7

Das würde ich dir auch sofort abnehmen, wenn da nicht mein Bekannter wäre.
Der sitzt nämlich bei mir und hinter der selben Firewall und hat nicht dieses Problem.
Zumal das Problem erst seit letzter Woche auftritt und nachdem ich die Fritzbox als meinen neuen Router im Einsatz habe.

Gut, könnte nun ein blöder Zufall sein, aber dann wäre immer noch mein Bekannter…
Ich werde Freitag/Samstag von einem anderen Standort aus den Zugriff versuchen (ohne mächtige Firewall).
Sollte es da gehen, liegt das Problem wohl tatsächlich am SSL Intercept (auch wenn dies vor drei Wochen noch kein Problem war).

8

Dein Bekannter könnte ‘Sichere Verbindungen’ und damit TLS bei seinem Server abgeschaltet haben.

9

Frag doch den Bekannten mal was er eingestellt hat.

Otto weiss schon warum er das vorschlägt :slight_smile: Plex erwartet eine Ende-zu-Ende Verschlüsselung zwischen Client und Server, die Dank dem SSL-intercept nicht gegeben ist. Für mich ein fragwürdiges Feature.

10

Also der Zwischenstand meines Tests:
Von einem andern Anschluss (Heimanschluss DSL Telekom) funktioniert der Aufruf ohne Probleme.
Über das Mobilfunknetz (Vodafone) funktioniert der Aufruf der Plex Web App ebenfalls ohne einen Server Absturz.

Anschließend habe ich die Option “Sichere Verbindung” auf “Deaktiviert” gesetzt und das ganze nochmals getestet. Brachte aber das selbe Problem. Der Server stürzt wieder ab.

11

@kopfpilot said:
Frag doch den Bekannten mal was er eingestellt hat.

Otto weiss schon warum er das vorschlägt :slight_smile: Plex erwartet eine Ende-zu-Ende Verschlüsselung zwischen Client und Server, die Dank dem SSL-intercept nicht gegeben ist. Für mich ein fragwürdiges Feature.

Wir haben bereits alles abgeglichen.
Fritzbox Einstellungen und Plex Server Einstellungen.
Wir benutzen die selben Ports in der Portweiterleitung und haben bei den relevanten Servereinstellungen die selben Optionen.
Es ist ja nicht so, dass es noch nie funktioniert hätte. Seit Einführung der Firewall hatte ich das SSL-Intercept Zertifikat, dass sich zwischen meinen Plex Server und meinen Client geschaltet hat und es hat ohne Probleme funktioniert. Außerdem sollte mein Bekannter/Kollege ja nach dieser Theorie das selbe Problem haben wie ich, denn er sitzt hinter der selben Firewall.

12

@cHr15714N said:
Anschließend habe ich die Option “Sichere Verbindung” auf “Deaktiviert” gesetzt und das ganze nochmals getestet. Brachte aber das selbe Problem. Der Server stürzt wieder ab.

Hast du den Server neu gestartet nach der Umstellung? Das ist erforderlich, sonst wird das nicht übernommen.

13

Ja, sogar mehrfach, da er sich immer wieder beendet hat :smiley:

14

Da wird es wohl ein vollständiges Log-Archiv brauchen.

Achte auch auf den Ordner
C:\Users\<username>\AppData\Local\Plex Media Server\Crash Reports

15

Ich nutze einen Mac, denke aber, dass ich das entsprechende Verzeichnis gefunden habe.
Müsste dieses hier sein:
/Volumes/christian/Library/Application Support/Plex Media Server/Crash Reports
Allerdings sind die Verzeichnisse leer.

16

Dann bin ich ratlos. Vor allem ohne Log files.

17

Wenn der Server abstürzt, Startest du diesen dann selbst wieder oder macht der das selbst?

Ich kenne das Phänomen das wenn der PMS sich selbst wieder Startet (Auf nem NAS tut er das) er die Crash logs löscht…

Du kommt an diese Crash logs nur ran wenn der Server nach dem Crash AUS bleibt…

18

Nein der Server startet nicht selber neu.
Ich muss ihn immer von Hand starten.
Ich werde das morgen mal testen, wenn ich Zeit habe.

19

Ich hab heute nochmals nach den Logfiles gewühlt… Im Crashreport Verzeichnis findet sich zwar ein Unterordner, der auch die Uhrzeit des Absturzes hat, aber ohne Inhalt. In den Konsolenlogs findet sich der Vermerk, dass der Crashreport an https://crashreport.plex.tv gesendet wurde.
Außerdem finde ich die folgende Zeile über den Absturz:

Feb 12 21:04:52 Plex-Mac-mini com.apple.xpc.launchd[1] (com.plexapp.plexmediaserver.15200[380]): Service exited with abnormal code: 5

Und dann noch diese Zeilen des PMP, der ebenfalls auf dem Server läuft und erkannt hat, dass sein Server offline gegangen ist. Mehr gibt es aber definitiv zu dieser Uhrzeit nicht.

2018-02-12 21:04:52 [ INFO ] JS: [MediaServerEventManager] Unexpectedly closed server event connection to Plex Mac mini
2018-02-12 21:04:52 [ INFO ] JS: [Connections] Retrying server events connection for Plex Mac mini in 1 seconds
2018-02-12 21:04:52 [ INFO ] JS: [Connections] Possible connection error for Plex Mac mini, retrying connection tests
2018-02-12 21:04:52 [ INFO ] JS: [Connections] Testing all 3 connection(s) for Plex Mac mini
2018-02-12 21:04:52 [ INFO ] JS: [Servers] Found all servers = Plex Mac mini, Cloud Sync, plex.tv
2018-02-12 21:04:52 [ INFO ] JS: [Commands] Executing testServerConnection
2018-02-12 21:04:52 [ INFO ] JS: [Commands] Executing testServerConnection
2018-02-12 21:04:52 [ INFO ] JS: [Commands] Executing testServerConnection

Alles sehr merkwürdig…
Falls noch jemand ne Idee hat, ich wäre sehr dankbar.

Ansonsten werde ich bei Gelegenheit meinen vorherigen EasyBox-Router anschließen.
Sollte es gar nicht klappen, dann werd ich wohl doch meinen Mac platt machen und neu installieren. :confused:

20

Hab das gleiche Problem. Beim Remotezugriff stürzt PMS einfach manchmal ab. Hab das Problem schon seit Monaten. Manchmal mehr, manchmal weniger.
Gleiche Fehler im Log:

Hab ich hier gerade gepostet:
https://forums.plex.tv/discussion/310202/plex-crashed-randomly-a-few-times-a-week-on-mac