PMS continues to automatically stop. Using Synology DSM

@whatisrouter

Would you be willing to conduct an experiment?

I suggest this because, while we can optimize the database manually, PMS terminating after 15-20 seconds is extremely severe.

Specifically, I’d like to test PMS itself minus your existing data.

  1. Open FileStation
  2. Navigate to PlexMediaServer
  3. Rename AppData → AppData.KEEP
  4. Create Folder AppData
  5. Right-click AppData → Properties
  6. Make certain “System Internal User” PlexMediaServer has Read/Write access
  7. Now start PMS
  8. Let sit completely idle until the CPU calms down (it’s performing first-run setup)
  9. When the CPU is idle,
    – Open an incognito browser window
    – Open PMS by the NAS LAN IP http://ip.addr.of.syno:32400/web
    – Start a normal setup of PMS
    HOWEVER
    – Give it a FRIENDLY NAME which won’t conflict with your existing (“TEST SERVER” ?)
    – Now add some media sections and let it build.
  10. When building is complete,
  11. Check the settings (replicate what you had before)
  12. Test it.

If it runs without incident then we’ve encountered a deep database error.
There is no remedy for that.

What we can do is move your watched history from the old to the new if you wish.

Once I’ve gotten to step 9 – Start a normal setup of PMS
I open plex web and the player loads but doesn’t prompt to setup a new server. It tries to load the old libraries but it’s unable to locate the media, ie the libraries are greyed out with a hazard symbol.

I feel like I’m doing something wrong

To confirm, at this stage

– Start a normal setup of PMS

I’m opening the synology NAS in an incognito browser and then loading plex from within the synology.

Thanks again

If it’s worst case and I need to rebuild the database from scratch, can I also keep music data history? tags, number of plays, star history etc etc

  1. Sounds like you forgot Incognito. If not, then Sign-out of Plex/web

  2. make certain you’re on the same subnet as the server (no remote or FQDN references). you need to be on 192.168.1.x

  3. When you open with Incognito:
    a. It will have you sign in.
    b. Knowing there is no known server (because we renamed the folder),
    c. YOu’ll be greeted by “Got It”
    d. Then start the setup wizard

Yes, we can pull all your watch history from instance to instance.

I’m definitely following all of those steps, incognito, 192.198…syno.svr… logging out, renaming AppData etc but I’m coming to the same conclusion when hitting sign in via chrome incognito.

It automatically signs into my plex account and takes me to home where the libraries are greyed out.

There should be no names in what you’re typing.

Also, to confirm, 192.168.x.x (not 192.198.x.x)

I would like you to stop Plex,
Use FileStation to navigate into PlexMediaServer/AppData/Plex Media Server
Right-click “Logs” → “Compress to Logs.zip”

Download then attach that ZIP file here.

ok, I managed to actually type the ip address correctly and go thru the earlier steps 1 thru 12
The music is playing without any issues
I’ve attached the logs from today here

Thanks!
Logs (1).zip (463.8 KB)

Hi, are you able to help any further with this one?
Thanks again

@whatisrouter

Sorry for the delay. I didn’t receive the first notification.

Unfortunately, I can’t use these logs. DEBUG logging has been turned off.
I don’t see any activity. I only see info, warn, or error.

Can you please recreate with DEBUG turned on?

Logs.zip (1.6 MB)
done and done
thanks

Can you share what 192.168.1.71 and 192.168.1.83 are ?

Those devices are spitting errors (Connection reset by peer).
(56, Failure when receiving data from the peer) (Recv failure: Connection reset by peer)

Please also confirm this is the Syno address?

Jul 02, 2022 09:05:27.275 [0x7fdfd9adf0d0] DEBUG - Detected primary interface: 192.168.1.100
Jul 02, 2022 09:05:27.275 [0x7fdfd9adf0d0] DEBUG - Network interfaces:
Jul 02, 2022 09:05:27.275 [0x7fdfd9adf0d0] DEBUG -  * 1 lo (127.0.0.1) (00-00-00-00-00-00) (loopback: 1)
Jul 02, 2022 09:05:27.275 [0x7fdfd9adf0d0] DEBUG -  * 3 eth0 (192.168.1.100) (00-11-32-89-69-81) (loopback: 0)
Jul 02, 2022 09:05:27.275 [0x7fdfd9adf0d0] DEBUG -  * 1 lo (::1) (00-00-00-00-00-00) (loopback: 1)
Jul 02, 2022 09:05:27.276 [0x7fdfd9adf0d0] DEBUG -  * 3 eth0 (2001:569:737d:4800:211:32ff:fe89:6981) (00-11-32-89-69-81) (loopback: 0)

