Server Version#:1.21.4.4079-1B7748a7b
Player Version#:
Ever since the latest PMS update my Plex server has randomly shut itself down twice. I went to access the media in my Plex client and it shows disconnected. I can access the media on my server through my Windows network fine. I go to the Plex App in the Package Center in Synology and see the Plex Server is off and I have to restart itPlex Media Server Logs_2021-03-06_14-53-42.zip (4.2 MB) . Never had this issue before. This most recent incident I was listening to music from it in my car when all of a sudden it just dropped. When I got home, I logged into my Synology, went to the app and saw it off. Additionally, and I don’t know if this is related to a Server update or not, a movie file I have that used to trigger Dolby Atmos on my receivers no longer does and is now transcoded down to Dolby Digital.
Synology DS220J. Latest DSM version, 6.2.3-25426 Update 3
1 Like
Your Plex logs show this, a commanded shutdown, on Mar 6 at 01:00:11.909 (1 am)
Mar 06, 2021 00:59:52.367 [0x7f8dfc51a0] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.1.82:8080/description.xml
Mar 06, 2021 00:59:52.367 [0x7f8dfc51a0] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.82 (Denon AVR-S710W)
Mar 06, 2021 01:00:11.909 [0x7f960c81a0] DEBUG - Shutting down with signal 15 (Terminated)
Mar 06, 2021 01:00:11.996 [0x7f960c81a0] DEBUG - Ordered to stop server.
Mar 06, 2021 01:00:12.457 [0x7f97162000] DEBUG - Stopping server...
Mar 06, 2021 01:00:12.457 [0x7f97162000] DEBUG - HttpServer: Stopping server.
Mar 06, 2021 01:00:13.506 [0x7f8dcd71a0] DEBUG - TranscodeJobManager: [Running] onServerShutdown
Mar 06, 2021 01:00:14.128 [0x7f8dcd71a0] DEBUG - TranscodeJobManager: [Running] -> [Shutdown]
Mar 06, 2021 01:00:14.821 [0x7f8dcd71a0] DEBUG - MetadataItemClusterRequestHandler: a shutdown event has been received
Mar 06, 2021 01:00:15.851 [0x7f960c81a0] WARN - Timed out waiting for server to finish.
Mar 06, 2021 01:00:16.240 [0x7f97162000] DEBUG - Server stopped.
Mar 06, 2021 01:00:16.807 [0x7f97162000] DEBUG - NetworkService: Stopping advertisement.
Mar 06, 2021 01:00:16.807 [0x7f97162000] DEBUG - Killing plug-ins.
Mar 06, 2021 01:00:16.807 [0x7f8dfc51a0] ERROR - Network Service: Error in advertiser handle read: 125 (Operation canceled) socket=76
Mar 06, 2021 01:00:17.058 [0x7f8dfc51a0] INFO - Network Service: Abandoning advertise socket, it was closed.
Mar 06, 2021 01:00:17.417 [0x7f960c81a0] DEBUG - Server is already in shutdown mode, ignoring this signal...
Mar 06, 2021 01:00:18.877 [0x7f97162000] DEBUG - [com.plexapp.system] Killing.
Mar 06, 2021 01:00:18.930 [0x7f97162000] DEBUG - ChildProcessMonitor: Waiting for Plex Tuner Service to exit.
Mar 06, 2021 01:00:18.968 [0x7f97162000] DEBUG - [JobRunner] Signalling job ID 14021 with 2
Mar 06, 2021 01:00:19.035 [0x7f960c81a0] WARN - JobManager: Could not find job for handle 13167
Mar 06, 2021 01:00:20.000 [0x7f8e5a11a0] DEBUG - Stopping file watcher for com.plexapp.system
Mar 06, 2021 01:00:20.666 [0x7f960c81a0] DEBUG - Jobs: '/volume1/@appstore/Plex Media Server/Plex Tuner Service' exit code for process 14021 is 0 (success)
Mar 06, 2021 01:00:20.705 [0x7f8e2b31a0] DEBUG - [ChildProcessMonitor] Plex Tuner Service exited.
Mar 06, 2021 01:00:20.824 [0x7f97162000] INFO - Killing process: Plex EAE Service (pid: 6582)
Mar 06, 2021 01:00:20.824 [0x7f97162000] DEBUG - Killing job.
Mar 06, 2021 01:00:20.824 [0x7f97162000] DEBUG - Signalling job ID 6582 with 9
Mar 06, 2021 01:00:20.825 [0x7f960c81a0] DEBUG - Jobs: '/volume1/Plex/Library/Application Support/Plex Media Server/Codecs/EasyAudioEncoder-1165-linux-aarch64/EasyAudioEncoder/EasyAudioEncoder' exit code for process 6582 is -9 (signal: Killed)
Mar 06, 2021 01:00:21.194 [0x7f97162000] DEBUG - Job was already killed, not killing again.
Mar 06, 2021 01:00:21.195 [0x7f97162000] DEBUG - Database: Shutting down.
Mar 06, 2021 01:00:21.204 [0x7f97162000] DEBUG - Captured session 0.
Do you have a Scheduled Task which is shutting it down?
Yes, my Synology shuts down every night at 1AM, and turns on again at 6 AM. Per Schedule. That is not related. It was on this morning and working fine until about an hour ago as I was in the middle of driving home
And the other time it shut down was in the middle of the day, just randomly when I went to go access media
The “Current” log shows you obtaining Log files.
Mar 05, 2021 17:48:21.230 [0x7f1fef7fe700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 418 bytes of content.
Mar 05, 2021 17:48:21.230 [0x7f203ebbc700] DEBUG - Completed: [8.17.107.239:64832] 200 GET /system/:/prefs (7 live) TLS GZIP 166ms 813 bytes (pipelined: 26)
Mar 05, 2021 17:48:22.559 [0x7f203e3bb700] DEBUG - Auth: authenticated user 1 as seanmcg182
Mar 05, 2021 17:48:22.559 [0x7f203f3bd700] DEBUG - Request: [8.17.107.239:64864 (WAN)] GET /diagnostics/logs (8 live) TLS GZIP Signed-in Token (seanmcg182)
Mar 05, 2021 17:48:22.560 [0x7f203f3bd700] DEBUG - Diagnostics: Building logfile zip
This is normal.
What am I not understanding please ?
1 Like
I can see this “current” session starting at 11:13:30
Mar 05, 2021 11:13:30.685 [0x7f963b61a0] INFO - Plex Media Server v1.21.4.4079-1b7748a7b - Synology DS220j aarch64 - build: linux-aarch64 synology - GMT -05:00
Mar 05, 2021 11:13:30.799 [0x7f963b61a0] INFO - Linux version: DSM 6.2.3.25426-3, language: en-US
Mar 05, 2021 11:13:30.799 [0x7f963b61a0] INFO - Processor Unknown architecture
Mar 05, 2021 11:13:30.799 [0x7f963b61a0] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Mar 05, 2021 11:13:30.329 [0x7f0fa231a0] DEBUG - Skipping over directory 'Van Halen', as nothing has changed; removing 11 media items from map.
Did the scheduled start not trigger as expected?
Please READ my original post, because your questions have nothing to do with it.
The problem is not my schedule for turning the Server on and off, the problem is your Plex app turning ITSELF off randomly. As I just said, the last time it did so was about an hour or so again, that’s 2:30 PM local time, nowhere near and nothing to do with the server going on and off 1 am to 6am
and searching through the forums, similar issues with it have cropped up before
Thanks so much, you’ve been hepful. Always appreciate when a company forum Support rep doesn’t bother reading the problem and fires back unrelated, useless misdirected replies. Further proof of the uselessness of forums and the millenial generation. It’s still doing it, by the way, not that anyone at this company gives a crap. Please, go back to your Playstation
Your logs show me:
- PMS log .5 - Startup on 03-Mar-2021 06:02:15
- PMS log .5 - Shutdown on 04-Mar-2021 01:00:26 – Commanded
- PMS log .4 - Startup on 04-Mar-2021 06:02:07 - Rollover to log .3 @ 15:51:33
- PMS log .3 - Continuing @ 15:51:33
- PMS log .3 - Shutdown on 05-Mar-2021 01:00:26 – Commanded
- PMS log .2 - Startup on 05-Mar-2021 06:02:05 - Rollovevr to log .1 @ 11:13:30
- PMS log .1 - Continuing @ 11:13:30
- PMS log .1 - Shutdown on 06-Mar-2021 @ 01:00:26 – Commanded
- PMS log - Startup on 06-Mar-2021 14:48:24
Mar 06, 2021 14:48:24.343 [0x7fb2e381a0] INFO - Plex Media Server v1.21.4.4079-1b7748a7b - Synology DS220j aarch64 - build: linux-aarch64 synology - GMT -05:00
Mar 06, 2021 14:48:24.344 [0x7fb2e381a0] INFO - Linux version: DSM 6.2.3.25426-3, language: en-US
Mar 06, 2021 14:48:24.344 [0x7fb2e381a0] INFO - Processor Unknown architecture
Mar 06, 2021 14:48:24.344 [0x7fb2e381a0] INFO - /var/packages/Plex Media Server/target/Plex Media Server
Mar 06, 2021 14:48:24.204 [0x7fb3be4000] DEBUG - BPQ: [Idle] -> [Starting]
- Logs capture and download on 06-Mar-2021 14:53:30
Mar 06, 2021 14:53:30.401 [0x7fb0cef1a0] DEBUG - Auth: authenticated user 1 as davidsco27
Mar 06, 2021 14:53:30.409 [0x7fb07131a0] DEBUG - Request: [192.168.1.2:53222 (Subnet)] GET /diagnostics/logs (10 live) TLS GZIP Signed-in Token (davidsco27)
Mar 06, 2021 14:53:30.457 [0x7fb07131a0] DEBUG - Diagnostics: Building logfile zip
- With exception of the delayed start on 06-Mar-2021 @ 14:53:23, I see no unusual behavior
- When did the failure occur ?
- Your description of the problem does not correlate to the log file presented.
- The automated startup & shutdown did NOT occur as expected on 06-Mar-2021
There are no visible PMS errors here.
I’m sorry if my question was not worked perfectly but it is relevant:
- Why didn’t PMS start when expected?
- Did something happen to the host ?
Can you resolve why PMS did not start at 06:02 on 06-March as it should ?
PS: I’ve been a development engineer for almost 40 years, having switched to support engineering a few years ago.
- No, I am not a millenial
- I became an engineer a few years after I built my IMSAI.
- I’ve been doing this so long YES, I do have the tee-shirt to go with it.
2 Likes
Always helps to READ the users comments and questions, you just might find the answer to your question there (copied and pasted again below) I guess that part is missing from the T-Shirt. The problem happened yet again last night as I was driving to pick up food. Happens when I’m in the middle of listening to music through Android Auto with Plexamp. All of a sudden the music stops and the play button turns into a square. Says “lost connection to server” and when I get home and log into my Synology and open the Packages I see PMS is shut down and I have to restart it again.
davidsco27Plex Pass
15d
Please READ my original post, because your questions have nothing to do with it.
The problem is not my schedule for turning the Server on and off, the problem is your Plex app turning ITSELF off randomly. As I just said, the last time it did so was about an hour or so again, that’s 2:30 PM local time, nowhere near and nothing to do with the server going on and off 1 am to 6am
and btw, I’ve been a computer technician probably since you were in diapers, maybe before, and on my own for over 20 years after getting fed up with dealing with the incompetence of others
Excuse me?
Just how old do you think I am?
What I’m TRYING to resolve here is simple: Server problem or Client problem.
Once that’s known, we can go futher.
What I THINK I see here is a player (Plexamp) disconnection problem.
If that’s the case then this is NOT a Synology problem.
I am the server-side guy, I am the Synology development engineer.
I don’t do the apps.
See why I’m trying to figure out where this needs to go?
No, it’s not a Synology problem. Synology is running fine. It’s the PMS app on my Synology that shuts itself down randomly when it’s feeding PlexAmp
@davidsco27 I have to agree with Chuck here. According to your logs PMS was turned off at 1 am on March 6 but did not come back on until 2:48 pm, when you likely turned it back on.
It appears that your scheduled task to turn PMS on at 6 am did not work. I do not know how PlexAmp was able to play music when you used it. PlexAmp can cache a lot of music ahead of time. It’s possible it was playing from this cache and not streaming from your server.
Do you have newer logs from PMS showing this behavior? Also the logs from PlexAmp the next time this happens will also help to identify issues.
1 Like
Wrong. Try again It goes off at 1 am every night and back on at 6 am. It was on at 6 am. It went OFF at 2:48 by itself. The scheduled task DID work as I was listening to music from it that afternoon until it shut itself down. It wasn’t playing an hour’s worth of cache, and I was using the Plex server earlier. Shouldn’t the Plex Server app come on when the Synology does?
Not according to your logs for March 6th. There is 0 activity is any of the log files from 1 am to 2:48 pm.
How are you shutting down and restarting your Synology? Do you shut down PMS before your Synology?
Not possible as it was used during that time.
I don’t touch PMS. The Synology is scheduled to shut down at 1am and turn on at 6am as it’s not being used. The only time I touch PMS is the times I have to manually restart it when it’s turned itself off
Possible or not, that’s what your logs indicate. If your PMS was running, it was doing so without producing any logging.
If you can provide new logs from PMS and PlexAmp next time this happens, we can look into this.
