What do these logs mean exactly? Trying to debug general instability

Plex Media Server Desktops & Laptops
1

Server Version#: 1.13.9.5456
Player Version#: Various Players

Running the latest official Docker image on Unraid.

I’ve got so many strange logs lately I don’t even know where to start. I see drops in video playback on many devices (tonight was my Nvidia Shield Plex app along with my Kodi Plex add-on both having issues), and I’d like to start to get to the bottom of things.

There are a ton of strange logs in my Plex Media Server.log file. Here’s a snippet below. I’m looking for what’s causing these logs and what I can do about them. Any help would be appreciated, and I can provide a full logs dump to Plex for more details.

Nov 05, 2018 20:19:35.441 [0x14e1bedfc700] WARN - Overzealous client asked for end range of 1966079, content size is 1117096; we'll clip.
Nov 05, 2018 20:19:35.441 [0x14e1bedfc700] WARN - Range could not be satisfied 1474560 - 1117095 (total size=1117096)
Nov 05, 2018 20:19:35.473 [0x14e1cdfff700] WARN - Overzealous client asked for end range of 1474559, content size is 1117096; we'll clip.
Nov 05, 2018 20:19:36.265 [0x14e1bebfb700] WARN - Overzealous client asked for end range of 2407339, content size is 2378952; we'll clip.
Nov 05, 2018 20:20:14.814 [0x14e1c65fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:21:20.275 [0x14e1c33ff700] INFO - Library section 2 (TV Shows) will be updated because of a change in /nas/TV Shows/Arrow
Nov 05, 2018 20:21:26.108 [0x14e1c65fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:21:42.008 [0x14e1c77fd700] WARN - Unable to load episode file ["seasons/7/episodes/4.xml"]
Nov 05, 2018 20:22:49.776 [0x14e1b4bff700] WARN - Overzealous client asked for end range of 2228223, content size is 546140; we'll clip.
Nov 05, 2018 20:22:49.776 [0x14e1b4bff700] WARN - Range could not be satisfied 1671168 - 546139 (total size=546140)
Nov 05, 2018 20:22:49.777 [0x14e1c77fd700] WARN - Overzealous client asked for end range of 1114111, content size is 546140; we'll clip.
Nov 05, 2018 20:22:49.777 [0x14e1c77fd700] WARN - Range could not be satisfied 557056 - 546139 (total size=546140)
Nov 05, 2018 20:22:49.778 [0x14e1d3ffd700] WARN - Overzealous client asked for end range of 1671167, content size is 546140; we'll clip.
Nov 05, 2018 20:22:49.778 [0x14e1d3ffd700] WARN - Range could not be satisfied 1114112 - 546139 (total size=546140)
Nov 05, 2018 20:33:29.030 [0x14e1d23ff700] WARN - Overzealous client asked for end range of 2165947, content size is 648600; we'll clip.
Nov 05, 2018 20:33:29.030 [0x14e1d23ff700] WARN - Range could not be satisfied 1769472 - 648599 (total size=648600)
Nov 05, 2018 20:33:38.429 [0x14e1c7bff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:33:43.721 [0x14e1ccfff700] WARN - Overzealous client asked for end range of 1015807, content size is 922892; we'll clip.
Nov 05, 2018 20:33:43.729 [0x14e1cdfff700] WARN - Overzealous client asked for end range of 1523711, content size is 922892; we'll clip.
Nov 05, 2018 20:33:43.730 [0x14e1cdfff700] WARN - Range could not be satisfied 1015808 - 922891 (total size=922892)
Nov 05, 2018 20:33:43.774 [0x14e1b4bff700] WARN - Overzealous client asked for end range of 2031615, content size is 922892; we'll clip.
Nov 05, 2018 20:33:43.774 [0x14e1b4bff700] WARN - Range could not be satisfied 1523712 - 922891 (total size=922892)
Nov 05, 2018 20:33:44.472 [0x14e1c65fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:33:50.516 [0x14e1bf3ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:33:56.254 [0x14e1ca9fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:33:56.284 [0x14e1ca9fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:33:56.284 [0x14e1ca9fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:33:56.284 [0x14e1ca9fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:33:56.285 [0x14e1ca9fe700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:34:59.287 [0x14e1c65fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:36:01.230 [0x14e1cf3ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:44:18.064 [0x14e1c67ff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 05, 2018 20:44:27.313 [0x14e1cdfff700] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
Nov 05, 2018 20:45:48.907 [0x14e1c7bff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:45:52.695 [0x14e1b4bff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 05, 2018 20:45:54.742 [0x14e1d3ffd700] WARN - Unable to load episode file ["seasons/5/episodes/28.xml"]
Nov 05, 2018 20:46:14.539 [0x14e1bf1fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:46:50.231 [0x14e1cdbfd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:46:56.624 [0x14e1c7bff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:47:02.650 [0x14e1d41fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:47:08.677 [0x14e1d63ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:47:08.687 [0x14e1d63ff700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:47:10.130 [0x14e1d3ffd700] WARN - Unable to load episode file ["seasons/5/episodes/26.xml"]
Nov 05, 2018 20:47:10.132 [0x14e1bebfb700] WARN - Unable to load episode file ["seasons/5/episodes/27.xml"]
Nov 05, 2018 20:47:13.834 [0x14e1d1bff700] WARN - SLOW QUERY: It took 230.000000 ms to retrieve 30 items.
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:49:22.820 [0x14e1c65fe700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:49:36.539 [0x14e1d3ffd700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:50:01.365 [0x14e1cebff700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:50:33.234 [0x14e1bf3ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:50:44.892 [0x14e1d13ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:51:15.298 [0x14e1bf3ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:51:15.313 [0x14e1bf3ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:51:15.313 [0x14e1bf3ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:51:15.313 [0x14e1bf3ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:51:15.313 [0x14e1bf3ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:51:15.313 [0x14e1bf3ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:51:15.314 [0x14e1bf3ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:51:15.314 [0x14e1bf3ff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:51:15.314 [0x14e1bf3ff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:51:15.314 [0x14e1bf3ff700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:52:15.386 [0x14e1bf1fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:52:26.964 [0x14e1c2ffd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:53:17.447 [0x14e1cfbff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:53:17.462 [0x14e1cfbff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:53:17.462 [0x14e1cfbff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Nov 05, 2018 20:53:17.462 [0x14e1cfbff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:53:17.462 [0x14e1cfbff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Nov 05, 2018 20:53:17.462 [0x14e1cfbff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:53:17.463 [0x14e1cfbff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Nov 05, 2018 20:53:17.463 [0x14e1cfbff700] ERROR - ClientProfileExtra: missing protocol parameter
Nov 05, 2018 20:53:17.463 [0x14e1cfbff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Nov 05, 2018 20:53:17.463 [0x14e1cfbff700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Nov 05, 2018 20:54:18.245 [0x14e1cfbff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:54:28.425 [0x14e1c61fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 05, 2018 20:55:48.477 [0x14e1d13ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
2

Bumping, as I have a ton of random logs that don’t make a ton of sense this morning (very little activity in Plex this AM, though there has been a few things played):

Nov 07, 2018 06:05:39.001 [0x145a293ca700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 07, 2018 06:05:47.641 [0x145a18bff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 06:05:47.800 [0x145a291c9700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 06:05:48.597 [0x145a2d7fd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 06:05:49.064 [0x145a25bff700] INFO - AutoUpdate: no updates available
Nov 07, 2018 06:31:09.221 [0x145a2abfd700] WARN - Got a transcode session ping without a session GUID (or with an invalid one).
Nov 07, 2018 07:05:04.845 [0x145a1cffd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 07:05:05.008 [0x1459f99fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 07:05:05.701 [0x145a1cffd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 07:05:17.023 [0x145a2d7fd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 07:05:17.031 [0x145a2d7fd700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 07, 2018 07:05:17.031 [0x145a2d7fd700] ERROR - MDE: no compatible media decisions are available
Nov 07, 2018 07:05:17.031 [0x145a2d7fd700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 07, 2018 07:05:17.031 [0x145a2d7fd700] ERROR - MDE: no compatible media decisions are available
Nov 07, 2018 07:05:17.032 [0x145a2d7fd700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 07, 2018 07:05:17.032 [0x145a2d7fd700] ERROR - MDE: no compatible media decisions are available
Nov 07, 2018 07:16:25.078 [0x145a293ca700] ERROR - Failed to delete session directory (boost::filesystem::remove: No such file or directory: "/ssd/transcoder-plextest/Transcode/Sessions/plex-transcode-547b74p3bj8y91bz7ljph8en-5c2572e4-c657-481a-9ef6-b55fe6fd71c3/chunk-00066")
Nov 07, 2018 07:19:32.242 [0x145a1d5fe700] WARN - Transcode runner appears to have died.
Nov 07, 2018 07:20:14.252 [0x145a277ff700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:20:40.005 [0x145a1d3fd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:28.892 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:28.992 [0x1459f93fb700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:29.671 [0x145a205fe700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:29.771 [0x145a205fe700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:29.825 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:29.925 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:31.335 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:31.435 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:32.201 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:32.301 [0x1459f93fb700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:33.902 [0x145a205fe700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:34.002 [0x145a205fe700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:36.265 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:36.365 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:38.421 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:38.521 [0x1459f93fb700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:44.270 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:44.370 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:32:50.712 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:50.812 [0x1459f93fb700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:33:03.947 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:33:04.047 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:33:05.893 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:33:05.994 [0x1459f93fb700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:33:38.774 [0x145a2abfd700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:33:38.874 [0x145a2abfd700] WARN - Timed out waiting for segment.
Nov 07, 2018 08:33:39.749 [0x145a1d3fd700] ERROR - Failed to delete session directory (boost::filesystem::remove: No such file or directory: "/ssd/transcoder-plextest/Transcode/Sessions/plex-transcode-2f287czgawvy2ej8miuudiga-1df065f2-cdb0-4a81-8150-bf73f00a5952/chunk-stream0-00573.m4s")
Nov 07, 2018 09:00:23.506 [0x145a1cffb700] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: No such file or directory: "/ssd/transcoder-plextest/Transcode/Sessions/plex-transcode-nna7o15kcykc014nzjx2heos-c0a7a04c-eb47-42df-8b09-74d79bb790c0/chunk-stream0-00143.m4s")
Nov 07, 2018 11:04:00.208 [0x145a26fff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 07, 2018 11:04:00.758 [0x145a26fff700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
Nov 07, 2018 11:04:00.903 [0x145a1d1fc700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 07, 2018 11:04:27.722 [0x145a1cffb700] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
Nov 07, 2018 11:12:59.606 [0x145a26fff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 11:12:59.728 [0x1459f97fd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 07, 2018 11:13:00.345 [0x1459f97fd700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
3

Bumping yet again - Any chance I can get help with general instability of Plex? So many errors in the Plex logs - I’d like to get to the bottom of them, but have no idea what most of it means.


Nov 11, 2018 19:13:15.384 [0x145e80bdb700] WARN - Unable to load episode file ["seasons/9/episodes/5.xml"]
Nov 11, 2018 19:13:16.492 [0x145e80bdb700] ERROR - QueryParser: Invalid field '&onlyTransient' found, ignoring.
Nov 11, 2018 19:13:25.597 [0x145e843ff700] ERROR - Error issuing curl_easy_perform(handle): 3
Nov 11, 2018 19:13:25.597 [0x145e843ff700] WARN - HTTP error requesting GET  (0, No error) (<url> malformed)
Nov 11, 2018 19:13:27.442 [0x145e75ffd700] ERROR - Error issuing curl_easy_perform(handle): 3
Nov 11, 2018 19:13:27.442 [0x145e75ffd700] WARN - HTTP error requesting GET  (0, No error) (<url> malformed)
Nov 11, 2018 19:14:04.468 [0x145e4a7ff700] WARN - Got a transcode session ping without a session GUID (or with an invalid one).
Nov 11, 2018 19:15:39.580 [0x145e6cfff700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Nov 11, 2018 19:15:41.290 [0x145e80bdb700] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
Nov 11, 2018 19:17:24.132 [0x145e51ffb700] ERROR - Throttle: timed out trying to read chunk 22
Nov 11, 2018 19:17:24.192 [0x145e6cfff700] ERROR - Throttle: timed out trying to read chunk 22
Nov 11, 2018 19:17:25.196 [0x145e527ff700] ERROR - Throttle: timed out trying to read chunk 22
Nov 11, 2018 19:22:07.688 [0x145e843ff700] ERROR - [Transcoder] [matroska,webm @ 0x14463c0] Read error at pos. 1034512735 (0x3da9695f)
Nov 11, 2018 19:23:27.892 [0x145e7d3ff700] ERROR - Error issuing curl_easy_perform(handle): 3
Nov 11, 2018 19:23:27.892 [0x145e7d3ff700] WARN - HTTP error requesting GET  (0, No error) (<url> malformed)
Nov 11, 2018 19:23:29.654 [0x145e4a7ff700] ERROR - Error issuing curl_easy_perform(handle): 3
Nov 11, 2018 19:23:29.654 [0x145e4a7ff700] WARN - HTTP error requesting GET  (0, No error) (<url> malformed)
Nov 11, 2018 19:24:01.892 [0x145e519f8700] ERROR - Throttle: timed out trying to read chunk 62
Nov 11, 2018 19:24:01.948 [0x145e527ff700] ERROR - Throttle: timed out trying to read chunk 62
Nov 11, 2018 19:24:03.216 [0x145e6cfff700] ERROR - Throttle: timed out trying to read chunk 62
Nov 11, 2018 19:24:03.856 [0x145e51ffb700] ERROR - Throttle: timed out trying to read chunk 62
Nov 11, 2018 19:24:31.443 [0x145e727ff700] INFO - Library section 2 (TV Shows) will be updated because of a change in /nas/TV Shows/Mars (2016)
Nov 11, 2018 19:24:48.969 [0x145e843ff700] WARN - Unable to load episode file ["seasons/2/episodes/1.xml"]
Nov 11, 2018 19:25:46.402 [0x145e51dfa700] ERROR - Error iterating EAE watchfolder directory: No such file or directory
Nov 11, 2018 19:29:35.357 [0x145e80bdb700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 11, 2018 19:29:35.390 [0x145e763ff700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 50 items.
Nov 11, 2018 19:30:49.642 [0x145e6c7fb700] ERROR - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Nov 11, 2018 19:30:49.642 [0x145e6c7fb700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 11, 2018 19:40:20.257 [0x145e4a7ff700] WARN - SLOW QUERY: It took 730.000000 ms to retrieve 30 items.
Nov 11, 2018 19:41:01.568 [0x145e4a7ff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Nov 11, 2018 19:41:02.578 [0x145e7b1fe700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
Nov 11, 2018 19:41:03.636 [0x145e7b1fe700] ERROR - QueryParser: Invalid field 'sectionID' found, ignoring.
Nov 11, 2018 19:41:19.303 [0x145e523fd700] ERROR - Error iterating EAE watchfolder directory: No such file or directory
Nov 11, 2018 19:43:46.701 [0x145e83bff700] ERROR - Session 0x145e6e056a60 terminated
Nov 11, 2018 19:44:53.742 [0x145e843ff700] ERROR - [Transcoder] [matroska,webm @ 0x14463c0] Read error at pos. 1076351898 (0x4027d39a)
Nov 11, 2018 19:51:58.256 [0x145e80bdb700] WARN - SLOW QUERY: It took 33920.000000 ms to retrieve 96 items.
Nov 11, 2018 19:51:58.537 [0x145e521fc700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Nov 11, 2018 19:52:24.020 [0x145e7f3ff700] WARN - SLOW QUERY: It took 127030.000000 ms to retrieve 24 items.
Nov 11, 2018 19:58:23.449 [0x145e6c7fb700] WARN - Unable to load episode file ["seasons/3/episodes/4.xml"]
Nov 11, 2018 19:59:29.791 [0x145e521fc700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Nov 11, 2018 20:02:32.665 [0x145e7b1fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:02:32.832 [0x145e7d3ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:02:33.974 [0x145e6c7fb700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:04:46.190 [0x145e6c7fb700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:04:46.381 [0x145e4a7ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:04:47.126 [0x145e6c7fb700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:04:51.023 [0x145e4d9fe700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:04:51.049 [0x145e4d9fe700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:04:51.049 [0x145e4d9fe700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:04:51.050 [0x145e4d9fe700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:04:51.050 [0x145e4d9fe700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:04:51.051 [0x145e4d9fe700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:04:51.052 [0x145e4d9fe700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:05:28.479 [0x145e51dfa700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:05:28.496 [0x145e51dfa700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:05:28.496 [0x145e51dfa700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:05:28.497 [0x145e51dfa700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:05:28.497 [0x145e51dfa700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:05:28.497 [0x145e51dfa700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:05:28.497 [0x145e51dfa700] ERROR - MDE: no compatible media decisions are available
Nov 11, 2018 20:06:25.431 [0x145e4b7fb700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Nov 11, 2018 20:06:25.445 [0x145e4b7fb700] ERROR - MDE: unable to find a working transcode profile for video stream
Nov 11, 2018 20:06:25.446 [0x145e4b7fb700] ERROR - MDE: no compatible media decisions are available
4

A lot of logged errors and warnings in logs do not have any impact. Would need debug logging to look into each one -

If you have instability, you need to say what that is and provide full logs zip file and indication of when the issue arose and what it was - eg crash, freeze, etc

Looking through all these errors, I would say the following are important and need following up

Nov 11, 2018 19:51:58.256 [0x145e80bdb700] WARN - SLOW QUERY: It took 33920.000000 ms to retrieve 96 items.
Nov 11, 2018 19:52:24.020 [0x145e7f3ff700] WARN - SLOW QUERY: It took 127030.000000 ms to retrieve 24 items.

Suggest you need to do a database optimize
See Global Library Actions / Optimize Database
https://support.plex.tv/articles/200392106-library-actions/

Nov 11, 2018 19:23:29.654 [0x145e4a7ff700] ERROR - Error issuing curl_easy_perform(handle): 3
Nov 11, 2018 19:23:29.654 [0x145e4a7ff700] WARN - HTTP error requesting GET  (0, No error) (<url> malformed)

This is probably just a dead old server on your account
Suggest you purge dead servers - Plex Web / Settings / Account - Authorized Devices / Server

Nov 11, 2018 19:41:19.303 [0x145e523fd700] ERROR - Error iterating EAE watchfolder directory: No such file or directory

Could have been permissions issue creating it. It is audio DTS / Dolby

Nov 07, 2018 08:32:28.892 [0x1459f93fb700] WARN - Transcode runner appears to have died.
Nov 07, 2018 08:32:28.992 [0x1459f93fb700] WARN - Timed out waiting for segment.

Nov 11, 2018 19:43:46.701 [0x145e83bff700] ERROR - Session 0x145e6e056a60 terminated
Nov 11, 2018 19:44:53.742 [0x145e843ff700] ERROR - [Transcoder] [matroska,webm @ 0x14463c0] Read error at pos. 1076351898 (0x4027d39a)

Transcoder falure / media files issues?

https://support.plex.tv/articles/201643703-reporting-issues-with-plex-media-server/
https://support.plex.tv/articles/200250417-plex-media-server-log-files/

5

Ok had the server crash on Saturday night - clients disconnected, webapp wouldn’t load, API calls failed from PRTG, etc. Wasn’t able to get in to do a diagnostics dump, but was able to grab the log files pre-reboot of the docker container. I’ve PM’d you those offline from this thread for security reasons. PRTG reported API timeouts at 11/17/2018 21:38:31 local time.

One of the things above you noted that I’d like to expand upon is the DB optimize. I’ve had several discussions about the SQLite DB over the past several months (example of one: SQLite Database Issues), as this error is extremely common throughout my logs.

My current configuration involves:

  • the DB on a RAMDisk so it’s extremely fast and high IOPS. (miltiple GB/s throughput)
  • I also optimize via the API call every 12 hours: curl -XPUT http://10.1.1.4:32400/library/optimize
  • On top of that, I run a maintenance script every night when the docker container reboots: https://hastebin.com/ojayivafip.bash. If this needs modification or updating, I’m all ears as it was patched together via several forum/reddit posts, but my overall idea at this point is my DB is either too large or too complex for SQLite (2200 movies & 30,000 TV show files), and this is “just the way it is”.

Would love to know more about how I can fix my DB woes.

6

To investigate problems debug logging is needed
see https://support.plex.tv/articles/201643703-reporting-issues-with-plex-media-server/

Please restart the server after enabling debug logging so we have fresh logs created and debug logging would be set for the start up stage

If you have specific issues, I would advise raising separate forum topics with the title of the thread summarizing the problem. You can add a link from here

7

I am seeing some repeated actions in the logs
Are you purging some directories daily?
eg - this should only happen once

Nov 13, 2018 04:17:05.708 [0x1541937ff700] ERROR - Error opening file '"/config/Library/Application Support/Plex Media Server/Cache/OCSP/main.der"' - No such file or directory (2)

Nov 14, 2018 04:08:58.474 [0x14b69bfff700] ERROR - Error opening file '"/config/Library/Application Support/Plex Media Server/Cache/OCSP/main.der"' - No such file or directory (2)

Nov 15, 2018 04:07:58.997 [0x152eee5fe700] ERROR - Error opening file '"/config/Library/Application Support/Plex Media Server/Cache/OCSP/main.der"' - No such file or directory (2)

Nov 16, 2018 04:29:21.887 [0x148d5bfff700] ERROR - Error opening file '"/config/Library/Application Support/Plex Media Server/Cache/OCSP/main.der"' - No such file or directory (2)

Nov 17, 2018 04:22:58.011 [0x14b0f85fe700] ERROR - Error opening file '"/config/Library/Application Support/Plex Media Server/Cache/OCSP/main.der"' - No such file or directory (2)

and codecs are being downloaded over and over

Nov 13, 2018 07:16:00.840 [0x1541909dc700] INFO - CodecManager: obtaining decoder 'aac'
Nov 13, 2018 07:16:01.661 [0x1541909dc700] INFO - CodecManager: obtaining encoder 'libx264'
Nov 13, 2018 07:16:02.559 [0x1541909dc700] INFO - CodecManager: obtaining encoder 'aac'
Nov 13, 2018 07:22:18.852 [0x154183dfe700] INFO - CodecManager: obtaining decoder 'ac3'
Nov 13, 2018 11:28:14.777 [0x1541907db700] INFO - CodecManager: obtaining decoder 'dca'
Nov 13, 2018 18:04:58.479 [0x15416d9fe700] INFO - CodecManager: obtaining decoder 'mp3'
Nov 13, 2018 18:04:59.284 [0x15416d9fe700] INFO - CodecManager: obtaining encoder 'libmp3lame'
Nov 13, 2018 19:34:28.832 [0x15415e1f4700] INFO - CodecManager: obtaining EAE

Nov 13, 2018 20:14:45.866 [0x14876cbff700] INFO - CodecManager: obtaining encoder 'ac3'

Nov 14, 2018 07:36:41.316 [0x14b6743ff700] INFO - CodecManager: obtaining decoder 'aac'
Nov 14, 2018 07:36:42.228 [0x14b6743ff700] INFO - CodecManager: obtaining encoder 'libx264'
Nov 14, 2018 07:36:43.235 [0x14b6743ff700] INFO - CodecManager: obtaining encoder 'aac'
Nov 14, 2018 14:16:24.411 [0x14b6661ea700] INFO - CodecManager: obtaining decoder 'ac3'
Nov 14, 2018 14:17:35.631 [0x14b698bd9700] INFO - CodecManager: obtaining EAE
Nov 14, 2018 18:22:06.547 [0x14b697bff700] INFO - CodecManager: obtaining decoder 'mp3'
Nov 14, 2018 18:22:07.461 [0x14b697bff700] INFO - CodecManager: obtaining encoder 'libmp3lame'
Nov 14, 2018 19:26:33.103 [0x14b68c3ff700] INFO - CodecManager: obtaining decoder 'dca'

Nov 15, 2018 06:28:53.056 [0x152ec5dfc700] INFO - CodecManager: obtaining decoder 'aac'
Nov 15, 2018 06:28:53.911 [0x152ec5dfc700] INFO - CodecManager: obtaining encoder 'libx264'
Nov 15, 2018 06:28:55.041 [0x152ec5dfc700] INFO - CodecManager: obtaining encoder 'aac'
Nov 15, 2018 09:06:54.994 [0x152ebb9fe700] INFO - CodecManager: obtaining decoder 'mp3'
Nov 15, 2018 09:06:55.872 [0x152ebb9fe700] INFO - CodecManager: obtaining encoder 'libmp3lame'
Nov 15, 2018 09:07:01.333 [0x152ee89fe700] INFO - CodecManager: obtaining decoder 'ac3'
Nov 15, 2018 09:07:02.129 [0x152ee89fe700] INFO - CodecManager: obtaining encoder 'ac3'
Nov 15, 2018 19:12:27.906 [0x152ee6dfe700] INFO - CodecManager: obtaining EAE

Nov 16, 2018 07:52:28.016 [0x148d4d3fb700] INFO - CodecManager: obtaining decoder 'dca'
Nov 16, 2018 07:52:28.769 [0x148d4d3fb700] INFO - CodecManager: obtaining encoder 'libx264'
Nov 16, 2018 07:52:29.556 [0x148d4d3fb700] INFO - CodecManager: obtaining encoder 'aac'
Nov 16, 2018 09:06:11.954 [0x148d4e7fd700] INFO - CodecManager: obtaining EAE
Nov 16, 2018 11:19:10.665 [0x148d2efff700] INFO - CodecManager: obtaining decoder 'aac_lc'
Nov 16, 2018 14:58:55.462 [0x148d453ff700] INFO - CodecManager: obtaining decoder 'aac'
Nov 16, 2018 15:13:26.582 [0x148d587fd700] INFO - CodecManager: obtaining decoder 'ac3'
Nov 16, 2018 18:45:16.430 [0x148d1f3f7700] INFO - CodecManager: obtaining decoder 'mp3'
Nov 16, 2018 18:45:17.395 [0x148d1f3f7700] INFO - CodecManager: obtaining encoder 'libmp3lame'

Nov 17, 2018 06:43:30.801 [0x14b0ed3ff700] INFO - CodecManager: obtaining decoder 'ac3'
Nov 17, 2018 06:43:31.554 [0x14b0ed3ff700] INFO - CodecManager: obtaining encoder 'libx264'
Nov 17, 2018 06:43:32.351 [0x14b0ed3ff700] INFO - CodecManager: obtaining encoder 'aac'
Nov 17, 2018 07:23:32.513 [0x14b0eabff700] INFO - CodecManager: obtaining decoder 'aac_lc'
Nov 17, 2018 09:45:08.463 [0x14b0f43ff700] INFO - CodecManager: obtaining decoder 'dca'
Nov 17, 2018 11:42:53.721 [0x14b0ea7fd700] INFO - CodecManager: obtaining EAE
Nov 17, 2018 14:58:40.168 [0x14b0f19fe700] INFO - CodecManager: obtaining decoder 'aac'
8

Indeed I am - the hastebin link has the nightly script I’m running, and it clears the cache and codecs folder:

rm -rf "/mnt/zfs/docker/plex/Library/Application Support/Plex Media Server/Codecs/"*
rm -rf "/mnt/zfs/docker/plex/Library/Application Support/Plex Media Server/Cache/"*

https://hastebin.com/ojayivafip.bash

The reason it’s in that script is that it’s one of those tricks that people tend to use around here when troubleshooting, so I threw it in with the database optimization process (the codecs one saved me once on a strange issue, after all). If it’s detrimental, I can remove them from the maintenance script.

9

why are doing this? There are good reasons for that cache and it helps when internet is down. Similarly codecs

You should remove those delete actions

10

The codecs removal & reboot has fixed an issue or two I had with the Nvidia Shield a few months back, so I just decided to do it to ensure I’d never hit that issue again. The cache was just as a general maintenance thing, mostly.

I’ll remove those actions as you noted.

11

Anything exciting found that would cause the Plex container to stop responding?

Also, any advice you can provide about my database woes? Sometimes I see queries taking several minutes to complete, which seems to be a very big issue.

12

A fix for deadlocks has just been released in beta 1.14.0.5468
So recommend updating to this version

If you continue to get deadlocks - search forum for posts with word deadlock and you should come across one of the many posts that specify what diagnostics are needed fir deadlocks - but probably initially just to go with logs

I do not want all your issues to continue into one general thread here - each issue needs to be on a separate dorumntopic with appropriate title and diagnostics and with debug logging enabled before the last launch of plex media server and kept enabled

With regards to dB slow queries - ensure it is optimized if adding / deleting lots of media

13

Ok I fired off my docker to use the beta Docker tag.

Version 1.14.0.5468

I’ll bump my thread about my database issues. The DB throws slow query all the time, not when adding/removing media, etc. I also run DB optimize literally 4 times a day via cron, with no perceived improvement in slow queries. I’ve even gotten to the point of moving my DB to RAMDisk for maximum performance, to no avail.

Thanks for your help!

14

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.