Need help fixing plex, not adding new media...again

Plex Media Server Metadata & Adding Files
1

hello, this happens on an off, where plex will not add any new media downloaded. mainly movies, for tv shows it seems to have no issues.

if I download a movie from “site” and add it to my movies folder on my synology, plex finds it right away, but if I use radarr, it will download and move the movie into the proper folder, but plex doesn’t pic kit up and add it, unless I remove the movie and add it manually.

these are the things I have tried:

1, verified all folder permissions, and plex has permissions to all my movie folders.
2, increased the amount of folders plex and monitor.
3, I did notice I have to go into my file system on my synology and go to properties of the new movie folder and give permission to plex. even though the main folder has full permission.

what else can I do?

my synology is running dsm 7

2

Radarr does not understand what Synology ACLs (permissions) are.

Therefore, in Radarr

  1. Directory permissions must be set to 755 (the ‘chmod folder’ option)
  2. File permissions must be set to 644 (which the chmod should also set for you)

You must also ensure that Radarr and the download client all run as the same username so Radarr has permission to make the changes.

This is a messy / delicate thing to get right. Best is to try one, check it, make changes if not right, try again — Repeat until correct.

3

Chmod is radar is already set to 755.
But I’m not sure where to see if it is set to 644.

And sabnzb everything looks good there.

4

How about making sure DEBUG logging is enabled, VERBOSE is disabled,

Next “Scan Files” so we can definitively see where and what the error(s) are.

When complete, Download the logs ZIP file and attach please.

5

how do I enable debug and verbose off?

6

Setttings - Server - General - Show Advanced.

You’ll see the checkboxes.
Default = Debug ON, Verbose OFF

7

got it, I will upload the logs in a few minutes.

8

Plex Media Server Logs_2022-06-04_17-37-32.zip (4.4 MB)

9

it seems plex is stuck on scanning my movies library, even though I clicked to cancel, it just restarts.

10

Thanks for the logs.

There are no permission errors.

What I do find is a naming error.

Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/The Last Dragonslayer (2016) (tt5807628)/The Last Dragonslayer (2016) (tt5807628)-fanart.jpg"

This should use {imdb: tt5807628} (Curly braces – not parenthesis)

The scanner separates by:

