I’m facing an issue where PMS does not report any errors (as far as I can tell) but is not successfully opening port :32400 on my server either. Three changes have been yesterday, in order of execution:
- Moved
'PLEX_MEDIA_SERVER_APPLICATION_SUPPORT_DIR'to NAS via NFS mount. The plex systemd service is fooled via the override, as recommended in the knowledge base. Had an issue with permissions at first and learned the hard way that UID and GID had to be in sync for NFS mounts. Fixed via simple chmod/chown. Plex started successfully and GUI was available at this point.
- Added a
Custom Server Access URL(whilst Plex was successfully running). No visible errors in PMS GUI at this point. I do however think that this is what killed PMS after I restarted the PMS service later that day. Have not found a solution since. - Moved the
/mnt/Moviesandmnt/TV Showsshares from CIFS to NFS. I’ve noticed the error below today though so I’ve changed back to CIFS. GUI still not running correctly, unfortunately.
ERROR - Couldn’t check for the existence of file “/mnt/Movies/.grab”: boost::filesystem::status: Permission denied: “/mnt/Movies/.grab”
Because my guess is that the issue is in the second step (as GUI ran successfully after step 1, and step 3 is rolled back to original state), I checked to see where PMS settings are stored so I can edit it while GUI is offline. Seems to be in Preferences.xml, which by itself looks awfully clean compared to what I configured. Also noticed that permissions looked wrong, so did a chmod 777 (whilst PMS service was stopped). No change in there too, unfortunately.
Also tried connecting to PMS via app.plex.tv (instead of the bundled GUI) but because port 32400 is not listening at all for any inbound connections, the online GUI also cannot connect back to my PMS.
Connection requests from client (10.10.0.5) making it into the box on which PMS is running (10.10.4.6), and PMS responding, which is weird by itself because there is no socket open locally on IPv4/TCP4 if shown in netstat:
[root@plex Plex Media Server]# tcpdump -i ens160 'port 32400'
tcpdump: verbose output suppressed, use -v or -vv for full protocol decode
listening on ens160, link-type EN10MB (Ethernet), capture size 262144 bytes
20:18:19.012053 IP 10.10.0.5.60483 > 10.10.4.6.32400: Flags [S], seq 102296045, win 65535, options [mss 1460,nop,wscale 6,nop,nop,TS val 812714524 ecr 0,sackOK,eol], length 0
20:18:19.012075 IP 10.10.4.6.32400 > 10.10.0.5.60483: Flags [S.], seq 1113523644, ack 102296046, win 28960, options [mss 1460,sackOK,TS val 3206384 ecr 812714524,nop,wscale 7], length 0
20:18:19.012927 IP 10.10.0.5.60483 > 10.10.4.6.32400: Flags [.], ack 1, win 2058, options [nop,nop,TS val 812714525 ecr 3206384], length 0
20:18:19.012991 IP 10.10.0.5.60483 > 10.10.4.6.32400: Flags [P.], seq 1:518, ack 1, win 684, options [nop,nop,TS val 812714525 ecr 3206384], length 517
20:18:19.012997 IP 10.10.4.6.32400 > 10.10.0.5.60483: Flags [.], ack 518, win 235, options [nop,nop,TS val 3206385 ecr 812714525], length 0
20:18:19.013075 IP 10.10.4.6.32400 > 10.10.0.5.60483: Flags [F.], seq 1, ack 518, win 235, options [nop,nop,TS val 3206385 ecr 812714525], length 0
20:18:19.013907 IP 10.10.0.5.60483 > 10.10.4.6.32400: Flags [.], ack 2, win 752, options [nop,nop,TS val 812714525 ecr 3206385], length 0
20:18:19.013913 IP 10.10.0.5.60483 > 10.10.4.6.32400: Flags [F.], seq 518, ack 2, win 821, options [nop,nop,TS val 812714526 ecr 3206385], length 0
20:18:19.013916 IP 10.10.4.6.32400 > 10.10.0.5.60483: Flags [.], ack 519, win 235, options [nop,nop,TS val 3206386 ecr 812714526], length 0
20:18:27.198874 IP 10.10.0.5.60497 > 10.10.4.6.32400: Flags [S], seq 1420863808, win 65535, options [mss 1460,nop,wscale 6,nop,nop,TS val 812722815 ecr 0,sackOK,eol], length 0
20:18:27.198896 IP 10.10.4.6.32400 > 10.10.0.5.60497: Flags [S.], seq 49272545, ack 1420863809, win 28960, options [mss 1460,sackOK,TS val 3214571 ecr 812722815,nop,wscale 7], length 0
20:18:27.199722 IP 10.10.0.5.60497 > 10.10.4.6.32400: Flags [.], ack 1, win 2058, options [nop,nop,TS val 812722816 ecr 3214571], length 0
20:18:27.199951 IP 10.10.0.5.60497 > 10.10.4.6.32400: Flags [P.], seq 1:518, ack 1, win 684, options [nop,nop,TS val 812722816 ecr 3214571], length 517
20:18:27.199957 IP 10.10.4.6.32400 > 10.10.0.5.60497: Flags [.], ack 518, win 235, options [nop,nop,TS val 3214572 ecr 812722816], length 0
20:18:27.200019 IP 10.10.4.6.32400 > 10.10.0.5.60497: Flags [F.], seq 1, ack 518, win 235, options [nop,nop,TS val 3214572 ecr 812722816], length 0
Netstat showing that PMS GUI only appears to be running on IPv6:
[root@plex Plex Media Server]# netstat -ntlp | grep :324
tcp 0 0 127.0.0.1:32401 0.0.0.0:* LISTEN 3549/Plex Media Ser
tcp 0 0 0.0.0.0:32469 0.0.0.0:* LISTEN 3619/Plex DLNA Serv
tcp6 0 0 :::32400 :::* LISTEN 3549/Plex Media Ser
Awfully clean Preferences.xml:
[root@plex Plex Media Server]# cat Preferences.xml
<?xml version="1.0" encoding="utf-8"?>
<Preferences OldestPreviousVersion="1.16.5.1554-1e5ff713d" MachineIdentifier="9625dbc2-9d84-462b-b3de-ab6a0d1293a2" ProcessedMachineIdentifier="688d3ccad4264c6c95a7400174a5e784bb4d78cf" AnonymousMachineIdentifier="a132d6d1-b356-4371-a3b3-9b0cba34d07e" MetricsEpoch="1"/>
[root@plex Plex Media Server]# ls -l Preferences.xml
-rwxrwxrwx. 1 plex plex 310 Sep 1 19:20 Preferences.xml
No logs in Plex Media Server.log that look relevant:
[root@plex Plex Media Server]# cat /mnt/Plex/Library/Application\ Support/Plex\ Media\ Server/Logs/Plex\ Media\ Server.log | grep ":324"
Sep 01, 2019 19:55:14.071 [0x7f41abfff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414
Sep 01, 2019 19:55:14.072 [0x7f41abfff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410
Sep 01, 2019 19:55:14.072 [0x7f41abfff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412
Sep 01, 2019 19:55:14.072 [0x7f41abfff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413
Sep 01, 2019 19:55:15.500 [0x7f41a9ffb700] DEBUG - HTTP requesting POST https://plex.tv/devices/688d3ccad4264c6c95a7400174a5e784bb4d78cf/unclaimed?Connection[][uri]=http://10.10.4.6:32400&Connection[][uri]=http://10.10.10.6:32400
Sep 01, 2019 19:55:15.747 [0x7f41a9ffb700] DEBUG - HTTP 200 response from POST https://plex.tv/devices/688d3ccad4264c6c95a7400174a5e784bb4d78cf/unclaimed?Connection[][uri]=http://10.10.4.6:32400&Connection[][uri]=http://10.10.10.6:32400
Sep 01, 2019 19:55:17.508 [0x7f419cff9700] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Sep 01, 2019 19:55:18.727 [0x7f419cff9700] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as bf1bfc5622b4356c7f44ced8cdf69d934adf09c3
Sep 01, 2019 19:55:18.893 [0x7f41a97fa700] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
Sep 01, 2019 19:55:24.071 [0x7f41abfff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://10.10.10.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:24.071 [0x7f41abfff700] DEBUG - HTTP requesting GET http://10.10.10.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:24.071 [0x7f41abfff700] DEBUG - HTTP 200 response from GET http://10.10.10.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:24.072 [0x7f41abfff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://10.10.10.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:25.071 [0x7f41abfff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://10.10.4.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:25.071 [0x7f41abfff700] DEBUG - HTTP requesting GET http://10.10.4.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:25.072 [0x7f41abfff700] DEBUG - HTTP 200 response from GET http://10.10.4.6:32469/DeviceDescription.xml
Sep 01, 2019 19:55:25.072 [0x7f41abfff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://10.10.4.6:32469/DeviceDescription.xml
Plex service running
[root@plex Plex Media Server]# service plexmediaserver status
Redirecting to /bin/systemctl status plexmediaserver.service
● plexmediaserver.service - Plex Media Server
Loaded: loaded (/usr/lib/systemd/system/plexmediaserver.service; enabled; vendor preset: disabled)
Drop-In: /etc/systemd/system/plexmediaserver.service.d
└─override.conf
Active: active (running) since Sun 2019-09-01 19:55:12 CEST; 15min ago
Main PID: 3549 (Plex Media Serv)
CGroup: /system.slice/plexmediaserver.service
├─3549 /usr/lib/plexmediaserver/Plex Media Server
├─3572 Plex Plug-in [com.plexapp.system] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /usr/lib/plexmediaserver/Res...
├─3618 /usr/lib/plexmediaserver/Plex Tuner Service /usr/lib/plexmediaserver/Resources/Tuner/Private /usr/lib/plexmediaserver/Resources/Tuner/Shared 1.16.5.1554-1e5ff713d 32600 /waitmutex
├─3619 /usr/lib/plexmediaserver/Plex DLNA Server
├─3662 Plex Plug-in [com.plexapp.plugins.trakttv] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /mnt/Plex/Library/A...
└─3699 Plex Plug-in [com.plexapp.agents.imdb] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /usr/lib/plexmediaserve...
Sep 01 19:55:12 plex systemd[1]: Started Plex Media Server.
Sep 01 19:55:25 plex Plex Tuner Serv[3618]: Plex Tuner Service: Critical: libusb_init failed
[root@plex Plex Media Server]# ps aux | grep Plex
plex 3549 0.1 1.3 1022348 50968 ? Ssl 19:55 0:01 /usr/lib/plexmediaserver/Plex Media Server
plex 3572 0.2 1.2 918612 49028 ? SNl 19:55 0:02 Plex Plug-in [com.plexapp.system] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/System.bundle
plex 3618 0.0 0.2 542028 10720 ? Sl 19:55 0:00 /usr/lib/plexmediaserver/Plex Tuner Service /usr/lib/plexmediaserver/Resources/Tuner/Private /usr/lib/plexmediaserver/Resources/Tuner/Shared 1.16.5.1554-1e5ff713d 32600 /waitmutex
plex 3619 0.0 0.4 886752 17100 ? Sl 19:55 0:00 /usr/lib/plexmediaserver/Plex DLNA Server
plex 3662 6.1 4.1 1032004 160240 ? Sl 19:55 0:59 Plex Plug-in [com.plexapp.plugins.trakttv] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /mnt/Plex/Library/Application Support/Plex Media Server/Plug-ins/Trakttv.bundle
plex 3699 0.1 1.1 861412 44048 ? Sl 19:55 0:01 Plex Plug-in [com.plexapp.agents.imdb] /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/Framework.bundle/Contents/Resources/Versions/2/Python/bootstrap.py --server-version 1.16.5.1554-1e5ff713d /usr/lib/plexmediaserver/Resources/Plug-ins-1e5ff713d/PlexMovie.bundle
Do you happen to have any suggestions for me to look in to? Thank you in advance.

