1.20.0.3133 new Plex agent: Opening an auto-created collection is very slow

Plex Media Server Plex Pass Betas
, ,
1

Hello! There are 2 issues I encounter recently, which are probably related.

  1. Opening an auto-generated collection is significantly slower than opening a manually created one. A manually created collection opens near instantly, while an auto-created one usually takes 5 seconds to load. A quick look over the log shows Plex tries to download collection poster and background from tmdb everytime I open an auto-created collection, even though the poster and background file was cached locally.

  2. I was unable to change the sorting order of one specific auto-created collection, namely the “Alien Collection”, everytime I change it it revert back on its own after a few minutes.

BTW I use Chinese as my library language, not sure if that has anything to do with the bug. I attached the log below, please take a look to determine if its a bug.

Plex Media Server.log (5.8 MB)

2

Would you please include the full ZIP file so we can also examine the agent logs simultaneously with the PMS log?

3

Thank you! Here is the full log file.
Plex Media Server Logs_2020-08-01_04-17-57.zip (6.6 MB)

4

Thank you for the logs.

I don’t know what’s happening to your network but if your clients are any indication then you have a bad networking problem.

The playback clients are constantly disconnecting.

Disconnects can be because the client hit STOP, or an App failure, or (likely in this case) a network failure.

A network failure would slow down anything PMS is trying to do.

ratingKey=7715&key=%2Flibrary%2Fmetadata%2F7715&playbackTime=3707&playQueueItemID=19654&state=playing&hasMDE=1&context=preplay%3Ahub.movie.similar&time=587000&duration=5389000 (10 live) TLS GZIP 7ms 454 bytes (pipelined: 37)
Jul 31, 2020 15:14:09.898 [0x7fe9cbfff700] DEBUG - Auth: authenticated user 1 as Fanxiang
Jul 31, 2020 15:14:09.898 [0x7fe9caffd700] DEBUG - Request: [192.168.50.2:49912 (Subnet)] GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS Signed-in Token (Fanxiang) (range: bytes=219743403-) 
Jul 31, 2020 15:14:09.901 [0x7fe9caffd700] DEBUG - Content-Length of /media/Movies/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org.mp4 is 1731694274 (of total: 1951437677).
Jul 31, 2020 15:14:09.908 [0x7fe9cb7fe700] DEBUG - Failed to stream media, client probably disconnected after 376832 bytes: 104 - Connection reset by peer
Jul 31, 2020 15:14:09.908 [0x7fe9cb7fe700] DEBUG - Completed after connection close: [192.168.50.2:49911] 206 GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS 56ms 376832 bytes (range: bytes=222872545-) 
Jul 31, 2020 15:14:09.945 [0x7fe9cb7fe700] DEBUG - Auth: authenticated user 1 as Fanxiang
Jul 31, 2020 15:14:09.945 [0x7fe982ffd700] DEBUG - Request: [192.168.50.2:49913 (Subnet)] GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS Signed-in Token (Fanxiang) (range: bytes=222873084-) 
Jul 31, 2020 15:14:09.948 [0x7fe982ffd700] DEBUG - Content-Length of /media/Movies/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org.mp4 is 1728564593 (of total: 1951437677).
Jul 31, 2020 15:14:09.951 [0x7fe9cbfff700] DEBUG - Failed to stream media, client probably disconnected after 573440 bytes: 104 - Connection reset by peer
Jul 31, 2020 15:14:09.951 [0x7fe9cbfff700] DEBUG - Completed after connection close: [192.168.50.2:49912] 206 GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS 53ms 573440 bytes (range: bytes=219743403-) 
Jul 31, 2020 15:14:09.990 [0x7fe9cb7fe700] DEBUG - Auth: authenticated user 1 as Fanxiang
Jul 31, 2020 15:14:09.990 [0x7fe9427fc700] DEBUG - Request: [192.168.50.2:49914 (Subnet)] GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS Signed-in Token (Fanxiang) (range: bytes=219749875-) 
Jul 31, 2020 15:14:09.995 [0x7fe9427fc700] DEBUG - Content-Length of /media/Movies/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org.mp4 is 1731687802 (of total: 1951437677).
Jul 31, 2020 15:14:09.998 [0x7fe9cbfff700] DEBUG - Failed to stream media, client probably disconnected after 983040 bytes: 104 - Connection reset by peer
Jul 31, 2020 15:14:09.998 [0x7fe9cbfff700] DEBUG - Completed after connection close: [192.168.50.2:49913] 206 GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS 53ms 983040 bytes (range: bytes=222873084-) 
Jul 31, 2020 15:14:10.042 [0x7fe9cb7fe700] DEBUG - Auth: authenticated user 1 as Fanxiang
Jul 31, 2020 15:14:10.042 [0x7fe9d0dbc700] DEBUG - Request: [192.168.50.2:49915 (Subnet)] GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS Signed-in Token (Fanxiang) (range: bytes=222873639-) 
Jul 31, 2020 15:14:10.049 [0x7fe9d0dbc700] DEBUG - Content-Length of /media/Movies/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org/German.Concentration.Camps.Factual.Survey.720p.BluRay.x264.AAC.MVGroup.org.mp4 is 1728564038 (of total: 1951437677).
Jul 31, 2020 15:14:10.052 [0x7fe9cbfff700] DEBUG - Failed to stream media, client probably disconnected after 376832 bytes: 104 - Connection reset by peer
Jul 31, 2020 15:14:10.052 [0x7fe9cbfff700] DEBUG - Completed after connection close: [192.168.50.2:49914] 206 GET /library/parts/9729/1571588448/file.mp4 (10 live) TLS 61ms 376832 bytes (range: bytes=219749875-) 
Jul 31, 2020 15:14:10.054 [0x7fe9cb7fe700] DEBUG - Auth: authenticated user 1 as Fanxiang
Jul 31, 2020 15:14:10.054 [0x7fe941ffb700] DEBUG - Request: [192.168.50.2:49422 (Subnet)] GET /:/timeline?ratingKey=7715&key=%2Flibrary%2Fmetadata%2F7715&playbackTime=3707&playQueueItemID=19654&state=buffering&hasMDE=1&context=preplay%3Ahub.movie.similar&time=587000&duration=5389000 (9 live) TLS GZIP Signed-in Token (Fanxiang)
Jul 31, 2020 15:14:10.056 [0x7fe941ffb700] DEBUG - Client [dushqnv67o4k8a0i0v3b8a2u] reporting timeline state buffering, progress of 587000/5389000ms for guid=, playbackTime=3707ms ratingKey=7715 url=, key=/library/metadata/7715, containerKey=, metadataId=7715, source=
Jul 31, 2020 15:14:10.057 [0x7fe941ffb700] DEBUG - Play progress on 7715 '德国集中营实情调查' - got played 587000 ms by account 1!
Jul 31, 2020 15:14:10.057 [0x7fe941ffb700] DEBUG - [Now] User is Fanxiang (ID: 1)
5

