Why does my server need to transcode/convert files?

Plex Media Server NAS & Devices
1

Server Version#: 1.25.3.5409
Player Version#: Plex app for Windows & Chrome browser

So this is not the right place to ask this question, since it’s a general question. But since there aren’t any place to ask this, I’ll ask it here.

For many years, I’ve run Plex server on my PC. I only play videos on my TV, and never had any issues. My very old Samsung TV could play 95% of all videos, by direct streaming, and rarely had to ask for transcoding by my PC. And my PC is very loud, when doing transcoding, and was in the same room as my TV.
My new Sony TV can play any video, without transcoding.

So I recently build a Raspberry Pi 4, to host my Plex server. I’ve played a 60 Mbit/s video on my TV, a 20 Mbit/s video on my average Galaxy A51, and a 5 Mbit/s video on my old Galaxy S5 at the same time. No videos are converted/transcoded by the Raspberry Pi, as all these units apparently supports direct streaming.
I played 15 minutes this way, without a single hickup on any of the devices, or on the raspberry. Why? Because it only has to provide the files, not doing any transcoding.

So here’s the issue. Why can’t my Chrome browser play any video, without transcoding? Why can’t the dedicated Plex Windows app play any video at all (error code 4294967283) from my Raspberry Pi? I don’t get why a simple smartphone from 2014 with Plex installed, can do something a quad core I4790K with a Nvidia GPU can’t!

2

Chrome like anything else has a prescribed codec support

Audio/Video - The Chromium Projects

Depended on the codec the content was encoded in and the supported codec profile of the playback device it may need to transcode for video and or audio streams respectively.

3

Well, just about all my videos are encoded with .H264 and Dolby Digital. And even my old Galaxy S5 can play back HEVC files, with Dolby Digital.

In the browser or Plex app on my PC, there isn’t even an option to choose “Direct Play”. There is on my phone, no matter, if the phone can play a video or not (they don’t like video with True-HD audio).

4

Activate debug logging in your server.
restart the server.
play the file in question for ~ 1 minute
Fetch the server logs and inspect the Plex Media Server.log.
Look for lines containing MDE: at the beginning.
These and the surrounding lines contain the best clues about why transcoding took place.

It can’t hurt to post the first ~20 lines from the Plex XML info of an affected video.

1 Like
5

Plex for Windows has a quality option for video quality.

image
image967×472 42.6 KB

6

Why do I even need to enable debugging, when it’s the player that doesn’t have “Direct Play” mode? My phones do, my TV does, my browser and the official Plex player for Windows doesn’t.

7

A web browser has the worst compatibility with media formats, containers, codecs.
There is no way to “enforce” direct play with a checkbox – because if you tried it, it would simply fail to play in many cases.

8

Try Plex Media Player (for Windows Home Theater PCs) looks like it might be what you are looking for.

9

I don’t think the log will help you much. When I play a video in the Plex app, it doesn’t even try. Just gives the error right away.
I played a video at 20:40:50, the playback error comes right away, but it takes 7 seconds, before there’s anything in the log.