192.168.1.83 is a telus wifi extender but I can’t identify …71. Do you have a trick to find the device name of the device on the network using the ip or mac address?

192.168.1.100 is the syno address
Thanks

@whatisrouter

nmap ip.address.of.device

Here is the output querying my NAS

[chuck@lizum ~.2001]$ nmap 192.168.0.20
Starting Nmap 7.80 ( https://nmap.org ) at 2022-07-08 20:26 EDT
Nmap scan report for glock (192.168.0.20)
Host is up (0.00011s latency).
Not shown: 990 closed ports
PORT     STATE SERVICE
22/tcp   open  ssh
111/tcp  open  rpcbind
139/tcp  open  netbios-ssn
445/tcp  open  microsoft-ds
514/tcp  open  shell
2049/tcp open  nfs
3551/tcp open  apcupsd
8181/tcp open  intermapper
8443/tcp open  https-alt
9090/tcp open  zeus-admin

Nmap done: 1 IP address (1 host up) scanned in 0.06 seconds
[chuck@lizum ~.2002]$

If I query the entire subnet:

sudo nmap -v -sn 192.168.0.0/24
<some entries redacted>
MAC Address: 00:17:88:B3:3D:18 (Philips Lighting BV)
Nmap scan report for 192.168.0.187
Host is up (0.075s latency).
MAC Address: 7C:25:DA:12:A9:1B (Unknown)
Nmap scan report for 192.168.0.200
Host is up (0.00033s latency).

This is for a Linux host. Synology doesn’t have nmap loaded (not needed).
If you’re using Windows or Mac, I’m sure there’s an equivalent.

Found it. It’s the 2nd of two Telus wifi boosters

Thanks… I’ve filtered out all that now.

I’m seeing something coming in over quickconnect (the hyperlink in package center).

Jul 02, 2022 09:05:40.465 [0x7fdfd5a60b38] DEBUG - Request: [192.168.1.89:55903 (Subnet)] GET / (21 live) GZIP
Jul 02, 2022 09:05:40.465 [0x7fdfd5a60b38] DEBUG - Completed: [192.168.1.89:55855] 200 GET /web/static/362b56e7c69551249027.woff (21 live) GZIP 1ms 63712 bytes (pipelined: 8)
Jul 02, 2022 09:05:40.466 [0x7fdfd5a60b38] DEBUG - Completed: [192.168.1.89:55903] 401 GET / (21 live) GZIP 0ms 435 bytes
Jul 02, 2022 09:05:40.466 [0x7fdfd5a1bb38] DEBUG - Request came in with unrecognized domain / IP '192-168-1-100.dbooty.direct.quickconnect.to' in header Host; treating as non-local
Jul 02, 2022 09:05:40.466 [0x7fdfd5a1bb38] DEBUG - Request: [192.168.1.89:55851 (Subnet)] GET /media/providers (21 live) GZIP
Jul 02, 2022 09:05:40.466 [0x7fdfd5a1bb38] DEBUG - Completed: [192.168.1.89:55851] 401 GET /media/providers (21 live) GZIP 0ms 357 bytes
Jul 02, 2022 09:05:40.467 [0x7fdfd5a60b38] DEBUG - Completed: [192.168.1.89:55904] 200 GET /web/js/chunk-441-b1989358201caee3597d-plex-4.76.1.22469-3117cd9.js (19 live) GZIP 47ms 648797 bytes (pipelined: 7)
Jul 02, 2022 09:05:40.532 [0x7fdfd5a1bb38] DEBUG - Request came in with unrecognized domain / IP '192-168-1-100.dbooty.direct.quickconnect.to' in header Host; treating as non-local
Jul 02, 2022 09:05:40.533 [0x7fdfd5320b38] DEBUG - Request: [192.168.1.89:55907 (Subnet)] GET /identity (19 live) GZIP

This is your workstation?

Notice the permission denied (http 401 errors)

because of what Synology did in DSM 7 with this, I would like to recommend you make a browser bookmark. In that bookmark, open plex by http://ip.addr.of.syno:32400/web. It works and avoids the problems with QuickConnect.

I’m still looking and don’t see anything which could cause the failure.

The next time it stops, would you please grab the logs while it’s still down?

Logs (2).zip (2.5 MB)
New logs attached.
The logs from the previous post were from the new test appdata folder which is why there may not have been much to report.
These are from the original appdata folder which has just been run and crashed instantly

Thanks again

@whatisrouter

In these logs, what you’re showing me is the server is running but the certificate is all broken up. It can’t talk to anything nor anything to it.

Jul 08, 2022 21:43:00.344 [0x7faf7124eb38] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.90]:60491: sslv3 alert certificate unknown
Jul 08, 2022 21:43:00.392 [0x7faf7124eb38] WARN - [CERT] TLS connection from [2001:569:737d:4800:c5fd:418d:58:c90c]:60492 came in with unrecognized plex.direct SNI name '2001-0569-737d-4800-0211-32ff-fe89-6981.d2390fe8effd4757adc69d7ee1761c13.plex.direct'; using installed plex.direct cert
Jul 08, 2022 21:43:00.398 [0x7faf71271b38] DEBUG - CERT: incomplete TLS handshake from [2001:569:737d:4800:c5fd:418d:58:c90c]:60492: sslv3 alert certificate unknown
Jul 08, 2022 21:43:11.325 [0x7faf7124eb38] WARN - [CERT] TLS connection from [::ffff:192.168.1.90]:60518 came in with unrecognized plex.direct SNI name '192-168-1-100.d2390fe8effd4757adc69d7ee1761c13.plex.direct'; using installed plex.direct cert
Jul 08, 2022 21:43:11.331 [0x7faf71271b38] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.90]:60518: sslv3 alert certificate unknown
Jul 08, 2022 21:43:11.342 [0x7faf71271b38] WARN - [CERT] TLS connection from [2001:569:737d:4800:c5fd:418d:58:c90c]:60519 came in with unrecognized plex.direct SNI name '2001-0569-737d-4800-0211-32ff-fe89-6981.d2390fe8effd4757adc69d7ee1761c13.plex.direct'; using installed plex.direct cert
Jul 08, 2022 21:43:11.348 [0x7faf7124eb38] DEBUG - CERT: incomplete TLS handshake from [2001:569:737d:4800:c5fd:418d:58:c90c]:60519: sslv3 alert certificate unknown
Jul 08, 2022 21:43:13.709 [0x7faf7070cb38] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.71:45514/agent.xml
Jul 08, 2022 21:43:13.709 [0x7faf7070cb38] DEBUG - HTTP requesting GET http://192.168.1.71:45514/agent.xml
Jul 08, 2022 21:43:13.711 [0x7faf70fb1b38] WARN - [HttpClient] HTTP error requesting GET http://192.168.1.71:45514/agent.xml (56, Failure when receiving data from the peer) (Recv failure: Connection reset by peer)
Jul 08, 2022 21:43:13.711 [0x7faf7070cb38] ERROR - SSDP: Error parsing device schema for http://192.168.1.71:45514/agent.xml
Jul 08, 2022 21:43:13.711 [0x7faf7070cb38] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.83:45514/agent.xml
Jul 08, 2022 21:43:13.711 [0x7faf7070cb38] DEBUG - HTTP requesting GET http://192.168.1.83:45514/agent.xml
Jul 08, 2022 21:43:13.714 [0x7faf70fb1b38] WARN - [HttpClient] HTTP error requesting GET http://192.168.1.83:45514/agent.xml (56, Failure when receiving data from the peer) (Recv failure: Connection reset by peer)
Jul 08, 2022 21:43:13.714 [0x7faf7070cb38] ERROR - SSDP: Error parsing device schema for http://192.168.1.83:45514/agent.xml
Jul 08, 2022 21:43:19.504 [0x7faf71271b38] DEBUG - Completed: [192.168.1.90:60367] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) TLS GZIP 20000ms 5 bytes (pipelined: 10)
Jul 08, 2022 21:43:19.513 [0x7faf705c1b38] DEBUG - Request: [192.168.1.90:60367 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) TLS GZIP Signed-in Token (Mr Daddy) (Chrome)
Jul 08, 2022 21:43:19.513 [0x7faf705c1b38] DEBUG - Content-Length is -1 (of total: -1).
Jul 08, 2022 21:43:23.939 [0x7faf705c1b38] DEBUG - Request: [192.168.1.80:59346 (Subnet)] GET /media/providers (6 live) GZIP Signed-in Token (Mr Daddy) (Chrome)
Jul 08, 2022 21:43:23.975 [0x7faf7124eb38] DEBUG - Completed: [192.168.1.80:59346] 200 GET /media/providers (6 live) GZIP 36ms 5382 bytes (pipelined: 1)
Jul 08, 2022 21:43:23.993 [0x7faf7124eb38] WARN - [CERT] TLS connection from [::ffff:192.168.1.80]:59347 came in with unrecognized plex.direct SNI name '192-168-1-100.d2390fe8effd4757adc69d7ee1761c13.plex.direct'; using installed plex.direct cert
Jul 08, 2022 21:43:23.997 [0x7faf71271b38] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.80]:59347: sslv3 alert certificate unknown
Jul 08, 2022 21:43:24.351 [0x7faf71271b38] WARN - [CERT] TLS connection from [2001:569:737d:4800:58fd:9162:6e48:b8fd]:59350 came in with unrecognized plex.direct SNI name '2001-0569-737d-4800-0211-32ff-fe89-6981.d2390fe8effd4757adc69d7ee1761c13.plex.direct'; using installed plex.direct cert
Jul 08, 2022 21:43:24.355 [0x7faf7124eb38] DEBUG - CERT: incomplete TLS handshake from [2001:569:737d:4800:58fd:9162:6e48:b8fd]:59350: sslv3 alert certificate unknown
Jul 08, 2022 21:43:29.826 [0x7faf71271b38] DEBUG - WebSocket: client initiated closeWe can try this with the saved AppData.  (swap things around)
Jul 08, 2022 21:43:29.826 [0x7faf71271b38] DEBUG - handleStreamRead code 2: End of file
Jul 08, 2022 21:43:29.826 [0x7faf71271b38] DEBUG - NotificationStream: Removing because of error
Jul 08, 2022 21:43:29.826 [0x7faf71271b38] DEBUG - Completed after connection close: [192.168.1.90:60397] -3 GET /:/websockets/notifications (8 live) GZIP 74234ms 21 bytes
Jul 08, 2022 21:43:39.514 [0x7faf7124eb38] DEBUG - Completed: [192.168.1.90:60367] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) TLS GZIP 20001ms 5 bytes (pipelined: 11)
Jul 08, 2022 21:43:43.710 [0x7faf70e9eb38] DEBUG - player hpp88xrj7krss1jw7amfnbvp was last refreshed 10 seconds ago
Jul 08, 2022 21:43:43.712 [0x7faf7070cb38] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.71:45514/agent.xml
Jul 08, 2022 21:43:43.712 [0x7faf7070cb38] DEBUG - HTTP requesting GET http://192.168.1.71:45514/agent.xml

We can do this in two steps or one. I prefer two steps but will do as you request.

We can try this with the saved AppData. (swap things around)

  1. We reset your certificate at Plex.tv and you restart the server.
  2. We stop PMS, delete the certificate it has locally, start PMS and let it download it again.

Just to confirm Saved appData, are you referring to the folder and files related to the original issue?

I’m happy to follow what you suggest. Do you mind giving instructions on how to deal with the certificate

Thanks

Please correct me if I’m wrong .

I thought we had saved the original AppData and then created this new test instance.

If that’s true, and the logs you just gave me are from that original,

  1. I don’t see or remember where we fixed the original certificate issue
  2. I am confused about what the current state of the server is. I don’t see any evidence of it failing… except what I saw in the previous set of logs.

Yes you’re correct. The last set of logs I uploaded (filename Logs (2).zip) are from the original AppData folder and not the AppData test scenario we ran.

Is the next step to reset the certificate as you suggested?

  1. We reset your certificate at Plex.tv and you restart the server.
  2. We stop PMS, delete the certificate it has locally, start PMS and let it download it again.

If so, how do I reset the certificate?