Very slow matching - it doesn't match in the window, but then it finds it after all?

I’ve just gotten around to converting a lot of my DVD’s to files for use with plex.
After converting it, and moving the files into the movie folder on the qnap, it detects that the file is there, but it doesn’t match it and doesn’t download metadata.
If I then asks it to match, it just does the rotating circle, and then after a long time, reports no matches found. But then after going to movie, it sometimes suddenly has a match anyways.

This is version: 1.13.8.5395

The match is one thing.

Fetching the metadata is another thing.
It is a separate process and can still go on after you have seen the message that everything has finished processing.
So just have a little more patience.

You can help Plex by organising and naming your files as described in the naming guidelines.
https://support.plex.tv/articles/categories/media-preparation/

Hmm, well I think 3-4 hours is too long. I’ve spent hours on renaming all movies to match the Plex way of doing things, so that’s in order.
After doing a match (it used to match it automatically), I can see it fetching metadata for most of them, but others doesn’t really.

Log files please?

The only way to know is by looking at what the agents are reporting back.

Ok, I hope these are ok, and can shed some light on it.

Plex Media Server Logs_2018-09-27_23-57-27.zip (2.9 MB)

I’ve noticed that sometimes it appears to not have matched, only to check as you suggested and see that it did in fact match.

How many files are you doing at a time? It’s taking me forever, but I’ve been adding about 7-10 at a time, enough to not completely fill the “Newly Added” field on the the home screen so that’s it’s easy to check that the matches worked, the “EXTRAS” I may have associated with them work, etc.

I find that the more I do in batches, the slower it gets. sometimes using the QBOOST on my QNAP TS-453Be helps, but I think it’s partially on the server side.

so far, it works, but it’s sloooooow going doing it this carefully.

I’ve done it for one at a time, while converting them.
Now I have a few that doesn’t match, and I’ve tried a few times, they are not even ‘obscure’ films, but
“The uninvited”, “The Town”, “The Other Guys” and “The A-Team”.
I’m on a TS-853A, and it hasn’t taken this long previously, it the first time I’ve added movies after the latest beta upgrade, so I’m wondering if something is failing in the latest update.

What is your CPU load / IO load?

PMS is overloaded and cannot complete lookups in time.

Sep 23, 2018 17:10:14.802 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:15:16.932 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:20:17.455 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:25:17.905 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:30:18.109 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:35:18.153 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 17:40:19.687 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.290000 seconds
Sep 23, 2018 17:50:19.972 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds
Sep 23, 2018 18:00:20.329 [0x7f003afff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.270000 seconds

It looks as if DVR/LiveTV is running and the box is attempting to transcode and getting very poor signal quaity

Sep 23, 2018 23:01:00.347 [0x7f0022bff700] ERROR - Unable to find title for item of type 5
Sep 23, 2018 23:01:01.201 [0x7f0031911700] ERROR - [Transcoder] [h264 @ 0x167f300] SPS unavailable in decode_picture_timing
Sep 23, 2018 23:01:01.203 [0x7f003ba23700] ERROR - [Transcoder] [h264 @ 0x167f300] non-existing PPS 0 referenced
Sep 23, 2018 23:01:01.206 [0x7f0031911700] ERROR - [Transcoder] [h264 @ 0x167f300] SPS unavailable in decode_picture_timing
Sep 23, 2018 23:01:01.208 [0x7f003ba23700] ERROR - [Transcoder] [h264 @ 0x167f300] non-existing PPS 0 referenced
Sep 23, 2018 23:01:01.210 [0x7f0031911700] ERROR - [Transcoder] [h264 @ 0x167f300] decode_slice_header error
Sep 23, 2018 23:01:01.212 [0x7f003ba23700] ERROR - [Transcoder] [h264 @ 0x167f300] no frame!

Plus, the database is fragmented as heck.

Did you add a ton of media at once?

tv%2Eplex%2Exmltv%3A%2F%2Fepisode%2FFormel%25201%253A%2520Singapores%2520Grand%2520Prix%2520-%2520Tr%25C3%25A6ning%25201%2F1%2F1
Sep 24, 2018 02:30:44.458 [0x7f001d37d700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 1.700000 seconds
Sep 24, 2018 02:48:42.992 [0x7f001e7ff700] WARN - SLOW QUERY: It took 2810.000000 ms to retrieve 0 items.
Sep 24, 2018 02:48:57.133 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:49:27.122 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:49:57.135 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:50:27.468 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:50:27.628 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:50:57.194 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:51:27.182 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:51:27.188 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream
Sep 24, 2018 02:51:57.195 [0x7f0029223700] ERROR - buildIndexFile: part has no video stream

Also,

Please enable DEBUG logging.

I am only seeing ERROR and INFO statements.

With DEBUG, it will get worse but I will be able to find out what is precipitating the failures.

Unfortunately I had to go to bed, it was 1.30 at night here, the CPU was probably loaded, it always is on the QNAP. Due to the previously reported playback problems (with DVB-C subtitles), I’ve tried setting the ‘live tv’ to transcode when recording, it haven’t helped.
It sounds very strange with the signal quality. If I go and use the HDHomerun app, and tune to different stations, it shows a signal streng that is at it lowest around 91%, but the signal and symbol quality is at a 100%, I’ve adjusted our signal amplifier a bit, I could see that at it’s lowest the strength was around 88%, now the lowest is at 94%, no change in quality.
I have added quite a bit of media, just now around 40 items, but I think that is at high as it goes.
This morning the Plex server was unreachable. I restarted the app but that didn’t change, so I’ve rebooted the entire NAS, and now it’s back online, and it can now match items, so I guess it was something gone wrong on the NAS somehow?

Ok, we will let the actual signal alone for the moment. If you’re running 90% - 100% and balanced, you’re good.

Regarding the NAS itself being slow, Do you have image file generation enabled (Advanced tab for each library section)? It is enabled by default. It will generate a huge amount of CPU load when the butler runs.

If you don’t need/want them, I do suggest disabling them. It will make life a lot easier on the CPU

I see, I’ve disabled them. I’m not completely sure what it does? Does it do a framegrab for showing in the media overview, or what? If so, I guess I should have it enabled on media like our own family recordings and stuff?

Yes, it does frame grabs at every chapter.

For your family videos, it makes perfect sense.
Movies usually come with chapter markers and the apps know this.

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