OS: Ubuntu Server 22.04.2 LTS
Kernel: 5.15.0-60 (non-rolling)
GPU: GeForce RTX 2060
Driver: 525.89.02 (CUDA 12.0) removed nvenc limit patch
Per my previous post, hw transcoding is working on 1.31.2.6715 with a variety of codec/resolution testing.
.-/+oossssoo+/-. xxxxx@MediaNAS
`:+ssssssssssssssssss+:` -------------
-+ssssssssssssssssssyyssss+- OS: Ubuntu 22.04.2 LTS x86_64
.ossssssssssssssssssdMMMNysssso. Host: PowerEdge R730xd
/ssssssssssshdmmNNmmyNMMMMhssssss/ Kernel: 5.19.0-32-generic
+ssssssssshmydMMMMMMMNddddyssssssss+ Uptime: 4 mins
/sssssssshNMMMyhhyyyyhmNMMMNhssssssss/ Packages: 1075 (dpkg), 5 (snap)
.ssssssssdMMMNhsssssssssshNMMMdssssssss. Shell: bash 5.1.16
+sssshhhyNMMNyssssssssssssyNMMMysssssss+ Resolution: 1680x1050
ossyNMMMNyMMhsssssssssssssshmmmhssssssso Terminal: /dev/pts/0
ossyNMMMNyMMhsssssssssssssshmmmhssssssso CPU: Intel Xeon E5-2640 v4 (40) @ 3.400GHz
+sssshhhyNMMNyssssssssssssyNMMMysssssss+ GPU: NVIDIA Quadro RTX 4000
.ssssssssdMMMNhsssssssssshNMMMdssssssss. Memory: 1097MiB / 32044MiB
/sssssssshNMMMyhhyyyyhdNMMMNhssssssss/
+sssssssssdmydMMMMMMMMddddyssssssss+
/ssssssssssshdmNNNNmyNMMMMhssssss/
.ossssssssssssssssssdMMMNysssso.
-+sssssssssssssssssyyyssss+-
`:+ssssssssssssssssss+:`
.-/+oossssoo+/-.
xxxxx@MediaNAS:~# nvidia-smi
Sun Feb 19 10:41:38 2023
+-----------------------------------------------------------------------------+
| NVIDIA-SMI 525.78.01 Driver Version: 525.78.01 CUDA Version: 12.0 |
|-------------------------------+----------------------+----------------------+
| GPU Name Persistence-M| Bus-Id Disp.A | Volatile Uncorr. ECC |
| Fan Temp Perf Pwr:Usage/Cap| Memory-Usage | GPU-Util Compute M. |
| | | MIG M. |
|===============================+======================+======================|
| 0 Quadro RTX 4000 Off | 00000000:82:00.0 Off | N/A |
| 30% 34C P8 7W / 125W | 3MiB / 8192MiB | 0% Default |
| | | N/A |
+-------------------------------+----------------------+----------------------+
+-----------------------------------------------------------------------------+
| Processes: |
| GPU GI CI PID Type Process name GPU Memory |
| ID ID Usage |
|=============================================================================|
| No running processes found |
+-----------------------------------------------------------------------------+
Server Version: 1.31.2.6715
Problems as described in my earlier post. (no difference between driver 515 and 525)
@ChuckPa for reference >> 5.15.0-60-generic nvidia 525.60.13 with NVIDIA GeForce GTX 1050 Ti (also tested 515) (AMD Ryzen 7 5700)
Found this post from here: https://www.reddit.com/r/PleX/comments/111heib/plex_not_using_gpu_for_hw_transcoding/
Server Version: 1.31.1.6716
Server info: Ubuntu 20.04.03 LTS using 1050Ti card that is passthrough from ESXi host.
NVIDIA Driver Version: 525.89.02
Kernel Version: 5.13.0-28-generic
Issue: HW transcoding does not work unless quality selected is below 720p. Anything higher creates a process in nvidia-smi but does not appear to be actually using the GPU and the “HW” label is missing from Plex dashboard.
1 Like
@jp0ll Try the version plexmediaserver_1.31.2.6715-ac8ee76c0_amd64.deb provided; it should address what you describe
The issue with changing quality on Chrome + Xeon platform is for sure also present on PMS 1.31.2.6715. Confirmed on multiple systems.
BUT, friends report another issues where playback was not starting at all for some media on Android & Apple phones is working better now. So looks like some improvements with the new version.
I just ran some more tests with Proxmox. Things are working exactly the same through the virtualization layer as on the physical machines.
Proxmox HOST 1:
OS: Proxmox 6.3 (kernel 5.15.85-1-pve)
CPU: i9-11900K
GPU: Nvidia Quadro P4000
Proxmox HOST 2:
OS: Proxmox 6.3 (kernel 5.15.85-1-pve)
CPU: Intel Xeon E5-2699A v4
GPU: Nvidia Quadro P4000
Guest VM:
OS: Ubuntu 22.04.1 (kernel 5.15.0-60-generic)
GPU: NVIDIA Quadro P4000 (passthru from Host)
Drivers: 525.60.13-0ubuntu0.22.04.1
CPU: Same results with any emulated CPU
PMS: 1.31.2.6715
Testing when Guest VM is running on HOST 1: Changing quality in Chrome works 100%
Testing when Guest VM is running on HOST 2: Changing quality in Chrome does not work
Interesting how the issues with Xeon platform, even remain through the virtualization layer.
- Not sure how to answer because yes the kernel is greater than 5.10 but the issue was independent of the CUDA version (issue exists in CUDA 11 and 12)
- Kernel is 6.1.9
Latest beta release did not resolve this for me. Anything above 720p transcode does not use HW transcoding still.
@jp0ll
Can you expand on that. Are you saying that it wont try to transcode with HW if you transcode to 1080 and uses software OR was HW transcoding failing to work at all unless you were at 720p.
If its not utilizing hw to transcode above 720p I think that may be a different issue.
It is not utilizing hardware transcoding for anything above 720p. Nvidia-smi shows a process for it but no HW transcoding is occurring.
The 6716 release the other day didn’t have the fix in it even though the patch notes said it did, seems like 1.31.1.6733 is the same as well (just tested and didn’t work on 1080p transcodes).
Until I see @ChuckPa confirm a PlexPass release has the fix, I’ll continue to use 1.31.2.6715 dev build which is the only build that fully works for me.
1 Like
Thanks for this tip.
Even on 1.31.1.6733, this is the only “fix” yet for hardware transcoding which has worked
.
1 Like
Folks,
Been tied up with testing / debugging on a variety of hosts.
I was using our internal Engineering build 1.31.2.6715 .
That build, which I’ve shared with a few, fixed several issues with Nvidia being the most profound.
The release (6716) which came out does not contain what I have in Engineer’s build 6715 does. ( The build system is sequential but the release system is tagged so the numbers can look weird at times )
So that I’m clear, is everyone saying build 6715 is still the only build which solves the issues?
( NOTE: There is a formal build, currently internal alpha testing, with these fixes as well which I will grab and share with select folks to confirm it’s good )
@ChuckPa
You mention "The release (6716) " I see that release plexmediaserver_1.31.1.6733-bc0674160_amd64.deb is still presented
Didn’t install the new version… it mentions fixes: (Transcoder) Hardware decoding using Nvidia GPUs could fail with certain driver versions (#14069) (#14070)
///Not really describing the transcoder issue encountered which had nothing to do with driver versions.\\
confirm that 6715 is the one resolving my issues.
I see it too.
That’s on my “To Do” list for today.
I’ve been getting firware / driver updates updated today.
With them almost done, I’ll be able to test.
The first I’ll hit are:
- Lab QNAP with GT1050
- My home P2200 machine
EDIT: Both work but those are not 100% worthy metrics:
- QNAP runs CUDA 11.7 (Qnap’ NVIDIA_GPU_DRV package)
- I use CUDA 12.0 but on 5.4 kernel
Not sure if this scenario has been brought up yet. I’m seeing issues with changing quality settings when there are 2 versions of the content 4k HDR/DolbyVision(HEVC) and 1080p SDR(HEVC). When I select the new quality, the playback goes black and the content wont play. The server still shows it playing but that it is buffering. I hadn’t experienced this issue prior to the HW transcode issues resolved in 1.31.2.6715. The issue also goes away if I remove one of the files. Would this be an issue with the server or the application not knowing which version to transcode from?
Additional testing: I tried setting my home limit to below the quality of both files and in this situation the content doesn’t even start playing and just spins. The server doesn’t even show that it’s playing.
Server OS: Ubuntu 22.04.1
PMS Version: 1.31.2.6715
NVIDIA Driver Version: 525.60.13
Android Plex App Version: 9.16.2.38798
Thanks!
Plex App Logs:
2-24 15:18:00.279 i: [Preplay] Notifying listeners with status: SUCCESS
02-24 15:18:01.802 i: [OneApp] User can execute command without restrictions because they are entitled.
02-24 15:18:01.802 i: [PlaybackManager] Preparing for <Content-Name>
02-24 15:18:01.802 i: [DefaultPlaybackManager] Passthrough is enabled.
02-24 15:18:01.805 i: [DefaultPlaybackManager] No codecs being downloaded.
02-24 15:18:01.806 i: [PlayQueues] Creating delayed remote PQ.
02-24 15:18:01.828 i: [PlayerService] Service has been created.
02-24 15:18:01.831 i: [PlayerService] Service is being initialised.
02-24 15:18:01.832 i: [Player] Changing to use ExoPlayer for playback.
02-24 15:18:01.835 i: [LoadControl] Setting buffer size to 73.19 MB / 0 seconds.
02-24 15:18:01.840 i: [ExoPlayerEngine] Constructed ExoPlayer to use a background looper.
02-24 15:18:01.862 i: [MemoryOptimisationBehaviour] Reducing memory cache.
02-24 15:18:01.871 i: [PictureInPictureBehaviour] Picture-in-picture unavailable as the activity is missing.
02-24 15:18:01.872 i: [MediaSessionHelper] Starting media session with tag: video
02-24 15:18:01.872 i: [MediaSessionHelper] Creating media session with tag: video
02-24 15:18:01.884 i: [CurrentItemMetadataBehaviour] Fetching current item
02-24 15:18:01.885 i: [MediaSessionBehaviour] Current item has changed
02-24 15:18:01.886 i: [MediaSessionBehaviour] Updating metadata for video.
02-24 15:18:01.886 i: Fetching [method:GET] https://192.168.80.6:32400/library/metadata/160874?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1&X-Plex-Token=...bLkd&X-Plex-Language=en-us
02-24 15:18:01.888 i: [InteractionBehaviour] Slept, reason: Startup.
02-24 15:18:01.889 i: [EnhancedSeekBehaviour] Loading BIF from https://192.168.80.6:32400/library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=...Lkd.
02-24 15:18:01.889 i: [PlayerService] Service has been started successfully.
02-24 15:18:01.890 i: [ExoPlayer][EventLogger] surfaceSize [eventTime=0.05, mediaPos=0.00, window=0, 0, 0]
02-24 15:18:01.891 i: [Player][ExoPlayer] Setting given surfaceView
02-24 15:18:01.891 i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
02-24 15:18:01.891 i: [DisplayBehaviour] New ExoPlayerEngine detected, enabling: true.
02-24 15:18:01.891 i: [Player][ExoPlayer] Using PlayQueueMediaSource
02-24 15:18:01.892 i: [ExoPlayerEngine] Media source has been created.
02-24 15:18:01.892 i: [MediaCodecVideoSyncRenderer] Setting allow dummy surface: false
02-24 15:18:01.894 i: [ExoPlayer][EventLogger] playWhenReady [eventTime=0.05, mediaPos=0.00, window=0, true, USER_REQUEST]
02-24 15:18:01.894 i: [ExoPlayer][EventLogger] timeline [eventTime=0.05, mediaPos=0.00, window=0, periodCount=1, windowCount=1, reason=PLAYLIST_CHANGED
02-24 15:18:01.895 i: [ExoPlayer][EventLogger] period [?]
02-24 15:18:01.895 i: [ExoPlayer][EventLogger] window [?, seekable=false, dynamic=true]
02-24 15:18:01.895 i: [ExoPlayer][EventLogger] ]
02-24 15:18:01.895 i: [Player][ExoPlayer] onTimelineChanged (Position: 0 ms, Duration: 0 ms)
02-24 15:18:01.896 i: [ExoPlayer][EventLogger] mediaItem [eventTime=0.05, mediaPos=0.00, window=0, reason=PLAYLIST_CHANGED]
02-24 15:18:01.896 i: [ExoPlayer][EventLogger] state [eventTime=0.06, mediaPos=0.00, window=0, BUFFERING]
02-24 15:18:01.914 i: [LoadControl] Setting buffer size to 73.19 MB / 0 seconds.
02-24 15:18:01.923 i: [LoadControl] Setting buffer size to 73.19 MB / 0 seconds.
02-24 15:18:01.930 i: [MediaDecisionEngine] Starting media decision for: <Content-Name> (part index 0)
02-24 15:18:01.930 i: [MediaDecisionEngine] Transcode server selected: Server-Name
02-24 15:18:01.939 i: [MediaDecisionEngine] Analyzing media: 3840x1606 25144kbps (Container: mkv, Video: hevc, Audio: eac3)
02-24 15:18:01.950 i: [MediaDecisionEngine] Resolution 1606 exceeds maximum allowed (1080)
02-24 15:18:01.951 i: [MediaDecisionEngine] Subtitle selected and local subtitles are disabled
02-24 15:18:01.951 i: [MediaDecisionEngine] Analyzing media: 1920x1080 4712kbps (Container: mkv, Video: hevc, Audio: eac3)
02-24 15:18:01.956 i: Creating PlayerActivity.
02-24 15:18:01.956 i: [Activity] Resuming the application, attempting to download item and children.
02-24 15:18:01.957 i: [Boot] PlayerActivity took 1ms to run create behaviours.
02-24 15:18:01.963 e: [MediaDecisionLoadable] Exception while determining media decision
02-24 15:18:01.963 e: An exception occurred: java.lang.NullPointerException: Attempt to invoke virtual method 'boolean com.plexapp.plex.net.s3.q2()' on a null object reference
02-24 15:18:01.964 e: Stacktrace: java.lang.NullPointerException: Attempt to invoke virtual method 'boolean com.plexapp.plex.net.s3.q2()' on a null object reference
at qh.n.i(SourceFile:1)
at com.plexapp.plex.net.h1.o(SourceFile:9)
at com.plexapp.plex.net.h1.w(SourceFile:345)
at com.plexapp.plex.net.h1.J(SourceFile:17)
at im.e.p(SourceFile:58)
at im.e.s(SourceFile:169)
at zf.o.load(SourceFile:23)
at y4.i0$d.run(SourceFile:52)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
at java.lang.Thread.run(Thread.java:923)
02-24 15:18:01.970 i: Resuming PlayerActivity.
02-24 15:18:01.972 i: [CurrentItemMetadataBehaviour] Fetched current item, success: true
02-24 15:18:01.976 i: [PlayerActivity] Creating fragment
02-24 15:18:01.984 i: [Player][DimensionsLayout] Resizing: 100 x 100
02-24 15:18:01.987 i: [DisplayBehaviour] Activity has been changed, searching for available modes.
02-24 15:18:01.988 i: [DisplayBehaviour] Current mode detected as 3840x2160 @ 59.9401Hz (41).
02-24 15:18:01.989 i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
02-24 15:18:01.990 i: [VideoAwakeBehaviour] Keep screen awake has been requested disabled for VideoAwakeBehaviour.
02-24 15:18:02.033 i: [BaseIndexFramesParser] Found version 0 with 749 indexes, and a multiplier of 1000.
02-24 15:18:02.067 i: [PictureInPictureBehaviour] Picture-in-picture is available and can be used.
02-24 15:18:02.075 i: [ChaptersSheetHud] Found 0 chapters.
02-24 15:18:02.085 i: [MenuSheetHud] Item changed (and fetched), invalidating settings...
02-24 15:18:02.191 i: [Player][View] Layout has been measured with a size of 1920 x 1080 (1920 x 1080).
02-24 15:18:02.196 i: [ExoPlayer][EventLogger] surfaceSize [eventTime=0.36, mediaPos=0.00, window=0, period=0, 1920, 1080]
02-24 15:18:02.197 i: [ASS][Renderer] Surface has been created, connecting to renderer.
02-24 15:18:02.199 i: [ASS][Renderer] Attempting to start renderer from setSurface.
02-24 15:18:02.199 w: [ASS][Renderer] Missing video size, can't start renderer yet.
02-24 15:18:02.208 i: [SystemUIBehaviour] Hiding the system user-interface.
PMS Logs: (the device in question is the 192.168.4.210)
Feb 24, 2023 15:18:03.387 [0x7f6bb2466b38] DEBUG - Request: [192.168.4.210:48726 (WAN)] GET /library/metadata/160874?asyncAugmentMetadata=1&checkFiles=1&includeExtras=1&includeReviews=1 (15 live) #fb3a TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => application/json / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:03.389 [0x7f6bb2466b38] DEBUG - [Req#fb3a] It took 10.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:03.391 [0x7f6bb2466b38] DEBUG - [Req#fb3a] It took 0.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] Selecting best audio stream for part ID 185032 (language: en)
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] Audio Stream: 92934, Subtitle Stream: 100633
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] Selecting best audio stream for part ID 185033 (language: en)
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:03.400 [0x7f6bb2466b38] DEBUG - [Req#fb3a] Audio Stream: 92936, Subtitle Stream: -1
Feb 24, 2023 15:18:03.408 [0x7f6bb2466b38] VERBOSE - [Req#fb3a] It took 0.0 sec to serialize a list with 1 elements.
Feb 24, 2023 15:18:03.408 [0x7f6bb2466b38] DEBUG - [Req#fb3a] Activity: registered new activity 66653c2b-02fa-458b-bfff-ca471e1b085f - "Refreshing"
Feb 24, 2023 15:18:03.408 [0x7f6bb1d08b38] DEBUG - [Req#fb3a] Augment: Completed movie augmentation in 0.0 sec.
Feb 24, 2023 15:18:03.408 [0x7f6bafba0b38] DEBUG - [Req#fb3a] Activity: updated activity 66653c2b-02fa-458b-bfff-ca471e1b085f - completed 0.0% - Refreshing
Feb 24, 2023 15:18:03.408 [0x7f6bafba0b38] DEBUG - [Req#fb3a] Activity: Ended activity 66653c2b-02fa-458b-bfff-ca471e1b085f.
Feb 24, 2023 15:18:03.409 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.210:48726] 200 GET /library/metadata/160874?asyncAugmentMetadata=1&checkFiles=1&includeExtras=1&includeReviews=1 (15 live) #fb3a TLS GZIP 22ms 9695 bytes (pipelined: 9)
Feb 24, 2023 15:18:03.527 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48722 (WAN)] GET /library/metadata/160874/related?count=10&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1 (15 live) #fb33 TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => application/xml / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Name => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:03.529 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:03.531 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:03.548 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 108 items.
Feb 24, 2023 15:18:03.550 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 124 items.
Feb 24, 2023 15:18:03.552 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 131 items.
Feb 24, 2023 15:18:03.554 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 174 items.
Feb 24, 2023 15:18:03.555 [0x7f6bb26b8b38] DEBUG - [Req#fb33] It took 0.000000 ms to retrieve 154 items.
Feb 24, 2023 15:18:03.559 [0x7f6bb26b8b38] VERBOSE - [Req#fb33] It took 0.0 sec to serialize a list with 1 elements.
Feb 24, 2023 15:18:03.562 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.210:48722] 200 GET /library/metadata/160874/related?count=10&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CLabel%2CMood%2CPart%2CPhoto%2CProducer%2CSimilar%2CTopic%2CVast%2CWriter&excludeFields=file%2Ctagline&includeAugmentations=1&includeDetails=1&includeEmpty=1&includeExternalMetadata=1&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&includeTrailers=1&includeTypeFirst=1&libraryHubsOnly=1 (15 live) #fb33 TLS GZIP 35ms 33260 bytes (pipelined: 4)
Feb 24, 2023 15:18:03.996 [0x7f6bb2466b38] DEBUG - Request: [192.168.4.210:48726 (WAN)] GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160875%2Fthumb%2F1669208370&height=1040&quality=90 (15 live) #fb5d TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => image/webp, image/* / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:03.996 [0x7f6bb2466b38] DEBUG - [Req#fb5d] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/160875/thumb/1669208370] (is local: 1 upscaled: 0)
Feb 24, 2023 15:18:04.003 [0x7f6bb2466b38] DEBUG - [Req#fb5d] The cache file did not exist, but the original did [/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/c1/c15644d258a7b9dc63d4db214bd9347a4b5b9126.jpg].
Feb 24, 2023 15:18:04.023 [0x7f6bb2466b38] DEBUG - [Req#fb5d] Created thumbnail of size 1040x585, has pixels: 1
Feb 24, 2023 15:18:04.046 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.210:48726] 200 GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160875%2Fthumb%2F1669208370&height=1040&quality=90 (15 live) #fb5d TLS GZIP 50ms 110456 bytes (pipelined: 10)
Feb 24, 2023 15:18:04.112 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48726 (WAN)] GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160876%2Fthumb%2F1669208370&height=1040&quality=90 (15 live) #fb67 TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => image/webp, image/* / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:04.112 [0x7f6bb26b8b38] DEBUG - [Req#fb67] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/160876/thumb/1669208370] (is local: 1 upscaled: 0)
Feb 24, 2023 15:18:04.112 [0x7f6bb26b8b38] DEBUG - [Req#fb67] The cache file did not exist, but the original did [/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/bd/bd3d596e36c162fe923dae09b616885609ca7bfa.jpg].
Feb 24, 2023 15:18:04.120 [0x7f6bb26b8b38] DEBUG - [Req#fb67] Created thumbnail of size 1005x565, has pixels: 1
Feb 24, 2023 15:18:04.139 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.210:48726] 200 GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160876%2Fthumb%2F1669208370&height=1040&quality=90 (15 live) #fb67 TLS GZIP 27ms 108942 bytes (pipelined: 11)
Feb 24, 2023 15:18:04.173 [0x7f6bb2466b38] DEBUG - Request: [192.168.4.210:48748 (WAN)] GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160877%2Fthumb%2F1669208370&height=1040&quality=90 (16 live) #fb0c TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => image/webp, image/* / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:04.173 [0x7f6bb2466b38] DEBUG - [Req#fb0c] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/160877/thumb/1669208370] (is local: 1 upscaled: 0)
Feb 24, 2023 15:18:04.174 [0x7f6bb2466b38] DEBUG - [Req#fb0c] The cache file did not exist, but the original did [/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/40/4041918b76ccc114479dedc63635e8ba3a9cc338.jpg].
Feb 24, 2023 15:18:04.180 [0x7f6bb2466b38] DEBUG - [Req#fb0c] Created thumbnail of size 912x513, has pixels: 1
Feb 24, 2023 15:18:04.195 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48726 (WAN)] GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160878%2Fthumb%2F1669208370&height=1040&quality=90 (16 live) #fb68 TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => image/webp, image/* / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:04.195 [0x7f6bb26b8b38] DEBUG - [Req#fb68] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/160878/thumb/1669208370] (is local: 1 upscaled: 0)
Feb 24, 2023 15:18:04.197 [0x7f6bb26b8b38] DEBUG - [Req#fb68/Req#fb6a] Computed media url for item 160878: https://metadata-static.plex.tv/extras/iva/744959/08a66489cff7ebe1a717dfa5a21f435b.jpg
Feb 24, 2023 15:18:04.197 [0x7f6bb26b8b38] DEBUG - [Req#fb68] Calling back into ourselves for photo to transcode, optimizing the process (status: 302)
Feb 24, 2023 15:18:04.199 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.210:48748] 200 GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160877%2Fthumb%2F1669208370&height=1040&quality=90 (16 live) #fb0c TLS GZIP 26ms 134412 bytes (pipelined: 1)
Feb 24, 2023 15:18:04.210 [0x7f6bb26b8b38] DEBUG - [Req#fb68/HCl#b9] HTTP requesting GET https://metadata-static.plex.tv/extras/iva/744959/08a66489cff7ebe1a717dfa5a21f435b.jpg
Feb 24, 2023 15:18:04.311 [0x7f6bb0661b38] DEBUG - [HttpClient/HCl#b9] HTTP/2.0 (0.1s) 200 response from GET https://metadata-static.plex.tv/extras/iva/744959/08a66489cff7ebe1a717dfa5a21f435b.jpg
Feb 24, 2023 15:18:04.311 [0x7f6bb0878b38] DEBUG - [HttpClient] Photo cache obtained 106766 bytes from https://metadata-static.plex.tv/extras/iva/744959/08a66489cff7ebe1a717dfa5a21f435b.jpg
Feb 24, 2023 15:18:04.311 [0x7f6bb0878b38] DEBUG - [HttpClient] Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/db/db9db27dec1dcd96ca63304a238c8cefd9bb1c46.jpg
Feb 24, 2023 15:18:04.317 [0x7f6bb46bfb38] VERBOSE - We didn't receive any data from 192.168.4.210:48724 in time, dropping connection.
Feb 24, 2023 15:18:04.324 [0x7f6bb0878b38] DEBUG - [HttpClient] Created thumbnail of size 912x513, has pixels: 1
Feb 24, 2023 15:18:04.341 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.210:48726] 200 GET /photo/:/transcode?width=1040&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160878%2Fthumb%2F1669208370&height=1040&quality=90 (16 live) #fb68 TLS GZIP 146ms 104649 bytes (pipelined: 12)
Feb 24, 2023 15:18:05.195 [0x7f6bb2466b38] DEBUG - Request: [192.168.4.210:48722 (WAN)] GET /library/metadata/160874?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1 (16 live) #fb66 TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => application/xml / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Name => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Playback-Id => dcc85ffe-5f46-40aa-84fa-95997f78ab32 / X-Plex-Playback-Session-Id => a7c6067f-269b-4934-8f54-61e2b6674c46 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:05.197 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48724 (WAN)] GET /library/parts/185032/indexes/sd?interval=10000 (16 live) #fb0a TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept-Encoding => gzip / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Dalvik/2.1.0 (Linux; U; Android 11; SHIELD Android TV Build/RQ1A.210105.003) / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:05.197 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 0.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:05.199 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 0.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185032 (language: en)
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 92934, Subtitle Stream: 100633
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185033 (language: en)
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.204 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 92936, Subtitle Stream: -1
Feb 24, 2023 15:18:05.206 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 10.000000 ms to retrieve 104 items.
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] VERBOSE - [IDLE] Adding (0->1) work item http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] VERBOSE - [IDLE] Server is now active
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] VERBOSE - [IDLE] * http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx - 1 active item(s)
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] DEBUG - Content-Length is 3927656 (of total: 3927656).
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] WARN - Failed to set up two way stream, caught exception: write: protocol is shutdown
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] DEBUG - Completed after connection close: [192.168.4.210:48724] 200 GET /library/parts/185032/indexes/sd?interval=10000 (17 live) #fb0a TLS GZIP 11ms 0 bytes (pipelined: 2)
Feb 24, 2023 15:18:05.208 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48750 (WAN)] GET /library/parts/185032/indexes/sd?interval=10000 (17 live) #fb69 TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept-Encoding => gzip / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Dalvik/2.1.0 (Linux; U; Android 11; SHIELD Android TV Build/RQ1A.210105.003) / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:05.209 [0x7f6bb26b8b38] VERBOSE - [IDLE] Adding (1->2) work item http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:05.209 [0x7f6bb26b8b38] VERBOSE - [IDLE] * http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx - 2 active item(s)
Feb 24, 2023 15:18:05.209 [0x7f6bb26b8b38] DEBUG - Content-Length is 3927656 (of total: 3927656).
Feb 24, 2023 15:18:05.220 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 10.000000 ms to retrieve 131 items.
Feb 24, 2023 15:18:05.222 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 0.000000 ms to retrieve 174 items.
Feb 24, 2023 15:18:05.224 [0x7f6bb2466b38] DEBUG - [Req#fb66] It took 10.000000 ms to retrieve 154 items.
Feb 24, 2023 15:18:05.228 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.228 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185251 (language: en)
Feb 24, 2023 15:18:05.228 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.228 [0x7f6bb2466b38] DEBUG - [Req#fb66] Subtitles: Found a candidate subtitle language [en] for a foreign film
Feb 24, 2023 15:18:05.228 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 94221, Subtitle Stream: 94226
Feb 24, 2023 15:18:05.230 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.230 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185252 (language: en)
Feb 24, 2023 15:18:05.230 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.230 [0x7f6bb2466b38] DEBUG - [Req#fb66] Subtitles: Found a candidate subtitle language [en] for a foreign film
Feb 24, 2023 15:18:05.230 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 94248, Subtitle Stream: 100880
Feb 24, 2023 15:18:05.232 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.232 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 249000 (language: en)
Feb 24, 2023 15:18:05.232 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.232 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 100732, Subtitle Stream: -1
Feb 24, 2023 15:18:05.233 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.233 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 249099 (language: en)
Feb 24, 2023 15:18:05.233 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.233 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 101104, Subtitle Stream: -1
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185040 (language: en)
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 92988, Subtitle Stream: -1
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185041 (language: en)
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.234 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 92990, Subtitle Stream: -1
Feb 24, 2023 15:18:05.235 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.235 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185067 (language: en)
Feb 24, 2023 15:18:05.235 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.235 [0x7f6bb2466b38] DEBUG - [Req#fb66] Subtitles: Found a candidate subtitle language [en] for a foreign film
Feb 24, 2023 15:18:05.235 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 93078, Subtitle Stream: 93081
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185068 (language: en)
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] Subtitles: Found a candidate subtitle language [en] for a foreign film
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 93101, Subtitle Stream: 93104
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185065 (language: en)
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.237 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 93074, Subtitle Stream: -1
Feb 24, 2023 15:18:05.238 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select an audio stream for account 1.
Feb 24, 2023 15:18:05.238 [0x7f6bb2466b38] DEBUG - [Req#fb66] Selecting best audio stream for part ID 185066 (language: en)
Feb 24, 2023 15:18:05.238 [0x7f6bb2466b38] DEBUG - [Req#fb66] We're going to try to auto-select a subtitle.
Feb 24, 2023 15:18:05.238 [0x7f6bb2466b38] DEBUG - [Req#fb66] Audio Stream: 93076, Subtitle Stream: -1
Feb 24, 2023 15:18:05.239 [0x7f6bb2466b38] VERBOSE - [Req#fb66] It took 0.1 sec to serialize a list with 1 elements.
Feb 24, 2023 15:18:05.242 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.210:48722] 200 GET /library/metadata/160874?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1 (17 live) #fb66 TLS GZIP 46ms 29718 bytes (pipelined: 5)
Feb 24, 2023 15:18:05.270 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.210:48726 (WAN)] GET /photo/:/transcode?width=128&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160874%2Fthumb%2F1676458849&height=128&quality=90 (17 live) #fb6d TLS GZIP Signed-in Token (deneo6) (SHIELD Android TV) / Accept => image/webp, image/* / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 11; SHIELD Android TV Build/RQ1A.210105.003; wv)PlexTV/9.16.2.38798 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 79acdd44-dff6-40e0-a113-50a3ed917c87 / X-Plex-Client-Identifier => 8ec58aeb98faaede-com-plexapp-android / X-Plex-Device => SHIELD Android TV / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => NVIDIA / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Marketplace => googlePlay / X-Plex-Model => mdarcy / X-Plex-Platform => Android / X-Plex-Platform-Version => 11 / X-Plex-Playback-Id => dcc85ffe-5f46-40aa-84fa-95997f78ab32 / X-Plex-Playback-Session-Id => a7c6067f-269b-4934-8f54-61e2b6674c46 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.2.38798
Feb 24, 2023 15:18:05.270 [0x7f6bb26b8b38] DEBUG - [Req#fb6d] Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/160874/thumb/1676458849] (is local: 1 upscaled: 0)
Feb 24, 2023 15:18:05.270 [0x7f6bb26b8b38] DEBUG - [Req#fb6d] The cache file did not exist, but the original did [/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/a2/a26a578079cbcc00aefcf33337c4e1307b0c2faa.jpg].
Feb 24, 2023 15:18:05.292 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.210:48750] 200 GET /library/parts/185032/indexes/sd?interval=10000 (17 live) #fb69 TLS GZIP 84ms 3927656 bytes (pipelined: 1)
Feb 24, 2023 15:18:05.292 [0x7f6bb46bfb38] VERBOSE - [IDLE] Removing (2->1) work item http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:05.292 [0x7f6bb46bfb38] VERBOSE - [IDLE] * http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx - 1 active item(s)
Feb 24, 2023 15:18:05.302 [0x7f6bb26b8b38] DEBUG - [Req#fb6d] Created thumbnail of size 85x128, has pixels: 1
Feb 24, 2023 15:18:05.303 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.210:48726] 200 GET /photo/:/transcode?width=128&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F160874%2Fthumb%2F1676458849&height=128&quality=90 (17 live) #fb6d TLS GZIP 33ms 6910 bytes (pipelined: 13)
Feb 24, 2023 15:18:06.933 [0x7f6bb48c2b38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:09.114 [0x7f6bb2466b38] DEBUG - [Req#fb43] [Now] User is deneo6 (ID: 1)
Feb 24, 2023 15:18:09.114 [0x7f6bb2466b38] DEBUG - [Req#fb43] [Now] Device is Android (Master BR).
Feb 24, 2023 15:18:09.114 [0x7f6bb2466b38] DEBUG - [Req#fb43] [Now] Profile is Android
Feb 24, 2023 15:18:09.114 [0x7f6bb2466b38] DEBUG - [Req#fb43] [Now] Updated play state for /library/metadata/224142.
Feb 24, 2023 15:18:09.114 [0x7f6bb2466b38] DEBUG - [Req#fb43] Statistics: (dc8ea9c7142ca3b9-com-plexapp-android) Reporting active playback in state 2 of type 4 (scrobble: 0) for account 1
Feb 24, 2023 15:18:09.115 [0x7f6bb2466b38] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Feb 24, 2023 15:18:09.115 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.179:59436] 200 GET /:/timeline?audioStreamID=101268&bufferedTime=0&duration=2627000&guid=plex%3A%2F%2Fepisode%2F5d9c13607b5c2e001e6d1add&key=%2Flibrary%2Fmetadata%2F224142&playbackTime=1739855&playQueueItemID=37485&ratingKey=224142&state=buffering&time=0&token=xxxxxxxxxxxxxxxxxxxx (17 live) #fb43 TLS GZIP 1ms 421 bytes (pipelined: 328)
Feb 24, 2023 15:18:09.117 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.48:63431 (WAN)] GET /status/sessions (17 live) #fb44 TLS GZIP Signed-in Token (deneo6) (Chrome) / Accept => application/json, text/plain, */* / Accept-Encoding => gzip, deflate, br / Accept-Language => en / Connection => keep-alive / Cookie => tautulli_token_f26b6d11f7594229b46ad8575fabb054=eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJ1c2VyX2lkIjoyNDQ0NTkyNSwidXNlciI6ImRlbmVvNiIsInVzZXJfZ3JvdXAiOiJhZG1pbiIsImV4cCI6MTY3ODk0MjAwNH0.4TcVinyjqLhnL2EM-gLZWRlNE5DpwMmI7noDMagYF8E; tautulli_token_3b6a6c55af2c46b5a06c26e3f8355f75=eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJ1c2VyX2lkIjoyNDQ0NTkyNSwidXNlciI6ImRlbmVvNiIsInVzZXJfZ3JvdXAiOiJhZG1pbiIsImV4cCI6MTY3ODk0MjA4MX0.Uxnqe8myYuUTxGWjQTuFvgvlnTPyxRupF2GEFhHKyt4 / Host => 192.168.80.6:32400 / Referer => https://192.168.80.6:32400/web/index.html / sec-ch-ua => "Chromium";v="110", "Not A(Brand";v="24", "Google Chrome";v="110" / sec-ch-ua-mobile => ?0 / sec-ch-ua-platform => "Windows" / Sec-Fetch-Dest => empty / Sec-Fetch-Mode => cors / Sec-Fetch-Site => same-origin / User-Agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36 / X-Plex-Client-Identifier => jj031p6ta6zhpzhuhsc5bbiy / X-Plex-Device => Windows / X-Plex-Device-Name => Chrome / X-Plex-Device-Screen-Resolution => 1279x1288,2560x1440 / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Chrome / X-Plex-Platform-Version => 110.0 / X-Plex-Product => Plex Web / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.100.1
Feb 24, 2023 15:18:09.117 [0x7f6bb26b8b38] DEBUG - [Req#fb44] [Now] Adding 1 sessions.
Feb 24, 2023 15:18:09.117 [0x7f6bb26b8b38] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Feb 24, 2023 15:18:09.117 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.48:63431] 200 GET /status/sessions (17 live) #fb44 TLS GZIP 0ms 350 bytes (pipelined: 13)
Feb 24, 2023 15:18:09.630 [0x7f6bb48c2b38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:10.541 [0x7f6bb46bfb38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:11.410 [0x7f6bb48c2b38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:16.933 [0x7f6bb48c2b38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:19.117 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.179:59436 (WAN)] GET /:/timeline?audioStreamID=101268&bufferedTime=0&duration=2627000&guid=plex%3A%2F%2Fepisode%2F5d9c13607b5c2e001e6d1add&key=%2Flibrary%2Fmetadata%2F224142&playbackTime=1739855&playQueueItemID=37485&ratingKey=224142&state=buffering&time=0&token=xxxxxxxxxxxxxxxxxxxx (17 live) #fb82 TLS GZIP Signed-in Token (deneo6) (Master BR) / Accept => application/xml / Accept-Encoding => gzip / Accept-Language => en-us / Connection => Keep-Alive / Host => 192.168.80.6:32400 / User-Agent => Mozilla/5.0 (Linux; Android 7.1.2; AFTMM Build/NS6295; wv)PlexTV/9.16.1.38616 / X-Plex-Advertising-DoNotTrack => 0 / X-Plex-Advertising-Identifier => 3faf4ccd-256b-4400-8b6f-4bd6e8823094 / X-Plex-Client-Identifier => dc8ea9c7142ca3b9-com-plexapp-android / X-Plex-Client-Identifier => dc8ea9c7142ca3b9-com-plexapp-android / X-Plex-Device => AFTMM / X-Plex-Device-Name => Master BR / X-Plex-Device-Screen-Density => 320 / X-Plex-Device-Screen-Resolution => 1920x1080 (Android TV) / X-Plex-Device-Vendor => Amazon / X-Plex-DRM => widevine:video / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en-us / X-Plex-Model => mantis / X-Plex-Platform => Android / X-Plex-Platform-Version => 7.1.2 / X-Plex-Playback-Id => eacb327d-d564-4846-98fd-e07989d16345 / X-Plex-Playback-Session-Id => 772e657e-6508-428e-a233-35ca397d0ba5 / X-Plex-Product => Plex for Android (TV) / X-Plex-Provider-Version => 6.3.0 / X-Plex-Provides => player,pubsub-player,controller / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 9.16.1.38616
Feb 24, 2023 15:18:19.118 [0x7f6bb26b8b38] DEBUG - [Req#fb82] Client [dc8ea9c7142ca3b9-com-plexapp-android] reporting timeline state buffering, progress of 0/2627000ms for guid=plex://episode/5d9c13607b5c2e001e6d1add, playbackTime=1739855ms ratingKey=224142 url=, key=/library/metadata/224142, containerKey=, metadataId=224142, source=
Feb 24, 2023 15:18:19.118 [0x7f6bb26b8b38] DEBUG - [Req#fb82] [Now] User is deneo6 (ID: 1)
Feb 24, 2023 15:18:19.118 [0x7f6bb26b8b38] DEBUG - [Req#fb82] [Now] Device is Android (Master BR).
Feb 24, 2023 15:18:19.118 [0x7f6bb26b8b38] DEBUG - [Req#fb82] [Now] Profile is Android
Feb 24, 2023 15:18:19.118 [0x7f6bb26b8b38] DEBUG - [Req#fb82] [Now] Updated play state for /library/metadata/224142.
Feb 24, 2023 15:18:19.119 [0x7f6bb26b8b38] DEBUG - [Req#fb82] Statistics: (dc8ea9c7142ca3b9-com-plexapp-android) Reporting active playback in state 2 of type 4 (scrobble: 0) for account 1
Feb 24, 2023 15:18:19.119 [0x7f6bb26b8b38] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Feb 24, 2023 15:18:19.119 [0x7f6bb46bfb38] DEBUG - Completed: [192.168.4.179:59436] 200 GET /:/timeline?audioStreamID=101268&bufferedTime=0&duration=2627000&guid=plex%3A%2F%2Fepisode%2F5d9c13607b5c2e001e6d1add&key=%2Flibrary%2Fmetadata%2F224142&playbackTime=1739855&playQueueItemID=37485&ratingKey=224142&state=buffering&time=0&token=xxxxxxxxxxxxxxxxxxxx (17 live) #fb82 TLS GZIP 1ms 421 bytes (pipelined: 329)
Feb 24, 2023 15:18:19.123 [0x7f6bb26b8b38] DEBUG - Request: [192.168.4.48:63431 (WAN)] GET /status/sessions (17 live) #fb83 TLS GZIP Signed-in Token (deneo6) (Chrome) / Accept => application/json, text/plain, */* / Accept-Encoding => gzip, deflate, br / Accept-Language => en / Connection => keep-alive / Cookie => tautulli_token_f26b6d11f7594229b46ad8575fabb054=eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJ1c2VyX2lkIjoyNDQ0NTkyNSwidXNlciI6ImRlbmVvNiIsInVzZXJfZ3JvdXAiOiJhZG1pbiIsImV4cCI6MTY3ODk0MjAwNH0.4TcVinyjqLhnL2EM-gLZWRlNE5DpwMmI7noDMagYF8E; tautulli_token_3b6a6c55af2c46b5a06c26e3f8355f75=eyJhbGciOiJIUzI1NiIsInR5cCI6IkpXVCJ9.eyJ1c2VyX2lkIjoyNDQ0NTkyNSwidXNlciI6ImRlbmVvNiIsInVzZXJfZ3JvdXAiOiJhZG1pbiIsImV4cCI6MTY3ODk0MjA4MX0.Uxnqe8myYuUTxGWjQTuFvgvlnTPyxRupF2GEFhHKyt4 / Host => 192.168.80.6:32400 / Referer => https://192.168.80.6:32400/web/index.html / sec-ch-ua => "Chromium";v="110", "Not A(Brand";v="24", "Google Chrome";v="110" / sec-ch-ua-mobile => ?0 / sec-ch-ua-platform => "Windows" / Sec-Fetch-Dest => empty / Sec-Fetch-Mode => cors / Sec-Fetch-Site => same-origin / User-Agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36 / X-Plex-Client-Identifier => jj031p6ta6zhpzhuhsc5bbiy / X-Plex-Device => Windows / X-Plex-Device-Name => Chrome / X-Plex-Device-Screen-Resolution => 1279x1288,2560x1440 / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Chrome / X-Plex-Platform-Version => 110.0 / X-Plex-Product => Plex Web / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.100.1
Feb 24, 2023 15:18:19.123 [0x7f6bb26b8b38] DEBUG - [Req#fb83] [Now] Adding 1 sessions.
Feb 24, 2023 15:18:19.123 [0x7f6bb26b8b38] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Feb 24, 2023 15:18:19.123 [0x7f6bb48c2b38] DEBUG - Completed: [192.168.4.48:63431] 200 GET /status/sessions (17 live) #fb83 TLS GZIP 0ms 350 bytes (pipelined: 14)
Feb 24, 2023 15:18:19.631 [0x7f6bb48c2b38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:20.541 [0x7f6bb46bfb38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:21.410 [0x7f6bb46bfb38] VERBOSE - WebSocket: processed 1 frame(s)
Feb 24, 2023 15:18:24.200 [0x7f6bb48c2b38] VERBOSE - We didn't receive any data from 192.168.4.210:48748 in time, dropping connection.
Feb 24, 2023 15:18:25.208 [0x7f6bb46bfb38] DEBUG - Sending data was too slow, timed out.
Feb 24, 2023 15:18:25.208 [0x7f6bb46bfb38] DEBUG - Completed after connection close: [192.168.4.210:48724] 200 GET /library/parts/185032/indexes/sd?interval=10000 (17 live) #fb0a TLS GZIP 20011ms 0 bytes (pipelined: 2)
Feb 24, 2023 15:18:25.209 [0x7f6bb46bfb38] VERBOSE - [IDLE] Removing (1->0) work item http_download - /library/parts/185032/indexes/sd?interval=10000&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 24, 2023 15:18:25.209 [0x7f6bb46bfb38] VERBOSE - [IDLE] Server is now idle
Feb 24, 2023 15:18:25.243 [0x7f6bb48c2b38] VERBOSE - We didn't receive any data from 192.168.4.210:48722 in time, dropping connection.
Feb 24, 2023 15:18:25.293 [0x7f6bb46bfb38] DEBUG - Sending data was too slow, timed out.
The issue with changing quality occurs (for me) whether or not I have multiple copies of the file.
I believe the root cause here is the transcoder restart mid-playback.
I’m working that up now for the engineer. 
we get both at the same time.
4 Likes
I am running the internal build like you asked, and it appears to have broken AV1 HW decode with Nvidia. This is on latest drivers, Ubuntu 22.04.
Plex Media Server.zip (289.3 KB)
1 Like
