Plex Media Server Logs_2021-05-08_14-36-19.zip (3.2 MB) Server Version#:1.23.0.4497
Player Version#:N/A
I recently lost my hard drive on my Mac and had to do a fresh installation of MacOS and restore from time machine.
Since then Plex medial server will start up and run for a while and then crash or shut down. Looking for suggestions.
Logs attached.
Take applications to not index in spotlight
Done. I’ll see how that goes. What is the problem this causes? It seems like the point of spotlight is to index applications so I can search for them.
Not sure if it is the problem, but there are other ways to find applications. Dashboard, Application folder in the side menu, Dock, or add applications folder to Dock.
The only other possible could be your energy settings, scheduled sleep. If you have your External Drives configured not to sleep. I found this to create a crash.
Example:
The thing is it is a server and should not sleep. Display can sleep.
Understood. The power settings are to never sleep and display shuts down at 15 minutes. I use this machine for time machine backup and my home networking controller. Both of those services are stable and not shutting down. it only seems to be Plex Media Server so far.
Still had the server shut down on it’s own after preventing spotlight from indexing applications.
Recently I checked my logs, Plex Media server part was showing a lot of Errors and I tried changing a Network setting. This resulted in the errors disappearing. It may well be a fix, give it a go.
That’s my current setting as well.
Do you use any AV or Little Snitch, VPN ?
Nope.
Anything I should be looking for in my logs? I don’t seem to find anything that indicates errors or warnings prior to shut down. In fact, I receive no notification that it has shut down or crashed or anything. It’s just all the sudden not running and the icon is gone from my top bar. It starts back up fine.
Turn debug logs on and save. Wait till it crashes, go to troubleshooting and save the Logs.
Look in the Plex Media server.log
OK. It crashed sometime after I went to bed last night. and I just restarted it. Will check now.
here is a system log from a few days ago showing something…
2021-05-08 14:41:37,383 (70000ef3d000) : DEBUG (runtime:717) - Handling request GET /system/:/prefs
2021-05-08 14:41:37,384 (70000ef3d000) : DEBUG (runtime:49) - Received packed state data (80 bytes)
2021-05-08 14:41:37,386 (70000ef3d000) : DEBUG (runtime:814) - Found route matching /system/:/prefs
2021-05-08 14:41:37,387 (70000ef3d000) : DEBUG (runtime:88) - Sending packed state data (99 bytes)
2021-05-08 14:41:37,387 (70000ef3d000) : DEBUG (runtime:924) - Response: [200] MediaContainer, 418 bytes
2021-05-08 15:35:10,086 (7000113db000) : DEBUG (runtime:1156) - Starting timed thread named ‘invalidation_timer’
2021-05-09 02:01:51,454 (1185985c0) : INFO (pipeinterface:87) - Stopping plug-in
Here is the last set of entries from the system log yesterday…
2021-05-10 13:36:26,728 (7000039a3000) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/library/sections’
2021-05-10 13:36:26,731 (7000039a3000) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/channels/all’
2021-05-10 14:34:26,721 (700005a3e000) : DEBUG (runtime:1156) - Starting timed thread named ‘invalidation_timer’
Here is the system log from when I restarted the server this morning.
2021-05-11 08:41:11,000 (1054de5c0) : INFO (core:349) - Starting framework core - Version: 2.6.3, Build: a1b1f3c (Thu May 06 15:48:04 UTC 2021)
2021-05-11 08:41:11,000 (1054de5c0) : DEBUG (core:361) - Using the elevated policy
2021-05-11 08:41:11,000 (1054de5c0) : DEBUG (core:365) - Extending whitelist: [‘cgi’, ‘tempfile’]
2021-05-11 08:41:11,001 (1054de5c0) : DEBUG (core:450) - Starting runtime component.
2021-05-11 08:41:11,002 (1054de5c0) : DEBUG (core:450) - Starting caching component.
2021-05-11 08:41:11,002 (1054de5c0) : DEBUG (core:450) - Starting data component.
2021-05-11 08:41:11,003 (1054de5c0) : DEBUG (core:450) - Starting networking component.
2021-05-11 08:41:11,003 (1054de5c0) : DEBUG (networking:261) - Loaded HTTP cookies
2021-05-11 08:41:11,005 (1054de5c0) : DEBUG (networking:428) - Setting the default network timeout to 20.0
2021-05-11 08:41:11,010 (1054de5c0) : DEBUG (core:450) - Starting localization component.
2021-05-11 08:41:11,010 (1054de5c0) : INFO (localization:409) - Setting the default locale to en-us
2021-05-11 08:41:11,011 (1054de5c0) : WARNING (data:179) - Error decoding with simplejson, using demjson instead (this will cause a performance hit) - Expecting property name enclosed in double quotes: line 26 column 1 (char 1078)
2021-05-11 08:41:11,012 (1054de5c0) : DEBUG (localization:427) - Loaded en strings
2021-05-11 08:41:11,013 (1054de5c0) : DEBUG (core:450) - Starting messaging component.
2021-05-11 08:41:11,013 (1054de5c0) : DEBUG (core:450) - Starting debugging component.
2021-05-11 08:41:11,013 (1054de5c0) : DEBUG (core:450) - Starting services component.
2021-05-11 08:41:11,013 (1054de5c0) : DEBUG (core:450) - Starting myplex component.
2021-05-11 08:41:11,013 (1054de5c0) : DEBUG (core:450) - Starting notifications component.
2021-05-11 08:41:11,133 (1054de5c0) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.system in namespace ‘metadata’
2021-05-11 08:41:11,145 (70000c8a1000) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2021-05-11 08:41:11,145 (1054de5c0) : DEBUG (runtime:1117) - Created a thread named ‘load_all_services’
2021-05-11 08:41:11,146 (1054de5c0) : DEBUG (runtime:1117) - Created a thread named ‘get_server_info’
2021-05-11 08:41:11,146 (1054de5c0) : DEBUG (core:150) - Finished starting framework core
2021-05-11 08:41:11,147 (1054de5c0) : DEBUG (core:560) - Loading plug-in code
And I found this in this mornings log:
2021-05-11 08:41:11,925 (1054de5c0) : CRITICAL (runtime:709) - Private handlers are no longer supported; couldn’t register <bound method StreamService.token_handler of <streamservice.StreamService object at 0x104e72210>>
It happened again overnight. It seems to be fine all day. Here are todays logs
Plex Media Server Logs_2021-05-12_07-43-03.zip (4.2 MB)
Here are the last entries in the system log before I restarted this morning (*system.log.1)
2021-05-11 17:49:52,898 (70000c81e000) : DEBUG (runtime:924) - Response: [200] str, 480371 bytes
2021-05-12 02:02:57,999 (1054de5c0) : INFO (pipeinterface:87) - Stopping plug-in
seems to be a pattern that it is fine during the day and then crashes at night. I wonder if there is some Mac routine going on at 2am that shuts plex down.
I would be more inclined to look at Plex’s scheduled tasks, remove one or two at a time till you have come to a conclusion. Start at the bottom.
Thanks! I think I found something. The backup directory does not exist on my computer.
The Plex Media Server directory is missing…
/Users/shaunv74/Library/Application Support/Plex Media Server/Plug-in Support/
Should I create this manually or do I need to perform some sort of reinstall?
I found there seems to be a few files and library locations missing from the OS reinstall. So going to perform a full uninstall reinstall. Thanks for the help @SE56 !
