High loading time for anything different than Albums in the music section

Plex Media Server
1

Server Version#: 1.41.6.9685
Player Version#: 4.145.1

Hello all,

It has been almost 10 years I am on plex with up and downs. I still managed to solve all my issues until today.

Let’s jump right into it.

My problem is related to the music library, and more specifically with the files having something other than ‘Album’ in the releasetype metadata (so it could be Single, EP, Album;Compilation…)

In fact once I click on an Artist, all the albums load right away, but then I have to wait a long (loooooong) time for the rest to show up. And if I have no albums for an artist (let’s say only compiles) it still have the same issue.

I’m using the option prefer local metadata for parsing the content.

Please find attached the log from the moment I click on an artist, until everything shows up. At Apr 27, 2025 09:55:24.492 is the moment when compiles and EPs show up approximately.

Apr 27, 2025 09:55:02.452 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:17787: Connection reset by peer
Apr 27, 2025 09:55:02.452 [132537155230520] Debug — CERT: incomplete TLS handshake from 192.168.61.1:40677: Connection reset by peer
Apr 27, 2025 09:55:03.488 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:7929: Connection reset by peer
Apr 27, 2025 09:55:03.489 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:22384: Connection reset by peer
Apr 27, 2025 09:55:03.536 [132537153121080] Verbose — X-Forwarded-For: 192.168.41.2
Apr 27, 2025 09:55:03.536 [132536841657144] Debug — Request: [192.168.41.2:56897 (WAN)] GET /library/metadata/4341154?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (17 live) #74a735 TLS GZIP Signed-in Token (AK-Admin) (Firefox) / accept => application/json, text/plain, */* / accept-encoding => gzip, deflate, br, zstd / accept-language => en / dnt => 1 / host => MYDOMAIN.com / referer => https://MYDOMAIN.com/web/index.html / sec-fetch-dest => empty / sec-fetch-mode => cors / sec-fetch-site => same-origin / user-agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 / x-forwarded-for => 192.168.41.2 / x-forwarded-proto => https / X-Plex-Client-Identifier => 82uy7iricvw043b21jhlzbli / X-Plex-Device => Windows / X-Plex-Device-Name => Firefox / X-Plex-Device-Screen-Resolution => 1920x965,1920x1080 / X-Plex-Drm => widevine / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Firefox / X-Plex-Platform-Version => 137.0 / X-Plex-Product => Plex Web / X-Plex-Provider-Version => 7.2 / X-Plex-Text-Format => plain / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.145.1
Apr 27, 2025 09:55:03.538 [132536841657144] Debug — [Req#74a735] Activity: registered new activity 256bb7ea-8f70-40ac-9b85-45f0269c3241 - "Refreshing"
Apr 27, 2025 09:55:03.539 [132536815143736] Debug — [Req#74a735] Activity: updated activity 256bb7ea-8f70-40ac-9b85-45f0269c3241 - completed 0.0% - Refreshing
Apr 27, 2025 09:55:03.539 [132536815143736] Debug — [Req#74a735] Activity: updated activity 256bb7ea-8f70-40ac-9b85-45f0269c3241 - completed 33.0% - Refreshing
Apr 27, 2025 09:55:03.539 [132536815143736] Debug — [Req#74a735] Activity: updated activity 256bb7ea-8f70-40ac-9b85-45f0269c3241 - completed 66.0% - Refreshing
Apr 27, 2025 09:55:03.540 [132536815143736] Debug — [Req#74a735] Activity: Ended activity 256bb7ea-8f70-40ac-9b85-45f0269c3241.
Apr 27, 2025 09:55:03.556 [132536841657144] Debug — There were 4 top-level paths for Aaron Copland.
Apr 27, 2025 09:55:03.756 [132536841657144] Verbose — [FindAllPagedWhere] Batched query with batch size of 6 took 31 ms
Apr 27, 2025 09:55:03.758 [132536841657144] Verbose — It took 0.2 sec to serialize a list with 1 elements.
Apr 27, 2025 09:55:03.759 [132537153121080] Debug — Completed: [192.168.41.2:56897] 200 GET /library/metadata/4341154?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (17 live) #74a735 TLS GZIP 223ms 5235 bytes (pipelined: 1)
Apr 27, 2025 09:55:03.759 [132537153121080] Verbose — Didn't receive a request from 192.168.41.2:56897: End of file
Apr 27, 2025 09:55:03.809 [132537155230520] Verbose — X-Forwarded-For: 192.168.41.2
Apr 27, 2025 09:55:03.809 [132536841657144] Debug — Request: [192.168.41.2:40053 (WAN)] GET /library/metadata/4341154/children?excludeAllLeaves=1 (17 live) #74a73c TLS Page 0-19 GZIP Signed-in Token (AK-Admin) (Firefox) / accept => application/json, text/plain, */* / accept-encoding => gzip, deflate, br, zstd / accept-language => en / dnt => 1 / host => MYDOMAIN.com / referer => https://MYDOMAIN.com/web/index.html / sec-fetch-dest => empty / sec-fetch-mode => cors / sec-fetch-site => same-origin / user-agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 / x-forwarded-for => 192.168.41.2 / x-forwarded-proto => https / X-Plex-Client-Identifier => 82uy7iricvw043b21jhlzbli / X-Plex-Container-Size => 20 / X-Plex-Container-Start => 0 / X-Plex-Device => Windows / X-Plex-Device-Name => Firefox / X-Plex-Device-Screen-Resolution => 1920x965,1920x1080 / X-Plex-Drm => widevine / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Firefox / X-Plex-Platform-Version => 137.0 / X-Plex-Product => Plex Web / X-Plex-Provider-Version => 7.2 / X-Plex-Text-Format => plain / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.145.1
Apr 27, 2025 09:55:03.811 [132536841657144] Debug — [Req#74a73c] Setting container serialization range to [0, 19] (total=-1)
Apr 27, 2025 09:55:03.813 [132537153121080] Verbose — X-Forwarded-For: 192.168.41.2
Apr 27, 2025 09:55:03.813 [132536996424504] Debug — Request: [192.168.41.2:13754 (WAN)] GET /library/sections/5/all?album.subformat!=Compilation,Live&artist.id=4341154&group=title&limit=100&ratingCount%3E=1&resolveTags=1&sort=ratingCount:desc&type=10 (18 live) #74a781 TLS Page 0-19 GZIP Signed-in Token (AK-Admin) (Firefox) / accept => application/json, text/plain, */* / accept-encoding => gzip, deflate, br, zstd / accept-language => en / dnt => 1 / host => MYDOMAIN.com / referer => https://MYDOMAIN.com/web/index.html / sec-fetch-dest => empty / sec-fetch-mode => cors / sec-fetch-site => same-origin / user-agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 / x-forwarded-for => 192.168.41.2 / x-forwarded-proto => https / X-Plex-Client-Identifier => 82uy7iricvw043b21jhlzbli / X-Plex-Container-Size => 20 / X-Plex-Container-Start => 0 / X-Plex-Device => Windows / X-Plex-Device-Name => Firefox / X-Plex-Device-Screen-Resolution => 1920x965,1920x1080 / X-Plex-Drm => widevine / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Firefox / X-Plex-Platform-Version => 137.0 / X-Plex-Product => Plex Web / X-Plex-Provider-Version => 7.2 / X-Plex-Text-Format => plain / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.145.1
Apr 27, 2025 09:55:03.813 [132536996424504] Debug — [Req#74a781] Setting container serialization range to [0, 19] (total=-1)
Apr 27, 2025 09:55:03.882 [132536996424504] Verbose — [Req#74a781] [FindAllPagedWhere] Batched query with batch size of 101 took 34 ms
Apr 27, 2025 09:55:03.887 [132536996424504] Verbose — It took 0.0 sec to serialize a list with 14 elements.
Apr 27, 2025 09:55:03.887 [132537153121080] Debug — Completed: [192.168.41.2:13754] 200 GET /library/sections/5/all?album.subformat!=Compilation,Live&artist.id=4341154&group=title&limit=100&ratingCount%3E=1&resolveTags=1&sort=ratingCount:desc&type=10 (18 live) #74a781 TLS GZIP Page 0-19 74ms 2806 bytes (pipelined: 1)
Apr 27, 2025 09:55:03.888 [132537153121080] Verbose — Didn't receive a request from 192.168.41.2:13754: End of file
Apr 27, 2025 09:55:03.944 [132537153121080] Verbose — X-Forwarded-For: 192.168.41.2
Apr 27, 2025 09:55:03.944 [132536996424504] Debug — Request: [192.168.41.2:20468 (WAN)] GET /library/metadata/4341154/related?includeAugmentations=1&includeExternalMetadata=1&includeMeta=1 (18 live) #74a78a TLS GZIP Signed-in Token (AK-Admin) (Firefox) / accept => application/json, text/plain, */* / accept-encoding => gzip, deflate, br, zstd / accept-language => en / dnt => 1 / host => MYDOMAIN.com / referer => https://MYDOMAIN.com/web/index.html / sec-fetch-dest => empty / sec-fetch-mode => cors / sec-fetch-site => same-origin / user-agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 / x-forwarded-for => 192.168.41.2 / x-forwarded-proto => https / X-Plex-Client-Identifier => 82uy7iricvw043b21jhlzbli / X-Plex-Device => Windows / X-Plex-Device-Name => Firefox / X-Plex-Device-Screen-Resolution => 1920x965,1920x1080 / X-Plex-Drm => widevine / X-Plex-Features => external-media,indirect-media,hub-style-list / X-Plex-Language => en / X-Plex-Model => bundled / X-Plex-Platform => Firefox / X-Plex-Platform-Version => 137.0 / X-Plex-Product => Plex Web / X-Plex-Provider-Version => 7.2 / X-Plex-Text-Format => plain / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx / X-Plex-Version => 4.145.1
Apr 27, 2025 09:55:03.980 [132537153121080] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:04.411 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 463 ms
Apr 27, 2025 09:55:04.413 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 0 ms
Apr 27, 2025 09:55:04.415 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 0 ms
Apr 27, 2025 09:55:04.495 [132537155230520] Debug — CERT: incomplete TLS handshake from 192.168.61.1:35623: Connection reset by peer
Apr 27, 2025 09:55:04.496 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:29772: Connection reset by peer
Apr 27, 2025 09:55:04.852 [132536841657144] Verbose — [Req#74a73c] [FindAllPagedWhere] Batched query with batch size of 0 took 534 ms
Apr 27, 2025 09:55:04.860 [132536841657144] Debug — [Req#74a73c] Setting container serialization range to [0, 19] (total=41)
Apr 27, 2025 09:55:04.861 [132536841657144] Verbose — It took 0.0 sec to serialize a list with 20 elements.
Apr 27, 2025 09:55:04.861 [132537153121080] Debug — Completed: [192.168.41.2:40053] 200 GET /library/metadata/4341154/children?excludeAllLeaves=1 (19 live) #74a73c TLS GZIP Page 0-19 1052ms 5048 bytes (pipelined: 1)
Apr 27, 2025 09:55:04.862 [132537153121080] Verbose — Didn't receive a request from 192.168.41.2:40053: End of file
Apr 27, 2025 09:55:05.515 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:19710: Connection reset by peer
Apr 27, 2025 09:55:05.515 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:9973: Connection reset by peer
Apr 27, 2025 09:55:05.582 [132537153121080] Verbose — Didn't receive a request from 192.168.41.2:65238: End of file
Apr 27, 2025 09:55:05.582 [132537153121080] Verbose — Didn't receive a request from 192.168.41.2:18455: End of file
Apr 27, 2025 09:55:05.975 [132537153121080] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:06.130 [132537153121080] Verbose — We didn't receive any data from 192.168.61.1:10299 in time, dropping connection.
Apr 27, 2025 09:55:06.519 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:8281: Connection reset by peer
Apr 27, 2025 09:55:06.520 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:18904: Connection reset by peer
Apr 27, 2025 09:55:07.152 [132537153121080] Verbose — We didn't receive any data from 192.168.61.1:31561 in time, dropping connection.
Apr 27, 2025 09:55:07.388 [132537155230520] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:07.526 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:13430: Connection reset by peer
Apr 27, 2025 09:55:07.526 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:16709: Connection reset by peer
Apr 27, 2025 09:55:08.552 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:17907: Connection reset by peer
Apr 27, 2025 09:55:08.552 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:64209: Connection reset by peer
Apr 27, 2025 09:55:09.041 [132537153121080] Verbose — X-Forwarded-For: 10.10.0.2
Apr 27, 2025 09:55:09.041 [132536841657144] Debug — Request: [10.10.0.2:63792 (WAN)] GET /web/index.html (15 live) #74a7eb TLS Signed-in / accept => text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,image/apng,*/*;q=0.8,application/signed-exchange;v=b3;q=0.9 / host => MYDOMAIN.com / user-agent => Uptime-Kuma/1.23.16 / x-forwarded-for => 10.10.0.2 / x-forwarded-proto => https
Apr 27, 2025 09:55:09.041 [132536841657144] Debug — [Req#74a7eb] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-d301f511a/WebClient.bundle/Contents/Resources/index.html"
Apr 27, 2025 09:55:09.041 [132536841657144] Verbose — [IDLE] Adding (0->1) work item http_download - /web/index.html
Apr 27, 2025 09:55:09.041 [132536841657144] Verbose — [IDLE] Server is now active
Apr 27, 2025 09:55:09.041 [132536841657144] Verbose — [IDLE] * http_download - /web/index.html - 1 active item(s)
Apr 27, 2025 09:55:09.041 [132536841657144] Debug — Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-d301f511a/WebClient.bundle/Contents/Resources/index.html is 30234 (of total: 30234).
Apr 27, 2025 09:55:09.041 [132537153121080] Debug — Completed: [10.10.0.2:63792] 200 GET /web/index.html (15 live) #74a7eb TLS 0ms 30234 bytes (pipelined: 1)
Apr 27, 2025 09:55:09.041 [132537153121080] Verbose — [IDLE] Removing (1->0) work item http_download - /web/index.html
Apr 27, 2025 09:55:09.041 [132537153121080] Verbose — [IDLE] Server is now idle
Apr 27, 2025 09:55:09.228 [132537153121080] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:09.559 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:19940: Connection reset by peer
Apr 27, 2025 09:55:09.559 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:64960: Connection reset by peer
Apr 27, 2025 09:55:10.208 [132537155230520] Verbose — We didn't receive any data from 192.168.61.1:48399 in time, dropping connection.
Apr 27, 2025 09:55:10.583 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:46314: Connection reset by peer
Apr 27, 2025 09:55:10.583 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:22962: Connection reset by peer
Apr 27, 2025 09:55:11.589 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:60989: Connection reset by peer
Apr 27, 2025 09:55:11.589 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:60672: Connection reset by peer
Apr 27, 2025 09:55:12.596 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:57512: Connection reset by peer
Apr 27, 2025 09:55:12.596 [132537153121080] Debug — CERT: incomplete TLS handshake from 192.168.61.1:25246: Connection reset by peer
Apr 27, 2025 09:55:13.272 [132537153121080] Verbose — We didn't receive any data from 192.168.61.1:57484 in time, dropping connection.
Apr 27, 2025 09:55:13.602 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:45320: Connection reset by peer
Apr 27, 2025 09:55:13.602 [132537155230520] Debug — CERT: incomplete TLS handshake from 192.168.61.1:42112: Connection reset by peer
Apr 27, 2025 09:55:13.717 [132537155230520] Verbose — We didn't receive any data from 192.168.41.2:61534 in time, dropping connection.
Apr 27, 2025 09:55:13.718 [132537155230520] Verbose — Didn't receive a request from 192.168.41.2:61534: End of file
Apr 27, 2025 09:55:13.982 [132537153121080] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:14.608 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:36797: Connection reset by peer
Apr 27, 2025 09:55:14.609 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:14986: Connection reset by peer
Apr 27, 2025 09:55:15.615 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:28948: Connection reset by peer
Apr 27, 2025 09:55:15.616 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:39210: Connection reset by peer
Apr 27, 2025 09:55:15.976 [132537153121080] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:16.622 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:20062: Connection reset by peer
Apr 27, 2025 09:55:16.623 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:55309: Connection reset by peer
Apr 27, 2025 09:55:17.357 [132537155230520] Verbose — We didn't receive any data from 192.168.61.1:7519 in time, dropping connection.
Apr 27, 2025 09:55:17.388 [132537155230520] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:17.631 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:25781: Connection reset by peer
Apr 27, 2025 09:55:17.631 [132537153121080] Debug — CERT: incomplete TLS handshake from 192.168.61.1:24422: Connection reset by peer
Apr 27, 2025 09:55:18.638 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:17197: Connection reset by peer
Apr 27, 2025 09:55:18.640 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:14990: Connection reset by peer
Apr 27, 2025 09:55:19.229 [132537155230520] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:19.647 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:63946: Connection reset by peer
Apr 27, 2025 09:55:19.647 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:38041: Connection reset by peer
Apr 27, 2025 09:55:20.402 [132537153121080] Verbose — We didn't receive any data from 192.168.61.1:9229 in time, dropping connection.
Apr 27, 2025 09:55:20.655 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:60799: Connection reset by peer
Apr 27, 2025 09:55:20.656 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:39754: Connection reset by peer
Apr 27, 2025 09:55:21.664 [132537155230520] Debug — CERT: incomplete TLS handshake from 192.168.61.1:11452: Connection reset by peer
Apr 27, 2025 09:55:21.665 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:52947: Connection reset by peer
Apr 27, 2025 09:55:22.448 [132537155230520] Verbose — We didn't receive any data from 192.168.61.1:40677 in time, dropping connection.
Apr 27, 2025 09:55:22.673 [132537153121080] Verbose — Didn't receive a request from 192.168.61.1:3347: Connection reset by peer
Apr 27, 2025 09:55:22.673 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:25511: Connection reset by peer
Apr 27, 2025 09:55:23.681 [132537153121080] Debug — CERT: incomplete TLS handshake from 192.168.61.1:60070: Connection reset by peer
Apr 27, 2025 09:55:23.682 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:5152: Connection reset by peer
Apr 27, 2025 09:55:23.981 [132537155230520] Verbose — WebSocket: processed 1 frame(s)
Apr 27, 2025 09:55:24.492 [132537153121080] Verbose — We didn't receive any data from 192.168.61.1:35623 in time, dropping connection.
Apr 27, 2025 09:55:24.494 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 20078 ms
Apr 27, 2025 09:55:24.499 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 0 ms
Apr 27, 2025 09:55:24.500 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 0 ms
Apr 27, 2025 09:55:24.664 [132536996424504] Verbose — [Req#74a78a] [FindAllPagedWhere] Batched query with batch size of 8 took 0 ms
Apr 27, 2025 09:55:24.667 [132536996424504] Debug — [Req#74a78a/Music] Closest item from 4341154 was a distance of 0.043 away.
Apr 27, 2025 09:55:24.668 [132536996424504] Debug — [Req#74a78a/Music] Furthest item from 4341154 was a distance of 0.078 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Symphony Of The Air is distance 0.043 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Orchestra Sinfonica di Milano Giuseppe Verdi is distance 0.061 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / South German Philharmonic Orchestra is distance 0.064 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Chicago Symphony Orchestra conducted by Fritz Reiner is distance 0.064 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Felix Mendelssohn is distance 0.072 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Danish National Symphony Orchestra is distance 0.072 away.
Apr 27, 2025 09:55:24.674 [132536996424504] Debug — [Req#74a78a] Music: Item  / Rochester Philharmonic Orchestra is distance 0.073 away.
Apr 27, 2025 09:55:24.685 [132536996424504] Debug — There were 4 top-level paths for Symphony Of The Air.
Apr 27, 2025 09:55:24.688 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:46311: Connection reset by peer
Apr 27, 2025 09:55:24.688 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:30496: Connection reset by peer
Apr 27, 2025 09:55:24.690 [132536996424504] Debug — There were 2 top-level paths for Orchestra Sinfonica di Milano Giuseppe Verdi.
Apr 27, 2025 09:55:24.691 [132536996424504] Debug — There were 1 top-level paths for South German Philharmonic Orchestra.
Apr 27, 2025 09:55:24.691 [132536996424504] Debug — There were 1 top-level paths for Chicago Symphony Orchestra conducted by Fritz Reiner.
Apr 27, 2025 09:55:24.706 [132536996424504] Debug — There were 4 top-level paths for Felix Mendelssohn.
Apr 27, 2025 09:55:24.708 [132536996424504] Debug — There were 3 top-level paths for Danish National Symphony Orchestra.
Apr 27, 2025 09:55:24.708 [132536996424504] Verbose — It took 0.0 sec to serialize a list with 8 elements.
Apr 27, 2025 09:55:24.713 [132537155230520] Debug — Completed: [192.168.41.2:20468] 200 GET /library/metadata/4341154/related?includeAugmentations=1&includeExternalMetadata=1&includeMeta=1 (13 live) #74a78a TLS GZIP 20768ms 30737 bytes (pipelined: 1)
Apr 27, 2025 09:55:25.107 [132537153121080] Verbose — X-Forwarded-For: 192.168.41.2
Apr 27, 2025 09:55:25.107 [132536996424504] Debug — Request: [192.168.41.2:20468 (WAN)] GET /photo/:/transcode?width=240&height=240&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F4285993%2Fthumb%2F1723658508%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) #74a8d5 TLS GZIP Signed-in Token (AK-Admin) (Firefox) / accept => image/avif,image/webp,image/png,image/svg+xml,image/*;q=0.8,*/*;q=0.5 / accept-encoding => gzip, deflate, br, zstd / accept-language => en-US,en;q=0.5 / dnt => 1 / host => MYDOMAIN.com / priority => u=5, i / referer => https://MYDOMAIN.com/web/index.html / sec-fetch-dest => image / sec-fetch-mode => no-cors / sec-fetch-site => same-origin / user-agent => Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:137.0) Gecko/20100101 Firefox/137.0 / x-forwarded-for => 192.168.41.2 / x-forwarded-proto => https / X-Plex-Token => xxxxxxxxxxxxxxxxxxxx
Apr 27, 2025 09:55:25.107 [132536996424504] Debug — [Req#74a8d5/PhotoTranscoder] Request for url [/library/metadata/4285993/thumb/1723658508?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 1)
Apr 27, 2025 09:55:25.107 [132536996424504] Verbose — [IDLE] Adding (0->1) work item http_download - /photo/:/transcode?width=240&height=240&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F4285993%2Fthumb%2F1723658508%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 27, 2025 09:55:25.107 [132536996424504] Verbose — [IDLE] Server is now active
Apr 27, 2025 09:55:25.107 [132536996424504] Verbose — [IDLE] * http_download - /photo/:/transcode?width=240&height=240&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F4285993%2Fthumb%2F1723658508%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx - 1 active item(s)
Apr 27, 2025 09:55:25.107 [132536996424504] Debug — Content-Length of /mnt/PlexDatabase/Plex Media Server/Cache/PhotoTranscoder/a4/a4abde0fc1cbfb844bd84cb1deb49c0aec9bd807.jpg is 17585 (of total: 17585).
Apr 27, 2025 09:55:25.107 [132537155230520] Debug — Completed: [192.168.41.2:20468] 200 GET /photo/:/transcode?width=240&height=240&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F4285993%2Fthumb%2F1723658508%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) #74a8d5 TLS GZIP 0ms 17585 bytes (pipelined: 2)
Apr 27, 2025 09:55:25.107 [132537155230520] Verbose — [IDLE] Removing (1->0) work item http_download - /photo/:/transcode?width=240&height=240&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F4285993%2Fthumb%2F1723658508%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 27, 2025 09:55:25.107 [132537155230520] Verbose — [IDLE] Server is now idle
Apr 27, 2025 09:55:25.696 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:16414: Connection reset by peer
Apr 27, 2025 09:55:25.696 [132537155230520] Verbose — Didn't receive a request from 192.168.61.1:55260: Connection reset by peer
Apr 27, 2025 09:55:25.977 [132537153121080] Verbose — WebSocket: processed 1 frame(s)

Thank you in advance for your help!

1 Like
2

This should have been vastly improved in Plex Media Server - #669 by drzoidberg33

Is the number of collections in your library excessive?

Performing a database repair with GitHub - ChuckPa/PlexDBRepair: Database repair utility for Plex Media Server databases can’t hurt either.

3

Hello OttoKerner,

Thank you for your quick response.

I am not sure how this relate to my issue:

  • (Music) Local lyrics and extras for tracks would not get picked up (PM-3185)
    => This would be done once I guess during parsing, but not really evey time I select an artist. Furthermore, picking up if it is an album or an EP is exactly the same process since they are both in the same metadata entry!?
  • (Performance) Loading media details screens can be slow when item is part of many collections (PM-2390)
    => I don’t have any collection setup. So every media is part of one an only library without being part of any collection.

On the other hand, I have done a database repair, not far ago, but I guess it won’t hurt to try it again.

But I find it really strange that plex is not able to do this kind of checks on its own. Here is an example, maybe a little bit off topic but worth mentioning none the less.
I have some knowledge in DB and I can clearly see that sometime plex leaves for example track entries in the metadata_items wile there parent’s are dead (a.k.a parent_id does not exist anymore in the id column) and this can cause issues both during scans and on the player. It isn’t really that hard to check if parent_id from all id are present, and clean those who are not (really one command that could save a lot of headache for people.)

Again sorry for the off topic but this is an example from many.

So back to my issue, I haven’t figured out what makes loading special type of albums (a.k.a compilations, eps, single..) so special for the UI, and I was hoping someone could help me out so I can have an idea to where I should look in the database for any “left over” or bad mapped elements.

Again thank you for your time.

Edit1: I am already using the server version you referred to :smiley:
Edit2: running a databaserepair did not help:

Completed: [192.168.41.2:38880] 200 GET /library/metadata/4341154/related?includeAugmentations=1&includeExternalMetadata=1&includeMeta=1 (8 live) 10ec TLS GZIP **20552ms** 30737 bytes (pipelined: 1)
4

Can these logs help in anyway understanding what is happening.
I get those just after optimizing the DB using the built in command

Apr 29, 2025 18:17:22.606 [129539696896824] Debug — [Req#758224/Database optimization/com.plexapp.plugins.library.db] Database optimization: complete.
Apr 29, 2025 18:17:22.613 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.615 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.616 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.618 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.619 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.621 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.622 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.624 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.626 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.627 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.629 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.630 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.632 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.633 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.635 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.637 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.638 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.640 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.641 [129539696896824] Info — [Req#758224/Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 17, statement aborts at 60: [select * from metadata_items limit 1] database schema has changed
Apr 29, 2025 18:17:22.657 [129539741969208] Warning — [Req#758488] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539766025016] Warning — [Req#758478] QueryParser: Invalid field 'sectionID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539766025016] Warning — [Req#758478] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539766025016] Warning — [Req#758478] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539751422776] Warning — [Req#758480] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539751422776] Warning — [Req#758480] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539751422776] Debug — [Req#758480] Setting container serialization range to [0, 92] (total=-1)
Apr 29, 2025 18:17:22.657 [129539737750328] Warning — [Req#758489] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539737750328] Warning — [Req#758489] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.657 [129539741969208] Warning — [Req#758488] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539741969208] Debug — [Req#758488] Setting container serialization range to [0, 35] (total=-1)
Apr 29, 2025 18:17:22.658 [129539755641656] Warning — [Req#75847e] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539737750328] Debug — [Req#758489] Setting container serialization range to [0, 35] (total=-1)
Apr 29, 2025 18:17:22.658 [129539739859768] Warning — [Req#758484] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539739859768] Warning — [Req#758484] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539739859768] Debug — [Req#758484] Setting container serialization range to [0, 35] (total=-1)
Apr 29, 2025 18:17:22.658 [129539747203896] Warning — [Req#758482] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539749313336] Warning — [Req#758481] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539735640888] Warning — [Req#758487] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539749313336] Warning — [Req#758481] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.658 [129539735640888] Warning — [Req#758487] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539735640888] Debug — [Req#758487] Setting container serialization range to [0, 35] (total=-1)
Apr 29, 2025 18:17:22.659 [129539749313336] Debug — [Req#758481] Setting container serialization range to [0, 92] (total=-1)
Apr 29, 2025 18:17:22.659 [129539763915576] Warning — [Req#75847b] QueryParser: Invalid field 'sectionID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539763915576] Warning — [Req#75847b] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539763915576] Warning — [Req#75847b] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539745094456] Warning — [Req#758483] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539745094456] Warning — [Req#758483] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539745094456] Debug — [Req#758483] Setting container serialization range to [0, 92] (total=-1)
Apr 29, 2025 18:17:22.659 [129539753532216] Warning — [Req#75847f] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539753532216] Warning — [Req#75847f] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.659 [129539753532216] Debug — [Req#75847f] Setting container serialization range to [0, 92] (total=-1)
Apr 29, 2025 18:17:22.660 [129539759860536] Warning — [Req#75847c] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.660 [129539759860536] Warning — [Req#75847c] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.660 [129539759860536] Debug — [Req#75847c] Setting container serialization range to [0, 92] (total=-1)
Apr 29, 2025 18:17:22.660 [129539757751096] Warning — [Req#75847d] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.660 [129539757751096] Warning — [Req#75847d] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.661 [129539731069752] Warning — [Req#75848a] QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Apr 29, 2025 18:17:22.661 [129539731069752] Warning — [Req#75848a] QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
5

Sorry for my English, I also experience the same problem, but my server runs on Windows, In my case it only happens with certain artists and what I see in common is that it has very large albums (more than 300 tracks), it affects both Plex web and Plexamp, especially the latter that takes longer to load. I already tried to rescan the library, update the metadata, optimize the database, clean packages and use PlexDBRepair, but nothing works, the only thing that has worked for me is if I disable “Group albums by type” in the library settings, but it is not my ideal solution.

6

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.