BUG - Synology after the update to 1.18 the cpu is always 99%

Plex Media Server NAS & Devices
1

Server Version#: 1.18.1.2019
LOG: Plex Media Server Logs_2019-11-13_07-44-47.zip (7,2 MB)

i use my synology nas with x86_64 cpu as plex server. until now everything worked fine. after the upgrade from 1.17 to 1.18 the cpu is always at 99% by the plex server. i hoped that the small updates will fix the problem. meanwhile i am at 1.18.1.2019 and the cpu is still at 99%.

after a restart the cpu is normal. but the moment i connect to a client the cpu goes to 99%. funnily enough the video stream works without jerking.

2

It looks like you are adding a significant amount of music files.

Music processing requires, at minimum, the first and last song of each delineated album to be audio fingerprinted for a correct album match.

If the first and last do not provide adequate match reliability, all songs will be fingerprinted.

This appears to be happening.

The C2538 is not a very fast CPU. It’s the same as in my DS1815+.

Nov 13, 2019 03:37:04.358 [0x7f61ee1c7700] INFO - Plex Media Server v1.18.1.2019-c186313fe - Synology DS1817+ x86_64 - build: linux-x86_64 synology - GMT 01:00
Nov 13, 2019 03:37:04.359 [0x7f61ee1c7700] INFO - Linux version: DSM 6.2.2.24922-4, language: en-US
Nov 13, 2019 03:37:04.359 [0x7f61ee1c7700] INFO - Processor         Intel(R) Atom(TM) CPU  C2538  @ 2.40GHz

On Synology, music files should be added in smaller groups . I find about 5000 or so at a time.

What you’re most likely experiencing, is that each file is being fingerprinted.

“[ Unbekanntes ]” (Unknown in German) and “[ Unknown ]” are indications of this.

