Plex server goes unresponsive periodically

General Discussions
1

Plex is running the official docker container (image id: db7e90bd3365). It has happened a few times tonight just simplying playing a video via the web browser. I see the following ERRORS in the plex media server log.

Jan 17, 2018 21:33:54.968 [0x7f01ff7ff700] ERROR - [Transcoder] [dash @ 0x2a16020] Cannot use rename on non file protocol, this may lead to races and temporary partial files
Jan 17, 2018 21:33:55.345 [0x7f01f9ff4700] ERROR - [Transcoder] [dash @ 0x224c5c0] Cannot use rename on non file protocol, this may lead to races and temporary partial files
Jan 17, 2018 21:33:55.432 [0x7f01f9ff4700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 223667608 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.466 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 225807784 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.473 [0x7f01f7ff0700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 226062760 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.481 [0x7f01ff7ff700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 226302376 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.520 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 228010408 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.538 [0x7f01ff7ff700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 229069240 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.554 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 229655992 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.594 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 231844264 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.608 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 232378792 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.664 [0x7f01f7ff0700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 234743224 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.670 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 234999208 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.688 [0x7f02233ff700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 235877800 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.731 [0x7f01f9ff4700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 237826456 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.737 [0x7f01ff7ff700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 238070200 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.788 [0x7f01fbff8700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 240010648 is out of range for mov/mp4 format
Jan 17, 2018 21:33:55.857 [0x7f02233ff700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 241677736 is out of range for mov/mp4 format
Jan 17, 2018 21:34:27.277 [0x7f01f77ef700] ERROR - Throttle: timed out trying to read chunk 336
Jan 17, 2018 21:35:36.380 [0x7f02243fe700] ERROR - EventSource: Retrying in 15 seconds.
Jan 17, 2018 21:35:36.384 [0x7f01fb7f7700] ERROR - [Transcoder] [mp4 @ 0x227acc0] Application provided duration: -32 / timestamp: 242867608 is out of range for mov/mp4 format
Jan 17, 2018 21:35:56.852 [0x7f0224bff700] ERROR - EventSource: Retrying in 30 seconds.

Unsure if this is related, but usually around the same time I get a few PubSubManager errors.

Jan 17, 2018 21:38:09.743 [0x7f02243fe700] WARN - PubSubManager: Connection to 139.162.177.42 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 139.162.115.125 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 139.162.202.171 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 103.3.62.6 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 184.105.148.97 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 82.94.168.47 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 45.79.211.86 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 45.33.118.95 failed: Connection timed out.
Jan 17, 2018 21:38:09.744 [0x7f02243fe700] WARN - PubSubManager: Connection to 50.116.59.4 failed: Connection timed out.

Are there other logs I can provide that may help? I’ve gotten to the point I can’t really watch a movie completely without it failing on me in the middle of watching.

2

Settings - Server - Help - Download Logs will provide everything we need to see in the ZIP file it gives you.

Those connections (PubSub timeout) are usually related to networking problems. The connection is failing because your computer can’t connect to those external IP addresses.

3

Attach the .zip here? Is it sanitized of private/sensitive data?

I know the server can connect to the internet as other docker containers are not having issues accessing the internet.

After more troubleshooting, I’m thinking other containers (sonarr/radarr) may be causing some contentions (maybe trouble internal to docker). I’m going to pull them off and put them on a different host.

4

Yes, the ZIP is sanitized. If you prefer, I will remove it as soon as I download it or you can PM it to me.

If you do PM the logs, please include a link to this thread so I know where to post back to.

5

Thanks for the logs. I found it immediately.

Jan 17, 2018 21:47:26.589 [0x7f014ebff700] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Jan 17, 2018 21:47:34.716 [0x7f0149bfc700] WARN - SLOW QUERY: It took 350.000000 ms to retrieve 0 items.
Jan 17, 2018 21:47:34.716 [0x7f013f7ff700] WARN - SLOW QUERY: It took 360.000000 ms to retrieve 0 items.
Jan 17, 2018 21:47:34.717 [0x7f01457fe700] WARN - SLOW QUERY: It took 310.000000 ms to retrieve 1 items.

Your database is in desperate need of optimization.
Hover over Libraries to expose the ellipsis (...) and click it
Click `Optimize Database’
When it completes, restart PMS to restart everything.

For future references. please keep “Verbose” logging turned off until requested. I was fortunate to find the problem… In most cases where Verbose is enabled, I don’t.

6

Thanks for pointing that out. That’s odd that I have the scheduled task to optimize the databases. Does manually triggering it do something different?

7

Engineering is working on a problem with the entire Butler (as we call it) and all its Scheduled Tasks. That’s why it wasn’t working as it should.

Until the next release, it is strongly recommended:

  1. Remain at 1.10.1
  2. Turn off all automatic optimizations
  3. Turn off Deep Analysis
  4. Turn off Automatic Bit Rate in all player apps.
8

Alrighty. Thanks for the help. I’ll let it run and see how it works now.

9

I am having this same issue with plex suddenly freezing for no particular reason. Started recently. Can I upload my logs (zipped) here as well?

Not sure of proper etiquette with these forums.