Server Version#: PMS-Docker 1.22.2.4282
Player Version#: Web 4.54.5
After adding a couple of new tv shows, I noticed that the poster didn’t load and the metadata not loading either.
Here is an example of a line that stands out and is listed a number of times:
Debug — Content-Length is -1 (of total: -1)
This system is running with the media being linked via SMB and the docker config is saved locally for better speed.
Apr 07, 2021 22:57:47.932 [0x7fdc24a6c700] Debug — Auth: authenticated user 1 as cyrus104
Apr 07, 2021 22:57:47.932 [0x7fdb95ffb700] Debug — Request: [10.100.10.241:64457 (WAN)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (6 live) GZIP Signed-in Token (cyrus104)
Apr 07, 2021 22:57:47.932 [0x7fdb95ffb700] Debug — Content-Length is -1 (of total: -1).
Apr 07, 2021 22:57:49.569 [0x7fdb73fff700] Debug — HTTP/1.1 (5.5s) 200 response from GET http://127.0.0.1:40623/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F364093%2F1%2F9%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F1%2Fepisodes%2F9%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_e2f072c2c851285cbee1870c1cb87c25072f6a14 (reused)
Apr 07, 2021 22:57:49.569 [0x7fdbda7fc700] Debug — Brought media local (metadata://seasons/1/episodes/9/thumbs/com.plexapp.agents.thetvdb_e2f072c2c851285cbee1870c1cb87c25072f6a14) in 5520ms.
Apr 07, 2021 22:57:49.569 [0x7fdbda7fc700] Debug — Loading information from media bundle for part ID 9177 from "/config/Library/Application Support/Plex Media Server/Media/localhost/a/14d3a54402b59a8db69554130df0e5ecc5568b8.bundle/Contents"
Apr 07, 2021 22:57:49.569 [0x7fdbda7fc700] Debug — There was a change for metadata item 4288 (Et in Arcadia Ego (1)), saving.
Apr 07, 2021 22:57:49.569 [0x7fdbda7fc700] Debug — Updating metadata item (save) (Et in Arcadia Ego (1)) with ID 4288
Apr 07, 2021 22:57:49.580 [0x7fdbda7fc700] Debug — * Season 1 (-1) Episode 10 (-1) (1900-01-01)
Apr 07, 2021 22:57:49.581 [0x7fdbda7fc700] Debug — Looking up season and index for an episode, indexKey=S01E10 dateKey=.
Apr 07, 2021 22:57:49.581 [0x7fdbda7fc700] Debug — * Found episode with key S01E10
Apr 07, 2021 22:57:49.581 [0x7fdbda7fc700] Debug — * Found episode s01e10
Apr 07, 2021 22:57:49.581 [0x7fdbda7fc700] Debug — Calculated media file path for path [upload://]: ["/config/Library/Application Support/Plex Media Server/Metadata/TV Shows/d/bbe2197ee05a6304cd693160e769b5c1c38f242.bundle/Uploads"]
Apr 07, 2021 22:57:49.581 [0x7fdbda7fc700] Debug — Calculated media file path for path [upload://]: ["/config/Library/Application Support/Plex Media Server/Metadata/TV Shows/e/5a62cb190d4aaa46b6820a255683e16a4d460b5.bundle/Uploads"]
Apr 07, 2021 22:57:49.582 [0x7fdbda7fc700] Debug — Migrating metadata settings from local://4289 -> com.plexapp.agents.thetvdb://364093/1/10?lang=en
Apr 07, 2021 22:57:49.582 [0x7fdbda7fc700] Debug — Doing expensive tags write for 'Et in Arcadia Ego (2)' because something changed.
Apr 07, 2021 22:57:49.584 [0x7fdbda7fc700] Debug — Item 4289 (Et in Arcadia Ego (2)) Scanning metadata graphic elements in XML file "seasons/1/episodes"/10.xml
Apr 07, 2021 22:57:49.585 [0x7fdbda7fc700] Debug — Item 4289 (Et in Arcadia Ego (2)) Scanning metadata graphic elements in XML file "seasons/1/episodes"/10.xml
Apr 07, 2021 22:57:49.586 [0x7fdb73fff700] Debug — HTTP requesting GET http://127.0.0.1:40623/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F364093%2F1%2F10%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F1%2Fepisodes%2F10%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_f8537ff35a8b7f7ec9d2d66a4954101edba5abef
Apr 07, 2021 22:57:55.095 [0x7fdb73fff700] Debug — HTTP/1.1 (5.5s) 200 response from GET http://127.0.0.1:40623/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F364093%2F1%2F10%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F1%2Fepisodes%2F10%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_f8537ff35a8b7f7ec9d2d66a4954101edba5abef (reused)
Apr 07, 2021 22:57:55.096 [0x7fdbda7fc700] Debug — Brought media local (metadata://seasons/1/episodes/10/thumbs/com.plexapp.agents.thetvdb_f8537ff35a8b7f7ec9d2d66a4954101edba5abef) in 5509ms.
Apr 07, 2021 22:57:55.096 [0x7fdbda7fc700] Debug — Loading information from media bundle for part ID 9178 from "/config/Library/Application Support/Plex Media Server/Media/localhost/3/8427c4fd9f69d9bc06c4329174b3e941044e4cd.bundle/Contents"
Apr 07, 2021 22:57:55.096 [0x7fdbda7fc700] Debug — There was a change for metadata item 4289 (Et in Arcadia Ego (2)), saving.
Apr 07, 2021 22:57:55.096 [0x7fdbda7fc700] Debug — Updating metadata item (save) (Et in Arcadia Ego (2)) with ID 4289
Apr 07, 2021 22:57:55.107 [0x7fdbda7fc700] Debug — Loading season 1 from "/config/Library/Application Support/Plex Media Server/Metadata/TV Shows/e/5a62cb190d4aaa46b6820a255683e16a4d460b5.bundle/Contents/_combined/seasons/1".
Apr 07, 2021 22:57:55.107 [0x7fdbda7fc700] Debug — Item 4279 (Season 1) Scanning metadata graphic elements in XML file "seasons"/1.xml
Apr 07, 2021 22:57:55.108 [0x7fdb73fff700] Debug — HTTP requesting GET http://127.0.0.1:40623/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F364093%2F1%3Flang%3Den&mediaType=3&url=metadata%3A%2F%2Fseasons%2F1%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_9072cc920f8518c11078a252541ea55099014606
Apr 07, 2021 22:58:00.983 [0x7fdb73fff700] Debug — HTTP/1.1 (5.9s) 200 response from GET http://127.0.0.1:40623/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F364093%2F1%3Flang%3Den&mediaType=3&url=metadata%3A%2F%2Fseasons%2F1%2Fposters%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_9072cc920f8518c11078a252541ea55099014606 (reused)
Apr 07, 2021 22:58:00.983 [0x7fdbda7fc700] Debug — Brought media local (metadata://seasons/1/posters/com.plexapp.agents.thetvdb_9072cc920f8518c11078a252541ea55099014606) in 5875ms.
Apr 07, 2021 22:58:00.984 [0x7fdbda7fc700] Debug — Item 4279 (Season 1) Scanning metadata graphic elements in XML file "seasons"/1.xml
Apr 07, 2021 22:58:00.984 [0x7fdbda7fc700] Debug — There was a change for metadata item 4279 (Season 1), saving.
Apr 07, 2021 22:58:00.984 [0x7fdbda7fc700] Debug — Updating metadata item (save) () with ID 4279
Apr 07, 2021 22:58:00.985 [0x7fdbda7fc700] Debug — Doing expensive tags write for 'Star Trek: Picard' because something changed.
Apr 07, 2021 22:58:00.986 [0x7fdbda7fc700] Debug — Doing expensive tags write for 'Star Trek: Picard' because something changed.
Apr 07, 2021 22:58:00.990 [0x7fdbda7fc700] Debug — Doing expensive tags write for 'Star Trek: Picard' because something changed.
Apr 07, 2021 22:58:00.994 [0x7fdbda7fc700] Debug — Loaded metadata for Star Trek: Picard (ID 4278) in 79828ms
Apr 07, 2021 22:58:00.994 [0x7fdbda7fc700] Debug — There was a change for metadata item 4278 (Star Trek: Picard), saving.
Apr 07, 2021 22:58:00.994 [0x7fdbda7fc700] Debug — Updating metadata item (save) (Star Trek: Picard) with ID 4278
Apr 07, 2021 22:58:00.995 [0x7fdbda7fc700] Debug — Done with metadata update for 4278
Apr 07, 2021 22:58:00.995 [0x7fdc24a6c700] Debug — Completed: [127.0.0.1:55972] 200 GET /:/metadata/notify?guid=com.plexapp.agents.thetvdb%3A%2F%2F364093%3Flang%3Den&path=TV+Shows%2Fe%2F5a62cb190d4aaa46b6820a255683e16a4d460b5.bundle&force=1&queueSize=0&id=4278&success=1&async=1 (6 live) GZIP 79832ms 166 bytes
Apr 07, 2021 22:58:01.003 [0x7fdc24a6c700] Debug — Auth: authenticated user 1 as cyrus104
Apr 07, 2021 22:58:01.004 [0x7fdbda7fc700] Debug — Request: [10.100.10.241:64464 (WAN)] GET /library/metadata/4278?includeExternalMedia=1&skipRefresh=1 (6 live) GZIP Signed-in Token (cyrus104)
Apr 07, 2021 22:58:01.019 [0x7fdbda7fc700] Debug — There were 1 top-level paths for Star Trek: Picard.
Apr 07, 2021 22:58:01.019 [0x7fdc24a6c700] Debug — Completed: [10.100.10.241:64464] 200 GET /library/metadata/4278?includeExternalMedia=1&skipRefresh=1 (6 live) GZIP 15ms 1779 bytes (pipelined: 1)
Apr 07, 2021 22:58:07.933 [0x7fdc2526d700] Debug — Completed: [10.100.10.241:64457] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (6 live) GZIP 20000ms 5 bytes (pipelined: 1)
Apr 07, 2021 22:58:07.942 [0x7fdc24a6c700] Debug — Auth: authenticated user 1 as cyrus104
Apr 07, 2021 22:58:07.942 [0x7fdbda7fc700] Debug — Request: [10.100.10.241:64464 (WAN)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP Signed-in Token (cyrus104)
Apr 07, 2021 22:58:07.942 [0x7fdbda7fc700] Debug — Content-Length is -1 (of total: -1).
