Issue upgrade from 1.22 - Crashing & Segfaults

foulou · July 28, 2021, 4:37am

Server Version#: 1.22.3.4523 on docker (linuxserver/plex)
Player Version#: any/latest/web/pc

Hi,

i’m having issue trying to use any version of plex that is more recent than 1.22.3.4523
I’m currently locked to use linuxserver/plex:bionic-1.22.3.4523-d0ce30438-ls30
If i try to update to latest from linuxserver/plex (which is focal based), i get some errors about Segfaults like this one every few minutes, sometimes multiple times per minutes, so plex can’t be used (i also tried some other containers, but they all seem to use focal base):

Jul 28 03:33:52 s165879 kernel: [234479.582577] Plex Media Serv[1606879]: segfault at 7fc244e2a030 ip 00007fc247da5f80 sp 00007fc23e6582a0 error 4 in ld-musl-x86_64.so.1[7fc247d5c000+53000]
Jul 28 03:33:52 s165879 kernel: [234479.582583] Code: 0f b6 49 06 48 c1 e1 08 48 09 c1 41 0f b6 41 07 48 09 c8 eb 02 48 98 48 8b 6c 24 28 45 85 e4 74 3a 41 0f b6 4a ff 48 8d 0c 49 <0f> b6 54 4d 00 c1 e2 18 0f b6 74 4d 01 48 c1 e6 10 48 63 d2 48 09

This mostly happens when i try to navigate to my movie collection (but it can happens while doing nothing, or transcoding too)

