Cannot start Track Radio on certain tracks

[alex3305]

Server Version#: 1.26.2.5797
Player Version#: 4.2.1 (PlexAmp)

I cannot seem to start a Track Radio on certain tracks in PlexAmp. Whenever I try to start Track Radio it gives me the following error message:

However when I try to play Album Radio it works, with the same track I just couldn’t start the Track Radio with. So:

• Track - Brad Paisley - Mud On The Tires doesn’t work
• Album - Brad Paisley - Mud On The Tires works and starts with Track - Brad Paisley - Mud On The Tires

The Plex Server logs (Plex Media Server.log) gives me several warnings and errors while trying to start playback:

May 30, 2022 20:55:37.045 [0x14e8ce801b38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.
May 30, 2022 20:56:04.415 [0x14e8cebbfb38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.
May 30, 2022 20:58:00.347 [0x14e8ce77bb38] WARN - SLOW QUERY: It took 320.000000 ms to retrieve 6 items.
May 30, 2022 20:58:02.255 [0x14e8ce738b38] WARN - SLOW QUERY: It took 1900.000000 ms to retrieve 0 items.
May 30, 2022 20:58:02.271 [0x14e8ceb96b38] WARN - SLOW QUERY: It took 1920.000000 ms to retrieve 6 items.
May 30, 2022 20:58:07.989 [0x14e8ced18b38] WARN - SLOW QUERY: It took 310.000000 ms to retrieve 6 items.
May 30, 2022 20:58:09.095 [0x14e8ce77bb38] INFO - AutoUpdate: no updates available
May 30, 2022 20:58:09.999 [0x14e8ceb96b38] WARN - SLOW QUERY: It took 2050.000000 ms to retrieve 0 items.
May 30, 2022 20:58:10.054 [0x14e8cebbfb38] WARN - SLOW QUERY: It took 2090.000000 ms to retrieve 6 items.
May 30, 2022 20:59:03.475 [0x14e8cf030b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 30, 2022 20:59:07.831 [0x14e8cd34db38] WARN - [Music] No external mapping for item 131159 of type 10.
May 30, 2022 20:59:08.088 [0x14e8cf030b38] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 9 items.
May 30, 2022 20:59:08.166 [0x14e8ce77bb38] WARN - SLOW QUERY: It took 280.000000 ms to retrieve 10 items.
May 30, 2022 20:59:08.307 [0x14e8cf030b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 30, 2022 20:59:21.968 [0x14e8cd34db38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.
May 30, 2022 20:59:24.788 [0x14e8cd514b38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.
May 30, 2022 20:59:58.566 [0x14e8cd34db38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.

For my server Type 9 are Albums and Type 10 are Tracks. However I’m at a loss where this issue resides. Is this perhaps a Plex issue?

[elan]

The “No external mapping for item” error implies your sonic database hasn’t computed data for that track yet, or an error occurred while computing it. You can select the “analyze” action on the album, which will ensure sonic data is recomputed the next time library scans or periodic tasks execute.

[alex3305]

Thank you for your response .

IMHO it is a bit strange that Sonic playback for the album was available, but not for the track within the album. So I think the Sonic data was corrupted somehow. I’ve applied the Analyze action on the album and now Sonic playback is disabled for both the album and tracks within the album. I’m now waiting until the periodic tasks are finished, so I can check again.

I hope this fixes it because my Sonic Analysis is running since december 2021 for about 6 hours a day and it would be heartbreaking if I had to start over.

[alex3305]

@elan After starting the Analyze action on the album and artist, Sonic playback was disabled for this artist. However after a full scan and some patience Sonic playback became available again. However it is still not working as I expect.

May 31, 2022 19:50:57.635 [0x146badbdeb38] WARN - [Music] No external mapping for item 141381 of type 8.
May 31, 2022 19:50:57.662 [0x146bae65db38] WARN - [Music] No external mapping for item 125618 of type 8.
May 31, 2022 19:51:07.554 [0x146badc33b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:51:11.565 [0x146bae65db38] WARN - [Track Radio/Music] No external mapping for item 131159 of type 10.
May 31, 2022 19:51:14.063 [0x146bae65db38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:14.064 [0x146bae65db38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.353 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
May 31, 2022 19:51:15.353 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.354 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.355 [0x146badb00b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.355 [0x146badb00b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.359 [0x146bae4a4b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.359 [0x146bae4a4b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.360 [0x146bad960b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.361 [0x146bad960b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.362 [0x146badc33b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.363 [0x146badc33b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.364 [0x146bad8f7b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.364 [0x146bad8f7b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.557 [0x146bae262b38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
May 31, 2022 19:51:15.557 [0x146bae262b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:15.557 [0x146bae262b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:16.460 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
May 31, 2022 19:51:16.460 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
May 31, 2022 19:51:16.460 [0x146bae8b9b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
May 31, 2022 19:51:38.171 [0x146bae4a4b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:51:45.882 [0x146bad8f7b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:51:55.023 [0x146bae65db38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:53:40.064 [0x146bae262b38] WARN - Held transaction for too long (/data/jenkins/server/3533902054/Library/MetadataCollection.cpp:481): 1.080000 seconds
May 31, 2022 19:53:40.822 [0x146bad620b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:53:45.288 [0x146bad481b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:53:54.569 [0x146bad481b38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:53:58.420 [0x146bad620b38] WARN - [Music] No external mapping for item 131144 of type 9.
May 31, 2022 19:54:02.114 [0x146bacbe4b38] WARN - [Track Radio/Music] No external mapping for item 131145 of type 10.
May 31, 2022 19:54:05.151 [0x146bacbe4b38] WARN - [Track Radio/Music] No external mapping for item 131148 of type 10.
May 31, 2022 19:54:08.658 [0x146bacbadb38] WARN - [Music] No external mapping for item 131127 of type 9.
May 31, 2022 19:54:10.776 [0x146bacb4eb38] WARN - [Track Radio/Music] No external mapping for item 131130 of type 10.
May 31, 2022 19:57:05.056 [0x146bacbadb38] WARN - [Music] No external mapping for item 131158 of type 9.
May 31, 2022 19:57:18.923 [0x146bacb4fb38] WARN - [Music] No external mapping for item 131176 of type 9.
May 31, 2022 19:57:23.889 [0x146bacbe4b38] WARN - [Music] No external mapping for item 145917 of type 9.
May 31, 2022 19:57:29.266 [0x146bacbadb38] WARN - [Music] No external mapping for item 131193 of type 9.
May 31, 2022 19:58:09.571 [0x146bacbe4b38] WARN - [Music] No external mapping for item 131111 of type 9.
May 31, 2022 19:58:14.314 [0x146bad620b38] WARN - [Music] No external mapping for item 131053 of type 9.
May 31, 2022 20:00:26.710 [0x146bad940b38] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 1 items.
May 31, 2022 20:01:31.192 [0x146bade6ab38] WARN - SLOW QUERY: It took 290.000000 ms to retrieve 10 items.

Looking into the Plex Library database it seems that these albums are indeed of the artist where the Sonic Playback refuses to start. And another artist I recently added. However you are right, there are no mappings inside the separate Sonic Analysis database. But I still find it strange that Sonic Radio works on album level and not on track level, despite both of those missing mappings. And besides that I expect Sonic Radio to remain grayed out when the analysis isn’t completed.

So I guess there is still something weird going on here…

[elan]

Hard to tell without better logs.

• Run analysis on the album.
• Turn on sonic analysis after a scan.
• Scan music and then cancel.
• Wait for sonic process to quiesce.
• Attach full server logs.

[alex3305]

@elan After your last reply I just waited until the sonic analysis of my library finally completed. Than I pressed analyze on the album, scanned the library and let sonic analysis do it’s thing. Which in fact fixed the issue .

Since I was computing the sonic analysis for a extensive library (50k+ tracks) on my dinky J4105 it took quite a long time for all of it to complete. Thus the late response. Thanks for you extensive input and troubleshooting effort! And of course for Plex and PlexAmp .

[elan]

Awesome, glad it worked!!