Wrong file of daily show gets played

[johnclayton]

Server Version#: 1.25.0.5282 (but has been an issue for a while now)
Player Version#: Web, 4.70.0 (but has happened on Apple TV and Roku as well)

Plex Media Server Logs_2021-12-01_11-35-01.zip (4.7 MB)

I have a daily show that, when there are episodes that don’t seem to have full metadata, the wrong file plays.

From the attached logs:

• Dec 01, 2021 11:34:19.329 attempt to play back item 353420 with a playqueue start request. This is an episode that has full metadata. The playback is started by clicking the play button on item 353420, not on the show’s resume/next button.
• Dec 01, 2021 11:34:19.362 the playqueue request for item 353420 completes.
• Dec 01, 2021 11:34:19.468 (very next line) a request comes in for item 353638, which is the episode without metadata and ultimately the episode that plays. This request does not appear in the Chrome debug console!

From there on out the playback of the wrong item continues as if it had been started.

I’m fairly certain this is a bug in the server somewhere. The response from the initial playqueue request has conflicting data that supports this behavior…playQueueSourceURI: "library://x/item/%2Flibrary%2Fmetadata%2F353420" but also playQueueSelectedMetadataItemID: "353638".

Item 353420 from 11-23 that has metadata that I’m trying to play:

<?xml version="1.0" encoding="UTF-8"?>
<MediaContainer size="1" allowSync="1" identifier="com.plexapp.plugins.library" librarySectionID="2" librarySectionTitle="TV" librarySectionUUID="19ba5d25-2aa2-4fec-af6a-a8d489cd1d1c" mediaTagPrefix="/system/bundle/media/flags/" mediaTagVersion="1638287659">
	<Video ratingKey="353420" key="/library/metadata/353420" skipParent="1" grandparentRatingKey="290337" guid="plex://episode/619a49e824e1cb5c6afbd42d" parentGuid="plex://season/613382ccc149170022cea692" grandparentGuid="plex://show/5d9c0807ffd9ef001e98b056" type="episode" title="Lady Gaga, Tony Bennett" grandparentKey="/library/metadata/290337" librarySectionTitle="TV" librarySectionID="2" librarySectionKey="/library/sections/2" grandparentTitle="The Late Show with Stephen Colbert" parentTitle="Season 7" contentRating="TV-PG" summary="Stephen welcomes an interview with and musical performance by Lady Gaga with Tony Bennett." index="47" parentIndex="7" thumb="/library/metadata/353420/thumb/1637834097" art="/library/metadata/290337/art/1637684395" grandparentThumb="/library/metadata/290337/thumb/1637684395" grandparentArt="/library/metadata/290337/art/1637684395" grandparentTheme="/library/metadata/290337/theme/1637684395" duration="2448916" originallyAvailableAt="2021-11-23" addedAt="1637747450" updatedAt="1637834097">
		<Media id="540560" duration="2448916" bitrate="4608" width="1920" height="1080" aspectRatio="1.78" audioChannels="2" audioCodec="aac" videoCodec="h264" videoResolution="1080" container="mkv" videoFrameRate="NTSC" audioProfile="lc" videoProfile="high">
			<Part accessible="1" exists="1" id="606050" key="/library/parts/606050/1637747391/file.mkv" duration="2448916" file="/media/TV Shows/The Late Show With Stephen Colbert (2015)/Season 07/The Late Show with Stephen Colbert - 2021-11-23 - Lady Gaga Tony Bennett.webdl-1080p.mkv" size="1411364490" audioProfile="lc" container="mkv" deepAnalysisVersion="6" indexes="sd" requiredBandwidths="4883,4678,4678,4678,4678,4678,4678,4678" videoProfile="high">
				<Stream id="1745230" streamType="1" default="1" codec="h264" index="0" bitrate="4483" bitDepth="8" chromaLocation="left" chromaSubsampling="4:2:0" codedHeight="1088" codedWidth="1920" colorPrimaries="bt709" colorRange="tv" colorSpace="bt709" colorTrc="bt709" frameRate="29.970" hasScalingMatrix="0" height="1080" level="40" profile="high" refFrames="4" requiredBandwidths="4758,4559,4559,4559,4559,4559,4559,4559" scanType="progressive" width="1920" displayTitle="1080p (H.264)" extendedDisplayTitle="1080p (H.264)"></Stream>
				<Stream id="1745231" streamType="2" selected="1" default="1" codec="aac" index="1" channels="2" bitrate="125" language="English" languageTag="en" languageCode="eng" audioChannelLayout="stereo" profile="lc" requiredBandwidths="125,125,125,125,125,125,125,125" samplingRate="48000" displayTitle="English (AAC Stereo)" extendedDisplayTitle="English (AAC Stereo)"></Stream>
				<Stream id="1745232" streamType="3" codec="srt" index="2" bitrate="0" language="English" languageTag="en" languageCode="eng" requiredBandwidths="1,1,1,1,1,1,1,1" title="English" displayTitle="English (SRT)" extendedDisplayTitle="English (SRT)"></Stream>
			</Part>
		</Media>
		<Guid id="imdb://tt16227176" />
		<Guid id="tmdb://3347002" />
		<Guid id="tvdb://8848741" />
		<Role id="68588" filter="actor=68588" tag="Tony Bennett" role="" thumb="https://metadata-static.plex.tv/people/5d7768264de0ee001fcc85df.jpg" />
		<Role id="45777" filter="actor=45777" tag="Lady Gaga" role="" thumb="https://metadata-static.plex.tv/9/people/9985e1d9b1ea8d6d61b51354e04f9405.jpg" />
		<Extras size="0"></Extras>
		<Related></Related>
	</Video>