Jan 14, 2022 20:40:57.150 [0x7fa7bfcaa8] DEBUG - Request: [192.168.1.100:54691 (Subnet)] POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2F24a054e2b0876b22e1d3c8f08026457ad74a1a68%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F377&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (4 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.150 [0x7fa7bfcaa8] DEBUG - PlayQueue: Converted 'server://24a054e2b0876b22e1d3c8f08026457ad74a1a68/com.plexapp.plugins.library/library/metadata/377' to 'library://x/item/%2Flibrary%2Fmetadata%2F377'
Jan 14, 2022 20:40:57.152 [0x7fa7bfcaa8] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Jan 14, 2022 20:40:57.155 [0x7fa7bfcaa8] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Jan 14, 2022 20:40:57.155 [0x7fa7bfcaa8] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Jan 14, 2022 20:40:57.155 [0x7fa7bfcaa8] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Jan 14, 2022 20:40:57.193 [0x7fa7ebeaa8] DEBUG - Request: [192.168.1.100:54692 (Subnet)] GET /photo/:/transcode?width=1920&height=1080&minSize=1&opacity=10&background=343a3f&url=%2Flibrary%2Fmetadata%2F377%2Fart%2F1641301770%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26X-Plex-Account-ID%3D1 (5 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.194 [0x7fa7ebeaa8] DEBUG - Photo transcoder: Request for url [/library/metadata/377/art/1641301770?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Account-ID=1] (is local: 1 upscaled: 0)
Jan 14, 2022 20:40:57.201 [0x7fa7bfcaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:57.201 [0x7fa7bfcaa8] DEBUG - Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:57.201 [0x7fa7bfcaa8] DEBUG - Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:57.206 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54691] 200 POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2F24a054e2b0876b22e1d3c8f08026457ad74a1a68%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F377&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (5 live) TLS GZIP 55ms 3209 bytes (pipelined: 1)
Jan 14, 2022 20:40:57.212 [0x7fa7ebeaa8] DEBUG - Calculated media file path for path [metadata://art/tv.plex.agents.movie_e94c2700ac7caeb76bfb1913a23abf36f7e5b8fd]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/6/29420e5a25e2781d808b8e025d5ad262aecbb2c.bundle/Contents/_combined/art/tv.plex.agents.movie_e94c2700ac7caeb76bfb1913a23abf36f7e5b8fd"]
Jan 14, 2022 20:40:57.213 [0x7fa7ebeaa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 14, 2022 20:40:57.245 [0x7fa7ebeaa8] DEBUG - Photo cache obtained 1053960 bytes from /library/metadata/377/art/1641301770?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Account-ID=1
Jan 14, 2022 20:40:57.245 [0x7fa7ebeaa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/bc/bce2a393b563cdfee188600ce47574398d90949a.jpg
Jan 14, 2022 20:40:57.278 [0x7fa7bfcaa8] DEBUG - Request: [192.168.1.100:54691 (Subnet)] GET /library/metadata/377?Accept-Language=da&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (5 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.300 [0x7fa7bfcaa8] DEBUG - Last refresh (2022-01-13 16:04:22) appears to be older than directory change time (2022-01-13 16:52:36), need to refresh local media agent.
Jan 14, 2022 20:40:57.336 [0x7fa7bfcaa8] DEBUG - Activity: registered new activity ae2b7eb5-55e8-4342-a31a-25cfef2f01ee - "Updating Metadata"
Jan 14, 2022 20:40:57.336 [0x7fa7bfcaa8] DEBUG - Activity: updated activity ae2b7eb5-55e8-4342-a31a-25cfef2f01ee - completed 49.0% - Updating Metadata
Jan 14, 2022 20:40:57.337 [0x7fa7bfcaa8] DEBUG - [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/fb9eae3-4185-linux-aarch64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Media Scanner' '--match' '--type' '1' '--log-file-suffix' ' Matcher' '--item' '377'
Jan 14, 2022 20:40:57.342 [0x7fa7bfcaa8] DEBUG - [JobRunner] Jobs: Starting child process with pid 2601160
Jan 14, 2022 20:40:57.350 [0x7fa7db5aa8] DEBUG - HubCache: Expiring '1/movie.genre.4/1/hubs/sections/4/daexternal-media,indirect-media/count=12&excludeFields=summary&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1' because event plex.event.item.updated referenced an item the hub contained
Jan 14, 2022 20:40:57.592 [0x7fa7bc0aa8] DEBUG - Request: [192.168.1.100:54693 (Subnet)] GET /library/sections/4/all?sort=addedAt:desc (8 live) TLS Page 0-33 GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.595 [0x7fa7bc0aa8] DEBUG - Setting container serialization range to [0, 33] (total=-1)
Jan 14, 2022 20:40:57.599 [0x7fa7c60aa8] DEBUG - Request: [192.168.1.100:54694 (Subnet)] GET /library/sections/4/all?unwatched=1&genre=1469&audienceRating%3E=7.0 (8 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.606 [0x7fa7923aa8] DEBUG - Request: [192.168.1.100:54695 (Subnet)] GET /library/sections/4/all?unwatched=1&audienceRating%3E=7.0&sort=audienceRating:desc (8 live) TLS Page 0-19 GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.623 [0x7fa7923aa8] DEBUG - Setting container serialization range to [0, 19] (total=-1)
Jan 14, 2022 20:40:57.643 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54694] 200 GET /library/sections/4/all?unwatched=1&genre=1469&audienceRating%3E=7.0 (8 live) TLS GZIP 51ms 1533 bytes (pipelined: 1)
Jan 14, 2022 20:40:57.663 [0x7fa7bc0aa8] DEBUG - It took 100.000000 ms to retrieve 34 items.
Jan 14, 2022 20:40:57.664 [0x7fa7923aa8] DEBUG - It took 70.000000 ms to retrieve 20 items.
Jan 14, 2022 20:40:57.666 [0x7fa7bc0aa8] DEBUG - Setting container serialization range to [0, 33] (total=34)
Jan 14, 2022 20:40:57.667 [0x7fa7923aa8] DEBUG - Setting container serialization range to [0, 19] (total=20)
Jan 14, 2022 20:40:57.677 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54695] 200 GET /library/sections/4/all?unwatched=1&audienceRating%3E=7.0&sort=audienceRating:desc (8 live) TLS GZIP Page 0-19 77ms 9377 bytes (pipelined: 1)
Jan 14, 2022 20:40:57.681 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54693] 200 GET /library/sections/4/all?sort=addedAt:desc (8 live) TLS GZIP Page 0-33 90ms 13128 bytes (pipelined: 1)
Jan 14, 2022 20:40:57.741 [0x7fa7bfcaa8] DEBUG - Killing job.
Jan 14, 2022 20:40:57.741 [0x7fa7bfcaa8] DEBUG - Signalling job ID 2601160 with 9
Jan 14, 2022 20:40:57.743 [0x7faa8eeaa8] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 2601160 is 0 (success)
Jan 14, 2022 20:40:57.767 [0x7fa7bfcaa8] DEBUG - Extras: 1 existing extras.
Jan 14, 2022 20:40:57.767 [0x7fa7bfcaa8] DEBUG - Extras: Checked extra 'Before The Flood' and found matched: 1 Media matched: 1
Jan 14, 2022 20:40:57.767 [0x7fa7bfcaa8] DEBUG - Loading information from media bundle for part ID 539 from "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/d/516b696d6c0f17e2ea150fe416fbc837f8a26e5.bundle/Contents/Plex Movie"
Jan 14, 2022 20:40:57.767 [0x7fa7bfcaa8] DEBUG - Loaded metadata for Before the Flood: DiCaprios klimakamp (ID 377) in 26ms
Jan 14, 2022 20:40:57.769 [0x7fa7bfcaa8] DEBUG - Done with metadata update for 377
Jan 14, 2022 20:40:57.770 [0x7fa7bfcaa8] DEBUG - Activity: Ended activity ae2b7eb5-55e8-4342-a31a-25cfef2f01ee.
Jan 14, 2022 20:40:57.811 [0x7fa7bfcaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:57.811 [0x7fa7bfcaa8] DEBUG - Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:57.811 [0x7fa7bfcaa8] DEBUG - Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:57.827 [0x7fa7bfcaa8] DEBUG - Activity: registered new activity d153b08a-6cce-438d-bdb0-c992a6e7bcf2 - "Refreshing"
Jan 14, 2022 20:40:57.828 [0x7fa747daa8] DEBUG - Activity: updated activity d153b08a-6cce-438d-bdb0-c992a6e7bcf2 - completed 0.0% - Refreshing
Jan 14, 2022 20:40:57.829 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54691] 200 GET /library/metadata/377?Accept-Language=da&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (8 live) TLS GZIP 551ms 4409 bytes (pipelined: 2)
Jan 14, 2022 20:40:57.868 [0x7fa7c60aa8] DEBUG - Request: [192.168.1.100:54691 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qp1ykzoh8sogg3yhcxs6wbm0&subtitles=none&copyts=1&Accept-Language=da (8 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.868 [0x7fa7c60aa8] DEBUG - [Transcode] Found session GUID of qp1ykzoh8sogg3yhcxs6wbm0 in session start.
Jan 14, 2022 20:40:57.868 [0x7fa7c60aa8] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jan 14, 2022 20:40:57.868 [0x7fa7c60aa8] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/377?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=dznimda6o25wkv1o6rbnmtlk
Jan 14, 2022 20:40:57.893 [0x7fa7c60aa8] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:57.893 [0x7fa7c60aa8] DEBUG - [Transcode] Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:57.893 [0x7fa7c60aa8] DEBUG - [Transcode] Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:57.894 [0x7fa7923aa8] DEBUG - Request: [192.168.1.100:54693 (Subnet)] GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=playing&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (8 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.897 [0x7fa7923aa8] DEBUG - Client [dznimda6o25wkv1o6rbnmtlk] reporting timeline state playing, progress of 0/5733000ms for guid=, playbackTime=0ms ratingKey=377 url=, key=/library/metadata/377, containerKey=, metadataId=377, source=
Jan 14, 2022 20:40:57.907 [0x7fa7c60aa8] DEBUG - [Transcode] Streaming Resource: Added session 0x7fa8c9afd8:dznimda6o25wkv1o6rbnmtlk
Jan 14, 2022 20:40:57.907 [0x7fa7c60aa8] DEBUG - [Transcode] Streaming Resource: Reached Decision id=377 codes=(MDE=1000,Direct play OK.) media=(id=437 part=(id=539 decision=direct play protocol=http streams=(Video=(id=1741 decision= width=1920 height=1080) Audio=(id=1742 decision= channels=0 rate=0))))
Jan 14, 2022 20:40:57.911 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54691] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qp1ykzoh8sogg3yhcxs6wbm0&subtitles=none&copyts=1&Accept-Language=da (8 live) TLS GZIP 43ms 3092 bytes (pipelined: 3)
Jan 14, 2022 20:40:57.923 [0x7fa7bc0aa8] DEBUG - Request: [192.168.1.100:54695 (Subnet)] GET /library/metadata/377?includeExternalMedia=1&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&asyncAugmentMetadata=1&checkFiles=1&skipRefresh=1 (8 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:57.980 [0x7fa7bc0aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:57.980 [0x7fa7bc0aa8] DEBUG - Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:57.980 [0x7fa7bc0aa8] DEBUG - Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:58.019 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54695] 200 GET /library/metadata/377?includeExternalMedia=1&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&asyncAugmentMetadata=1&checkFiles=1&skipRefresh=1 (8 live) TLS GZIP 98ms 4296 bytes (pipelined: 2)
Jan 14, 2022 20:40:58.025 [0x7fa7923aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:58.025 [0x7fa7923aa8] DEBUG - Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:58.025 [0x7fa7923aa8] DEBUG - Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:58.030 [0x7fa7923aa8] DEBUG - [Now] User is Akyhne (ID: 1)
Jan 14, 2022 20:40:58.030 [0x7fa7923aa8] DEBUG - [Now] Device is windows (ALEX-PC).
Jan 14, 2022 20:40:58.030 [0x7fa7923aa8] DEBUG - [Now] Profile is Plex Desktop
Jan 14, 2022 20:40:58.030 [0x7fa7923aa8] DEBUG - [Now] Updated play state for /library/metadata/377.
Jan 14, 2022 20:40:58.031 [0x7fa7923aa8] DEBUG - Statistics: (6y8movwue99e6quwsjnaa7xt) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 14, 2022 20:40:58.043 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54693] 200 GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=playing&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (8 live) TLS GZIP 149ms 374 bytes (pipelined: 2)
Jan 14, 2022 20:40:58.130 [0x7fa7bfcaa8] DEBUG - Request: [192.168.1.100:54699 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.130 [0x7fa7bfcaa8] DEBUG - [Now] Adding 1 sessions.
Jan 14, 2022 20:40:58.133 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54699] 200 GET /status/sessions (9 live) TLS GZIP 3ms 3373 bytes (pipelined: 1)
Jan 14, 2022 20:40:58.139 [0x7fa7c60aa8] DEBUG - Request: [192.168.1.100:54693 (Subnet)] GET /playQueues/134?repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.140 [0x7fa7c60aa8] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Jan 14, 2022 20:40:58.141 [0x7fa7c60aa8] DEBUG - PlayQueue: No need to refresh, active window is operational.
Jan 14, 2022 20:40:58.143 [0x7fa7bc0aa8] DEBUG - Request: [192.168.1.100:54695 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=inltl9msqipev07oo7036iry&offset=0&subtitles=auto&copyts=1&Accept-Language=da (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.144 [0x7fa7bc0aa8] DEBUG - [Transcode] Found session GUID of inltl9msqipev07oo7036iry in session start.
Jan 14, 2022 20:40:58.144 [0x7fa7bc0aa8] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jan 14, 2022 20:40:58.144 [0x7fa7bc0aa8] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/377?includeBandwidths=1&offset=0&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=dznimda6o25wkv1o6rbnmtlk
Jan 14, 2022 20:40:58.154 [0x7fa7c60aa8] DEBUG - PlayQueue: Client 6y8movwue99e6quwsjnaa7xt requested ownership of play queue 134, but already had it.
Jan 14, 2022 20:40:58.158 [0x7fa7c60aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:58.158 [0x7fa7c60aa8] DEBUG - Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:58.158 [0x7fa7c60aa8] DEBUG - Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:58.162 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54693] 200 GET /playQueues/134?repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (9 live) TLS GZIP 23ms 3209 bytes (pipelined: 3)
Jan 14, 2022 20:40:58.167 [0x7fa7923aa8] DEBUG - Request: [192.168.1.100:54691 (Subnet)] GET /status/sessions (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.168 [0x7fa7923aa8] DEBUG - [Now] Adding 1 sessions.
Jan 14, 2022 20:40:58.170 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54691] 200 GET /status/sessions (9 live) TLS GZIP 2ms 3241 bytes (pipelined: 4)
Jan 14, 2022 20:40:58.195 [0x7fa7bc0aa8] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:58.195 [0x7fa7bc0aa8] DEBUG - [Transcode] Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:58.195 [0x7fa7bc0aa8] DEBUG - [Transcode] Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: analyzing media item 437
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): Direct Play is disabled
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.200 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.201 [0x7fa7bc0aa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): selected media 0 / 437
Jan 14, 2022 20:40:58.202 [0x7fa7bc0aa8] DEBUG - [Transcode] Streaming Resource: Reached Decision id=377 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=437 part=(id=539 decision=transcode container=mkv protocol=http streams=(Video=(id=1741 decision=copy width=1920 height=1080) Audio=(id=1742 decision=copy channels=6 rate=48000))))
Jan 14, 2022 20:40:58.205 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54695] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=inltl9msqipev07oo7036iry&offset=0&subtitles=auto&copyts=1&Accept-Language=da (9 live) TLS GZIP 61ms 3084 bytes (pipelined: 3)
Jan 14, 2022 20:40:58.323 [0x7fa7ebeaa8] DEBUG - Created thumbnail of size 1920x1080, has pixels: 1
Jan 14, 2022 20:40:58.386 [0x7fa7bfcaa8] DEBUG - Request: [192.168.1.100:54695 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=qw22o0ciy1au4mnotewdlt1b&offset=0&subtitles=auto&copyts=1&Accept-Language=da (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.386 [0x7fa7bfcaa8] DEBUG - [Transcode] Found session GUID of qw22o0ciy1au4mnotewdlt1b in session start.
Jan 14, 2022 20:40:58.386 [0x7fa7bfcaa8] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Jan 14, 2022 20:40:58.386 [0x7fa7bfcaa8] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/377?includeBandwidths=1&offset=0&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=dznimda6o25wkv1o6rbnmtlk
Jan 14, 2022 20:40:58.406 [0x7fa7bfcaa8] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Jan 14, 2022 20:40:58.406 [0x7fa7bfcaa8] DEBUG - [Transcode] Selecting best audio stream for part ID 539 (autoselect: 0 language: da)
Jan 14, 2022 20:40:58.406 [0x7fa7bfcaa8] DEBUG - [Transcode] Audio Stream: 1742, Subtitle Stream: -1
Jan 14, 2022 20:40:58.412 [0x7fa7bfcaa8] DEBUG - [Transcode] Scaled up video bitrate to 3502Kbps based on 1.500000x fudge factor.
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: analyzing media item 437
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): Direct Play is disabled
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no direct play video profile exists for http/mkv/h264/ac3
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): Direct Streaming is disabled, so video stream will be transcoded
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): no remuxable profile found, so video stream will be transcoded
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] Scaled up video bitrate to 3502Kbps based on 1.500000x fudge factor.
Jan 14, 2022 20:40:58.413 [0x7fa7bfcaa8] DEBUG - [Transcode] MDE: Before the Flood: DiCaprios klimakamp (2016): selected media 0 / 437
Jan 14, 2022 20:40:58.414 [0x7fa7ebeaa8] DEBUG - Converted 32-bit thumbnail of type 1 to 24-bit with pixels
Jan 14, 2022 20:40:58.415 [0x7fa7bfcaa8] DEBUG - [Transcode] Streaming Resource: Session 0x7fa8c9afd8:dznimda6o25wkv1o6rbnmtlk changed transcode slot usage to used.  Used slots is now 1
Jan 14, 2022 20:40:58.415 [0x7fa7bfcaa8] DEBUG - [Transcode] Streaming Resource: Reached Decision id=377 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=437 part=(id=539 decision=transcode container=mkv protocol=http streams=(Video=(id=1741 decision=transcode bitrate=3502 encoder=libx264 width=1920 height=1080) Audio=(id=1742 decision=copy channels=6 rate=48000))))
Jan 14, 2022 20:40:58.418 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54695] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F377&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=qw22o0ciy1au4mnotewdlt1b&offset=0&subtitles=auto&copyts=1&Accept-Language=da (9 live) TLS GZIP 32ms 2958 bytes (pipelined: 4)
Jan 14, 2022 20:40:58.521 [0x7fa7c60aa8] DEBUG - Request: [192.168.1.100:54695 (Subnet)] GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=paused&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.523 [0x7fa7c60aa8] DEBUG - Client [dznimda6o25wkv1o6rbnmtlk] reporting timeline state paused, progress of 0/5733000ms for guid=, playbackTime=0ms ratingKey=377 url=, key=/library/metadata/377, containerKey=, metadataId=377, source=
Jan 14, 2022 20:40:58.531 [0x7fa7584aa8] DEBUG - Augment: Found a provider match for 'Before the Flood: DiCaprios klimakamp'.
Jan 14, 2022 20:40:58.538 [0x7fa7584aa8] DEBUG - Augment: Completed movie augmentation in 0.7 sec.
Jan 14, 2022 20:40:58.539 [0x7fa747daa8] DEBUG - Activity: Ended activity d153b08a-6cce-438d-bdb0-c992a6e7bcf2.
Jan 14, 2022 20:40:58.566 [0x7fa7c60aa8] DEBUG - [Now] User is Akyhne (ID: 1)
Jan 14, 2022 20:40:58.566 [0x7fa7c60aa8] DEBUG - [Now] Device is windows (ALEX-PC).
Jan 14, 2022 20:40:58.566 [0x7fa7c60aa8] DEBUG - [Now] Profile is Plex Desktop
Jan 14, 2022 20:40:58.566 [0x7fa7c60aa8] DEBUG - [Now] Updated play state for /library/metadata/377.
Jan 14, 2022 20:40:58.567 [0x7fa7c60aa8] DEBUG - Statistics: (6y8movwue99e6quwsjnaa7xt) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Jan 14, 2022 20:40:58.572 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54695] 200 GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=paused&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (9 live) TLS GZIP 51ms 374 bytes (pipelined: 5)
Jan 14, 2022 20:40:58.595 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:54692] 200 GET /photo/:/transcode?width=1920&height=1080&minSize=1&opacity=10&background=343a3f&url=%2Flibrary%2Fmetadata%2F377%2Fart%2F1641301770%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26X-Plex-Account-ID%3D1 (9 live) TLS GZIP 1401ms 40473 bytes (pipelined: 1)
Jan 14, 2022 20:40:58.638 [0x7fa7923aa8] DEBUG - Request: [192.168.1.100:54692 (Subnet)] GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=error&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (9 live) TLS GZIP Signed-in Token (Akyhne)
Jan 14, 2022 20:40:58.638 [0x7fa7923aa8] WARN - Could not convert "state" ("error") to the correct type
Jan 14, 2022 20:40:58.639 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:54692] 400 GET /:/timeline?ratingKey=377&key=%2Flibrary%2Fmetadata%2F377&playbackTime=0&playQueueItemID=2419&state=error&hasMDE=1&context=library%3Ahub.movie.genre&row=2&col=0&time=0&duration=5733000 (9 live) TLS GZIP 1ms 384 bytes (pipelined: 2)
10