Nov 13, 2019 03:37:06.102 [0x7f61d4e31700] DEBUG - HTTP requesting GET http://127.0.0.1:41738/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78317&id=78317
Nov 13, 2019 03:37:06.144 [0x7f61d7d11700] DEBUG - Request: [127.0.0.1:35610 (Loopback)] GET /:/plugins (10 live) GZIP Signed-in Token (c.rebler@protonmail.com)
Nov 13, 2019 03:37:06.146 [0x7f61ed60f700] DEBUG - Completed: [127.0.0.1:35610] 200 GET /:/plugins (10 live) GZIP 2ms 613 bytes
Nov 13, 2019 03:37:06.155 [0x7f61d4e31700] DEBUG - HTTP 200 response from GET http://127.0.0.1:41738/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78317&id=78317
Nov 13, 2019 03:37:06.163 [0x7f61d4e31700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 159 bytes of content.
Nov 13, 2019 03:37:06.174 [0x7f616b447700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78317&id=78317
Nov 13, 2019 03:37:06.175 [0x7f61ed8fd700] DEBUG - Completed: [127.0.0.1:35608] 200 GET /system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78317&id=78317 (10 live) GZIP 79ms 436 bytes
Nov 13, 2019 03:37:06.175 [0x7f616b447700] DEBUG - Refreshing child item '[Unknown Album]' with agent plex
Nov 13, 2019 03:37:06.177 [0x7f616b447700] DEBUG - Matching '[Unknown Album]' (ID 78450) with agent plex
Nov 13, 2019 03:37:06.216 [0x7f616b447700] DEBUG - It took 140.000000 ms to retrieve 220 items.
Nov 13, 2019 03:37:06.223 [0x7f616b447700] DEBUG - Issuing search query: http://127.0.0.1:32400/system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.224 [0x7f616b447700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.225 [0x7f61d7159700] DEBUG - Request: [127.0.0.1:35611 (Loopback)] GET /system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4 (10 live) GZIP Signed-in
Nov 13, 2019 03:37:06.226 [0x7f61d7159700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.226 [0x7f61d7159700] DEBUG - HTTP requesting GET http://127.0.0.1:41738/system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.238 [0x7f61d7159700] DEBUG - HTTP 200 response from GET http://127.0.0.1:41738/system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.238 [0x7f61d7159700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 899 bytes of content.
Nov 13, 2019 03:37:06.239 [0x7f61ed8fd700] DEBUG - Completed: [127.0.0.1:35611] 200 GET /system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4 (10 live) GZIP 13ms 712 bytes
Nov 13, 2019 03:37:06.239 [0x7f616b447700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/search?mediaType=9&id=78450&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FDjorjaymix%25202%252FJOYKIT~1%252EMP3&lang=en&openSubtitlesHash=8395a26d9132a55c&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&plexHash=35fadb21c82bffd2221ecc1fea014a40f53bc3c4
Nov 13, 2019 03:37:06.239 [0x7f616b447700] WARN - No matches for search.
Nov 13, 2019 03:37:06.239 [0x7f616b447700] DEBUG - Refreshing metadata for '[Unknown Album]' (agent: plex)
Nov 13, 2019 03:37:06.242 [0x7f616b447700] DEBUG - Refreshing GUID: 'local://78450'
Nov 13, 2019 03:37:06.242 [0x7f616b447700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450
Nov 13, 2019 03:37:06.244 [0x7f61d7d11700] DEBUG - Request: [127.0.0.1:35613 (Loopback)] GET /system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450 (10 live) GZIP Signed-in
Nov 13, 2019 03:37:06.244 [0x7f61d7d11700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450
Nov 13, 2019 03:37:06.244 [0x7f61d7d11700] DEBUG - HTTP requesting GET http://127.0.0.1:41738/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450
Nov 13, 2019 03:37:06.385 [0x7f616b735700] DEBUG - Request: [127.0.0.1:35615 (Loopback)] GET /:/plugins (10 live) GZIP Signed-in Token (c.rebler@protonmail.com)
Nov 13, 2019 03:37:06.387 [0x7f61ed60f700] DEBUG - Completed: [127.0.0.1:35615] 200 GET /:/plugins (10 live) GZIP 1ms 613 bytes
Nov 13, 2019 03:37:06.507 [0x7f61d7d11700] DEBUG - HTTP 200 response from GET http://127.0.0.1:41738/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450
Nov 13, 2019 03:37:06.507 [0x7f61d7d11700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 159 bytes of content.
Nov 13, 2019 03:37:06.508 [0x7f616b447700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450
Nov 13, 2019 03:37:06.508 [0x7f61ed60f700] DEBUG - Completed: [127.0.0.1:35613] 200 GET /system/agents/update?mediaType=9&force=1&respectTags=0&parentGUID=plex%3A%2F%2Fartist%2F5d07bbfc403c6402904a5ec9&parentID=9247&guid=local%3A%2F%2F78450&id=78450 (10 live) GZIP 264ms 437 bytes
Nov 13, 2019 03:37:06.508 [0x7f616b447700] DEBUG - Refreshing child item '[Unknown Album]' with agent plex
Nov 13, 2019 03:37:06.509 [0x7f616b447700] DEBUG - Matching '[Unknown Album]' (ID 78489) with agent plex
Nov 13, 2019 03:37:06.535 [0x7f616b447700] DEBUG - It took 70.000000 ms to retrieve 109 items.
Nov 13, 2019 03:37:06.539 [0x7f616b447700] DEBUG - Issuing search query: http://127.0.0.1:32400/system/agents/search?mediaType=9&id=78489&identifier=plex&duration=385044&filename=%252Fvolume1%252Fmusic%252F!!!%2520BEARBEITEN%2520!!!%252FMp3-4%252C31%2520GB%252FFav

You will notice it’s not matching. This return: (“local”)

Nov 13, 2019 03:37:06.242 [0x7f616b447700] DEBUG - Refreshing GUID: 'local://78450'

Confirms it can find no media source.

Without knowing how you curate your music, it appears to be rescanning the entire library from the beginning and attempting to match.

I believe you can either wait or start with add smaller pieces at a time.
PMS has no limit to how many sub folders can be added.

I recommend adding in blocks of about 5000 songs and allowing it to finish before adding another block of 5000 to the library.

3

thanks that surprises me now. i have had these music files inside for months. that means with the update to 1.18. something was triggered now. either this music was not scanned until now or it was deleted by the update until now everything and will be reassigned again

4

Remember, Engineering did finally release the upgraded music scanner which replaced Premium Music. It will go through your library and upgrade everything. It’s annoying but a necessary step. It’s also impossible to do music library upgrading piecemeal and have a usable library in the process.

5

ok thanks for this information and help.

then i will scan it

6

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