</MediaContainer>

Item 353638 from 11-29 that doesn’t have metadata is keeps playing when I try to play 353420:

<?xml version="1.0" encoding="UTF-8"?>
<MediaContainer size="1" allowSync="1" identifier="com.plexapp.plugins.library" librarySectionID="2" librarySectionTitle="TV" librarySectionUUID="19ba5d25-2aa2-4fec-af6a-a8d489cd1d1c" mediaTagPrefix="/system/bundle/media/flags/" mediaTagVersion="1638287659">
	<Video ratingKey="353638" key="/library/metadata/353638" skipParent="1" grandparentRatingKey="290337" guid="local://353638" parentGuid="plex://season/613382ccc149170022cea692" grandparentGuid="plex://show/5d9c0807ffd9ef001e98b056" type="episode" title="Episode 11-29" grandparentKey="/library/metadata/290337" librarySectionTitle="TV" librarySectionID="2" librarySectionKey="/library/sections/2" grandparentTitle="The Late Show with Stephen Colbert" parentTitle="Season 7" contentRating="TV-PG" summary="" parentIndex="7" viewCount="1" skipCount="2" lastViewedAt="1638313127" thumb="/library/metadata/353638/thumb/1638263777" art="/library/metadata/290337/art/1637684395" grandparentThumb="/library/metadata/290337/thumb/1637684395" grandparentArt="/library/metadata/290337/art/1637684395" grandparentTheme="/library/metadata/290337/theme/1637684395" duration="2445375" originallyAvailableAt="2021-11-29" addedAt="1638263776" updatedAt="1638263777">
		<Media id="540953" duration="2445375" bitrate="4606" width="1920" height="1080" aspectRatio="1.78" audioChannels="2" audioCodec="aac" videoCodec="h264" videoResolution="1080" container="mkv" videoFrameRate="NTSC" audioProfile="lc" videoProfile="high">
			<Part accessible="1" exists="1" id="606443" key="/library/parts/606443/1638263680/file.mkv" duration="2445375" file="/media/TV Shows/The Late Show With Stephen Colbert (2015)/Season 07/The Late Show with Stephen Colbert - 2021-11-29 - Peter Jackson Chelsea Cutler.webdl-1080p.mkv" size="1408666132" audioProfile="lc" container="mkv" deepAnalysisVersion="6" indexes="sd" requiredBandwidths="4720,4603,4603,4603,4603,4603,4603,4603" videoProfile="high">
				<Stream id="1749720" streamType="1" default="1" codec="h264" index="0" bitrate="4481" bitDepth="8" chromaLocation="left" chromaSubsampling="4:2:0" codedHeight="1088" codedWidth="1920" colorPrimaries="bt709" colorRange="tv" colorSpace="bt709" colorTrc="bt709" frameRate="29.970" hasScalingMatrix="0" height="1080" level="40" profile="high" refFrames="4" requiredBandwidths="4595,4478,4478,4478,4478,4478,4478,4478" scanType="progressive" width="1920" displayTitle="1080p (H.264)" extendedDisplayTitle="1080p (H.264)"></Stream>
				<Stream id="1749721" streamType="2" selected="1" default="1" codec="aac" index="1" channels="2" bitrate="125" language="English" languageTag="en" languageCode="eng" audioChannelLayout="stereo" profile="lc" requiredBandwidths="125,125,125,125,125,125,125,125" samplingRate="48000" displayTitle="English (AAC Stereo)" extendedDisplayTitle="English (AAC Stereo)"></Stream>
				<Stream id="1749722" streamType="3" codec="srt" index="2" bitrate="0" language="English" languageTag="en" languageCode="eng" requiredBandwidths="1,1,1,1,1,1,1,1" title="English" displayTitle="English (SRT)" extendedDisplayTitle="English (SRT)"></Stream>
			</Part>
		</Media>
		<Extras size="0"></Extras>
		<Related></Related>
	</Video>
