Server Version# 1.32.3.7192
Player Version#: 1.70.2.3845-31e96af6
System: Synology DS1819+ with DSM 7.1.1-42962 Update 6
Hi all!
Just to branch out my issue from another topic, I am seeing an issue where my Plex server will continuously search for credits for the same movies on a nightly basis. It is the same movies each time, and it is always the movies in my 4K / HEVC collection. All of the movies in this case, have been ripped directly from a Blu-Ray without Transcoding, and a few of the movies I’ve checked using ffmpeg to confirm the movie files are not corrupt.
In Plex Media Server.log, I will typically see the following. Note the logs are copied from my previous post in the linked thread:
May 25, 2023 08:31:08.035 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 08:31:18.054 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 08:54:31.734 [140634185087800] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 25, 2023 09:01:18.443 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 09:01:28.463 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 09:31:28.860 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 09:31:38.879 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 09:54:26.732 [140634245327672] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 25, 2023 10:01:39.546 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 10:01:49.565 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 10:31:49.998 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 10:32:00.020 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 10:54:32.440 [140634185087800] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 25, 2023 11:02:00.407 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 11:02:10.433 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 11:32:10.836 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 11:32:20.856 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 11:54:26.779 [140634202237752] WARN - NAT: PMP, got an error: Not Supported by gateway.
May 25, 2023 12:02:21.320 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 12:02:31.336 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 12:32:31.699 [140634306628408] ERROR - [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 12:32:41.715 [140634306628408] ERROR - [CreditsDetectionManager] Job failed: Scanner job failed
In Plex Media Server Credits.log, I also see the following. Note that the file always ends here, and is the accompanying log file from the other thread. It does not show any error message what so ever as to why the Credits Detection fails.
May 25, 2023 11:32:21.247 [139960861637376] INFO - Plex Media Scanner v1.32.2.7100-248a2daf0 - Synology DS1819+ x86_64 - build: linux-x86_64 - GMT -04:00
May 25, 2023 11:32:21.248 [139960861637376] INFO - Linux version: DSM 7.1.1.42962-5, language: en-US
May 25, 2023 11:32:21.248 [139960861637376] INFO - Processor: 4-core Intel(R) Atom(TM) CPU C3538 @ 2.10GHz
May 25, 2023 11:32:21.248 [139960861637376] INFO - /volume1/@appstore/PlexMediaServer/Plex Media Scanner -C -f /<removed> --log-file-suffix Credits --creditsTempDataPath /var/packages/PlexMediaServer/shares/PlexMediaServer/AppData/tmp/PlexCreditsDetection-2e13715b-81d5-4cf9-8101-bcb7c66e4e25
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 13 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 14 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 15 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 16 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 17 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 18 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 19 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.532 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 20 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 21 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 22 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 23 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 24 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 25 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 26 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 27 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 28 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 29 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
May 25, 2023 11:32:21.533 [139960862165824] WARN - [FFMPEG] - Could not find codec parameters for stream 30 (Subtitle: hdmv_pgs_subtitle (pgssub)): unspecified size
Consider increasing the value for the 'analyzeduration' (20000000) and 'probesize' (20000000) options
After turning on Debug logging, I will receive log entries like this:
May 25, 2023 22:50:06.913 [140634183252792] ERROR - [Req#7400/CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
May 25, 2023 22:50:16.913 [140634183252792] DEBUG - [Req#7400/CreditsDetectionManager] Killing job.
May 25, 2023 22:50:16.913 [140634183252792] DEBUG - [Req#7400/CreditsDetectionManager] Signalling job ID 9094 with 9
May 25, 2023 22:50:16.913 [140634183252792] DEBUG - [Req#7400/CreditsDetectionManager] Activity: updated activity 88b645c2-13e8-4bd3-8be3-1220a8195df4 - completed 75.0% - Detecting Credits
May 25, 2023 22:50:16.917 [140634397236024] DEBUG - Jobs: '/volume1/@appstore/PlexMediaServer/Plex Media Scanner' exit code for process 9094 is -9 (signal: Killed)
May 25, 2023 22:50:16.951 [140634183252792] ERROR - [Req#7400/CreditsDetectionManager] Job failed: Scanner job failed
May 25, 2023 22:50:16.952 [140634183252792] DEBUG - [Req#7400/CreditsDetectionManager] Activity: Ended activity 88b645c2-13e8-4bd3-8be3-1220a8195df4.
In each case, the Scanner will fail at almost exactly 30 minutes to the T for each movie. Before the failure, I can see the Plex Scanner process writing to the Plex /tmp directory a batch of thumbnails of the movie. In an example movie, Crouching Tiger, Hidden Dragon the Transcoder within that half hour will create approximately 230 thumbnails into the /tmp directory before the process stops and the /tmp directory is dumped. If I run the same command Plex is executing on a Windows system running ffmpeg, ffmpeg will without error, create 361 thumbnails to my working directory and exit.
It seems the problem may be due to some sort of Watchdog or Timeout inside of Plex, caused by the fact that the slow Atom CPU in the NAS isn’t able to process HEVC video footage on a single core very quickly. Plex seems to limit this background detection to a single core as well, whereas it could probably finish if it were allowed to use more resources. When I run the same operation on Windows, I gave it an entire AMD Ryzen 5800X3D to decode the video, and it was able to complete the operation within a couple of minutes. I do not have issues with any movie that is H.264, VC-1, or MPEG-2 Encoded in 1080p.
In the above screenshot, I’m simply showing the ffmpeg output that occurred on Windows. The NAS made it to the file that was selected before the Plex Credits Detection ended for the movie, and the /tmp directory was dumped. In every case, the exact error is: [CreditsDetectionManager] BufferingLineReader: failed to read line (error: -2)
Any suggestions or insight on correcting this problem?