I indeed had some problems playing this specific file, but I don’t think this has anything to do with the collection issue, all other movies on my server plays fine.

From what I observed the issue is that plex request downloading poster from tmdb even though the file already exist locally, slowing down the process.

Aug 01, 2020 08:31:55.636 [0x7fe9c9ffb700] DEBUG - HTTP 200 response from GET https://image.tmdb.org/t/p/original/vhgPpFwb7Px6xAlIX09S1IhGXHl.jpg
Aug 01, 2020 08:31:55.638 [0x7fe9c9ffb700] DEBUG - HTTP requesting GET https://image.tmdb.org/t/p/original/dsqk2vt1t16EKxoVk31NSZcPmR2.jpg
Aug 01, 2020 08:31:56.915 [0x7fe9c9ffb700] DEBUG - HTTP 200 response from GET https://image.tmdb.org/t/p/original/dsqk2vt1t16EKxoVk31NSZcPmR2.jpg
Aug 01, 2020 08:31:56.918 [0x7fe9c9ffb700] DEBUG - Item 54968 (异化三部曲) Scanning metadata graphic elements in XML file ""/Info.xml
Aug 01, 2020 08:31:56.919 [0x7fe9c9ffb700] DEBUG - Local media already exists (metadata://posters/0bda06e6d760d7814c1cc17e1410ab2520e1bd45)
Aug 01, 2020 08:31:56.919 [0x7fe9c9ffb700] DEBUG - Item 54968 (异化三部曲) Scanning metadata graphic elements in XML file ""/Info.xml
Aug 01, 2020 08:31:56.919 [0x7fe9c9ffb700] DEBUG - Local media already exists (metadata://art/a16d41767cc2af13312daafb79cd9d863972a40f)
Aug 01, 2020 08:31:56.919 [0x7fe9c9ffb700] DEBUG - Collections: Refreshed attributes for 1 collections in section 14 in 4867ms.
6

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