</MediaContainer>

[anon18523487]

Your XML for the second episode doesn’t appear to have an index value. I wonder if that index is actually missing or just not being shown in the XML. Are you able to check the DB directly to see?

My guess is the missing index is throwing off the ordering of the queue.

[johnclayton]

Good eye! That would actually explain the presentation in the clients, where episodes in this metadata-less and index-less state appear at the beginning of the season listing. Not sure how that would come in to play in PQ creation, but it’s certainly suspect.

Here’s the database entries of those 2 episodes, their season, and show records. Sure enough the one that keeps playing, 353638, has index=-1. It also looks like it’s not actually matched, either.

metadata_items.csv.log (2.7 KB)

[johnclayton]

Whelp I just did a “Fix Match” on the show (selecting the same show it was already matched as) and the unmatched episodes matched and play correctly now.

[anon18523487]

Glad to hear. Although it does appear there may be a bug with unmatched date-based episodes. I’ll have to check if this is intentional. I can see why a date based episode may not get an index since there is no clue as to what it should be.

I have SxEy unmatched episodes and they are indexed using the episode number I gave it.

[johnclayton]

Yeah, probably still an issue in there somewhere…it should play the item that it’s been asked to play.

As far as naming, I don’t have episode or season numbers in the file name itself, just the date. It is in the correct season folder though. The file names are in the XML samples.

One non-default setting I have on this show that may is that it’s set to hide seasons under advanced.

[anon18523487]

When looking at the episodes in Web, where is that episode? In my test, it shows up as the very first episode, even before episode 1.

[johnclayton]

Item 353420 was the first one displayed, followed by another unmatched item, followed by the matched items in the expected order.

After fixing the matching the 2 unmatched episodes are now actually the latest ones, which is correct by date.

[anon18523487]

Thanks. I’m not able to reproduce the issue yet but will keep trying.

[johnclayton]

Checking if you’ve been able to reproduce this yet as it’s still a problem in 1.25.3.5409. It’s not so much the missing metadata or matching issues, but the playing the wrong episode is really annoying.

Let me know if there’s anything I can do or provide to help diagnose. Thanks!

[anon18523487]

No, still not able to reproduce. Would you mind sharing that show with me so I can try? Is there an episode you can clearly reproduce this with?

[johnclayton]

Invite incoming!

Not sure if it’s related, but with my current view state I’m trying to play Episode 72, but the Episode 01-17 is what actually plays. Episode 01-17 should be Episode 73 and after the one I am trying to watch. So you may need to mark the intermediate episodes as watched to get it to repro. Just tried with my wife’s account, all episodes marked as unplayed, still got Episode 01-17 when trying to play Episode 72.

Pure speculation, but I have this show set to hide seasons. Not sure if that changes the behavior or not but it is only the daily talk shows that I have seen this issue with, all of which are set to hide seasons.

Earlier today I tried refreshing the show metadata and using Fix Match... to try to get it fixed with no luck. I’ll let you try to repro in this state before doing anything else with this series.

[anon18523487]

Thanks. I’m able to reproduce with that library. Let me see what I can do.

[anon18523487]

Can you grab the server logs for me? Just need the one covering my attempt just now. 1-19-22 6am UTC.

[johnclayton]

Sorry, @anon18523487, it seems that with debug logging enabled I only have about 6 hours worth of logs. Mind trying again so I can snag them sooner? I’ll keep an eye on this thread.

Update 1: Actually it looks like when the latest episode was grabbed it “fixed” Episode 01-17.

This happens frequently, I’ll ping you when it happens again.

Update 2: The Daily Show is now stuck in the same state, just shared it with you!

[anon18523487]

Thanks. I’ve already filed an issue with the team. Added info on this new show. Definitely appears to be when episodes can’t be matched.