Doesn’t work either. It doesn’t even try to play any files, doesn’t even report an error. i made sure to enable direct playback, both for local and remote content, and hardware acceleration.

11

Can you look at the Plex Dashboard on the web app, while playing this item?
I wonder if it says that the player is “remote”.

12

Just to clarify, neither the Plex app or the Plex HPC app even tries to play the videos. The error comes imediately with a code in the Plex app, and just an error in the HPC .
The Plex app triggers a “Now playing” event on the dashboard, but it only gives the title, an icon and the user playing the video. Nothing about whether it’s remote, or not.
The Plex HPC doesn’t even trigger anything in the dashboard.

And I actually DID just find a few files of dozens, that will actually play in Google Chrome. But they are in the same codec, as the others.
By loooking in the dashboard, I figured out, that if the video stream plays in “direct” mode, but the audio stream needs to be transcoded, the browser will play the video. But not if the video needs transcoding.

13

On a Raspberry Pi, it’s not an option to transcode videos. It can manage very small video conversions, like 720P 2 Mbit/s, but not if the source file is big.
And what I’m really trying to figure out, is why a mobile phone can play direct streaming, while a Windows app can’t.

14

Have you tested all three of these apps?

  • Google Chrome
  • Plex for Windows
  • Plex Media Player (for Windows Home Theater PCs)
15

Yeah, those are the apps, I’ve been mentioning all the time. And the only ones, that doean’t work (Windows based).

16

it is possible to get the logs from the windows app after you get that (error code 4294967283). The server logs covering the same event would be helpful as well.

17 
Jan 15, 2022 01:56:59.095 [14636] INFO - [WebController:visibilityChanged] Visibility changed signal received change to: Windowed
Jan 15, 2022 01:56:59.095 [14636] INFO - [WebController:visibilityChanged/WebController:handleSyntheticWindowStates] Synthetic window states for visibility Windowed: WindowNoState
Jan 15, 2022 01:57:02.650 [14636] INFO - [WebController:saveAppearance] Saved geometry: w:1532 h:822 x:448 y:316
Jan 15, 2022 01:57:02.650 [14636] INFO - [WebController:saveAppearance] Saved state: windowStates:WindowNoState screen:\\.\DISPLAY1
Jan 15, 2022 01:57:05.303 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:05.304 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:05.334 [14636] DEBUG - Disabling OS screensaver
Jan 15, 2022 01:57:05.348 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:05.762 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:06.199 [14636] INFO - [Web] [MDE] Augmented profile: {
  "directPlay": {
    "3gpp": {},
    "asf": {},
    "avi": {},
    "mkv": {},
    "mov": {},
    "mp4": {},
    "mpeg": {},
    "mpegts": {},
    "rm": {},
    "wtv": {}
  },
  "directStream": {}
}
Jan 15, 2022 01:57:06.199 [14636] INFO - [Web] [MDE] Starting analysis of 1080 (mkv, h264, ac3, 40, high)
Jan 15, 2022 01:57:06.199 [14636] INFO - [Web] [MDE] Analyzing direct play
Jan 15, 2022 01:57:06.200 [14636] INFO - [Web] [MDE] Analyzing video direct stream
Jan 15, 2022 01:57:06.200 [14636] INFO - [Web] [MDE] Analyzing audio direct stream
Jan 15, 2022 01:57:06.200 [14636] INFO - [Web] [MDE] Analyzing playability
Jan 15, 2022 01:57:06.201 [14636] INFO - [Web] [MDE] Finished analysis of: 1080 (mkv, h264, ac3, 40, high) {
  "canPlay": true,
  "canDirectPlay": true,
  "canDirectStreamVideo": true,
  "canDirectStreamAudio": true,
  "bitrate": 5039,
  "videoResolution": 1080
}
Jan 15, 2022 01:57:06.202 [14636] INFO - [Web] [PDE] Player decision: {
  "playerType": "html",
  "protocol": "http",
  "canDirectPlay": true
}
Jan 15, 2022 01:57:06.204 [14636] INFO - [Web] [Transcoder] Video (decision) options: {
  "hasMDE": 1,
  "path": "/library/metadata/3924",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 1,
  "directStream": 1,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "lan",
  "addDebugOverlay": 0,
  "autoAdjustQuality": 0,
  "directStreamAudio": 1,
  "advancedSubtitles": "text",
  "X-Plex-Session-Identifier": "s8mo8nqi4e9fvd1v4ggssaq4",
  "session": "v70vth4l2bc7hxdtgbyg1cyc",
  "subtitles": "none",
  "copyts": 1,
  "X-Plex-Incomplete-Segments": 1
}
Jan 15, 2022 01:57:06.267 [14636] INFO - [Web] [Player] Timeline, {
  "ratingKey": "3924",
  "key": "/library/metadata/3924",
  "playbackTime": 0,
  "playQueueItemID": "3407",
  "state": "playing",
  "hasMDE": 1,
  "context": "library:hub.movie.recentlyreleased",
  "row": 0,
  "col": 0,
  "time": 0,
  "duration": 4042000
}
Jan 15, 2022 01:57:06.268 [14636] ERROR - [Web] Did not move header "accept" to query string. This can result in an unnecessary OPTIONS preflight request.
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'audio-channels' set to 'auto-safe'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'audio-device' set to 'auto'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'audio-spdif' set to ''
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'audio-exclusive' set to 'no'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'hwdec' set to 'auto'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - hwdec property set to auto
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.333 [14636] DEBUG - [MPVEngine] Property 'sub-color' set to '#ffffff'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'sub-border-color' set to '#000000'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'sub-align-y' set to 'bottom'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'sub-align-x' set to 'center'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'sub-font-size' set to '45'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'correct-downscaling' set to 'no'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'cscale' set to 'spline36'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'deband' set to 'no'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'dither-depth' set to 'no'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'dscale' set to 'spline36'
Jan 15, 2022 01:57:06.334 [14636] DEBUG - [MPVEngine] Property 'linear-downscaling' set to 'no'
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine] Property 'scale' set to 'spline36'
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine] Property 'sigmoid-upscaling' set to 'no'
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-channels="auto-safe" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-device="auto" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-spdif="" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-exclusive="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: hwdec="auto" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-color="#ffffff" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-border-color="#000000" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-y="bottom" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-x="center" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-font-size=45 -> 1
Jan 15, 2022 01:57:06.335 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: correct-downscaling="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: cscale="spline36" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deband="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dither-depth="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dscale="spline36" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: linear-downscaling="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: scale="spline36" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sigmoid-upscaling="no" -> 1
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.335 [14636] INFO - [Engine] Queue media: {"audio":{"index":0},"effectiveFrameRate":25,"startPositionSeconds":0,"subtitle":{"index":-1,"url":""},"type":"video","url":"https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da"}
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [Engine] Queue called with startPositionSeconds=0
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [Engine] Queue called with audio.index=0
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [Engine] Queue called with subtitle.index=-1 subtitle.url=
Jan 15, 2022 01:57:06.335 [14636] DEBUG - [Engine] [Widevine] Updated URL: .
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-client-name' set to 'Plex'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-lavf-probe-info' set to 'true'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-mkv-probe-start-time' set to 'false'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-seekable-cache' set to 'yes'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'gpu-hwdec-interop' set to 'auto'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'title' set to 'Plex'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'tls-verify' set to 'yes'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'osd-level' set to '0'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-display' set to 'false'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'vo' set to 'libmpv'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'pause' set to 'yes'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.336 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.336 [14636] INFO - [Engine/MPVEngine] Loading https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da into MPV.
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [Engine/MPVEngine] Property 'start' set to '+0'
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [Engine/MPVEngine] Property 'aid' set to 'no'
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [Engine/MPVEngine] Property 'sid' set to 'no'
Jan 15, 2022 01:57:06.337 [14636] INFO - [MPVEngine] Playback has paused.
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Paused'
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-client-name="Plex" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-lavf-probe-info=true -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-mkv-probe-start-time=false -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-seekable-cache="yes" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: gpu-hwdec-interop="auto" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: title="Plex" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: tls-verify="yes" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: osd-level="0" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-display=false -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: vo="libmpv" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause="yes" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.337 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Setting option 'stream-lavf-o' = 'resolve_hosts=[192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:192.168.1.115]' (flags = 16)
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: start="+0" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: aid="no" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sid="no" -> 1
Jan 15, 2022 01:57:06.337 [14636] DEBUG - [MPVEngine/mpv] ffmpeg: Opening https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da
Jan 15, 2022 01:57:06.338 [14636] INFO - [WebController] Shrinking video to position: top-left, width: -1, height: -1, edgeGutterX: 0, edgeGutterY: 0, stackedAbove: 0
Jan 15, 2022 01:57:06.371 [14636] INFO - [MPVEngine] Resuming playback.
Jan 15, 2022 01:57:06.372 [14636] INFO - [MPVEngine] Playback has resumed.
Jan 15, 2022 01:57:06.372 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Playing'
Jan 15, 2022 01:57:06.372 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause=no -> 1
Jan 15, 2022 01:57:06.372 [14636] WARN - [MPVEngine/mpv] cplayer: Warning: property 'video-aspect' is deprecated, refer to 'video-params/aspect' and 'video-aspect-override'.
Jan 15, 2022 01:57:06.373 [20232] INFO - Debug info:
File: 
  URL:  https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da 
  Container:  ? 
  Native seeking:  no 

Video: 
  Codec:  ? 
  Size:  ? x ? 
  Duration:  0.00 
  FPS (container):  0.00 
  FPS (filters):  0.00 
  Aspect:  -1.00 
  Bitrate:  ? 
  Display FPS:  0.00 
  Hardware Decoding:  ?  ( ? ) 

Audio:  
  Codec:  ? 
  Bitrate:  ? 
  Channels:  ?  (  )  ->  ?  (  ) 
  Output driver:  ? 

Cache: 
  Total Used (B):  ? 
  Forward Used (B):  ? 
  Forward Available (B):  157286400 
  Backward Available (B):  52428800 
  Speed (bytes per second):  ? 

Misc:  
  Seekable:  ? 
  Partially seekable:  ? 
  Paused for cache:  ? 
  Buffering state:  ? 
  Frame drop count:  ? 
  Mistimed frames:  ? 
  Time:  0.00  /  0.00  ( 0.00 %) 
  State:    waiting   