I should add that the plex server reports that it is unable to backup its database when the job runs every 3 days. Which is weird because in the folder, there is backups (i think it’s mostly related to size (or well i hope)

 4.3G Jul 28 03:30 com.plexapp.plugins.library.blobs.db
 4.2G Jul 17 13:03 com.plexapp.plugins.library.blobs.db-2021-07-17
 4.2G Jul 20 13:06 com.plexapp.plugins.library.blobs.db-2021-07-20
 4.2G Jul 23 13:04 com.plexapp.plugins.library.blobs.db-2021-07-23
 4.3G Jul 26 13:03 com.plexapp.plugins.library.blobs.db-2021-07-26
  32K Jul 28 03:36 com.plexapp.plugins.library.blobs.db-shm
 2.1K Jul 28 03:34 com.plexapp.plugins.library.blobs.db-wal
 1.3G Jul 28 04:27 com.plexapp.plugins.library.db
 968M Mar 19 14:02 com.plexapp.plugins.library.db-2021-03-19
 995M Mar 22 14:51 com.plexapp.plugins.library.db-2021-03-22
1015M Mar 25 14:03 com.plexapp.plugins.library.db-2021-03-25
1002M Mar 28 14:03 com.plexapp.plugins.library.db-2021-03-28
  32K Jul 28 04:32 com.plexapp.plugins.library.db-shm
 1.1M Jul 28 04:32 com.plexapp.plugins.library.db-wal
 7.2M Feb 22 03:04 com.plexapp.plugins.trakttv.db
  32K Feb 22 03:15 com.plexapp.plugins.trakttv.db-shm
 4.0M Feb 22 03:15 com.plexapp.plugins.trakttv.db-wal

I don’t really know what to do, my host is a ubuntu Focal linux server :

# lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description:    Ubuntu 20.04.2 LTS
Release:        20.04
Codename:       focal

# cpuinfo
python version: 3.8.5.final.0 (64 bit)
cpuinfo version: (4, 0, 0)
Vendor ID: GenuineIntel
Hardware Raw:
Brand: Intel(R) Core(TM) i7-8700K CPU @ 3.70GHz

I join a backup of the logs from plex too, hoping it could help pinpoint my issue.

There seems to be complains about livetv, which i don’t use anymore, but can’t remove traces of it, because xteve homerun is not present in plex anymore
It seems to complain about sync lists, but i can’t remove these lists too.

Thanks whoever can help about this !
Plex Media Server Logs_2021-07-27_23-23-38.zip (331.4 KB)

scrathe · August 4, 2021, 9:10pm

I am also seeing a similar error to yours @foulou

[Wed Aug  4 10:44:45 2021] Plex Media Scan[14838]: segfault at 155477c70030 ip 000015547aad5f80 sp 00007ffcb4f66190 error 4 in ld-musl-x86_64.so.1[15547aa8c000+53000]
[Wed Aug  4 10:44:45 2021] Code: 0f b6 49 06 48 c1 e1 08 48 09 c1 41 0f b6 41 07 48 09 c8 eb 02 48 98 48 8b 6c 24 28 45 85 e4 74 3a 41 0f b6 4a ff 48 8d 0c 49 <0f> b6 54 4d 00 c1 e2 18 0f b6 74 4d 01 48 c1 e6 10 48 63 d2 48 09

Server Version#: 1.24.0.4897 on docker (linuxserver/plex)
Player Version#: any/latest/web/pc
Host OS# unRAID 6.9.2 (Linux 5.10.28-Unraid x86_64)

Not sure if it’s related, but my logs are filled with of DB errors (below). Tried this (link) but errors persist; Repair a Corrupt Database 1.22.0 and earlier | Plex Support

Aug 04, 2021 16:07:08.998	WARN	Took too long (0.140000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.998	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.912	WARN	Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.410000 seconds
Aug 04, 2021 16:07:08.912	WARN	Took too long (0.290000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.912	WARN	Transaction that was running was started on thread 0x14957392cb38 at ../Statistics/StatisticsManager.cpp:249
Aug 04, 2021 16:07:08.696	WARN	Took too long (0.120000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.696	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.649	WARN	Took too long (0.130000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.649	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.302	WARN	Took too long (0.230000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.302	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.196	WARN	Took too long (0.330000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.196	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.085	WARN	Took too long (0.120000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:08.085	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:07.468	WARN	SLOW QUERY: It took 280.000000 ms to retrieve 1 items.
Aug 04, 2021 16:07:07.420	WARN	SLOW QUERY: It took 330.000000 ms to retrieve 1 items.
Aug 04, 2021 16:07:07.346	WARN	Took too long (0.120000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:07.346	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:07.124	WARN	SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 04, 2021 16:07:07.106	WARN	Took too long (0.110000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:07.106	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.998	WARN	Took too long (0.110000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.998	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.945	WARN	Took too long (0.230000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.945	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.729	WARN	Took too long (0.110000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.729	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.667	WARN	Took too long (0.110000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.667	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.459	WARN	Took too long (0.110000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.459	WARN	Transaction that was running was started on thread 0x14957650cb38 at ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:07:06.345	ERROR	Thread: Uncaught exception running async task which was spawned by thread 0x1495758ffb38: sqlite3_statement_backend::loadOne: database disk image is malformed
Aug 04, 2021 16:07:06.341	ERROR	[Database optimization/com.plexapp.plugins.library.db] Exception inside transaction (inside=1) (../Library/FullTextSearch.cpp:90): sqlite3_statement_backend::loadOne: database disk image is malformed
Aug 04, 2021 16:07:06.337	ERROR	[Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 11, database corruption at line 88814 of [1b256d97b5]
Aug 04, 2021 16:07:06.337	ERROR	[Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 11, statement aborts at 8: [DELETE FROM "main"."spellfix_tag_titles_vocab" WHERE id=50239] database disk image is malformed
Aug 04, 2021 16:07:06.337	ERROR	[Database optimization/com.plexapp.plugins.library.db] SQLITE3:0x80000001, 11, statement aborts at 10: [delete from spellfix_tag_titles] database disk image is malformed
Aug 04, 2021 16:07:06.089	WARN	[Database optimization/com.plexapp.plugins.library.db] Held transaction for too long (../Library/FullTextSearch.cpp:80): 4.670000 seconds
Aug 04, 2021 16:07:01.390	WARN	[Database optimization/com.plexapp.plugins.library.db] Held transaction for too long (../Library/FullTextSearch.cpp:69): 1.450000 seconds
Aug 04, 2021 16:06:59.804	WARN	[Database optimization/com.plexapp.plugins.library.db] Held transaction for too long (../Library/FullTextSearch.cpp:59): 6.950000 seconds
Aug 04, 2021 16:06:44.815	WARN	Took too long (0.250000 seconds) to start a transaction on ../Library/MetadataItem.cpp:1121
Aug 04, 2021 16:06:44.815	WARN	Transaction that was running was started on thread 0x1495746eab38 at ../Library/DatabaseFixups.cpp:202
Aug 04, 2021 16:06:44.813	WARN	[Database optimization/com.plexapp.plugins.library.db] Held transaction for too long (../Library/DatabaseFixups.cpp:213): 0.270000 seconds
Aug 04, 2021 16:06:41.258	WARN	QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 04, 2021 16:06:41.258	WARN	QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Aug 04, 2021 16:06:41.258	WARN	QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 04, 2021 16:06:41.256	WARN	QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 04, 2021 16:06:41.256	WARN	QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Aug 04, 2021 16:06:41.256	WARN	QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.

foulou · August 4, 2021, 11:50pm

Yes, Unfortunately, i’ve also done the database repair multiple times, and every 3 days, when the scheduled task run, it always says that it’s can’t backup, and it’s corrupted… what can be done about it ? I’m out of solutions

i’ve also regenerated all my metadata, rescanned everything, because i see it’s really when i go into my Movie library that Plex crash, but the crash reports are empty…

ChuckPa · August 5, 2021, 12:18am

Are any of you storing the container on a network share / volume ?

If so – that’s why. Network sharing protocols don’t support the file locking needed for the DB. It will keep corrupting itself.

Also – turn DEBUG logging back on. Not saving any disk space (size is fixed) but does make it possible to see what it was trying to do

foulou · August 5, 2021, 2:34am

Hi,

no for my part the container volumes are on a raid0 of 2 x 450Gb SSD

i’ll check to turn on debug mode

foulou · August 12, 2021, 12:24am

@ChuckPa
I alway see this when i start server, i don’t know if it’s related, it search for items to and sync lists, that doesn’t exists anymore
Also further in the log i see it search for xteve hdhomerun i added then removed at some point like 2 years ago, is there any way to cleanup DB of sync list issues and DVB ?
Thanks

Aug 07, 2021 21:30:25.162 [0x7fbcbaa38700] INFO - Plex Media Server v1.22.3.4523-d0ce30438 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 debian - GMT -05:00
Aug 07, 2021 21:30:25.163 [0x7fbcbaa38700] INFO - Linux version: 5.4.0-80-generic, language: C
Aug 07, 2021 21:30:25.163 [0x7fbcbaa38700] INFO - Processor Intel(R) Core(TM) i7-8700K CPU @ 3.70GHz
Aug 07, 2021 21:30:25.163 [0x7fbcbaa38700] INFO - /usr/lib/plexmediaserver/Plex Media Server
Aug 07, 2021 21:30:33.514 [0x7fbc82ffd700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 07, 2021 21:31:24.767 [0x7fbc64ff9700] WARN - Sync: sync item 47334338 in sync list 51650930 belonging to user 50310641 (xxxxx@msn.com) is not authorized to sync
Aug 07, 2021 21:31:24.772 [0x7fbc64ff9700] WARN - Sync: sync item 47674140 in sync list 57077712 belonging to user 69757860 (xxxxxxxxxxxxxx@hotmail.fr) is not authorized to sync
Aug 07, 2021 21:31:24.981 [0x7fbc64ff9700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 0.230000 seconds
Aug 07, 2021 21:31:25.780 [0x7fbc64ff9700] WARN - Sync: sync item 48213826 in sync list 60258268 belonging to user 79021820 (xxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 21:31:25.780 [0x7fbc64ff9700] WARN - Sync: sync item 48403669 in sync list 51132866 belonging to user 23062689 (xxxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 21:32:50.074 [0x7fbc417fa700] ERROR - Error issuing curl_easy_perform(handle): 28
Aug 07, 2021 21:34:27.770 [0x7fbc40ff9700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:34:27.835 [0x7fbc9cde0700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:34:27.955 [0x7fbc40ff9700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 415
Aug 07, 2021 21:35:30.024 [0x7fbc25ffb700] WARN - Held transaction for too long (../Library/MetadataCollection.cpp:497): 0.170000 seconds
Aug 07, 2021 21:40:08.342 [0x7fbc437fe700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 07, 2021 21:40:16.317 [0x7fbbdeffd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:41:15.939 [0x7fbc81ebb700] WARN - Sync: sync item 47334338 in sync list 51650930 belonging to user 50310641 (xxxxxxxxxxxxxxx@msn.com) is not authorized to sync
Aug 07, 2021 21:41:15.950 [0x7fbc81ebb700] WARN - Sync: sync item 47674140 in sync list 57077712 belonging to user 69757860 (xxxxxxxxxxxxx@hotmail.fr) is not authorized to sync
Aug 07, 2021 21:41:16.148 [0x7fbc81ebb700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 0.200000 seconds
Aug 07, 2021 21:41:16.899 [0x7fbc81ebb700] WARN - Sync: sync item 48213826 in sync list 60258268 belonging to user 79021820 (xxxxxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 21:41:16.899 [0x7fbc81ebb700] WARN - Sync: sync item 48403669 in sync list 51132866 belonging to user 23062689 (xxxxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 21:41:19.787 [0x7fbc667fc700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:41:22.093 [0x7fbc816ba700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:41:22.093 [0x7fbc816ba700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.106 [0x7fbbdd7fa700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:41:22.106 [0x7fbbdd7fa700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.603 [0x7fbc437fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.624 [0x7fbc816ba700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:41:22.624 [0x7fbc816ba700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.846 [0x7fbc82ffd700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.846 [0x7fbc82ffd700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.849 [0x7fbc82ffd700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:22.849 [0x7fbc82ffd700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:23.017 [0x7fbc64ff9700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:41:23.018 [0x7fbc64ff9700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 60258268, sync item 48213826 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F762465
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 57077712, sync item 47674140 has an empty guid: library:///directory/%2Flibrary%2Fmetadata%2F380636%2Fchildren%3FexcludeAllLeaves%3D1
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 55477512 belonging to user 50310641 (xxxxxxxxxxx@msn.com) has died of old age, ignoring
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 51650930, sync item 47334338 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F22864
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 51132866, sync item 48403669 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F72449
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 27195464 belonging to user 17229242 ([ xxxxxxx ]) has died of old age, ignoring
Aug 07, 2021 21:41:27.223 [0x7fbc257fa700] WARN - Sync: sync list 57887984 belonging to user 17229242 ([ xxxxxxxxxx ]) has died of old age, ignoring
Aug 07, 2021 21:41:27.255 [0x7fbc257fa700] INFO - Sync: downloaded 8 sync list(s) with 15 sync items(s): 0 new, 0 updated, 0 deleted
Aug 07, 2021 21:41:30.195 [0x7fbc667fc700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:41:30.246 [0x7fbc66ffd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 415
Aug 07, 2021 21:45:16.899 [0x7fbbdeffd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:49:06.803 [0x7fbc816ba700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:49:06.803 [0x7fbc816ba700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:06.830 [0x7fbc81ebb700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:06.830 [0x7fbc81ebb700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:06.832 [0x7fbc81ebb700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:06.832 [0x7fbc81ebb700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:07.393 [0x7fbc827fc700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:49:07.393 [0x7fbc827fc700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:07.477 [0x7fbc437fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:07.658 [0x7fbc02ffd700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:49:07.659 [0x7fbc02ffd700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:07.679 [0x7fbc66ffd700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:49:07.680 [0x7fbc66ffd700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 60258268, sync item 48213826 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F762465
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 57077712, sync item 47674140 has an empty guid: library:///directory/%2Flibrary%2Fmetadata%2F380636%2Fchildren%3FexcludeAllLeaves%3D1
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 55477512 belonging to user 50310641 (xxxxxxxxx@msn.com) has died of old age, ignoring
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 51650930, sync item 47334338 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F22864
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 51132866, sync item 48403669 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F72449
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 27195464 belonging to user 17229242 ([ xxx ]) has died of old age, ignoring
Aug 07, 2021 21:49:12.634 [0x7fbc81ebb700] WARN - Sync: sync list 57887984 belonging to user 17229242 ([ xxxxxxx ]) has died of old age, ignoring
Aug 07, 2021 21:49:12.648 [0x7fbc81ebb700] INFO - Sync: downloaded 8 sync list(s) with 15 sync items(s): 0 new, 0 updated, 0 deleted
Aug 07, 2021 21:51:41.506 [0x7fbc01ffb700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:56:50.605 [0x7fbbdd7fa700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:50.605 [0x7fbbdd7fa700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:50.610 [0x7fbbdd7fa700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:50.610 [0x7fbbdd7fa700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:50.619 [0x7fbc827fc700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:56:50.620 [0x7fbc827fc700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:50.866 [0x7fbc277fe700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:56:50.866 [0x7fbc277fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:51.143 [0x7fbc9e5e3700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:56:51.143 [0x7fbc9e5e3700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:51.185 [0x7fbc816ba700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:51.506 [0x7fbc677fe700] ERROR - Unknown metadata type: folder
Aug 07, 2021 21:56:51.507 [0x7fbc677fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 60258268, sync item 48213826 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F762465
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 57077712, sync item 47674140 has an empty guid: library:///directory/%2Flibrary%2Fmetadata%2F380636%2Fchildren%3FexcludeAllLeaves%3D1
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 55477512 belonging to user 50310641 (rockeur_robin@msn.com) has died of old age, ignoring
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 51650930, sync item 47334338 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F22864
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 51132866, sync item 48403669 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F72449
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 27195464 belonging to user 17229242 ([ xxxxxxx ]) has died of old age, ignoring
Aug 07, 2021 21:56:55.108 [0x7fbc827fc700] WARN - Sync: sync list 57887984 belonging to user 17229242 ([ xxxxxxx ]) has died of old age, ignoring
Aug 07, 2021 21:56:55.122 [0x7fbc827fc700] INFO - Sync: downloaded 8 sync list(s) with 15 sync items(s): 0 new, 0 updated, 0 deleted
Aug 07, 2021 21:59:07.505 [0x7fbc816ba700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:59:07.631 [0x7fbc24ff9700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 21:59:07.712 [0x7fbc816ba700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 415
Aug 07, 2021 22:00:03.483 [0x7fbc667fc700] WARN - SLOW QUERY: It took 500.000000 ms to retrieve 50 items.
Aug 07, 2021 22:00:32.839 [0x7fbc64ff9700] WARN - SLOW QUERY: It took 320.000000 ms to retrieve 1 items.
Aug 07, 2021 22:01:07.417 [0x7fbbdeffd700] WARN - Held transaction for too long (../Library/MetadataCollection.cpp:497): 0.150000 seconds
Aug 07, 2021 22:01:44.754 [0x7fbb98ff9700] WARN - NAT: PMP, got an error: Not Supported by gateway.
Aug 07, 2021 22:03:27.244 [0x7fbc03fff700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 07, 2021 22:03:31.404 [0x7fbbcb356700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 89 items.
Aug 07, 2021 22:03:35.567 [0x7fbc277fe700] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 51 items.
Aug 07, 2021 22:06:24.812 [0x7fbbc1ffb700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 70 items.
Aug 07, 2021 22:06:28.978 [0x7fbbbeff5700] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 70 items.
Aug 07, 2021 22:06:29.206 [0x7fbbdeffd700] WARN - Held transaction for too long (../Library/MetadataItem.cpp:3802): 0.750000 seconds
Aug 07, 2021 22:06:31.376 [0x7fbbbf7f6700] WARN - Waited one whole second for a busy database.
Aug 07, 2021 22:06:31.801 [0x7fbbdeffd700] WARN - Held transaction for too long (../Library/MetadataItem.cpp:3802): 0.360000 seconds
Aug 07, 2021 22:06:43.005 [0x7fbbbcff1700] WARN - Held transaction for too long (../Library/MetadataCollection.cpp:497): 0.330000 seconds
Aug 07, 2021 22:06:43.303 [0x7fbb9affd700] WARN - MDE: unable to find a working transcode profile for video stream
Aug 07, 2021 22:06:43.303 [0x7fbb9affd700] WARN - MDE: unable to find a working transcode profile for video stream
Aug 07, 2021 22:07:18.779 [0x7fbb9affd700] WARN - MDE: unable to find a working transcode profile for video stream
Aug 07, 2021 22:07:18.779 [0x7fbb9affd700] WARN - MDE: unable to find a working transcode profile for video streamx
Aug 07, 2021 22:07:42.803 [0x7fbb9bfff700] WARN - Sync: sync item 47334338 in sync list 51650930 belonging to user 50310641 (xxxxxxxxxxx@msn.com) is not authorized to sync
Aug 07, 2021 22:07:42.818 [0x7fbb9bfff700] WARN - Sync: sync item 47674140 in sync list 57077712 belonging to user 69757860 (xxxxxxxxxxx@hotmail.fr) is not authorized to sync
Aug 07, 2021 22:07:42.985 [0x7fbb9bfff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 0.180000 seconds
Aug 07, 2021 22:07:43.683 [0x7fbb9bfff700] WARN - Sync: sync item 48213826 in sync list 60258268 belonging to user 79021820 (xxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 22:07:43.684 [0x7fbb9bfff700] WARN - Sync: sync item 48403669 in sync list 51132866 belonging to user 23062689 (xxxxxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 22:08:54.758 [0x7fbbc37fe700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:54.758 [0x7fbbc37fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:54.761 [0x7fbbc37fe700] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:54.761 [0x7fbbc37fe700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:54.835 [0x7fbc25ffb700] ERROR - Unknown metadata type: folder
Aug 07, 2021 22:08:54.835 [0x7fbc25ffb700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:54.870 [0x7fbc257fa700] ERROR - Unknown metadata type: folder
Aug 07, 2021 22:08:54.870 [0x7fbc257fa700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:55.389 [0x7fbc67fff700] ERROR - Unknown metadata type: folder
Aug 07, 2021 22:08:55.389 [0x7fbc67fff700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:55.759 [0x7fbc02ffd700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:55.839 [0x7fbc827fc700] ERROR - Unknown metadata type: folder
Aug 07, 2021 22:08:55.839 [0x7fbc827fc700] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 60258268, sync item 48213826 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F762465
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 57077712, sync item 47674140 has an empty guid: library:///directory/%2Flibrary%2Fmetadata%2F380636%2Fchildren%3FexcludeAllLeaves%3D1
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 55477512 belonging to user 50310641 (xxxxxxxxx@msn.com) has died of old age, ignoring
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 51650930, sync item 47334338 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F22864
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 51132866, sync item 48403669 has an empty guid: library:///item/%2Flibrary%2Fmetadata%2F72449
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 27195464 belonging to user 17229242 ([ Fleur ]) has died of old age, ignoring
Aug 07, 2021 22:08:59.240 [0x7fbbcbb57700] WARN - Sync: sync list 57887984 belonging to user 17229242 ([ Fleur ]) has died of old age, ignoring
Aug 07, 2021 22:08:59.287 [0x7fbbcbb57700] INFO - Sync: downloaded 8 sync list(s) with 15 sync items(s): 0 new, 0 updated, 0 deleted
Aug 07, 2021 22:09:03.040 [0x7fbbc2ffd700] WARN - SLOW QUERY: It took 230.000000 ms to retrieve 66 items.
Aug 07, 2021 22:09:03.044 [0x7fbbc37fe700] WARN - SLOW QUERY: It took 320.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:03.044 [0x7fbbc3fff700] WARN - SLOW QUERY: It took 340.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:07.176 [0x7fbc67fff700] WARN - SLOW QUERY: It took 230.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:07.190 [0x7fbc667fc700] WARN - SLOW QUERY: It took 330.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:11.323 [0x7fbbcb356700] WARN - SLOW QUERY: It took 230.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:11.326 [0x7fbc667fc700] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:15.467 [0x7fbbc27fc700] WARN - SLOW QUERY: It took 230.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:15.472 [0x7fbbc9352700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 64 items.
Aug 07, 2021 22:09:23.784 [0x7fbbc9352700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:23.790 [0x7fbc417fa700] WARN - SLOW QUERY: It took 240.000000 ms to retrieve 71 items.
Aug 07, 2021 22:09:32.013 [0x7fbc26ffd700] WARN - Held transaction for too long (../Statistics/StatisticsManager.cpp:249): 0.170000 seconds
Aug 07, 2021 22:09:44.384 [0x7fbc667fc700] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 1 items.
Aug 07, 2021 22:09:44.397 [0x7fbc03fff700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 78 items.
Aug 07, 2021 22:09:44.397 [0x7fbbcbb57700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 83 items.
Aug 07, 2021 22:09:44.404 [0x7fbbbd7f2700] WARN - SLOW QUERY: It took 280.000000 ms to retrieve 94 items.
Aug 07, 2021 22:10:34.931 [0x7fbbc1ffb700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 22:11:12.345 [0x7fbc03fff700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 07, 2021 22:11:12.345 [0x7fbc816ba700] WARN - SLOW QUERY: It took 210.000000 ms to retrieve 1 items.
Aug 07, 2021 22:11:12.346 [0x7fbc027fc700] WARN - SLOW QUERY: It took 220.000000 ms to retrieve 1 items.
Aug 07, 2021 22:11:12.347 [0x7fbbc9b53700] WARN - SLOW QUERY: It took 240.000000 ms to retrieve 1 items.
Aug 07, 2021 22:11:12.349 [0x7fbbc37fe700] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 1 items.
Aug 07, 2021 22:11:34.249 [0x7fbb9bfff700] WARN - Sync: sync item 47334338 in sync list 51650930 belonging to user 50310641 (xxxxxxxxx@msn.com) is not authorized to sync
Aug 07, 2021 22:11:34.253 [0x7fbb9bfff700] WARN - Sync: sync item 47674140 in sync list 57077712 belonging to user 69757860 (xxxxxxxxx@hotmail.fr) is not authorized to sync
Aug 07, 2021 22:11:34.416 [0x7fbb9bfff700] WARN - Held transaction for too long (../Sync/SyncItemGenerator.cpp:153): 0.170000 seconds
Aug 07, 2021 22:11:35.045 [0x7fbb9bfff700] WARN - Sync: sync item 48213826 in sync list 60258268 belonging to user 79021820 (xxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 22:11:35.045 [0x7fbb9bfff700] WARN - Sync: sync item 48403669 in sync list 51132866 belonging to user 23062689 (xxxxxxxxxxxxx@gmail.com) is not authorized to sync
Aug 07, 2021 22:11:41.953 [0x7fbb9affd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 22:11:52.724 [0x7fbb9affd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 22:11:52.798 [0x7fbc24ff9700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 415
Aug 07, 2021 22:13:15.765 [0x7fbc24ff9700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424
Aug 07, 2021 22:13:18.913 [0x7fbb9affd700] WARN - Webhook: Error delivering payload to https://scrobblers.trakt.tv/plex/6xxxxxxxxxxxxxx/1d08xxxxxxxxxxx115d9: 424

ChuckPa · August 12, 2021, 12:39am

WARN or ERROR, without DEBUG is not helpful.

Excerpts are not helpful.

Settings - Server - General - Show Advanced
Check the DEBUG logging box
Click SAVE
Now restart

(DEBUG logging is enabled by default. Logs are fixed size so there is no danger of using too much disk space)

scrathe · August 13, 2021, 8:24pm

thanks @ChuckPa for your suggestions on additional logging methods. and to answer your question about storage, my setup is using unRAID and docker volume mappings. media lives on array/HDD, plex app/metadata/etc lives on cache/SSD. no network mappings involved.

here’s a dump of relevant logs associated with the segfaults i’m seeing. look for “unhandled exception” in Plex Media Scanner logs.

docker

****** PLEX MEDIA SCANNER CRASHED, CRASH REPORT WRITTEN: /config/Library/Application Support/Plex Media Server/Crash Reports/1.24.0.4930-ab6e1a058/PLEX MEDIA SCANNER/716af4d1-d494-444c-2be46a92-120dfd77.dmp
Got nothing for: Introduction
****** PLEX MEDIA SCANNER CRASHED, CRASH REPORT WRITTEN: /config/Library/Application Support/Plex Media Server/Crash Reports/1.24.0.4930-ab6e1a058/PLEX MEDIA SCANNER/91ff5e3c-67eb-43b1-383e1ba0-00b01f63.dmp
****** PLEX MEDIA SCANNER CRASHED, CRASH REPORT WRITTEN: /config/Library/Application Support/Plex Media Server/Crash Reports/1.24.0.4930-ab6e1a058/PLEX MEDIA SCANNER/fd09aeba-8926-489f-fcbda993-9736971e.dmp
****** PLEX MEDIA SCANNER CRASHED, CRASH REPORT WRITTEN: /config/Library/Application Support/Plex Media Server/Crash Reports/1.24.0.4930-ab6e1a058/PLEX MEDIA SCANNER/e90a8b15-7903-4b0d-140a6298-fb764a26.dmp
Got nothing for: Series Channel Seven Wonders of Ancient Egypt

linux dmesg

[Fri Aug 13 14:39:39 2021] Plex Media Scan[7467]: segfault at 1540ff181030 ip 0000154103545f80 sp 00007ffefd2da1a0 error 4 in ld-musl-x86_64.so.1[1541034fc000+53000]
[Fri Aug 13 14:39:39 2021] Code: 0f b6 49 06 48 c1 e1 08 48 09 c1 41 0f b6 41 07 48 09 c8 eb 02 48 98 48 8b 6c 24 28 45 85 e4 74 3a 41 0f
b6 4a ff 48 8d 0c 49 <0f> b6 54 4d 00 c1 e2 18 0f b6 74 4d 01 48 c1 e6 10 48 63 d2 48 09
[Fri Aug 13 14:41:56 2021] Plex Media Scan[12327]: segfault at 14fa80c85030 ip 000014fa83aeaf80 sp 00007ffe68bb3020 error 4 in ld-musl-x86_64.so.1[14fa83aa1000+53000]
[Fri Aug 13 14:41:56 2021] Code: 0f b6 49 06 48 c1 e1 08 48 09 c1 41 0f b6 41 07 48 09 c8 eb 02 48 98 48 8b 6c 24 28 45 85 e4 74 3a 41 0f
b6 4a ff 48 8d 0c 49 <0f> b6 54 4d 00 c1 e2 18 0f b6 74 4d 01 48 c1 e6 10 48 63 d2 48 09
[Fri Aug 13 14:43:02 2021] Plex Media Scan[14619]: segfault at 145d47d30030 ip 0000145d4ab95f80 sp 00007ffd56795060 error 4 in ld-musl-x86_64.so.1[145d4ab4c000+53000]
[Fri Aug 13 14:43:02 2021] Code: 0f b6 49 06 48 c1 e1 08 48 09 c1 41 0f b6 41 07 48 09 c8 eb 02 48 98 48 8b 6c 24 28 45 85 e4 74 3a 41 0f
b6 4a ff 48 8d 0c 49 <0f> b6 54 4d 00 c1 e2 18 0f b6 74 4d 01 48 c1 e6 10 48 63 d2 48 09

Plex Media Scanner.log

Aug 13, 2021 14:40:48.657 [0x152e7f418b38] INFO - Plex Media Scanner v1.24.0.4930-ab6e1a058 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 - GMT -05:00
Aug 13, 2021 14:40:48.657 [0x152e7f418b38] INFO - Linux version: 5.10.28-Unraid, language: en-US
Aug 13, 2021 14:40:48.657 [0x152e7f418b38] INFO - Processor AMD Ryzen 5 5600X 6-Core Processor             
Aug 13, 2021 14:40:48.657 [0x152e7f418b38] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --scan --refresh --section 2 --activity 875d0154-3601-400e-9641-3162193ab3bb --directory /media/TV/House Hunters (1999)/Season 185
Aug 13, 2021 14:40:48.660 [0x152e82269848] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Aug 13, 2021 14:40:48.728 [0x152e82269848] DEBUG - It took 0.000000 ms to retrieve 902 items.
Aug 13, 2021 14:40:48.887 [0x152e82269848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:48.887 [0x152e7d3eeb38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882448
Aug 13, 2021 14:40:48.888 [0x152e7d3eeb38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882448
Aug 13, 2021 14:40:48.888 [0x152e82269848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:48.888 [0x152e82269848] DEBUG - Scanning TV Shows using en(Plex Series Scanner) with 14 current media items and 1 section locations in the database.
Aug 13, 2021 14:40:48.943 [0x152e82269848] DEBUG - Performing a scan with 'Plex Series Scanner' (language: en virtual: 0).
Aug 13, 2021 14:40:48.943 [0x152e82269848] DEBUG -   * Scanning /media/TV/House Hunters (1999)/Season 185
Aug 13, 2021 14:40:48.944 [0x152e82269848] DEBUG - Scanner: Processing directory /media/TV/House Hunters (1999)/Season 185 (parent: yes)
Aug 13, 2021 14:40:48.964 [0x152e82269848] DEBUG - Skipping over directory 'House Hunters (1999)/Season 185', as nothing has changed; removing 14 media items from map.
Aug 13, 2021 14:40:48.964 [0x152e7d3eeb38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20185
Aug 13, 2021 14:40:48.964 [0x152e82269848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20185
Aug 13, 2021 14:40:48.964 [0x152e7d3eeb38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20185 (reused)
Aug 13, 2021 14:40:48.965 [0x152e82269848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20185 (reused)
Aug 13, 2021 14:40:48.965 [0x152e82269848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Aug 13, 2021 14:40:48.966 [0x152e82269848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000 (reused)
Aug 13, 2021 14:40:48.966 [0x152e82269848] DEBUG - Removing 0 media items that were left.
Aug 13, 2021 14:40:48.966 [0x152e82269848] DEBUG - Removing 0 directories that were left.
Aug 13, 2021 14:40:49.075 [0x152e82269848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=100
Aug 13, 2021 14:40:49.075 [0x152e82269848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=100 (reused)
Aug 13, 2021 14:40:49.075 [0x152e82269848] DEBUG - Refreshing section 2 of type: 2
Aug 13, 2021 14:40:49.387 [0x152e82269848] DEBUG - The show 'The Late Show With Stephen Colbert' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:49.600 [0x152e82269848] DEBUG - The show 'The Tonight Show Starring Jimmy Fallon' has unmatched leaves, and show was matched, so we'll refresh.

Plex Media Scanner.1.log

Aug 13, 2021 14:40:47.235 [0x14fcd362db38] INFO - Plex Media Scanner v1.24.0.4930-ab6e1a058 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 - GMT -05:00
Aug 13, 2021 14:40:47.235 [0x14fcd362db38] INFO - Linux version: 5.10.28-Unraid, language: en-US
Aug 13, 2021 14:40:47.235 [0x14fcd362db38] INFO - Processor AMD Ryzen 5 5600X 6-Core Processor             
Aug 13, 2021 14:40:47.235 [0x14fcd362db38] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --scan --refresh --section 2 --activity 7e3f5cf4-938d-4e35-9111-a7cad26e1f38 --directory /media/TV/House Hunters (1999)/Season 169
Aug 13, 2021 14:40:47.237 [0x14fcd647e848] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Aug 13, 2021 14:40:47.327 [0x14fcd647e848] DEBUG - It took 10.000000 ms to retrieve 902 items.
Aug 13, 2021 14:40:47.558 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882447
Aug 13, 2021 14:40:47.559 [0x14fcd647e848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:47.559 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882447
Aug 13, 2021 14:40:47.560 [0x14fcd647e848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:47.560 [0x14fcd647e848] DEBUG - Scanning TV Shows using en(Plex Series Scanner) with 14 current media items and 1 section locations in the database.
Aug 13, 2021 14:40:47.645 [0x14fcd647e848] DEBUG - Performing a scan with 'Plex Series Scanner' (language: en virtual: 0).
Aug 13, 2021 14:40:47.645 [0x14fcd647e848] DEBUG -   * Scanning /media/TV/House Hunters (1999)/Season 169
Aug 13, 2021 14:40:47.646 [0x14fcd647e848] DEBUG - Scanner: Processing directory /media/TV/House Hunters (1999)/Season 169 (parent: yes)
Aug 13, 2021 14:40:47.678 [0x14fcd647e848] DEBUG - Skipping over directory 'House Hunters (1999)/Season 169', as nothing has changed; removing 14 media items from map.
Aug 13, 2021 14:40:47.678 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20169
Aug 13, 2021 14:40:47.678 [0x14fcd647e848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20169
Aug 13, 2021 14:40:47.679 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20169 (reused)
Aug 13, 2021 14:40:47.680 [0x14fcd647e848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20169 (reused)
Aug 13, 2021 14:40:47.680 [0x14fcd647e848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Aug 13, 2021 14:40:47.680 [0x14fcd647e848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000 (reused)
Aug 13, 2021 14:40:47.680 [0x14fcd647e848] DEBUG - Removing 0 media items that were left.
Aug 13, 2021 14:40:47.680 [0x14fcd647e848] DEBUG - Removing 0 directories that were left.
Aug 13, 2021 14:40:47.817 [0x14fcd647e848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=100
Aug 13, 2021 14:40:47.818 [0x14fcd647e848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=100 (reused)
Aug 13, 2021 14:40:47.819 [0x14fcd647e848] DEBUG - Refreshing section 2 of type: 2
Aug 13, 2021 14:40:48.171 [0x14fcd647e848] DEBUG - The show 'The Late Show With Stephen Colbert' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:48.374 [0x14fcd647e848] DEBUG - The show 'The Tonight Show Starring Jimmy Fallon' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:48.407 [0x14fcd647e848] DEBUG - The show 'Wheel of Fortune' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:48.424 [0x14fcd11c9b38] DEBUG - Refreshing 3 IDs.
Aug 13, 2021 14:40:48.425 [0x14fcd11c9b38] DEBUG - Refreshing metadata for 'The Late Show With Stephen Colbert' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:48.425 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:48.425 [0x14fcd11c9b38] DEBUG - Refreshing GUID: 'plex://show/5fc6ac816b022a002d707444'
Aug 13, 2021 14:40:48.425 [0x14fcd11c9b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617
Aug 13, 2021 14:40:48.425 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:48.428 [0x14fcd11c9b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617 (reused)
Aug 13, 2021 14:40:48.429 [0x14fcd11c9b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:48.429 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:48.429 [0x14fcd11c9b38] DEBUG - Refreshing metadata for 'The Tonight Show Starring Jimmy Fallon' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:48.429 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:48.429 [0x14fcd11c9b38] DEBUG - Refreshing GUID: 'plex://show/5fc6a9f3b360df002ddf7f67'
Aug 13, 2021 14:40:48.429 [0x14fcd11c9b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812
Aug 13, 2021 14:40:48.429 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:48.430 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:48.432 [0x14fcd11c9b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812 (reused)
Aug 13, 2021 14:40:48.432 [0x14fcd11c9b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:48.432 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:48.432 [0x14fcd11c9b38] DEBUG - Refreshing metadata for 'Wheel of Fortune' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:48.432 [0x14fcd11c9b38] DEBUG - Refreshing GUID: 'plex://show/5fc6a968140a9f002d9e25e4'
Aug 13, 2021 14:40:48.432 [0x14fcd11c9b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275
Aug 13, 2021 14:40:48.432 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:48.432 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:48.433 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:48.436 [0x14fcd11c9b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275 (reused)
Aug 13, 2021 14:40:48.436 [0x14fcd11c9b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:48.436 [0x14fcd1719b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:48.436 [0x14fcd1719b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)

Plex Media Scanner.2.log

Aug 13, 2021 14:40:46.062 [0x14c8e3728b38] INFO - Plex Media Scanner v1.24.0.4930-ab6e1a058 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 - GMT -05:00
Aug 13, 2021 14:40:46.062 [0x14c8e3728b38] INFO - Linux version: 5.10.28-Unraid, language: en-US
Aug 13, 2021 14:40:46.062 [0x14c8e3728b38] INFO - Processor AMD Ryzen 5 5600X 6-Core Processor             
Aug 13, 2021 14:40:46.062 [0x14c8e3728b38] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --scan --refresh --section 2 --activity b3ccec11-cdf6-4ed9-8c9c-b757d904ab3f --directory /media/TV/House Hunters (1999)/Season 171
Aug 13, 2021 14:40:46.065 [0x14c8e6579848] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Aug 13, 2021 14:40:46.143 [0x14c8e6579848] DEBUG - It took 10.000000 ms to retrieve 902 items.
Aug 13, 2021 14:40:46.324 [0x14c8e6579848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:46.324 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882446
Aug 13, 2021 14:40:46.325 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882446
Aug 13, 2021 14:40:46.325 [0x14c8e6579848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:46.325 [0x14c8e6579848] DEBUG - Scanning TV Shows using en(Plex Series Scanner) with 25 current media items and 1 section locations in the database.
Aug 13, 2021 14:40:46.383 [0x14c8e6579848] DEBUG - Performing a scan with 'Plex Series Scanner' (language: en virtual: 0).
Aug 13, 2021 14:40:46.383 [0x14c8e6579848] DEBUG -   * Scanning /media/TV/House Hunters (1999)/Season 171
Aug 13, 2021 14:40:46.384 [0x14c8e6579848] DEBUG - Scanner: Processing directory /media/TV/House Hunters (1999)/Season 171 (parent: yes)
Aug 13, 2021 14:40:46.411 [0x14c8e6579848] DEBUG - Skipping over directory 'House Hunters (1999)/Season 171', as nothing has changed; removing 25 media items from map.
Aug 13, 2021 14:40:46.411 [0x14c8e6579848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20171
Aug 13, 2021 14:40:46.411 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20171
Aug 13, 2021 14:40:46.412 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20171 (reused)
Aug 13, 2021 14:40:46.412 [0x14c8e6579848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20171 (reused)
Aug 13, 2021 14:40:46.412 [0x14c8e6579848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Aug 13, 2021 14:40:46.413 [0x14c8e6579848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000 (reused)
Aug 13, 2021 14:40:46.413 [0x14c8e6579848] DEBUG - Removing 0 media items that were left.
Aug 13, 2021 14:40:46.413 [0x14c8e6579848] DEBUG - Removing 0 directories that were left.
Aug 13, 2021 14:40:46.515 [0x14c8e6579848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=100
Aug 13, 2021 14:40:46.516 [0x14c8e6579848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=100 (reused)
Aug 13, 2021 14:40:46.516 [0x14c8e6579848] DEBUG - Refreshing section 2 of type: 2
Aug 13, 2021 14:40:46.774 [0x14c8e6579848] DEBUG - The show 'The Late Show With Stephen Colbert' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:46.957 [0x14c8e6579848] DEBUG - The show 'The Tonight Show Starring Jimmy Fallon' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:46.990 [0x14c8e6579848] DEBUG - The show 'Wheel of Fortune' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:47.007 [0x14c8e12c4b38] DEBUG - Refreshing 3 IDs.
Aug 13, 2021 14:40:47.008 [0x14c8e12c4b38] DEBUG - Refreshing metadata for 'The Late Show With Stephen Colbert' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:47.008 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:47.008 [0x14c8e12c4b38] DEBUG - Refreshing GUID: 'plex://show/5fc6ac816b022a002d707444'
Aug 13, 2021 14:40:47.008 [0x14c8e12c4b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617
Aug 13, 2021 14:40:47.008 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:47.012 [0x14c8e12c4b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617 (reused)
Aug 13, 2021 14:40:47.012 [0x14c8e12c4b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:47.012 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:47.012 [0x14c8e12c4b38] DEBUG - Refreshing metadata for 'The Tonight Show Starring Jimmy Fallon' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:47.012 [0x14c8e12c4b38] DEBUG - Refreshing GUID: 'plex://show/5fc6a9f3b360df002ddf7f67'
Aug 13, 2021 14:40:47.012 [0x14c8e12c4b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812
Aug 13, 2021 14:40:47.012 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:47.012 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:47.013 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:47.015 [0x14c8e12c4b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812 (reused)
Aug 13, 2021 14:40:47.015 [0x14c8e12c4b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:47.015 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:47.016 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:47.016 [0x14c8e12c4b38] DEBUG - Refreshing metadata for 'Wheel of Fortune' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:47.016 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:47.016 [0x14c8e12c4b38] DEBUG - Refreshing GUID: 'plex://show/5fc6a968140a9f002d9e25e4'
Aug 13, 2021 14:40:47.016 [0x14c8e12c4b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275
Aug 13, 2021 14:40:47.016 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:47.019 [0x14c8e12c4b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275 (reused)
Aug 13, 2021 14:40:47.019 [0x14c8e12c4b38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:47.019 [0x14c8e1814b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:47.019 [0x14c8e1814b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)

Plex Media Scanner.3.log

Aug 13, 2021 14:40:44.822 [0x14acd24d9b38] INFO - Plex Media Scanner v1.24.0.4930-ab6e1a058 - Docker Docker Container (LinuxServer.io) x86_64 - build: linux-x86_64 - GMT -05:00
Aug 13, 2021 14:40:44.822 [0x14acd24d9b38] INFO - Linux version: 5.10.28-Unraid, language: en-US
Aug 13, 2021 14:40:44.822 [0x14acd24d9b38] INFO - Processor AMD Ryzen 5 5600X 6-Core Processor             
Aug 13, 2021 14:40:44.822 [0x14acd24d9b38] INFO - /usr/lib/plexmediaserver/Plex Media Scanner --scan --refresh --section 2 --activity 749f498c-7995-4d79-bfae-6ec9278b118c --directory /media/TV/House Hunters (1999)/Season 160
Aug 13, 2021 14:40:44.823 [0x14acd532a848] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.35.5, threadsafe=1
Aug 13, 2021 14:40:44.889 [0x14acd532a848] DEBUG - It took 10.000000 ms to retrieve 902 items.
Aug 13, 2021 14:40:45.060 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882445
Aug 13, 2021 14:40:45.060 [0x14acd532a848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:45.061 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1628882445
Aug 13, 2021 14:40:45.061 [0x14acd532a848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=0
Aug 13, 2021 14:40:45.061 [0x14acd532a848] DEBUG - Scanning TV Shows using en(Plex Series Scanner) with 9 current media items and 1 section locations in the database.
Aug 13, 2021 14:40:45.129 [0x14acd532a848] DEBUG - Performing a scan with 'Plex Series Scanner' (language: en virtual: 0).
Aug 13, 2021 14:40:45.129 [0x14acd532a848] DEBUG -   * Scanning /media/TV/House Hunters (1999)/Season 160
Aug 13, 2021 14:40:45.130 [0x14acd532a848] DEBUG - Scanner: Processing directory /media/TV/House Hunters (1999)/Season 160 (parent: yes)
Aug 13, 2021 14:40:45.146 [0x14acd532a848] DEBUG - Skipping over directory 'House Hunters (1999)/Season 160', as nothing has changed; removing 9 media items from map.
Aug 13, 2021 14:40:45.146 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20160
Aug 13, 2021 14:40:45.146 [0x14acd532a848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20160
Aug 13, 2021 14:40:45.146 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/updateProgressMessage?message=Scanning%20House%20Hunters%20%281999%29%2FSeason%20160 (reused)
Aug 13, 2021 14:40:45.146 [0x14acd532a848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=%2B0&subtitle=House%20Hunters%20%281999%29%20-%20Season%20160 (reused)
Aug 13, 2021 14:40:45.147 [0x14acd532a848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000
Aug 13, 2021 14:40:45.148 [0x14acd532a848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=99.000000 (reused)
Aug 13, 2021 14:40:45.148 [0x14acd532a848] DEBUG - Removing 0 media items that were left.
Aug 13, 2021 14:40:45.148 [0x14acd532a848] DEBUG - Removing 0 directories that were left.
Aug 13, 2021 14:40:45.275 [0x14acd532a848] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/activities/?percentComplete=100
Aug 13, 2021 14:40:45.276 [0x14acd532a848] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT http://127.0.0.1:32400/activities/?percentComplete=100 (reused)
Aug 13, 2021 14:40:45.276 [0x14acd532a848] DEBUG - Refreshing section 2 of type: 2
Aug 13, 2021 14:40:45.545 [0x14acd532a848] DEBUG - The show 'The Late Show With Stephen Colbert' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:45.753 [0x14acd532a848] DEBUG - The show 'The Tonight Show Starring Jimmy Fallon' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:45.788 [0x14acd532a848] DEBUG - The show 'Wheel of Fortune' has unmatched leaves, and show was matched, so we'll refresh.
Aug 13, 2021 14:40:45.806 [0x14acd007eb38] DEBUG - Refreshing 3 IDs.
Aug 13, 2021 14:40:45.807 [0x14acd007eb38] DEBUG - Refreshing metadata for 'The Late Show With Stephen Colbert' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:45.807 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:45.807 [0x14acd007eb38] DEBUG - Refreshing GUID: 'plex://show/5fc6ac816b022a002d707444'
Aug 13, 2021 14:40:45.807 [0x14acd007eb38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617
Aug 13, 2021 14:40:45.807 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:45.810 [0x14acd007eb38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6ac816b022a002d707444&id=211617 (reused)
Aug 13, 2021 14:40:45.810 [0x14acd007eb38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:45.810 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:45.811 [0x14acd007eb38] DEBUG - Refreshing metadata for 'The Tonight Show Starring Jimmy Fallon' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:45.811 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=211617&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:45.811 [0x14acd007eb38] DEBUG - Refreshing GUID: 'plex://show/5fc6a9f3b360df002ddf7f67'
Aug 13, 2021 14:40:45.811 [0x14acd007eb38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812
Aug 13, 2021 14:40:45.811 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:45.811 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:45.814 [0x14acd007eb38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a9f3b360df002ddf7f67&id=155812 (reused)
Aug 13, 2021 14:40:45.814 [0x14acd007eb38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:45.814 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:45.814 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=155812&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)
Aug 13, 2021 14:40:45.814 [0x14acd007eb38] DEBUG - Refreshing metadata for 'Wheel of Fortune' (agent: com.plexapp.agents.thetvdb)
Aug 13, 2021 14:40:45.814 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued
Aug 13, 2021 14:40:45.815 [0x14acd007eb38] DEBUG - Refreshing GUID: 'plex://show/5fc6a968140a9f002d9e25e4'
Aug 13, 2021 14:40:45.815 [0x14acd007eb38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275
Aug 13, 2021 14:40:45.815 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=3&parentID=-1&rootID=-1&metadataState=queued (reused)
Aug 13, 2021 14:40:45.817 [0x14acd007eb38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=2&force=1&respectTags=0&guid=plex%3A%2F%2Fshow%2F5fc6a968140a9f002d9e25e4&id=206275 (reused)
Aug 13, 2021 14:40:45.817 [0x14acd007eb38] ERROR - Error response for update: 5 (Unhandled exception).
Aug 13, 2021 14:40:45.817 [0x14acd05c5b38] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle
Aug 13, 2021 14:40:45.818 [0x14acd05c5b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=2&metadataItemID=206275&metadataType=2&state=5&parentID=-1&rootID=-1&metadataState=idle (reused)

ChuckPa · August 13, 2021, 8:44pm

Please…

Settings - Server - Troubleshooting - Download Logs

Attach the ZIP

I restate - Excerpts are not useful - nothing actionable here because the corresponding agent files are not present. Excerpts are only valid when the message itself is single scope (such as a certificate error)

scrathe · August 14, 2021, 6:14pm

is there a private method to share these log files?

foulou · August 20, 2021, 5:37am

Well, spent a few hours deleting movies that had no metadata matches (which i think were the culprit here), done some full metadata redownload of all librairies( because some metadata were from old scan agent which no longer exist in the plex server, second culprit i guess). waited a few days, and for the past week the DB was able to backup successfully. So today i tried to upgrade, to 1.24 and no more crash ! (well it did crash 2 times the 1st boot of the container, but now it’s ok) !!!
Do’nt know what was wrong even with debug log it wasn’t clear !

Thanks !

system · November 18, 2021, 5:37am

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

Topic		Replies	Views
Server-linux Plex Media Scan segmentation faults Desktops & Laptops server-linux	64	3077	April 4, 2020
Plex Media Server keeps crashing Plex Media Server server-linux	26	195	February 24, 2024
Plex crashes randomly after latest update Plex Media Server server-linux	101	2884	September 22, 2021
Plex Server Crashing after update (Docker) Plex Media Server server-docker	15	1108	October 8, 2020
Issues with my Plex server NAS & Devices server-linux	25	527	November 5, 2023