Circle of Death on Resume with Chromecast with Google TV and TV Player App

Plex Players Streaming Devices
, ,
1

Version 1.40.0.7998 - Ubuntu 20.04 - native

I have an issue that i cannot sort out. I have looked at the logs and cannot see why it is failing and it may be that its on the player side not the server side.

This is occuring on a google for tv chromecast and a sony tv plex app (maybe more but at least those). I start a movie from the beginning. Direct plays and loads up fine. Then the next day i go to resume and its yellow spin of death. I thought it was just a single movie so i multiplexed the film and thought it would work today and it didn’t. I then realized today that it was also doing this for the movie i started yseterday when i couldn’t get resume to work. I switched to my tv app thinking it was just the google tv, and it couldn’t resume the flick either. pulled out my phone and it would resume just fine. starting the films from the beginning on both the google tv and the sony’s plex app worked fine.

i can direct message someone the full logs as what i show below doesn’t tell me much. But it says the player closed the connection which i did after i let it spin for forever. thoughts?

Mar 20, 2024 07:35:12.768 [140451127520056] DEBUG - Request: [192.168.1.112:40298 (Subnet)] GET /video/:/transcode/universal/decision?advancedSubtitles=text&audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=136960&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F12063&protocol=*&session=c2cec4bdd4685b3f-com-plexapp-android&skipSubtitles=1&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (13 live) #4ea TLS GZIP Signed-in Token (djfriday13) (Chromecast Google TV)
Mar 20, 2024 07:35:12.769 [140451127520056] DEBUG - [Req#4ea/Transcode] Found session GUID of c2cec4bdd4685b3f-com-plexapp-android in session start.
Mar 20, 2024 07:35:12.771 [140451127520056] DEBUG - [Req#4ea/Transcode] TranscodeUniversalRequest: adapting profile with augmentation data: add-limitation(scope=videoCodec&scopeName=h264&type=upperBound&name=video.level&value=51)+add-limitation(scope=videoCodec&scopeName=*&type=upperBound&name=video.width&value=3840&replace=true)+add-limitation(scope=videoCodec&scopeName=*&type=upperBound&name=video.height&value=2160&replace=true)+add-transcode-target(type=videoProfile&context=streaming&protocol=hls&container=mkv&videoCodec=h264,hevc&audioCodec=aac_latm,aac,aac,ac3,alac,dca,eac3,flac,mp1,mp2,mp3,opus,vorbis,wmav1,wmav2,wmalossless,wmapro,wmavoice&subtitleCodec=ass,pgs,subrip,dvd_subtitle,mov_text,vtt,dvb_subtitle&replace=true)+add-transcode-target-settings(type=videoProfile&context=streaming&protocol=hls&CopyMatroskaAttachments=true)+add-limitation(scope=videoAudioCodec&scopeName=aac&type=upperBound&name=audio.channels&value=8&replace=true)+add-transcode-target-audio-codec(type=videoProfile&context=streaming&protocol=hls&audioCodec=mp3)+add-transcode-target-audio-codec(type=videoProfile&context=streaming&protocol=hls&audioCodec=ac3)+add-transcode-target-audio-codec(type=videoProfile&context=streaming&protocol=hls&audioCodec=eac3)+add-transcode-target-audio-codec(type=videoProfile&context=streaming&protocol=hls&audioCodec=dca)+add-transcode-target-audio-codec(type=videoProfile&context=streaming&protocol=hls&audioCodec=opus)+add-limitation(scope=videoCodec&scopeName=*&type=NotMatch&name=video.anamorphic&value=999&replace=true)+add-transcode-target(type=subtitleProfile&context=streaming&protocol=http&container=mkv&subtitleCodec=srt)+append-transcode-target-codec(type=videoProfile&context=streaming&protocol=hls&videoCodec=hevc)+add-limitation(scope=videoCodec&scopeName=hevc&type=Match&name=video.profile&list=main|main 10)+add-limitation(scope=videoTranscodeTarget&scopeName=hevc&scopeType=videoCodec&context=streaming&protocol=hls&type=match&name=video.colorTrc&list=smpte2084|bt709|bt470m|smpte240m|smpte170m|bt470bg|bt2020-10&isRequired=false)
Mar 20, 2024 07:35:12.772 [140451127520056] DEBUG - [Req#4ea/Transcode] TranscodeUniversalRequest: using augmented profile Android
Mar 20, 2024 07:35:12.772 [140451127520056] DEBUG - [Req#4ea/Transcode] Downloading document http://127.0.0.1:32400/library/metadata/12063?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=c2cec4bdd4685b3f-com-plexapp-android
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode/Req#4f4] It took 0.000000 ms to retrieve 135 items.
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode] We're going to try to auto-select an audio stream for account 1.
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode] Selecting best audio stream for part ID 43120 (language: en)
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode] We're going to try to auto-select a subtitle.
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode] Subtitles: Found a candidate subtitle language [en] for a foreign film
Mar 20, 2024 07:35:12.778 [140451127520056] DEBUG - [Req#4ea/Transcode] Audio Stream: 98399, Subtitle Stream: 98400
Mar 20, 2024 07:35:12.779 [140451127520056] DEBUG - [Req#4ea/Transcode] We're going to try to auto-select an audio stream for account 1.
Mar 20, 2024 07:35:12.779 [140451127520056] DEBUG - [Req#4ea/Transcode] Selecting best audio stream for part ID 43027 (language: en)
Mar 20, 2024 07:35:12.779 [140451127520056] DEBUG - [Req#4ea/Transcode] Audio Stream: 97977, Subtitle Stream: 97979
Mar 20, 2024 07:35:12.785 [140451127520056] DEBUG - [Req#4ea/Transcode] Streaming Resource: Added session 0x7fbd5497f7b8:c2cec4bdd4685b3f-com-plexapp-android
Mar 20, 2024 07:35:12.785 [140451127520056] DEBUG - [Req#4ea/Transcode] Streaming Resource: Reached Decision id=12063 codes=(MDE=1000,Direct play OK.) media=(id=42708 part=(id=43120 decision=direct play protocol=* streams=(Video=(id=98398 decision= width=1920 height=800) Audio=(id=98399 decision= channels=0 rate=0) Subtitle=(id=98400 decision=ignore languageCode=eng location=embedded))))
Mar 20, 2024 07:35:12.786 [140451153644344] DEBUG - Completed: [192.168.1.112:40298] 200 GET /video/:/transcode/universal/decision?advancedSubtitles=text&audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&hasMDE=1&location=lan&maxVideoBitrate=200000&mediaBufferSize=136960&mediaIndex=0&partIndex=0&path=%2Flibrary%2Fmetadata%2F12063&protocol=*&session=c2cec4bdd4685b3f-com-plexapp-android&skipSubtitles=1&subtitleSize=100&videoBitrate=200000&videoQuality=100&videoResolution=3840x2160 (13 live) #4ea TLS GZIP 18ms 7263 bytes (pipelined: 3)
Mar 20, 2024 07:35:12.812 [140451125410616] DEBUG - Request: [192.168.1.112:40298 (Subnet)] GET /library/parts/43120/1710878803/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=136960 (13 live) #4f6 TLS Signed-in Token (djfriday13) (Chromecast Google TV)
Mar 20, 2024 07:35:12.816 [140451125410616] DEBUG - Content-Length of /volume1/Media/Movies/Napoleon (2023)/Napoleon (2023) WEBDL-1080p.mkv is 10886082642 (of total: 10886082642).
Mar 20, 2024 07:35:13.058 [140451155753784] DEBUG - Failed to stream media, client probably disconnected after 2064384 bytes: 32 - Broken pipe
Mar 20, 2024 07:35:13.058 [140451155753784] DEBUG - Completed after connection close: [192.168.1.112:40298] 200 GET /library/parts/43120/1710878803/file.mkv?autoAdjustQuality=0&hasMDE=1&location=lan&mediaBufferSize=136960 (13 live) #4f6 TLS 245ms 2064384 bytes (pipelined: 4)
2 Likes
2

I am also seeing a similar issue on the same platform (GTV – on multiple GTVs)-- specifically w/resuming where it does not (spins and errors out) BUT also if I play and try and fwd a large amount it does the same. I am going to attempt to dig some more this weekend and try with various movie types, viewers, and so on – I did briefly try windows browser and it resumed fine.

3

Yes I can’t seek either. This is with set subtitles and without. Forcing a transcode can at least let you watch the movie. So I’m watching snything I resume in 720p right now.

4

I have downloaded the networking logging logs from the chromecast. If any ninja, staff or employee can take a look ill send them your way.

i am seeing the below which i think is what is being reported while i let the wheel spin for awhile

03-26 17:38:30.230  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:30.401  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:30.550  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:30.759  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:30.906  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.063  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.201  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.346  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.499  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.648  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.797  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:31.947  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:32.113  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:32.263  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:32.402  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
03-26 17:38:32.535  w: [LoadControl] Target buffer size reached with less than 500ms of buffered media data.
5

Ok I just also tried this on my shield and got the same behavior. But I let it sit for several minutes and it eventually started. It was 32 minutes into the movie and it makes me think it is trying to buffer (or some other word for something similar) the film from the beginning and it took several minutes before it buffered to 32 minutes. This is direct play as well. All local. The film has been sent through mkvtoolnix as well. Anyone have any thoughts? Have never seen this behavior on a shield although I have had many a issue with other streaming apps/devices.

6

here is a snippet of the server log. started trying to play around 17:25. Took almost 2 minutes of spinning before the movie started to play. Let it play for several seconds then stopped and pulled the logs. This is on a nvidia shield. local. direct play.
Plex Media Server.log (155.5 KB)

7

@BigWheel is this something you can take a look at if i send you the network logs? I’m seeing this issue on chromecast, sony tv app, nvidia shield. all with most up to date app (not beta). The file resumes fine in windows plex client and web player. all direct play.

8

Thanks for report. sorry i did not see this topic.

I have a Chromecast/Google TV as well that I use every day but I can’t reproduce.

You mentioned it ok’ish when transcoding. What kind of files are they? (codecs and such) Are they particularly high bitrate or anything?

9

sorry for poor explanation. yes transcoding works on all devices and plays the file fine. below are the file info from the two movies. the shield is hardwired. shield and chromecast devices are connected at or above 300mbps as tested using iperf.

Media
Duration 2:38:53
Bitrate 9133 kbps
Width 1920
Height 800
Aspect Ratio 2.35
Video Resolution 1080p
Container MKV
Video Frame Rate 24p
Video Profile high
Part
Duration 2:38:53
File Napoleon (2023).mkv
Size 10.14 GB
Container MKV
Video Profile high
Codec H264
Bitrate 8371 kbps
Language English
Language Tag en
Bit Depth 8
Chroma Location left
Chroma Subsampling 4:2:0
Coded Height 800
Coded Width 1920
Color Primaries bt709
Color Range tv
Color Space bt709
Color Trc bt709
Frame Rate 23.976 fps
Height 800
Level 4.0
Original true
Profile high
Ref Frames 4
Scan Type progressive
Width 1920
Display Title 1080p (H.264)
Extended Display Title 1080p (H.264)
Codec EAC3
Channels 6
Bitrate 762 kbps
Language English
Language Tag en
Original true
Sampling Rate 48000 Hz
Display Title English (EAC3 5.1)
Extended Display Title English (EAC3 5.1)
Forced true
Codec SRT
Language English
Language Tag en
Original true
Title Forced
Display Title English Forced (SRT)
Extended Display Title Forced (English SRT)
Codec SRT
Language English
Language Tag en
Original true
Display Title English (SRT)
Extended Display Title English (SRT)
Codec SRT
Language English
Language Tag en
Hearing Impaired true
Original true
Title SDH
Display Title English SDH (SRT)
Extended Display Title SDH (English SRT)

Media
Duration 2:10:39
Bitrate 7205 kbps
Width 1920
Height 816
Aspect Ratio 2.35
Video Resolution 1080p
Container MKV
Video Frame Rate 24p
Video Profile high
Part
Duration 2:10:39
File Ferrari (2023).mkv
Size 6.58 GB
Container MKV
Video Profile high
Codec H264
Bitrate 6440 kbps
Language English
Language Tag en
Bit Depth 8
Chroma Location left
Chroma Subsampling 4:2:0
Coded Height 816
Coded Width 1920
Color Primaries bt709
Color Range tv
Color Space bt709
Color Trc bt709
Frame Rate 23.976 fps
Height 816
Level 4.0
Original true
Pixel Aspect Ratio 959:960
Profile high
Ref Frames 4
Scan Type progressive
Width 1920
Display Title 1080p (H.264)
Extended Display Title 1080p (H.264)
Codec EAC3
Channels 6
Bitrate 765 kbps
Language English
Language Tag en
Original true
Sampling Rate 48000 Hz
Display Title English (EAC3 5.1)
Extended Display Title English (EAC3 5.1)
Codec SRT
Language English
Language Tag en
Original true
Display Title English (SRT)
Extended Display Title English (SRT)
Codec SRT
Language English
Language Tag en
Hearing Impaired true
Original true
Title SDH
Display Title English SDH (SRT)
Extended Display Title SDH (English SRT)
Codec SRT
Language Spanish
Language Tag es
Dub true
Title Latin American
Display Title Spanish (SRT)
Extended Display Title Latin American (Spanish SRT)
10

Sorry I forgot to get back to you. I did get the file. It does take about 10 seconds to start up after hitting resume button.

So you were saying if you gave me the full file it would take like 30 min to start up?

I have not seen this with any of my own files

I’m going to make a report and add your so our QA an devs teams can have a look when they can. I can’t promise any ETA for fix

Also i just wanted to make sure. You are hitting the Resume button on the details page correct? Not leaving it on pause for hours then coming back to it.

11

Not 30 minutes to resume. But if it was trying to resume from the 30 minute marker it would take almost 2 minutes to resume. I presume if I tried it from the hour marker it would take even longer. I can provide another sample from another movie as well.

Yes resuming from the details page. But this isn’t just resume. If I start from the beginning of the film and try to seek to say the 30 minute mark you see the same behavior.

Happy to assist with whatever. And the workaround for me now is to force a transcode which at least lets me pick up where I left off! Appreciate the response.

12

I also can send you a larger sample if you have a good way for me to send. I could probably set something up on my nas or something to share the entire file if needed.

13

Also having same issue with Docker Plex and Shield Pro.

14

I sent @BigWheel a sample file last week that he could reproduce with. He said he submitted it internally but that there was no eta on diagnosis or a fix obviously.

15

I have also the exact same issue.

I left it spinning for about 2 minutes but it didn’t resume(maybe I had to wait longer) until I stopped it.
Anyway, this has to be fixed.

16

If I leave it spin, movie will eventually start. It took about 10mins for 54mins into the movie.

17

do you have any kind of update here @BigWheel ? did they find an issue or has it still not been diagnosed?

18

sorry i do not have an upate

1 Like
19

On of our QA guys was able to reproduce with some files but not others. We are looking into why that is.

1 Like
20

Thanks for the update!