Jan 15, 2022 01:57:06.423 [14636] ERROR - [MPVEngine] loading failed.
Jan 15, 2022 01:57:06.423 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Stopped'
Jan 15, 2022 01:57:06.424 [14636] ERROR - [MPVEngine/mpv] ffmpeg: tls: Peer certificate failed verification
Jan 15, 2022 01:57:06.424 [14636] ERROR - [MPVEngine/mpv] stream: Failed to open https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da.
Jan 15, 2022 01:57:06.424 [14636] DEBUG - [MPVEngine/mpv] cplayer: Opening failed or was aborted: https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/library/parts/4165/1641993117/file.mkv?X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da&Accept-Language=da
Jan 15, 2022 01:57:06.424 [14636] DEBUG - [MPVEngine/mpv] cplayer: finished playback, loading failed (reason 4)
Jan 15, 2022 01:57:06.424 [14636] INFO - [MPVEngine/mpv] cplayer: 
Jan 15, 2022 01:57:06.427 [14636] ERROR - [Web] [QTMedia(5)] A critical error occurred: 4294967283 Der opstod en ukendt fejl (4294967283)
Jan 15, 2022 01:57:06.428 [14636] ERROR - [Web] [Player] Direct Play failed, but server can transcode. Attempting to fallback to either Direct Stream or transcode...
Jan 15, 2022 01:57:06.491 [14636] ERROR - [Web] [QTMedia(5)] componentWillUnmount rejected: {
  "type": "[object Error]",
  "stack": "Error: Resolved after unmount\n    at file:///C:/Program%20Files/Plex/Plex/web-client/js/main-179-f339c3576533099a619d-plex-4.71.0.20682-5e9f24d.js:2:2287300\n    at async file:///C:/Program%20Files/Plex/Plex/web-client/js/933-933-0d4cec1635626a888db0-plex-4.71.0.20682-5e9f24d.js:2:983635",
  "name": "Error",
  "message": "Resolved after unmount"
}
Jan 15, 2022 01:57:06.538 [14636] INFO - [Web] [MDE] Augmented profile: {
  "directPlay": {
    "3gpp": {},
    "asf": {},
    "avi": {},
    "mkv": {},
    "mov": {},
    "mp4": {},
    "mpeg": {},
    "mpegts": {},
    "rm": {},
    "wtv": {}
  },
  "directStream": {}
}
Jan 15, 2022 01:57:06.538 [14636] INFO - [Web] [MDE] Starting analysis of 1080 (mkv, h264, ac3, 40, high)
Jan 15, 2022 01:57:06.538 [14636] INFO - [Web] [MDE] Analyzing direct play
Jan 15, 2022 01:57:06.539 [14636] ERROR - [Web] [MDE] Direct play failed; option is disabled
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [MDE] Cannot direct play: allowDirectPlay
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [MDE] Analyzing video direct stream
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [MDE] Analyzing audio direct stream
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [MDE] Analyzing playability
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [MDE] Finished analysis of: 1080 (mkv, h264, ac3, 40, high) {
  "canPlay": true,
  "canDirectPlay": false,
  "canDirectStreamVideo": true,
  "canDirectStreamAudio": true,
  "bitrate": 5039,
  "videoResolution": 1080
}
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [PDE] Player decision: {
  "playerType": "html",
  "protocol": "http",
  "canDirectPlay": false
}
Jan 15, 2022 01:57:06.539 [14636] INFO - [Web] [Transcoder] Video (start) options: {
  "hasMDE": 1,
  "path": "/library/metadata/3924",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 1,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "lan",
  "addDebugOverlay": 0,
  "autoAdjustQuality": 0,
  "directStreamAudio": 1,
  "advancedSubtitles": "text",
  "X-Plex-Session-Identifier": "s8mo8nqi4e9fvd1v4ggssaq4",
  "session": "qyd7b8035te1gkuwp4zw0kfo",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Jan 15, 2022 01:57:06.540 [14636] INFO - [Web] [Transcoder] Video (decision) options: {
  "hasMDE": 1,
  "path": "/library/metadata/3924",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 1,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "lan",
  "addDebugOverlay": 0,
  "autoAdjustQuality": 0,
  "directStreamAudio": 1,
  "advancedSubtitles": "text",
  "X-Plex-Session-Identifier": "s8mo8nqi4e9fvd1v4ggssaq4",
  "session": "qyd7b8035te1gkuwp4zw0kfo",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'audio-channels' set to 'auto-safe'
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'audio-device' set to 'auto'
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'audio-spdif' set to ''
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'audio-exclusive' set to 'no'
Jan 15, 2022 01:57:06.672 [14636] DEBUG - [MPVEngine] Property 'hwdec' set to 'auto'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - hwdec property set to auto
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sub-color' set to '#ffffff'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sub-border-color' set to '#000000'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sub-align-y' set to 'bottom'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sub-align-x' set to 'center'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sub-font-size' set to '45'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'correct-downscaling' set to 'no'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'cscale' set to 'spline36'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'deband' set to 'no'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'dither-depth' set to 'no'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'dscale' set to 'spline36'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'linear-downscaling' set to 'no'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'scale' set to 'spline36'
Jan 15, 2022 01:57:06.673 [14636] DEBUG - [MPVEngine] Property 'sigmoid-upscaling' set to 'no'
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-channels="auto-safe" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-device="auto" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-spdif="" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-exclusive="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: hwdec="auto" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-color="#ffffff" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-border-color="#000000" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-y="bottom" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-x="center" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-font-size=45 -> 1
Jan 15, 2022 01:57:06.674 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: correct-downscaling="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: cscale="spline36" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deband="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dither-depth="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dscale="spline36" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: linear-downscaling="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: scale="spline36" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sigmoid-upscaling="no" -> 1
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.674 [14636] INFO - [Engine] Queue media: {"audio":{"index":0},"effectiveFrameRate":25,"startPositionSeconds":0,"subtitle":{"index":-1,"url":""},"type":"video","url":"https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da"}
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine] Queue called with startPositionSeconds=0
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine] Queue called with audio.index=0
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine] Queue called with subtitle.index=-1 subtitle.url=
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine] [Widevine] Updated URL: .
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-client-name' set to 'Plex'
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-lavf-probe-info' set to 'true'
Jan 15, 2022 01:57:06.674 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-mkv-probe-start-time' set to 'false'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-seekable-cache' set to 'yes'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'gpu-hwdec-interop' set to 'auto'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'title' set to 'Plex'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'tls-verify' set to 'yes'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'osd-level' set to '0'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-display' set to 'false'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'vo' set to 'libmpv'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'pause' set to 'yes'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.675 [14636] INFO - [Engine/MPVEngine] Loading https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da into MPV.
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'start' set to '+0'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'aid' set to 'no'
Jan 15, 2022 01:57:06.675 [14636] DEBUG - [Engine/MPVEngine] Property 'sid' set to 'no'
Jan 15, 2022 01:57:06.676 [14636] INFO - [MPVEngine] Playback has paused.
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Paused'
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-client-name="Plex" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-lavf-probe-info=true -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-mkv-probe-start-time=false -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-seekable-cache="yes" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: gpu-hwdec-interop="auto" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: title="Plex" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: tls-verify="yes" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: osd-level="0" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-display=false -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: vo="libmpv" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause="yes" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.676 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Setting option 'stream-lavf-o' = 'resolve_hosts=[192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:192.168.1.115]' (flags = 16)
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: start="+0" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: aid="no" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sid="no" -> 1
Jan 15, 2022 01:57:06.676 [14636] DEBUG - [MPVEngine/mpv] ffmpeg: Opening https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da
Jan 15, 2022 01:57:06.676 [14636] INFO - [WebController] Shrinking video to position: top-left, width: -1, height: -1, edgeGutterX: 0, edgeGutterY: 0, stackedAbove: 0
Jan 15, 2022 01:57:06.707 [14636] INFO - [MPVEngine] Resuming playback.
Jan 15, 2022 01:57:06.707 [14636] INFO - [MPVEngine] Playback has resumed.
Jan 15, 2022 01:57:06.708 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Playing'
Jan 15, 2022 01:57:06.708 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause=no -> 1
Jan 15, 2022 01:57:06.708 [20232] INFO - Debug info:
File: 
  URL:  https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da 
  Container:  ? 
  Native seeking:  no 

Video: 
  Codec:  ? 
  Size:  ? x ? 
  Duration:  0.00 
  FPS (container):  0.00 
  FPS (filters):  0.00 
  Aspect:  -1.00 
  Bitrate:  ? 
  Display FPS:  0.00 
  Hardware Decoding:  ?  ( ? ) 

Audio:  
  Codec:  ? 
  Bitrate:  ? 
  Channels:  ?  (  )  ->  ?  (  ) 
  Output driver:  ? 

Cache: 
  Total Used (B):  ? 
  Forward Used (B):  ? 
  Forward Available (B):  157286400 
  Backward Available (B):  52428800 
  Speed (bytes per second):  ? 

Misc:  
  Seekable:  ? 
  Partially seekable:  ? 
  Paused for cache:  ? 
  Buffering state:  ? 
  Frame drop count:  ? 
  Mistimed frames:  ? 
  Time:  0.00  /  0.00  ( 0.00 %) 
  State:    waiting   
Jan 15, 2022 01:57:06.746 [14636] ERROR - [MPVEngine] loading failed.
Jan 15, 2022 01:57:06.746 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Stopped'
Jan 15, 2022 01:57:06.746 [14636] ERROR - [MPVEngine/mpv] ffmpeg: tls: Peer certificate failed verification
Jan 15, 2022 01:57:06.746 [14636] ERROR - [MPVEngine/mpv] stream: Failed to open https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da.
Jan 15, 2022 01:57:06.746 [14636] DEBUG - [MPVEngine/mpv] cplayer: Opening failed or was aborted: https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=qyd7b8035te1gkuwp4zw0kfo&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da
Jan 15, 2022 01:57:06.746 [14636] DEBUG - [MPVEngine/mpv] cplayer: finished playback, loading failed (reason 4)
Jan 15, 2022 01:57:06.746 [14636] INFO - [MPVEngine/mpv] cplayer: 
Jan 15, 2022 01:57:06.747 [14636] ERROR - [Web] [QTMedia(6)] A critical error occurred: 4294967283 Der opstod en ukendt fejl (4294967283)
Jan 15, 2022 01:57:06.748 [14636] ERROR - [Web] [Player] Direct Stream failed, but server can transcode. Attempting to fallback to video transcode...
Jan 15, 2022 01:57:06.785 [14636] INFO - [Web] [MDE] Augmented profile: {
  "directPlay": {
    "3gpp": {},
    "asf": {},
    "avi": {},
    "mkv": {},
    "mov": {},
    "mp4": {},
    "mpeg": {},
    "mpegts": {},
    "rm": {},
    "wtv": {}
  },
  "directStream": {}
}
18 

