Scheduled Tasks - Video Preview Thumbnail Generation

Plex Media Server NAS & Devices
1

Server Version#: 1.18.5.2309
Player Version#: N/A

I’ve noticed this for a few weeks now, when the Butler is running, it fails to start generating video preview thumbnails due to a Uncaught Exception:

Jan 29, 2020 17:03:56.445 [0x7f746d7fa700] ERROR - Butler: Uncaught exception starting generator GenerateMediaIndexFiles: sqlite3_statement_backend::loadOne: database is locked

About half of my library has indexes already created. If I manually Analyze a show or library it runs, and when new media is added, it also runs, only the Scheduled Tasks generation isn’t working. This even happens with every scheduled task disabled other than Generate video preview thumbnails.

2

Sorry but it’s impossible to diagnose from only a snippet.
Please capture what’s happening and include the entire DEBUG logs ZIP.

3

Here you go.

4

Good news, using the latest PMS beta (version 1.18.6.2348) the scheduled video thumbnails activity started as expected.

Of concern however, it looks like all the files it attempted to generate previews for ended in BIF Failure. I looked in the Contents/Indexes directory where it was supposed to be generating them at and there was a “tmp” directory, no JPEGs were being spit out. Plex would read the entire file, but didn’t generate anything and ended in failure. The progress meter for the activity never filled while the activity was in progress either.

I have my library set to generate them on both Add and Scheduled tasks, items newly added to Plex were able to have their indexes built correctly.

5

I’m seeing success entries in your logs.

Feb 03, 2020 13:09:22.404 [0x7f406bfff700] DEBUG - Activity: updated activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6 - completed 98.4% - Generating video preview thumbnails
Feb 03, 2020 13:09:22.904 [0x7f41090e1700] DEBUG - Activity: updated activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6 - completed 98.8% - Generating video preview thumbnails
Feb 03, 2020 13:09:23.409 [0x7f404b7fe700] DEBUG - Activity: updated activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6 - completed 99.1% - Generating video preview thumbnails
Feb 03, 2020 13:09:23.908 [0x7f40ebfff700] DEBUG - Activity: updated activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6 - completed 99.6% - Generating video preview thumbnails
Feb 03, 2020 13:09:24.089 [0x7f40e97fa700] DEBUG - Activity: updated activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6 - completed 100.0% - Generating video preview thumbnails
Feb 03, 2020 13:09:24.103 [0x7f410bea7700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 3619915 is 0 (success)
Feb 03, 2020 13:09:24.449 [0x7f40d57fa700] DEBUG - Activity: Ended activity 66b4d7e0-400f-44d4-8f9c-7cbd6a2849e6.
Feb 03, 2020 13:09:24.450 [0x7f40d57fa700] DEBUG - Updating part with ID=309592 [/storage/TV/TV/Teen Wolf (2011)/01x11 - Formality Bluray-1080p Remux.mkv]
Feb 03, 2020 13:09:24.450 [0x7f40d57fa700] DEBUG - Building index (320x240) for parts for MetadataItem 222967 (Code Breaker)
Feb 03, 2020 13:09:24.453 [0x7f40d57fa700] DEBUG - Activity: registered new activity 8f47284f-5b0e-4e89-8922-07950061af6c - Generating video preview thumbnails
Feb 03, 2020 13:09:24.453 [0x7f40d57fa700] DEBUG - Job running: EAE_ROOT='/tmp/pms-e18f36a6-99c5-4cf0-a4b0-03f6862de355/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/8bf330d-2937-linux-x86_64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:v' 'h264' '-skip_frame:v' 'nokey' '-i' '/storage/TV/TV/Teen Wolf (2011)/01x12 - Code Breaker Bluray-1080p Remux.mkv' '-threads' '0' '-nostats' '-q' '3' '-loglevel' 'quiet' '-filter_complex' '[0:V:0] fps=fps=0.500000:round=up,scale=w=320:h=240:force_original_aspect_ratio=decrease [out]' '-map' '[out]' '/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/3/4d4120db83174b0ae18fcd8381dc0d328056443.bundle/Contents/Indexes/tmp/img-%06d.jpg' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/bif/8f47284f-5b0e-4e89-8922-07950061af6c/progress'
Feb 03, 2020 13:09:24.453 [0x7f40d57fa700] DEBUG - Jobs: Starting child process with pid 3621137

Feb 03, 2020 14:46:47.622 [0x7f410a92d700] DEBUG - Completed: [72.24.75.39:45927] 200 GET /library/metadata/157306?includeExtras=1&includeRelated=1&includeRelatedCount=10&checkFiles=1&includeReviews=1&includeExternalMetadata=1&asyncAugmentMetadata=1 (17 live) TLS GZIP 91ms 63004 bytes (pipelined: 1)
Feb 03, 2020 14:46:47.676 [0x7f4097fff700] DEBUG - Activity: updated activity 626f4847-67f3-4e12-83fa-0e37393243ae - completed 100.0% - Generating video preview thumbnails
Feb 03, 2020 14:46:47.687 [0x7f410bea7700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 3725587 is 0 (success)
Feb 03, 2020 14:46:47.697 [0x7f410a92d700] DEBUG - Auth: authenticated user 17595799 as tlsenior1968@gmail.com
6

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