Lot's of errors in log about wrong DB fields

General Discussions
1

Looking into the Logs for the first time, I do see lot’s of Query errors. What does that mean?

Jul 05, 2021 13:20:38.132 [0x1499d0e5eb38] WARN - QueryParser: Invalid field 'subtype' found, ignoring.
Jul 05, 2021 13:20:38.132 [0x1499d0e5eb38] WARN - QueryParser: Invalid field 'smart' found, ignoring.
Jul 05, 2021 13:21:08.603 [0x1499d0e5eb38] WARN - QueryParser: Invalid field 'sectionID' found, ignoring.
Jul 05, 2021 12:54:46.175 [0x1499cf3a7b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 05, 2021 12:54:46.175 [0x1499cf3a7b38] WARN - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jul 05, 2021 12:54:43.693 [0x1499d0952b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
2

It’s not your database. Those are likely called from a client requesting information from your server such as hubs for the home screen or other things it needs. Those messages just mean that the client sent parameters that your server doesn’t understand. Are you running an older version of PMS? If so, this is normal.

3

No, everythings latest releases of Server (Linux), and Clients (Android Mobile, Fire-TV 4K Ultra, Windows, LG WebOS, Plex Dash).

Whenever theres a new release I upgrade immediately.

No plugins, no fancy tools. Must be on your side.

4

If you send me the entire log, I can check what is sending those.

5

I’m getting these too

Newest beta server
Newest Shield TV Client

Jul 10, 2021 14:08:59.172 [4084] WARN - SLOW QUERY: It took 234.375000 ms to retrieve 93 items.
Jul 10, 2021 14:21:37.552 [11708] INFO - Library section 2 (Films) will be updated because of a change in "M:\Films\The Fate of the Furious (2017)\The Fate of the Furious (2017) - Remux-2160p.mkv"
Jul 10, 2021 14:22:37.566 [9196] INFO - Notification: Scanning the "Films" section - 
Jul 10, 2021 14:22:37.657 [2912] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:37.657 [2912] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:37.658 [2912] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:38.035 [2328] WARN - SLOW QUERY: It took 390.625000 ms to retrieve 93 items.
Jul 10, 2021 14:22:38.202 [2912] WARN - SLOW QUERY: It took 328.125000 ms to retrieve 50 items.
Jul 10, 2021 14:22:38.634 [1308] ERROR - Unknown metadata type: 
Jul 10, 2021 14:22:43.391 [9196] WARN - Held transaction for too long (..\Library\MetadataCollection.cpp:499): 0.203125 seconds
Jul 10, 2021 14:22:43.618 [9196] WARN - Held transaction for too long (..\Library\MetadataCollection.cpp:499): 0.171875 seconds
Jul 10, 2021 14:22:43.673 [9196] INFO - Notification: Library scan complete - Extra information may still be downloading from the Internet
Jul 10, 2021 14:22:43.791 [2328] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:43.791 [2328] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:43.791 [2328] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 14:22:43.897 [0624] WARN - Held transaction for too long (..\Library\MetadataItem.cpp:7148): 0.343750 seconds
Jul 10, 2021 14:22:44.148 [3844] WARN - SLOW QUERY: It took 687.500000 ms to retrieve 93 items.
Jul 10, 2021 14:22:44.389 [2328] WARN - SLOW QUERY: It took 500.000000 ms to retrieve 50 items.
Jul 10, 2021 14:22:45.485 [0624] WARN - Held transaction for too long (..\Library\MetadataItem.cpp:9431): 2.703125 seconds
Jul 10, 2021 14:22:45.733 [8912] WARN - Held transaction for too long (..\Library\MetadataCollection.cpp:499): 0.109375 seconds
Jul 10, 2021 14:22:45.758 [8912] WARN - Held transaction for too long (..\Library\MetadataItem.cpp:7148): 0.156250 seconds
Jul 10, 2021 14:22:49.203 [2328] WARN - SLOW QUERY: It took 296.875000 ms to retrieve 93 items.
Jul 10, 2021 14:43:09.145 [11708] WARN - NAT: PMP, got an error: NATPMP_ERR_RECVFROM.
Jul 10, 2021 15:43:09.473 [11708] WARN - NAT: PMP, got an error: NATPMP_ERR_RECVFROM.
Jul 10, 2021 16:43:09.086 [7032] WARN - NAT: PMP, got an error: NATPMP_ERR_RECVFROM.
Jul 10, 2021 17:43:09.877 [11708] WARN - NAT: PMP, got an error: NATPMP_ERR_RECVFROM.
Jul 10, 2021 18:43:10.086 [11708] WARN - NAT: PMP, got an error: NATPMP_ERR_RECVFROM.
Jul 10, 2021 19:08:20.951 [2536] WARN - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jul 10, 2021 19:08:20.951 [2536] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:08:20.951 [2536] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 19:08:21.636 [11920] ERROR - Unknown metadata type: folder
Jul 10, 2021 19:08:21.636 [11920] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 19:08:21.637 [11920] WARN - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jul 10, 2021 19:08:25.692 [11920] WARN - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jul 10, 2021 19:08:25.832 [11920] WARN - QueryParser: Invalid field 'libraryHubsOnly' found, ignoring.
Jul 10, 2021 19:28:15.253 [5360] INFO - AutoUpdate: no updates available
Jul 10, 2021 19:28:16.915 [3988] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:16.915 [7868] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:16.915 [7868] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:16.915 [7868] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:16.917 [3988] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:17.175 [7868] WARN - SLOW QUERY: It took 250.000000 ms to retrieve 50 items.
Jul 10, 2021 19:28:17.207 [5024] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:17.207 [5024] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:17.207 [5024] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Jul 10, 2021 19:28:17.487 [5024] WARN - SLOW QUERY: It took 218.750000 ms to retrieve 50 items.
6

Hi, did you ever find a solution to this? I’m having the same issue with mine PMS, everything on the latest release.

7

My solution was to stop checking the log

9 Likes
8

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