Jun 04, 2019 09:54:11.100 [0x7f99997fa700] DEBUG - Request: [127.0.0.1:57266 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=analyzing (7 live) GZIP Signed-in Token (X@gmail.com)
Jun 04, 2019 09:54:11.100 [0x7f9a2effd700] DEBUG - Completed: [127.0.0.1:57266] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=analyzing (7 live) GZIP 0ms 166 bytes
Jun 04, 2019 09:54:11.101 [0x7f999bfff700] DEBUG - Request: [127.0.0.1:57268 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=thumbnailing (7 live) GZIP Signed-in Token (X@gmail.com)
Jun 04, 2019 09:54:11.101 [0x7f9a2effd700] DEBUG - Completed: [127.0.0.1:57268] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=thumbnailing (7 live) GZIP 0ms 166 bytes
Jun 04, 2019 09:54:11.101 [0x7f99997fa700] DEBUG - Request: [127.0.0.1:57270 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=idle (7 live) GZIP Signed-in Token (X@gmail.com)
Jun 04, 2019 09:54:11.102 [0x7f9a2effd700] DEBUG - Completed: [127.0.0.1:57270] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=2551&metadataType=1&state=-1&mediaState=idle (7 live) GZIP 0ms 166 bytes
When doing a library scan for new media, it does 2 passes. The first pass, is to download the metadata and grab file information and what not, the second pass does the logs pasted above for each movie. These are my logs for each movie, with the metadataItemID changing each time.
It seems as though, it is attempting to grab information for the following settings with the second pass
However, I have all those settings disabled… surely plex is smart enough it would skip the second pass if I have those settings disabled? You can see they are all complete in 0ms, but it can still only process 10-15 files a minute, long delay between items. And there is no way chapters video previews are being generated in 0ms
There is no first pass, second pass. There are a bunch of things Plex does. Those settings are skipped, but they still get logged, hence the 0ms time, just so we can be sure it is still going through the logic correctly and not skipping things. These checks should not take up much time so I doubt this is causing any type of bottleneck if that is your concern.
Plex Media Scanner.log (955.7 KB)
Took 1.5 days doing just this sort of scan
Not sure what you are trying to say. The log you provided shows less than 1 minute of activity. That activity is not any of the things you turned off. This is the regular analysis the server does on every file. That analysis cannot be turned off. And yes, it can take a few seconds per file to do that analysis. However, you log indicates that PMS ran into issues reading your files while performing this analysis, so that can slow things down.
Plex Media Server.log (4.4 MB)
Sorry, meant to show server logs!
And what should I be looking for?
It is doing nothing but those 3 tasks on my movies recently imported movies , took 1.5 days to complete. So let me explain what happened -
I had 5k movies to import to Plex. Cool. They all showed up in plex with metadata downloaded after maybe 6 hours or so, not sure. Cool My logs were like
etc…
Processing Item B
Adding Item B
Processing Item A
Adding Item A
Then it after it did that for 5k movies. All 5k movies were in my plex library, had meta data from imdb, had file meta data(audio codecs, etc) just fine in plex it started like this
Processing Item A
Processing Item A
Processing Item A
Processing item B
Processing item B
Processing item B
And so forth, And my logs posted are 1 hour out of the 30+ hours it took to complete for my movies when doing the second part of the logging. When all the tasks it is trying to do are disabled. It seems plex checks if it should do the task after calling the task rather then skipping the task completely, which makes no sense…
Plex doesn’t account for the case where you disable all 3 items, so it does something like in the second part of the logging:
for movie in movies:
if analyze = true:
movie.analyze
if chapters = true:
movie.chapters
if thumbnails = true:
movie.thumbnails
It doesn’t check that you don’t want to do any of that. It loops through all the movies anyways
I guess you can call what Plex does as passes, but not really. It does all these analysis in parallel, but some like this media analysis I mentioned has a lower priority than the metadata collection. This way you can start using your media right away while this analysis can be performed in the background. This is all normal and for 5K movies, 1.5 days sounds about right considering the errors I saw in your earlier log.
Alright, thanks…
What is/are the error(s) you are referring to so I can try and figure out how to fix it?
PMS analyses your file and some of the errors indicate PMS couldn’t find the information it was looking for. It doesn’t mean the file is bad, just that PMS couldn’t find what it needed. It could just be the encoder you used did things differently than what PMS expected. Unless you actually run into issues watching these files, I wouldn’t waste the time trying to track these down just to get a faster analysis.