Jan 15, 2022 01:57:06.785 [14636] INFO - [Web] [MDE] Starting analysis of 1080 (mkv, h264, ac3, 40, high)
Jan 15, 2022 01:57:06.785 [14636] INFO - [Web] [MDE] Analyzing direct play
Jan 15, 2022 01:57:06.785 [14636] ERROR - [Web] [MDE] Direct play failed; option is disabled
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [MDE] Cannot direct play: allowDirectPlay
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [MDE] Analyzing video direct stream
Jan 15, 2022 01:57:06.786 [14636] ERROR - [Web] [MDE] Direct stream video failed; option is disabled
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [MDE] Analyzing audio direct stream
Jan 15, 2022 01:57:06.786 [14636] ERROR - [Web] [MDE] Direct stream audio failed; option is disabled
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [MDE] Analyzing playability
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [MDE] Finished analysis of: 1080 (mkv, h264, ac3, 40, high) {
  "canPlay": true,
  "canDirectPlay": false,
  "canDirectStreamVideo": false,
  "canDirectStreamAudio": false,
  "bitrate": 5039,
  "videoResolution": 1080
}
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [PDE] Player decision: {
  "playerType": "html",
  "protocol": "http",
  "canDirectPlay": false
}
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [Transcoder] Video (start) options: {
  "hasMDE": 1,
  "path": "/library/metadata/3924",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 0,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "lan",
  "X-Plex-Session-Identifier": "s8mo8nqi4e9fvd1v4ggssaq4",
  "session": "cffk2ltcrt3gzbbpdhrafi08",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Jan 15, 2022 01:57:06.786 [14636] INFO - [Web] [Transcoder] Video (decision) options: {
  "hasMDE": 1,
  "path": "/library/metadata/3924",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 0,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "lan",
  "X-Plex-Session-Identifier": "s8mo8nqi4e9fvd1v4ggssaq4",
  "session": "cffk2ltcrt3gzbbpdhrafi08",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Jan 15, 2022 01:57:06.800 [14636] ERROR - [Web] [QTMedia(6)] componentWillUnmount rejected: {
  "type": "[object Error]",
  "stack": "Error: Resolved after unmount\n    at file:///C:/Program%20Files/Plex/Plex/web-client/js/main-179-f339c3576533099a619d-plex-4.71.0.20682-5e9f24d.js:2:2287300\n    at async file:///C:/Program%20Files/Plex/Plex/web-client/js/933-933-0d4cec1635626a888db0-plex-4.71.0.20682-5e9f24d.js:2:983635",
  "name": "Error",
  "message": "Resolved after unmount"
}
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'audio-channels' set to 'auto-safe'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'audio-device' set to 'auto'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'audio-spdif' set to ''
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'audio-exclusive' set to 'no'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'hwdec' set to 'auto'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - hwdec property set to auto
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'sub-color' set to '#ffffff'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'sub-border-color' set to '#000000'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'sub-align-y' set to 'bottom'
Jan 15, 2022 01:57:06.879 [14636] DEBUG - [MPVEngine] Property 'sub-align-x' set to 'center'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'sub-font-size' set to '45'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'correct-downscaling' set to 'no'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'cscale' set to 'spline36'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'deband' set to 'no'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'dither-depth' set to 'no'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'dscale' set to 'spline36'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'linear-downscaling' set to 'no'
Jan 15, 2022 01:57:06.880 [14636] DEBUG - [MPVEngine] Property 'scale' set to 'spline36'
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine] Property 'sigmoid-upscaling' set to 'no'
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-channels="auto-safe" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-device="auto" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-spdif="" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-exclusive="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: hwdec="auto" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-color="#ffffff" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-border-color="#000000" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-y="bottom" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-align-x="center" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sub-font-size=45 -> 1
Jan 15, 2022 01:57:06.881 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: correct-downscaling="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: cscale="spline36" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deband="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dither-depth="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: dscale="spline36" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: linear-downscaling="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: scale="spline36" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sigmoid-upscaling="no" -> 1
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.881 [14636] INFO - [Engine] Queue media: {"audio":{"index":0},"effectiveFrameRate":25,"startPositionSeconds":0,"subtitle":{"index":-1,"url":""},"type":"video","url":"https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da"}
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [Engine] Queue called with startPositionSeconds=0
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [Engine] Queue called with audio.index=0
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [Engine] Queue called with subtitle.index=-1 subtitle.url=
Jan 15, 2022 01:57:06.881 [14636] DEBUG - [Engine] [Widevine] Updated URL: .
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-client-name' set to 'Plex'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-lavf-probe-info' set to 'true'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-mkv-probe-start-time' set to 'false'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'demuxer-seekable-cache' set to 'yes'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'gpu-hwdec-interop' set to 'auto'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'title' set to 'Plex'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'tls-verify' set to 'yes'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'osd-level' set to '0'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-display' set to 'false'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'vo' set to 'libmpv'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'pause' set to 'yes'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'deinterlace' set to 'false'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'speed' set to '1'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'volume' set to '90'
Jan 15, 2022 01:57:06.882 [14636] DEBUG - [Engine/MPVEngine] Property 'audio-normalize-downmix' set to 'yes'
Jan 15, 2022 01:57:06.882 [14636] INFO - [Engine/MPVEngine] Loading https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da into MPV.
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [Engine/MPVEngine] Property 'start' set to '+0'
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [Engine/MPVEngine] Property 'aid' set to 'no'
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [Engine/MPVEngine] Property 'sid' set to 'no'
Jan 15, 2022 01:57:06.883 [14636] INFO - [MPVEngine] Playback has paused.
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Paused'
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-client-name="Plex" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-lavf-probe-info=true -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-mkv-probe-start-time=false -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: demuxer-seekable-cache="yes" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: gpu-hwdec-interop="auto" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: title="Plex" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: tls-verify="yes" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: osd-level="0" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-display=false -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: vo="libmpv" -> 1
Jan 15, 2022 01:57:06.883 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause="yes" -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: deinterlace=false -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: speed=1.000000 -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: volume=90.000000 -> 1
Jan 15, 2022 01:57:06.884 [14636] WARN - [MPVEngine/mpv] cplayer: Option af-remove: item label @ac3 not found.
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: audio-normalize-downmix="yes" -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Setting option 'stream-lavf-o' = 'resolve_hosts=[192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:192.168.1.115]' (flags = 16)
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: start="+0" -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: aid="no" -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: sid="no" -> 1
Jan 15, 2022 01:57:06.884 [14636] DEBUG - [MPVEngine/mpv] ffmpeg: Opening https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da
Jan 15, 2022 01:57:06.884 [14636] INFO - [WebController] Shrinking video to position: top-left, width: -1, height: -1, edgeGutterX: 0, edgeGutterY: 0, stackedAbove: 0
Jan 15, 2022 01:57:06.908 [14636] INFO - [MPVEngine] Resuming playback.
Jan 15, 2022 01:57:06.908 [14636] INFO - [MPVEngine] Playback has resumed.
Jan 15, 2022 01:57:06.908 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Playing'
Jan 15, 2022 01:57:06.908 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause=no -> 1
Jan 15, 2022 01:57:06.908 [20232] INFO - Debug info:
File: 
  URL:  https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da 
  Container:  ? 
  Native seeking:  no 

Video: 
  Codec:  ? 
  Size:  ? x ? 
  Duration:  0.00 
  FPS (container):  0.00 
  FPS (filters):  0.00 
  Aspect:  -1.00 
  Bitrate:  ? 
  Display FPS:  0.00 
  Hardware Decoding:  ?  ( ? ) 

Audio:  
  Codec:  ? 
  Bitrate:  ? 
  Channels:  ?  (  )  ->  ?  (  ) 
  Output driver:  ? 

Cache: 
  Total Used (B):  ? 
  Forward Used (B):  ? 
  Forward Available (B):  157286400 
  Backward Available (B):  52428800 
  Speed (bytes per second):  ? 

Misc:  
  Seekable:  ? 
  Partially seekable:  ? 
  Paused for cache:  ? 
  Buffering state:  ? 
  Frame drop count:  ? 
  Mistimed frames:  ? 
  Time:  0.00  /  0.00  ( 0.00 %) 
  State:    waiting   
Jan 15, 2022 01:57:06.946 [14636] ERROR - [MPVEngine] loading failed.
Jan 15, 2022 01:57:06.947 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Stopped'
Jan 15, 2022 01:57:06.947 [14636] ERROR - [MPVEngine/mpv] ffmpeg: tls: Peer certificate failed verification
Jan 15, 2022 01:57:06.947 [14636] ERROR - [MPVEngine/mpv] stream: Failed to open https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da.
Jan 15, 2022 01:57:06.947 [14636] DEBUG - [MPVEngine/mpv] cplayer: Opening failed or was aborted: https://192-168-1-115.d2e0dd537b1a4e92b0a25cf6259e4f79.plex.direct:32400/video/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F3924&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&session=cffk2ltcrt3gzbbpdhrafi08&offset=0&subtitles=auto&copyts=1&Accept-Language=da&X-Plex-Session-Identifier=s8mo8nqi4e9fvd1v4ggssaq4&X-Plex-Chunked=1&X-Plex-Incomplete-Segments=1&X-Plex-Product=Plex%20for%20Windows&X-Plex-Version=1.39.1.2763-300bb607&X-Plex-Client-Identifier=6y8movwue99e6quwsjnaa7xt&X-Plex-Platform=windows&X-Plex-Platform-Version=10.0.19042&X-Plex-Sync-Version=2&X-Plex-Features=external-media%2Cindirect-media&X-Plex-Model=standalone&X-Plex-Device-Name=ALEX-PC&X-Plex-Device-Screen-Resolution=2560x1377%2C2560x1440&X-Plex-Client-Profile-Name=Plex%20Desktop&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&X-Plex-Language=da
Jan 15, 2022 01:57:06.947 [14636] DEBUG - [MPVEngine/mpv] cplayer: finished playback, loading failed (reason 4)
Jan 15, 2022 01:57:06.947 [14636] INFO - [MPVEngine/mpv] cplayer: 
Jan 15, 2022 01:57:06.948 [14636] ERROR - [Web] [QTMedia(7)] A critical error occurred: 4294967283 Der opstod en ukendt fejl (4294967283)
Jan 15, 2022 01:57:06.949 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:06.960 [14636] INFO - [MPVEngine] Pausing playback.
Jan 15, 2022 01:57:06.960 [14636] DEBUG - [MPVEngine/QHotkeyInput] Playback state is now 'Stopped'
Jan 15, 2022 01:57:06.960 [14636] DEBUG - [MPVEngine/mpv] cplayer: Set property: pause=yes -> 1
Jan 15, 2022 01:57:06.960 [14636] INFO - [Web] [Player] Timeline, {
  "ratingKey": "3924",
  "key": "/library/metadata/3924",
  "playbackTime": 0,
  "playQueueItemID": "3407",
  "state": "paused",
  "hasMDE": 1,
  "context": "library:hub.movie.recentlyreleased",
  "row": 0,
  "col": 0,
  "time": 0,
  "duration": 4042000
}
Jan 15, 2022 01:57:06.960 [14636] ERROR - [Web] Did not move header "accept" to query string. This can result in an unnecessary OPTIONS preflight request.
Jan 15, 2022 01:57:06.961 [14636] DEBUG - Enabling OS screensaver
Jan 15, 2022 01:57:07.040 [14636] INFO - [Web] [Player] Timeline, {
  "ratingKey": "3924",
  "key": "/library/metadata/3924",
  "playbackTime": 0,
  "playQueueItemID": "3407",
  "state": "error",
  "hasMDE": 1,
  "context": "library:hub.movie.recentlyreleased",
  "row": 0,
  "col": 0,
  "time": 0,
  "duration": 4042000
}
Jan 15, 2022 01:57:07.040 [14636] ERROR - [Web] Did not move header "accept" to query string. This can result in an unnecessary OPTIONS preflight request.
Jan 15, 2022 01:57:07.040 [14636] INFO - [Web] [Metrics] Updating session last interaction time
Jan 15, 2022 01:57:07.041 [14636] INFO - [Web] [Metrics] Updating session last interaction time
19

It didn’t fit into one reply. It’s a bloody long log, just for a few seconds.

20

I can’t really say where the log starts and stops. According to the server’s clock (on the Pi), I started playback at around 01:57:00. The error comes within 1-2 seconds.

Jan 15, 2022 01:56:58.031 [0x7fa8002aa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.031 [0x7fa681caa8] DEBUG - It took 140.000000 ms to retrieve 0 items.
Jan 15, 2022 01:56:58.044 [0x7fa739aaa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.048 [0x7fa681caa8] DEBUG - Migrating metadata settings from local://3873 -> plex://episode/6060abee20f9da002de2780a
Jan 15, 2022 01:56:58.051 [0x7fa681caa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.054 [0x7fa7130aa8] DEBUG - BlurHashManager: Generated for item 501 thumb in 176 ms.
Jan 15, 2022 01:56:58.054 [0x7fa7130aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/501/art/1642208217] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.062 [0x7fa681caa8] DEBUG - Updating metadata item (save) () with ID 3873
Jan 15, 2022 01:56:58.085 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 19 : Perilous Payloads (ID 502) in 153ms
Jan 15, 2022 01:56:58.085 [0x7fa6957aa8] DEBUG - There was a change for metadata item 502 (Special Report Season 19 : Perilous Payloads), saving.
Jan 15, 2022 01:56:58.085 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 19 : Perilous Payloads) with ID 502
Jan 15, 2022 01:56:58.092 [0x7fa745aaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.092 [0x7fa745aaa8] DEBUG - Selecting best audio stream for part ID 665 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.092 [0x7fa745aaa8] DEBUG - Audio Stream: 2104, Subtitle Stream: -1
Jan 15, 2022 01:56:58.096 [0x7fa70b6aa8] DEBUG - It took 80.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.099 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58448] 200 GET /library/metadata/500?skipRefresh=1 (25 live) TLS GZIP 141ms 1725 bytes (pipelined: 100)
Jan 15, 2022 01:56:58.101 [0x7fa70b6aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:58.111 [0x7fa68cbaa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_67a8110e0654d3fed0cdda2b598114577814ed41)
Jan 15, 2022 01:56:58.111 [0x7fa68cbaa8] DEBUG - There was a change for metadata item 3916 (Terror over Egypt (Metrojet Flight 9268)), saving.
Jan 15, 2022 01:56:58.111 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (Terror over Egypt (Metrojet Flight 9268)) with ID 3916
Jan 15, 2022 01:56:58.123 [0x7fa6957aa8] DEBUG - Done with metadata update for 502
Jan 15, 2022 01:56:58.128 [0x7fa7130aa8] DEBUG - It took 70.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.130 [0x7fa683faa8] DEBUG - Photo transcoder: Request for url [/library/metadata/502/thumb/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.136 [0x7fa68cbaa8] DEBUG - Done with metadata update for 3916
Jan 15, 2022 01:56:58.139 [0x7fa739aaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.139 [0x7fa739aaa8] DEBUG - Selecting best audio stream for part ID 666 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.139 [0x7fa739aaa8] DEBUG - Audio Stream: 2106, Subtitle Stream: -1
Jan 15, 2022 01:56:58.150 [0x7fa68cbaa8] DEBUG - Season: episode 8 in season 17 has seen a guid change (local://3917 => plex://episode/5d9c0c3802391c001f5ad3f4)
Jan 15, 2022 01:56:58.162 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58508] 200 GET /library/metadata/501?skipRefresh=1 (25 live) TLS GZIP 196ms 1743 bytes (pipelined: 10)
Jan 15, 2022 01:56:58.164 [0x7fa6957aa8] DEBUG - It took 90.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.175 [0x7fa68cbaa8] DEBUG - Migrating metadata settings from local://3917 -> plex://episode/5d9c0c3802391c001f5ad3f4
Jan 15, 2022 01:56:58.177 [0x7fa68cbaa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.182 [0x7fa6957aa8] DEBUG - Season: episode 20 in season 0 has seen a guid change (local://503 => plex://episode/61168170ba9776002cf82e64)
Jan 15, 2022 01:56:58.185 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (Caught on Tape (TransAsia Flight 235)) with ID 3917
Jan 15, 2022 01:56:58.188 [0x7fa681caa8] DEBUG - Loaded metadata for Meltdown Over Kathmandu (ID 3873) in 105ms
Jan 15, 2022 01:56:58.189 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Meltdown Over Kathmandu' because something changed.
Jan 15, 2022 01:56:58.191 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Meltdown Over Kathmandu' because something changed.
Jan 15, 2022 01:56:58.192 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Meltdown Over Kathmandu' because something changed.
Jan 15, 2022 01:56:58.204 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://503 -> plex://episode/61168170ba9776002cf82e64
Jan 15, 2022 01:56:58.206 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.209 [0x7fa683faa8] DEBUG - Calculated media file path for path [media://b/e6daab6bc466aaee05bb3bea307c6ec37e49004.bundle/Contents/Thumbnails/thumb1.jpg]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/b/e6daab6bc466aaee05bb3bea307c6ec37e49004.bundle/Contents/Thumbnails/thumb1.jpg"]
Jan 15, 2022 01:56:58.209 [0x7fa7130aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:58.223 [0x7fa683faa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 15, 2022 01:56:58.224 [0x7fa683faa8] DEBUG - Photo cache obtained 18726 bytes from /library/metadata/502/thumb/1642208218
Jan 15, 2022 01:56:58.225 [0x7fa683faa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/44/44634234a403cb8b940b01c47591fd5fb926c8f1.jpg
Jan 15, 2022 01:56:58.230 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 503
Jan 15, 2022 01:56:58.245 [0x7fa683faa8] DEBUG - Created thumbnail of size 100x56, has pixels: 1
Jan 15, 2022 01:56:58.272 [0x7fa68cbaa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.302 [0x7fa683faa8] DEBUG - BlurHashManager: Generated for item 502 thumb in 172 ms.
Jan 15, 2022 01:56:58.306 [0x7fa7790aa8] DEBUG - Request: [192.168.1.100:58508 (Subnet)] GET /library/metadata/3872?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.310 [0x7fa683faa8] DEBUG - Photo transcoder: Request for url [/library/metadata/502/art/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.318 [0x7fa681caa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_710b72d3b9e8afcec61c6891de210c6b5a723e00)
Jan 15, 2022 01:56:58.318 [0x7fa681caa8] DEBUG - There was a change for metadata item 3873 (Meltdown Over Kathmandu), saving.
Jan 15, 2022 01:56:58.318 [0x7fa681caa8] DEBUG - Updating metadata item (save) (Meltdown Over Kathmandu) with ID 3873
Jan 15, 2022 01:56:58.334 [0x7fa681caa8] DEBUG - Done with metadata update for 3873
Jan 15, 2022 01:56:58.336 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 19 : Runway in Sight (ID 503) in 101ms
Jan 15, 2022 01:56:58.336 [0x7fa6957aa8] DEBUG - There was a change for metadata item 503 (Special Report Season 19 : Runway in Sight), saving.
Jan 15, 2022 01:56:58.336 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 19 : Runway in Sight) with ID 503
Jan 15, 2022 01:56:58.346 [0x7fa6957aa8] DEBUG - Done with metadata update for 503
Jan 15, 2022 01:56:58.350 [0x7fa7301aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/503/thumb/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.362 [0x7fa683faa8] DEBUG - It took 80.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.364 [0x7fa68cbaa8] DEBUG - Loaded metadata for Caught on Tape (TransAsia Flight 235) (ID 3917) in 143ms
Jan 15, 2022 01:56:58.364 [0x7fa68cbaa8] DEBUG - Doing expensive tags write for 'Caught on Tape (TransAsia Flight 235)' because something changed.
Jan 15, 2022 01:56:58.393 [0x7fa6957aa8] DEBUG - Season: episode 21 in season 0 has seen a guid change (local://504 => plex://episode/61168170ba9776002cf82e62)
Jan 15, 2022 01:56:58.394 [0x7fa681caa8] DEBUG - Season: episode 7 in season 21 has seen a guid change (local://3874 => plex://episode/6060abee20f9da002de2780b)
Jan 15, 2022 01:56:58.409 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://504 -> plex://episode/61168170ba9776002cf82e62
Jan 15, 2022 01:56:58.412 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.416 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 504
Jan 15, 2022 01:56:58.438 [0x7fa683faa8] DEBUG - It took 90.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.439 [0x7fa681caa8] DEBUG - Migrating metadata settings from local://3874 -> plex://episode/6060abee20f9da002de2780b
Jan 15, 2022 01:56:58.441 [0x7fa681caa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.451 [0x7fa7790aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.451 [0x7fa7790aa8] DEBUG - Selecting best audio stream for part ID 4108 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.451 [0x7fa7790aa8] DEBUG - Audio Stream: 12341, Subtitle Stream: -1
Jan 15, 2022 01:56:58.456 [0x7fa683faa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:58.457 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:58508] 200 GET /library/metadata/3872?skipRefresh=1 (25 live) TLS GZIP 151ms 2362 bytes (pipelined: 11)
Jan 15, 2022 01:56:58.460 [0x7fa7301aa8] DEBUG - It took 70.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.462 [0x7fa7301aa8] DEBUG - Calculated media file path for path [media://e/48dbebd5ca65990cd62470b76e2d68f8c82f88c.bundle/Contents/Thumbnails/thumb1.jpg]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/e/48dbebd5ca65990cd62470b76e2d68f8c82f88c.bundle/Contents/Thumbnails/thumb1.jpg"]
Jan 15, 2022 01:56:58.463 [0x7fa681caa8] DEBUG - Updating metadata item (save) () with ID 3874
Jan 15, 2022 01:56:58.466 [0x7fa7301aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 15, 2022 01:56:58.480 [0x7fa7301aa8] DEBUG - Photo cache obtained 18173 bytes from /library/metadata/503/thumb/1642208218
Jan 15, 2022 01:56:58.480 [0x7fa7301aa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/21/219823afd294f0c11d7f5c41cc63b2977f0e5ba9.jpg
Jan 15, 2022 01:56:58.488 [0x7fa68cbaa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_701a15cb22f339236f4f3325a31e122c86122876)
Jan 15, 2022 01:56:58.488 [0x7fa68cbaa8] DEBUG - There was a change for metadata item 3917 (Caught on Tape (TransAsia Flight 235)), saving.
Jan 15, 2022 01:56:58.488 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (Caught on Tape (TransAsia Flight 235)) with ID 3917
Jan 15, 2022 01:56:58.498 [0x7fa7301aa8] DEBUG - Created thumbnail of size 100x56, has pixels: 1
Jan 15, 2022 01:56:58.507 [0x7fa71bbaa8] DEBUG - Request: [192.168.1.100:58448 (Subnet)] GET /library/metadata/501?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.507 [0x7fa74b5aa8] DEBUG - Request: [192.168.1.100:58508 (Subnet)] GET /library/metadata/3873?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.507 [0x7fa698caa8] DEBUG - Request: [192.168.1.100:58505 (Subnet)] GET /library/metadata/502?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.562 [0x7fa7301aa8] DEBUG - BlurHashManager: Generated for item 503 thumb in 212 ms.
Jan 15, 2022 01:56:58.562 [0x7fa7301aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/503/art/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.567 [0x7fa74b5aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.567 [0x7fa74b5aa8] DEBUG - Selecting best audio stream for part ID 4109 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.567 [0x7fa74b5aa8] DEBUG - Audio Stream: 12344, Subtitle Stream: -1
Jan 15, 2022 01:56:58.572 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:58508] 200 GET /library/metadata/3873?skipRefresh=1 (25 live) TLS GZIP 65ms 1955 bytes (pipelined: 12)
Jan 15, 2022 01:56:58.588 [0x7fa71bbaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.588 [0x7fa68cbaa8] WARN - Took too long (0.210000 seconds) to start a transaction on /data/jenkins/server/3961000342/Library/MetadataItem.cpp:1125
Jan 15, 2022 01:56:58.588 [0x7fa68cbaa8] WARN - Transaction that was running was started on thread 0x7fa681caa8 at /data/jenkins/server/3961000342/Library/MetadataItem.cpp:1125
Jan 15, 2022 01:56:58.588 [0x7fa71bbaa8] DEBUG - Selecting best audio stream for part ID 666 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.588 [0x7fa71bbaa8] DEBUG - Audio Stream: 2106, Subtitle Stream: -1
Jan 15, 2022 01:56:58.591 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:58448] 200 GET /library/metadata/501?skipRefresh=1 (25 live) TLS GZIP 84ms 1743 bytes (pipelined: 101)
Jan 15, 2022 01:56:58.598 [0x7fa698caa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.598 [0x7fa698caa8] DEBUG - Selecting best audio stream for part ID 667 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.598 [0x7fa698caa8] DEBUG - Audio Stream: 2108, Subtitle Stream: -1
Jan 15, 2022 01:56:58.604 [0x7fa6957aa8] WARN - Took too long (0.240000 seconds) to start a transaction on /data/jenkins/server/3961000342/Library/MetadataItem.cpp:9305
Jan 15, 2022 01:56:58.604 [0x7fa6957aa8] WARN - Transaction that was running was started on thread 0x7fa68cbaa8 at /data/jenkins/server/3961000342/Library/MetadataItem.cpp:1125
Jan 15, 2022 01:56:58.605 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 19 : Deadly Confusion (ID 504) in 167ms
Jan 15, 2022 01:56:58.605 [0x7fa6957aa8] DEBUG - There was a change for metadata item 504 (Special Report Season 19 : Deadly Confusion), saving.
Jan 15, 2022 01:56:58.605 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 19 : Deadly Confusion) with ID 504
Jan 15, 2022 01:56:58.608 [0x7fa68cbaa8] DEBUG - Done with metadata update for 3917
Jan 15, 2022 01:56:58.612 [0x7fa7301aa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.618 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:58505] 200 GET /library/metadata/502?skipRefresh=1 (25 live) TLS GZIP 110ms 1741 bytes (pipelined: 10)
Jan 15, 2022 01:56:58.626 [0x7fa6957aa8] DEBUG - Done with metadata update for 504
Jan 15, 2022 01:56:58.637 [0x7fa6b04aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/504/thumb/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.640 [0x7fa68cbaa8] DEBUG - Season: episode 9 in season 17 has seen a guid change (local://3918 => plex://episode/5d9c0c3802391c001f5ad400)
Jan 15, 2022 01:56:58.649 [0x7fa6957aa8] DEBUG - Season: episode 22 in season 0 has seen a guid change (local://505 => plex://episode/6116816fba9776002cf82e60)
Jan 15, 2022 01:56:58.657 [0x7fa68cbaa8] DEBUG - Migrating metadata settings from local://3918 -> plex://episode/5d9c0c3802391c001f5ad400
Jan 15, 2022 01:56:58.663 [0x7fa68cbaa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.664 [0x7fa7301aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:58.672 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (Deadly Discussions (LAPA Flight 3142)) with ID 3918
Jan 15, 2022 01:56:58.677 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://505 -> plex://episode/6116816fba9776002cf82e60
Jan 15, 2022 01:56:58.683 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.702 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 505
Jan 15, 2022 01:56:58.705 [0x7fa745aaa8] DEBUG - Request: [192.168.1.100:58505 (Subnet)] GET /library/metadata/502?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.718 [0x7fa739aaa8] DEBUG - Request: [192.168.1.100:58448 (Subnet)] GET /library/metadata/503?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.728 [0x7fa68cbaa8] DEBUG - It took 70.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.742 [0x7fa6b04aa8] DEBUG - It took 70.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.748 [0x7fa6b04aa8] DEBUG - Calculated media file path for path [media://5/d10cc45b3d0b35e693342c69b3f70566b6d14ab.bundle/Contents/Thumbnails/thumb1.jpg]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/5/d10cc45b3d0b35e693342c69b3f70566b6d14ab.bundle/Contents/Thumbnails/thumb1.jpg"]
Jan 15, 2022 01:56:58.754 [0x7fa6b04aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 15, 2022 01:56:58.755 [0x7fa6b04aa8] DEBUG - Photo cache obtained 22832 bytes from /library/metadata/504/thumb/1642208218
Jan 15, 2022 01:56:58.755 [0x7fa6b04aa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/0c/0c7a2f18b8df13d524bff5199d2fee04bb914ec7.jpg
Jan 15, 2022 01:56:58.767 [0x7fa745aaa8] DEBUG - It took 90.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.782 [0x7fa6b04aa8] DEBUG - Created thumbnail of size 100x56, has pixels: 1
Jan 15, 2022 01:56:58.782 [0x7fa681caa8] DEBUG - Loaded metadata for Mission Disaster (ID 3874) in 194ms
Jan 15, 2022 01:56:58.782 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Mission Disaster' because something changed.
Jan 15, 2022 01:56:58.787 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Mission Disaster' because something changed.
Jan 15, 2022 01:56:58.789 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Mission Disaster' because something changed.
Jan 15, 2022 01:56:58.803 [0x7fa6022aa8] DEBUG - Request: [192.168.1.100:58508 (Subnet)] GET /library/metadata/3873?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.811 [0x7fa68cbaa8] DEBUG - Loaded metadata for Deadly Discussions (LAPA Flight 3142) (ID 3918) in 122ms
Jan 15, 2022 01:56:58.811 [0x7fa68cbaa8] DEBUG - Doing expensive tags write for 'Deadly Discussions (LAPA Flight 3142)' because something changed.
Jan 15, 2022 01:56:58.819 [0x7fa71deaa8] DEBUG - Request: [192.168.1.100:58506 (Subnet)] GET /statistics/bandwidth?timespan=6 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:58.827 [0x7fa6b04aa8] DEBUG - BlurHashManager: Generated for item 504 thumb in 189 ms.
Jan 15, 2022 01:56:58.827 [0x7fa6b04aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/504/art/1642208218] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:58.842 [0x7fa739aaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.842 [0x7fa739aaa8] DEBUG - Selecting best audio stream for part ID 668 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.843 [0x7fa739aaa8] DEBUG - Audio Stream: 2110, Subtitle Stream: -1
Jan 15, 2022 01:56:58.846 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58448] 200 GET /library/metadata/503?skipRefresh=1 (25 live) TLS GZIP 130ms 1741 bytes (pipelined: 102)
Jan 15, 2022 01:56:58.869 [0x7fa681caa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_5929f6ef805d70ab45b4f2aa1cb0302fb8d78599)
Jan 15, 2022 01:56:58.869 [0x7fa681caa8] DEBUG - There was a change for metadata item 3874 (Mission Disaster), saving.
Jan 15, 2022 01:56:58.869 [0x7fa681caa8] DEBUG - Updating metadata item (save) (Mission Disaster) with ID 3874
Jan 15, 2022 01:56:58.879 [0x7fa681caa8] DEBUG - Done with metadata update for 3874
Jan 15, 2022 01:56:58.887 [0x7fa68cbaa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_53c83aa9db08f5c11703c90df9e8f44ac264e0f3)
Jan 15, 2022 01:56:58.888 [0x7fa68cbaa8] DEBUG - There was a change for metadata item 3918 (Deadly Discussions (LAPA Flight 3142)), saving.
Jan 15, 2022 01:56:58.888 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (Deadly Discussions (LAPA Flight 3142)) with ID 3918
Jan 15, 2022 01:56:58.890 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58506] 200 GET /statistics/bandwidth?timespan=6 (25 live) TLS GZIP 73ms 1662 bytes (pipelined: 15)
Jan 15, 2022 01:56:58.898 [0x7fa68cbaa8] DEBUG - Done with metadata update for 3918
Jan 15, 2022 01:56:58.899 [0x7fa6957aa8] DEBUG - It took 60.000000 ms to retrieve 0 items.
Jan 15, 2022 01:56:58.905 [0x7fa681caa8] DEBUG - Season: episode 8 in season 21 has seen a guid change (local://3875 => plex://episode/6060abef20f9da002de2780c)
Jan 15, 2022 01:56:58.917 [0x7fa745aaa8] DEBUG - It took 80.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.921 [0x7fa68cbaa8] DEBUG - Season: episode 10 in season 17 has seen a guid change (local://3919 => plex://episode/5d9c0c3802391c001f5ad3e8)
Jan 15, 2022 01:56:58.922 [0x7fa681caa8] DEBUG - Migrating metadata settings from local://3875 -> plex://episode/6060abef20f9da002de2780c
Jan 15, 2022 01:56:58.922 [0x7fa6022aa8] DEBUG - It took 100.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.923 [0x7fa6b04aa8] DEBUG - It took 130.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:58.924 [0x7fa8002aa8] DEBUG - It took 90.000000 ms to retrieve 6 items.
Jan 15, 2022 01:56:58.926 [0x7fa681caa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.933 [0x7fa681caa8] DEBUG - Updating metadata item (save) () with ID 3875
Jan 15, 2022 01:56:58.936 [0x7fa68cbaa8] DEBUG - Migrating metadata settings from local://3919 -> plex://episode/5d9c0c3802391c001f5ad3e8
Jan 15, 2022 01:56:58.947 [0x7fa68cbaa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:58.951 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (The Lost Plane (Thai Airways Flight 311)) with ID 3919
Jan 15, 2022 01:56:58.962 [0x7fa745aaa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:58.962 [0x7fa745aaa8] DEBUG - Selecting best audio stream for part ID 667 (autoselect: 0 language: da)
Jan 15, 2022 01:56:58.962 [0x7fa745aaa8] DEBUG - Audio Stream: 2108, Subtitle Stream: -1
Jan 15, 2022 01:56:58.977 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58505] 200 GET /library/metadata/502?skipRefresh=1 (25 live) TLS GZIP 273ms 1741 bytes (pipelined: 11)
Jan 15, 2022 01:56:58.997 [0x7fa6957aa8] DEBUG - It took 130.000000 ms to retrieve 0 items.
Jan 15, 2022 01:56:58.999 [0x7fa68cbaa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.001 [0x7fa6b04aa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.003 [0x7fa6b04aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:59.025 [0x7fa6022aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:59.025 [0x7fa6022aa8] DEBUG - Selecting best audio stream for part ID 4109 (autoselect: 0 language: da)
Jan 15, 2022 01:56:59.025 [0x7fa6022aa8] DEBUG - Audio Stream: 12344, Subtitle Stream: -1
Jan 15, 2022 01:56:59.033 [0x7fa681caa8] DEBUG - Loaded metadata for Caught In a Jam (ID 3875) in 81ms
Jan 15, 2022 01:56:59.035 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Caught In a Jam' because something changed.
Jan 15, 2022 01:56:59.039 [0x7fa929eaa8] DEBUG - Completed: [192.168.1.100:58508] 200 GET /library/metadata/3873?skipRefresh=1 (25 live) TLS GZIP 238ms 1955 bytes (pipelined: 13)
Jan 15, 2022 01:56:59.047 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Caught In a Jam' because something changed.
Jan 15, 2022 01:56:59.055 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Caught In a Jam' because something changed.
Jan 15, 2022 01:56:59.060 [0x7fa68cbaa8] DEBUG - Loaded metadata for The Lost Plane (Thai Airways Flight 311) (ID 3919) in 92ms
Jan 15, 2022 01:56:59.060 [0x7fa68cbaa8] DEBUG - Doing expensive tags write for 'The Lost Plane (Thai Airways Flight 311)' because something changed.
Jan 15, 2022 01:56:59.094 [0x7fa8002aa8] DEBUG - It took 70.000000 ms to retrieve 6 items.
Jan 15, 2022 01:56:59.102 [0x7fa7530aa8] DEBUG - Request: [192.168.1.100:58508 (Subnet)] GET /library/metadata/3874?skipRefresh=1 (25 live) TLS GZIP Signed-in Token (Akyhne)
Jan 15, 2022 01:56:59.116 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 19 : Radio Silence (ID 505) in 375ms
Jan 15, 2022 01:56:59.120 [0x7fa6957aa8] DEBUG - There was a change for metadata item 505 (Special Report Season 19 : Radio Silence), saving.
Jan 15, 2022 01:56:59.120 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 19 : Radio Silence) with ID 505
Jan 15, 2022 01:56:59.139 [0x7fa6957aa8] DEBUG - Done with metadata update for 505
Jan 15, 2022 01:56:59.141 [0x7fa681caa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_2d46c273cb61077b10b5e28dc7d8a60a2fa96b2a)
Jan 15, 2022 01:56:59.141 [0x7fa681caa8] DEBUG - There was a change for metadata item 3875 (Caught In a Jam), saving.
Jan 15, 2022 01:56:59.141 [0x7fa681caa8] DEBUG - Updating metadata item (save) (Caught In a Jam) with ID 3875
Jan 15, 2022 01:56:59.142 [0x7fa6cb3aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/505/thumb/1642208219] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:59.160 [0x7fa681caa8] DEBUG - Done with metadata update for 3875
Jan 15, 2022 01:56:59.163 [0x7fa68cbaa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_8a5d6fa384bc1af511c619c9f4ec72b5b95fcaab)
Jan 15, 2022 01:56:59.165 [0x7fa68cbaa8] DEBUG - There was a change for metadata item 3919 (The Lost Plane (Thai Airways Flight 311)), saving.
Jan 15, 2022 01:56:59.165 [0x7fa68cbaa8] DEBUG - Updating metadata item (save) (The Lost Plane (Thai Airways Flight 311)) with ID 3919
Jan 15, 2022 01:56:59.172 [0x7fa7530aa8] DEBUG - It took 100.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.173 [0x7fa6957aa8] DEBUG - Season: episode 23 in season 0 has seen a guid change (local://506 => plex://episode/6116816dba9776002cf82e5e)
Jan 15, 2022 01:56:59.183 [0x7fa68cbaa8] DEBUG - Done with metadata update for 3919
Jan 15, 2022 01:56:59.191 [0x7fa68cbaa8] DEBUG - Activity: Ended activity 86b47f1e-bd50-4b80-b499-5094f9ff0e86.
Jan 15, 2022 01:56:59.194 [0x7fa681caa8] DEBUG - Season: episode 9 in season 21 has seen a guid change (local://3876 => plex://episode/6060abef20f9da002de2780d)
Jan 15, 2022 01:56:59.197 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://506 -> plex://episode/6116816dba9776002cf82e5e
Jan 15, 2022 01:56:59.198 [0x7fa7530aa8] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 15, 2022 01:56:59.199 [0x7fa7530aa8] DEBUG - Selecting best audio stream for part ID 4110 (autoselect: 0 language: da)
Jan 15, 2022 01:56:59.199 [0x7fa7530aa8] DEBUG - Audio Stream: 12347, Subtitle Stream: -1
Jan 15, 2022 01:56:59.202 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:59.205 [0x7fa927baa8] DEBUG - Completed: [192.168.1.100:58508] 200 GET /library/metadata/3874?skipRefresh=1 (25 live) TLS GZIP 103ms 1975 bytes (pipelined: 14)
Jan 15, 2022 01:56:59.209 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 506
Jan 15, 2022 01:56:59.220 [0x7fa6cb3aa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.221 [0x7fa6cb3aa8] DEBUG - Calculated media file path for path [media://2/47eff1dad6c52445f48136c6f355bd0e6d609e8.bundle/Contents/Thumbnails/thumb1.jpg]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/2/47eff1dad6c52445f48136c6f355bd0e6d609e8.bundle/Contents/Thumbnails/thumb1.jpg"]
Jan 15, 2022 01:56:59.223 [0x7fa681caa8] DEBUG - Migrating metadata settings from local://3876 -> plex://episode/6060abef20f9da002de2780d
Jan 15, 2022 01:56:59.225 [0x7fa681caa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:59.227 [0x7fa6cb3aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 15, 2022 01:56:59.231 [0x7fa6cb3aa8] DEBUG - Photo cache obtained 35617 bytes from /library/metadata/505/thumb/1642208219
Jan 15, 2022 01:56:59.231 [0x7fa6cb3aa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/20/2025fdd83e45ae1dd8ba663087aafc3d9c179adf.jpg
Jan 15, 2022 01:56:59.237 [0x7fa6cb3aa8] DEBUG - Created thumbnail of size 100x56, has pixels: 1
Jan 15, 2022 01:56:59.243 [0x7fa68cbaa8] DEBUG - It took 60.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.246 [0x7fa681caa8] DEBUG - Updating metadata item (save) () with ID 3876
Jan 15, 2022 01:56:59.270 [0x7fa68cbaa8] DEBUG - Activity: registered new activity 604024bb-a390-483c-a943-1dbcddce2862 - "Updating Metadata"
Jan 15, 2022 01:56:59.276 [0x7fa6cb3aa8] DEBUG - BlurHashManager: Generated for item 505 thumb in 134 ms.
Jan 15, 2022 01:56:59.279 [0x7fa6cb3aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/505/art/1642208219] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:59.329 [0x7fa681caa8] DEBUG - Loaded metadata for Seconds From Touchdown (ID 3876) in 60ms
Jan 15, 2022 01:56:59.329 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Seconds From Touchdown' because something changed.
Jan 15, 2022 01:56:59.345 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Seconds From Touchdown' because something changed.
Jan 15, 2022 01:56:59.349 [0x7fa681caa8] DEBUG - Doing expensive tags write for 'Seconds From Touchdown' because something changed.
Jan 15, 2022 01:56:59.387 [0x7fa68cbaa8] DEBUG - [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/fb9eae3-4185-linux-aarch64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Media Scanner' '--match' '--type' '2' '--log-file-suffix' ' Matcher' '--item' '3878' '--match-tag-mode=all'
Jan 15, 2022 01:56:59.391 [0x7fa68cbaa8] DEBUG - [JobRunner] Jobs: Starting child process with pid 2942338
Jan 15, 2022 01:56:59.393 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 19 : Design Flaws (ID 506) in 147ms
Jan 15, 2022 01:56:59.398 [0x7fa6957aa8] DEBUG - There was a change for metadata item 506 (Special Report Season 19 : Design Flaws), saving.
Jan 15, 2022 01:56:59.398 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 19 : Design Flaws) with ID 506
Jan 15, 2022 01:56:59.413 [0x7fa681caa8] DEBUG - Local media already exists (metadata://posters/tv.plex.agents.series_da66498661f249d5af64afaccafd906c92f93d2d)
Jan 15, 2022 01:56:59.413 [0x7fa681caa8] DEBUG - There was a change for metadata item 3876 (Seconds From Touchdown), saving.
Jan 15, 2022 01:56:59.413 [0x7fa681caa8] DEBUG - Updating metadata item (save) (Seconds From Touchdown) with ID 3876
Jan 15, 2022 01:56:59.418 [0x7fa6957aa8] DEBUG - Done with metadata update for 506
Jan 15, 2022 01:56:59.422 [0x7fa6911aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/506/thumb/1642208219] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:59.424 [0x7fa6cb3aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Jan 15, 2022 01:56:59.432 [0x7fa6957aa8] DEBUG - Season: episode 24 in season 0 has seen a guid change (local://507 => plex://episode/6116816dba9776002cf82e5d)
Jan 15, 2022 01:56:59.436 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://507 -> plex://episode/6116816dba9776002cf82e5d
Jan 15, 2022 01:56:59.438 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:59.443 [0x7fa681caa8] DEBUG - Done with metadata update for 3876
Jan 15, 2022 01:56:59.450 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 507
Jan 15, 2022 01:56:59.458 [0x7fa681caa8] DEBUG - Season: episode 10 in season 21 has seen a guid change (local://3877 => plex://episode/6060abef20f9da002de2780e)
Jan 15, 2022 01:56:59.458 [0x7faa8eeaa8] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 2942318 is 0 (success)
Jan 15, 2022 01:56:59.459 [0x7fa6957aa8] WARN - Held transaction for too long (/data/jenkins/server/3961000342/Library/MetadataItem.cpp:1127): 0.390000 seconds
Jan 15, 2022 01:56:59.463 [0x7fa68a8aa8] DEBUG - Activity: Ended activity 49d6e178-4f42-41ce-8f14-4799e014fd3d.
Jan 15, 2022 01:56:59.468 [0x7fa681caa8] DEBUG - Migrating metadata settings from local://3877 -> plex://episode/6060abef20f9da002de2780e
Jan 15, 2022 01:56:59.471 [0x7fa681caa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:59.473 [0x7fa8002aa8] WARN - SLOW QUERY: It took 440.000000 ms to retrieve 6 items.
Jan 15, 2022 01:56:59.474 [0x7fa6911aa8] WARN - SLOW QUERY: It took 450.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.474 [0x7fa681caa8] DEBUG - Updating metadata item (save) () with ID 3877
Jan 15, 2022 01:56:59.490 [0x7fa68a8aa8] DEBUG - It took 80.000000 ms to retrieve 1 items.
Jan 15, 2022 01:56:59.495 [0x7fa6957aa8] DEBUG - Loaded metadata for Special Report Season 20: Courage in the Cockpit (ID 507) in 34ms
Jan 15, 2022 01:56:59.495 [0x7fa6957aa8] DEBUG - There was a change for metadata item 507 (Special Report Season 20: Courage in the Cockpit), saving.
Jan 15, 2022 01:56:59.495 [0x7fa6957aa8] DEBUG - Updating metadata item (save) (Special Report Season 20: Courage in the Cockpit) with ID 507
Jan 15, 2022 01:56:59.496 [0x7fa68a8aa8] DEBUG - Activity: registered new activity a197874a-3814-4173-8d6e-1b1533be4a7c - "Generating video chapter thumbnails"
Jan 15, 2022 01:56:59.500 [0x7fa68a8aa8] DEBUG - [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/fb9eae3-4185-linux-aarch64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Media Scanner' '--generate' '--chapter-thumbs-only' '--item' '3417' '--log-file-suffix' ' Chapter Thumbnails'
Jan 15, 2022 01:56:59.505 [0x7fa68a8aa8] DEBUG - [JobRunner] Jobs: Starting child process with pid 2942339
Jan 15, 2022 01:56:59.513 [0x7fa6957aa8] DEBUG - Done with metadata update for 507
Jan 15, 2022 01:56:59.520 [0x7fa7153aa8] DEBUG - Photo transcoder: Request for url [/library/metadata/507/thumb/1642208219] (is local: 1 upscaled: 0)
Jan 15, 2022 01:56:59.530 [0x7fa6957aa8] DEBUG - Season: episode 25 in season 0 has seen a guid change (local://508 => plex://episode/6116816cba9776002cf82e5b)
Jan 15, 2022 01:56:59.532 [0x7fa6911aa8] DEBUG - Calculated media file path for path [media://d/993de99752442f957a0134f479cfd7bf5e35370.bundle/Contents/Thumbnails/thumb1.jpg]: ["/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Media/localhost/d/993de99752442f957a0134f479cfd7bf5e35370.bundle/Contents/Thumbnails/thumb1.jpg"]
Jan 15, 2022 01:56:59.535 [0x7fa6911aa8] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Jan 15, 2022 01:56:59.538 [0x7fa6911aa8] DEBUG - Photo cache obtained 29706 bytes from /library/metadata/506/thumb/1642208219
Jan 15, 2022 01:56:59.538 [0x7fa6911aa8] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/b2/b216ea6ee0f62951311da997d8e28fb88dca2b1e.jpg
Jan 15, 2022 01:56:59.539 [0x7fa6957aa8] DEBUG - Migrating metadata settings from local://508 -> plex://episode/6116816cba9776002cf82e5b
Jan 15, 2022 01:56:59.541 [0x7fa6957aa8] DEBUG -  -> Simply giving existing setting new GUID.
Jan 15, 2022 01:56:59.545 [0x7fa6957aa8] DEBUG - Updating metadata item (save) () with ID 508