(numbers) = year
{source: id_number} = tvdb,tmdb, or imdb as source followed by the ID
[string to ignore] = Anything inside the ` is ignored.

11

I just dont understand why plex will not add new movies from radarr.

12

One of the things I saw which might be the problem…

When you download, is the download going directly into your movies library BEFORE it’s completed ?

I ask because I see a great deal of
Library section 10 (Movies) will be updated because of a change in

messages. This means that files/directories are being written over and over – forcing PMS to restart the scan before it can complete.

13

the movie only goes into my movie library after it is completed.

also plex seems to me stuck on scanning the movies library, I cancel it and it just continues as if I didn’t cancel it.

14

Experiment time.

  1. Stop your download client. (Don’t allow any activity)
  2. exit the program / (shutdown) if easily done
  3. Now observe PMS behavior.

Next step - - Turn off “Automatically update my library”, Save the change, Restart PMS, and perform one scan.

Here are two examples of what I’m seeing.

Jun 04, 2022 17:08:45.325 [0x7f526d39ab38] INFO - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126).mkv"
Jun 04, 2022 17:08:45.343 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126).mkv"
Jun 04, 2022 17:08:45.377 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126)-fanart.jpg"
Jun 04, 2022 17:08:45.421 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126)-fanart.jpg"
Jun 04, 2022 17:08:45.461 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126)-poster.jpg"
Jun 04, 2022 17:08:45.480 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Adaptation. (2002) (tt0268126)/Adaptation. (2002) (tt0268126)-poster.jpg"
Jun 04, 2022 17:08:45.567 [0x7f526f219b38] DEBUG - It took 100.000000 ms to retrieve 11110 items.
Jun 04, 2022 17:08:45.621 [0x7f526d39ab38] INFO - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Social Nightmare (2013) (tt2953196)/Social Nightmare (2013) (tt2953196)-poster.jpg"
Jun 04, 2022 17:08:45.636 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Social Nightmare (2013) (tt2953196)/Social Nightmare (2013) (tt2953196)-poster.jpg"
Jun 04, 2022 17:08:45.665 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Social Nightmare (2013) (tt2953196)/Social Nightmare (2013) (tt2953196).mp4"
Jun 04, 2022 17:08:45.680 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Social Nightmare (2013) (tt2953196)/Social Nightmare (2013) (tt2953196).mp4"
Jun 04, 2022 17:08:45.721 [0x7f526dd3ab38] DEBUG - Library Updater: Requested that section 10 be updated, force=0, deep=0, subdir=/volume1/media/movies/The Autopsy of Jane Doe (2016) (tt3289956) iTunes=0
Jun 04, 2022 17:08:45.721 [0x7f526dd3ab38] DEBUG - Library Updater: Queueing section 10
Jun 04, 2022 17:08:45.778 [0x7f526a7b9b38] DEBUG - Push: Processing new content in section 10 for 20 users.
Jun 04, 2022 17:08:45.779 [0x7f526a7b9b38] DEBUG - Push: There are 0 new top level things for filter '' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.780 [0x7f526a7b9b38] DEBUG - Push: There are 0 new top level things for filter 'label!=Not%20For%20Izabelle' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.787 [0x7f526a7b9b38] DEBUG - Push: There are 0 new top level things for filter 'label=Peyton%20Allowed' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.787 [0x7f526a7b9b38] DEBUG - Push: Processing new content complete in 0.0 sec.
Jun 04, 2022 17:08:45.788 [0x7f526a563b38] DEBUG - Push: Processing new content in section 10 for 20 users.
Jun 04, 2022 17:08:45.788 [0x7f5269373b38] DEBUG - MyPlex: sendMapping resetting state - previous mapping state: 'Mapped - Not Published (Bad Response)'.
Jun 04, 2022 17:08:45.788 [0x7f5269373b38] DEBUG - MyPlex: mapping state set to 'Unknown'.
Jun 04, 2022 17:08:45.789 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter '' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.792 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label!=Not%20For%20Izabelle' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.794 [0x7f5269373b38] VERBOSE - It took 0.0 sec to serialize a list with 11 elements.
Jun 04, 2022 17:08:45.794 [0x7f5269373b38] DEBUG - MyPlex: Sending Server Info to myPlex (user=Patrickwhalen10@icloud.com, ip=, port=10975)
Jun 04, 2022 17:08:45.794 [0x7f5269373b38] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Jun 04, 2022 17:08:45.796 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label=Peyton%20Allowed' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.797 [0x7f526a563b38] DEBUG - Push: Processing new content complete in 0.0 sec.
Jun 04, 2022 17:08:45.797 [0x7f526a563b38] DEBUG - Push: Processing new content in section 10 for 20 users.
Jun 04, 2022 17:08:45.798 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter '' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.799 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label!=Not%20For%20Izabelle' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.803 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label=Peyton%20Allowed' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.803 [0x7f526a563b38] DEBUG - Push: Processing new content complete in 0.0 sec.
Jun 04, 2022 17:08:45.804 [0x7f526a563b38] DEBUG - Push: Processing new content in section 10 for 20 users.
Jun 04, 2022 17:08:45.805 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter '' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.806 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label!=Not%20For%20Izabelle' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.809 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label=Peyton%20Allowed' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.810 [0x7f526a563b38] DEBUG - Push: Processing new content complete in 0.0 sec.
Jun 04, 2022 17:08:45.810 [0x7f526a563b38] DEBUG - Push: Processing new content in section 10 for 20 users.
Jun 04, 2022 17:08:45.811 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter '' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.812 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label!=Not%20For%20Izabelle' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.816 [0x7f526a563b38] DEBUG - Push: There are 0 new top level things for filter 'label=Peyton%20Allowed' (movie.addedAt>>=1654307434&sort=movie.addedAt:desc&type=1).
Jun 04, 2022 17:08:45.817 [0x7f526a563b38] DEBUG - Push: Processing new content complete in 0.0 sec.
Jun 04, 2022 17:08:45.817 [0x7f526d39ab38] INFO - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729)-poster.jpg"
Jun 04, 2022 17:08:45.838 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729)-poster.jpg"
Jun 04, 2022 17:08:45.839 [0x7f526a148b38] DEBUG - Library Updater: Requested that section 21 be updated, force=0, deep=0, subdir=/volume1/media/live concerts/Ac Dc No Bull (1996) (tt0255773) iTunes=0
Jun 04, 2022 17:08:45.839 [0x7f526a148b38] DEBUG - Library Updater: Section 21 is already being updated.
Jun 04, 2022 17:08:45.878 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729)-fanart.jpg"
Jun 04, 2022 17:08:45.900 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729)-fanart.jpg"
Jun 04, 2022 17:08:45.935 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729).mp4"
Jun 04, 2022 17:08:45.953 [0x7f526d39ab38] VERBOSE - Library section 10 (Movies) will be updated because of a change in "/volume1/media/movies/Smokey and the Bandit (1977) (tt0076729)/Smokey and the Bandit (1977) (tt0076729).mp4"
Jun 04, 2022 17:08:45.963 [0x7f526d9a4b38] DEBUG - Library Updater: Requested that section 10 be updated, force=0, deep=0, subdir=/volume1/media/movies/All the Old Knives (2022) (tt3706352) iTunes=0
Jun 04, 2022 17:08:45.963 [0x7f526d9a4b38] DEBUG - Library Updater: Queueing section 10
Jun 04, 2022 17:08:46.006 [0x7f526dd13b38] DEBUG - [HttpClient] HTTP/2.0 (0.2s) 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Jun 04, 2022 17:08:46.006 [0x7f5269373b38] DEBUG - MyPlex: Published Mapping State response was 201

There’s 2 different modification notifications within the same one second.

If these are torrents, you have a misconfiguration with the intermediate and final destination locations

15

ok I closed the download client (sabnzbd)
in plex I turned off automatically scan, and restarted it and scanned did a scan. ( on my kids library, and it immediately added a new movie that I downloaded.

EDIT: I scanned my main movie library and it added another movie I just downloaded as a test.

16

Does this confirm that your downloads are not going to the intermediate directory (which PMS doesn’t monitor) while actively downloading?

17

not sure, because when I download a movie I have it go into a download-incomplete folder, and when it is done it goes into a downloads-complete folder, that radarr then moves to file to the final location.

18

Somewhere in that chain, your downloader or radarr isn’t doing what it should.

19

and I have no idea what could be the issue, or how to fix it.

20

I really think its a folder permission, because I removed a movie from the movie folder and placed it somewhere else like my kids library, and it will not add to plex.