Sorry, only get a bit time now to play around with this. No new HEVC files appear in the library, tried The Nun (2018) this time.
To me it looks like PMS recognizes the directory, but it doesn’t add it to the library for some reason
from PMS log:
Mar 06, 2020 08:40:32.437 [0x7ff7097fa700] DEBUG - Activity: registered new activity a67b4a01-a537-457c-a5a3-e31aaf894709 - Cleaning bundles
Mar 06, 2020 08:40:32.439 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 0.0% - Cleaning bundles
Mar 06, 2020 08:40:32.440 [0x7ff73f517700] DEBUG - Completed: [88.80.246.216:53875] 200 PUT /library/clean/bundles?async=1 (5 live) TLS GZIP 3ms 377 bytes (pipelined: 1)
Mar 06, 2020 08:40:33.262 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 25.0% - Cleaning bundles
Mar 06, 2020 08:40:33.262 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 50.0% - Cleaning bundles
Mar 06, 2020 08:40:33.263 [0x7ff70affd700] DEBUG - Deleted 0 metadata bundles.
Mar 06, 2020 08:40:33.263 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 50.0% - Cleaning bundles
Mar 06, 2020 08:40:33.522 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 75.0% - Cleaning bundles
Mar 06, 2020 08:40:33.523 [0x7ff70affd700] DEBUG - Activity: updated activity a67b4a01-a537-457c-a5a3-e31aaf894709 - completed 100.0% - Cleaning bundles
Mar 06, 2020 08:40:33.525 [0x7ff70affd700] DEBUG - Deleted 0 media bundles.
Mar 06, 2020 08:40:33.525 [0x7ff70affd700] DEBUG - Activity: Ended activity a67b4a01-a537-457c-a5a3-e31aaf894709.
Mar 06, 2020 08:41:23.835 [0x7ff70a7fc700] DEBUG - Sync: uploadStatus
Mar 06, 2020 08:41:28.418 [0x7ff6fa7fc700] INFO - Library section 4 (Movies) will be updated because of a change in ‘“/data/hdd0/Video/Movies”/The Nun (2018)’
Mar 06, 2020 08:41:28.420 [0x7ff6fa7fc700] DEBUG - [Notify] New directory: “/data/hdd0/Video/Movies/The Nun (2018)”, adding recursively.
Mar 06, 2020 08:41:28.420 [0x7ff6fa7fc700] DEBUG - [Notify] Now watching “/data/hdd0/Video/Movies/The Nun (2018)”
Mar 06, 2020 08:41:33.418 [0x7ff6fbfff700] DEBUG - Library Updater: Requested that section 4 be updated, force=0, deep=0, subdir=/data/hdd0/Video/Movies iTunes=0
Mar 06, 2020 08:41:33.418 [0x7ff6fbfff700] DEBUG - Library Updater: Queueing section 4
Mar 06, 2020 08:41:33.420 [0x7ff70affd700] DEBUG - Activity: registered new activity 8e82c592-1f2d-4e89-97d9-154701e13256 - Scanning
Mar 06, 2020 08:41:33.421 [0x7ff70affd700] DEBUG - Scanning section 4
Mar 06, 2020 08:41:33.421 [0x7ff70affd700] DEBUG - Activity: updated activity 8e82c592-1f2d-4e89-97d9-154701e13256 - completed 0.0% - Scanning Movies
Mar 06, 2020 08:41:33.422 [0x7ff70affd700] DEBUG - Killing job.
Mar 06, 2020 08:41:33.422 [0x7ff70affd700] DEBUG - Signalling job ID 18940 with 9
Mar 06, 2020 08:41:33.422 [0x7ff70affd700] DEBUG - Job running: EAE_ROOT=‘/tmp/pms-958831a3-53bf-4700-8899-f5c2e8de1cf9/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=‘/var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Codecs/0bc617e-2965-linux-x86_64/’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ ‘/usr/lib/plexmediaserver/Plex Media Scanner’ ‘–scan’ ‘–refresh’ ‘–section’ ‘4’ ‘–activity’ ‘8e82c592-1f2d-4e89-97d9-154701e13256’ ‘–directory’ ‘/data/hdd0/Video/Movies’
Mar 06, 2020 08:41:33.423 [0x7ff70affd700] DEBUG - Jobs: Starting child process with pid 19428
Scanner:
Mar 06, 2020 08:41:33.472 [0x7f3ca7fce700] INFO - Plex Media Scanner v1.18.7.2457-77cb9455c - Debian GNU/Linux PC x86_64 - build: linux-x86_64 - GMT 01:00
Mar 06, 2020 08:41:33.473 [0x7f3ca7fce700] INFO - Linux version: 10 (buster), language: en-US
Mar 06, 2020 08:41:33.473 [0x7f3ca7fce700] INFO - Processor AMD E-350D APU with Radeon™ HD Graphicsÿÿ$0
Mar 06, 2020 08:41:33.473 [0x7f3ca7fce700] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --scan --refresh --section 4 --activity 8e82c592-1f2d-4e89-97d9-154701e13256 --directory /data/hdd0/Video/Movies
Mar 06, 2020 08:41:33.483 [0x7f3ca8ab5740] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Mar 06, 2020 08:41:33.929 [0x7f3ca8ab5740] DEBUG - It took 20.000000 ms to retrieve 602 items.
Mar 06, 2020 08:41:34.122 [0x7f3ca8ab5740] DEBUG - It took 20.000000 ms to retrieve 602 items.
Mar 06, 2020 08:41:34.219 [0x7f3ca8ab5740] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=0
Mar 06, 2020 08:41:34.220 [0x7f3ca4a17700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=4&sinceTime=1583479294
Mar 06, 2020 08:41:34.223 [0x7f3ca4a17700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=4&sinceTime=1583479294
Mar 06, 2020 08:41:34.223 [0x7f3ca8ab5740] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=0
Mar 06, 2020 08:41:34.223 [0x7f3ca8ab5740] DEBUG - Scanning Movies using en(Plex Movie Scanner) with 0 current media items and 1 section locations in the database…
Mar 06, 2020 08:41:34.478 [0x7f3ca8ab5740] DEBUG - Performing a scan with ‘Plex Movie Scanner’ (language: en virtual: 0).
Mar 06, 2020 08:41:34.478 [0x7f3ca8ab5740] DEBUG - * Scanning /data/hdd0/Video/Movies
Mar 06, 2020 08:41:34.479 [0x7f3ca8ab5740] DEBUG - Scanner: Processing directory /data/hdd0/Video/Movies (parent: no)
Mar 06, 2020 08:41:34.484 [0x7f3ca8ab5740] DEBUG - Skipping over directory ‘’, as nothing has changed; removing 0 media items from map.
Mar 06, 2020 08:41:34.488 [0x7f3ca8ab5740] DEBUG - Scanner: Processing directory /data/hdd0/Video/Movies/The Nun (2018) (parent: yes)
Mar 06, 2020 08:41:34.490 [0x7f3ca8ab5740] DEBUG - Skipping over directory ‘The Nun (2018)’, as nothing has changed; removing 0 media items from map.
Mar 06, 2020 08:41:34.490 [0x7f3ca4a17700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20The%20Nun%20(2018)
Mar 06, 2020 08:41:34.492 [0x7f3ca8ab5740] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B49.5&subtitle=The%20Nun%20(2018)
Mar 06, 2020 08:41:34.493 [0x7f3ca4a17700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20The%20Nun%20(2018)
Mar 06, 2020 08:41:34.496 [0x7f3ca8ab5740] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B49.5&subtitle=The%20Nun%20(2018)
Mar 06, 2020 08:41:34.496 [0x7f3ca8ab5740] DEBUG - Scanner: Processing directory /data/hdd0/Video/Movies/Anna (2019) (parent: yes)
Mar 06, 2020 08:41:34.498 [0x7f3ca8ab5740] DEBUG - Skipping over directory ‘Anna (2019)’, as nothing has changed; removing 0 media items from map.
Mar 06, 2020 08:41:34.499 [0x7f3ca4a17700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20Anna%20(2019)
Mar 06, 2020 08:41:34.499 [0x7f3ca8ab5740] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B49.5&subtitle=Anna%20(2019)
Mar 06, 2020 08:41:34.502 [0x7f3ca4a17700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20Anna%20(2019)
Mar 06, 2020 08:41:34.505 [0x7f3ca8ab5740] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B49.5&subtitle=Anna%20(2019)
Mar 06, 2020 08:41:34.506 [0x7f3ca8ab5740] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Mar 06, 2020 08:41:34.509 [0x7f3ca8ab5740] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Mar 06, 2020 08:41:34.509 [0x7f3ca8ab5740] DEBUG - Removing 0 media items that were left.
Mar 06, 2020 08:41:34.509 [0x7f3ca8ab5740] DEBUG - Removing 0 directories that were left.
Mar 06, 2020 08:41:34.509 [0x7f3ca8ab5740] DEBUG - Updating directory ‘’ (ID 5) to time 2020-03-06 08:41:28.
Mar 06, 2020 08:41:34.544 [0x7f3ca8ab5740] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=100
Mar 06, 2020 08:41:34.547 [0x7f3ca8ab5740] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=100
Mar 06, 2020 08:41:34.547 [0x7f3ca8ab5740] DEBUG - Refreshing section 4 of type: 1
Mar 06, 2020 08:41:34.554 [0x7f3c9e4a2700] DEBUG - Refreshing 0 IDs.
This is what I don’t understand:
Skipping over directory ‘The Nun (2018)’, as nothing has changed; removing 0 media items from map.
Huh? Why? What?
Also:
Mar 06, 2020 08:41:33.929 [0x7f3ca8ab5740] DEBUG - It took 20.000000 ms to retrieve 602 items.
602? PLEX says, there is 586 items in the library, but yes, around 20 of files is not listed in the library