Hi All,
I’m running plex as a docker container on Unraid 6.5.3. Docker is 17.09.1-ce. It’s running on a custom server, dual 5690 with 48gb of ram so it shouldn’t be crashing due to limited resources…
Recently I’ve been getting this error where PMS just keeps shutting down, I’m not entirely sure why this is happening. But here is the last bit of the log when it gets the shutdown signal:
Aug 11, 2018 12:11:09.610 [0x14f7e1fff700] DEBUG - Auth: authenticated user 17023645 as xxxxxxxxxx@gmail.com
Aug 11, 2018 12:12:23.750 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:12:38.781 [0x14f7e1fff700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:12:53.808 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:13:08.828 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:13:23.866 [0x14f7e1fff700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:13:30.905 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 8938560 as xxxxxx
Aug 11, 2018 12:13:38.896 [0x14f7e1fff700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:13:42.332 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:13:42.332 [0x14f7e1dfe700] DEBUG - HTTP requesting GET https://downloads.plex.tv/relay/relay_v1.pub
Aug 11, 2018 12:13:42.333 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:13:42.394 [0x14f7e1dfe700] DEBUG - HTTP 200 response from GET https://downloads.plex.tv/relay/relay_v1.pub
Aug 11, 2018 12:13:42.394 [0x14f7e1dfe700] DEBUG - Relay: refreshed host key from https://downloads.plex.tv/relay/relay_v1.pub
Aug 11, 2018 12:13:42.394 [0x14f7e1dfe700] DEBUG - Relay: starting relay.
Aug 11, 2018 12:13:42.394 [0x14f7e1dfe700] DEBUG - Job running: PLEXTOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Relay' '-p' '443' '-N' '-R' '0:localhost:32401' '-o' 'StrictHostKeyChecking=yes' '-o' 'UserKnownHostsFile=/config/Library/Application Support/Plex Media Server/Cache/relayHostKey.txt' '-o' 'NoneSwitch=yes' '-o' 'NoneEnabled=yes' '-o' 'LogLevel=VERBOSE' '-o' 'PreferredAuthentications=password' '-o' 'PubkeyAuthentication=no' '-l' 'Blaze9' '-F' '/dev/null' 'ip_masked_for_forums'
Aug 11, 2018 12:13:42.401 [0x14f7e1dfe700] DEBUG - Jobs: Starting child process with pid 20496
Aug 11, 2018 12:13:42.402 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:13:43.013 [0x14f7a3de2700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Aug 11, 2018 12:13:43.013 [0x14f7a3de2700] DEBUG - HTTP requesting GET https://plex.tv/servers/1e6d05544084e95960ec699fa45380a492f56b86/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Aug 11, 2018 12:13:43.668 [0x14f7a3de2700] DEBUG - HTTP 200 response from GET https://plex.tv/servers/1e6d05544084e95960ec699fa45380a492f56b86/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Aug 11, 2018 12:13:43.672 [0x14f7a3de2700] DEBUG - MyPlex: updating with 75 access tokens
Aug 11, 2018 12:13:43.689 [0x14f7d27ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 11, 2018 12:13:44.094 [0x14f7d27ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 11, 2018 12:13:44.958 [0x14f7a25fc700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 11, 2018 12:13:45.360 [0x14f7a25fc700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Aug 11, 2018 12:13:52.916 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:13:53.376 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:13:53.376 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:13:53.376 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:13:53.376 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:13:53.927 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:13:56.693 [0x14f7e1fff700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:14:08.134 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:08.134 [0x14f7e1fff700] DEBUG - Relay: reused an existing key for https://downloads.plex.tv/relay/relay_v1.pub
Aug 11, 2018 12:14:08.134 [0x14f7e1fff700] DEBUG - Relay: starting relay.
Aug 11, 2018 12:14:08.134 [0x14f7e1fff700] DEBUG - Job running: PLEXTOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Relay' '-p' '443' '-N' '-R' '0:localhost:32401' '-o' 'StrictHostKeyChecking=yes' '-o' 'UserKnownHostsFile=/config/Library/Application Support/Plex Media Server/Cache/relayHostKey.txt' '-o' 'NoneSwitch=yes' '-o' 'NoneEnabled=yes' '-o' 'LogLevel=VERBOSE' '-o' 'PreferredAuthentications=password' '-o' 'PubkeyAuthentication=no' '-l' 'Blaze9' '-F' '/dev/null' '"ip_here_masked_for_forums"'
Aug 11, 2018 12:14:08.135 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:08.142 [0x14f7e1fff700] DEBUG - Jobs: Starting child process with pid 20519
Aug 11, 2018 12:14:08.142 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:08.946 [0x14f7e1fff700] DEBUG - Auth: authenticated user 1 as Blaze9
Aug 11, 2018 12:14:18.767 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:14:19.142 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:19.142 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:19.142 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:19.142 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:32.788 [0x14f7e1fff700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:14:32.936 [0x14f7a43e5700] DEBUG - Checking if time for scheduled update
Aug 11, 2018 12:14:33.236 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:33.236 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:33.237 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:33.237 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:45.818 [0x14f7e1dfe700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:14:46.179 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:46.179 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:14:46.179 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:14:46.179 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:15:02.817 [0x14f7e1fff700] DEBUG - Auth: authenticated user 8938560 as un_masked
Aug 11, 2018 12:15:03.337 [0x14f7e1dfe700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:15:03.337 [0x14f7e1dfe700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:15:03.337 [0x14f7e1fff700] DEBUG - EventSource: Got event [data] '<Message host="ip_here_masked_for_forums" port="443" command="startRelay"/>'
Aug 11, 2018 12:15:03.338 [0x14f7e1fff700] DEBUG - Relay: already have an active relay connection for this server
Aug 11, 2018 12:15:04.259 [0x14f7f1719800] DEBUG - Shutting down with signal 15
Aug 11, 2018 12:15:04.259 [0x14f7f1719800] DEBUG - Ordered to stop server.
Aug 11, 2018 12:15:04.259 [0x14f7f1719800] WARN - Timed out waiting for server to finish.
Aug 11, 2018 12:15:04.259 [0x14f7f1719800] DEBUG - Stopping server...
Aug 11, 2018 12:15:04.259 [0x14f7f1719800] DEBUG - HttpServer: Stopping server.
Aug 11, 2018 12:15:04.259 [0x14f7df9fe700] DEBUG - MetadataItemClusterRequestHandler: a shutdown event has been received
Aug 11, 2018 12:15:04.259 [0x14f7df9fe700] DEBUG - TranscodeJobManager: [Running] onServerShutdown
Aug 11, 2018 12:15:04.259 [0x14f7df9fe700] DEBUG - TranscodeJobManager: [Running] -> [Shutdown]
Aug 11, 2018 12:15:04.260 [0x14f7f1719800] DEBUG - Server stopped.
Aug 11, 2018 12:15:04.398 [0x14f7f1719800] DEBUG - NetworkService: Stopping advertisement.
Aug 11, 2018 12:15:05.732 [0x14f7f1719800] DEBUG - Server is already in shutdown mode, ignoring this signal...
Aug 11, 2018 12:15:05.732 [0x14f7e5fff700] DEBUG - Server is already in shutdown mode, ignoring this signal...
Aug 11, 2018 12:15:05.733 [0x14f7e2fff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Relay' exit code for process 20519 is -15 (signal: Terminated)
Aug 11, 2018 12:15:05.733 [0x14f7e2fff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Relay' exit code for process 20496 is -15 (signal: Terminated)
Aug 11, 2018 12:15:05.735 [0x14f7e2fff700] DEBUG - Jobs: '/config/Library/Application Support/Plex Media Server/Codecs/EasyAudioEncoder-141-linux-ubuntu-x86_64/EasyAudioEncoder/EasyAudioEncoder' exit code for process 18207 is -15 (signal: Terminated)
Aug 11, 2018 12:15:05.736 [0x14f7e2fff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex DLNA Server' exit code for process 403 is -15 (signal: Terminated)
Aug 11, 2018 12:15:05.736 [0x14f7dbbff700] DEBUG - Plex DLNA Server exited.
Aug 11, 2018 12:15:05.836 [0x14f7dbbff700] DEBUG - JobRunner: exec of /usr/lib/plexmediaserver/Plex DLNA Server cancelled. In shutdown state.
Aug 11, 2018 12:15:05.836 [0x14f7dbbff700] ERROR - Plex DLNA Server failed to start
Aug 11, 2018 12:15:05.880 [0x14f7e2fff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Tuner Service' exit code for process 405 is 0 (success)
Aug 11, 2018 12:15:05.880 [0x14f7de1fe700] DEBUG - Plex Tuner Service exited.
Aug 11, 2018 12:15:05.981 [0x14f7de1fe700] DEBUG - JobRunner: exec of /usr/lib/plexmediaserver/Plex Tuner Service cancelled. In shutdown state.
Aug 11, 2018 12:15:05.981 [0x14f7de1fe700] ERROR - Plex Tuner Service failed to start
Would any additional logs be necessary? Please let me know what else is needed.
Thanks!
