As the title says, two Tuesdays in a row now I’ve found my Plex server not running sometime during the early hours of the morning. Since I had to restart Plex anyway I just updated to v1.7.4.4017-3886b5c85
Pulled some info out of the logs:
----last entries in “Plex Media Server.log” before restart----
Jun 27, 2017 14:07:38.478 [0x80bc54800] WARN - LPE: falling back to slow path evaluation: /library/metadata/1195/children//library/metadata/1204/all
Jun 27, 2017 14:08:49.216 [0x80d82a000] WARN - LPE: falling back to slow path evaluation: /library/metadata/1195/children//library/metadata/1222/all
Jun 27, 2017 14:09:16.015 [0x80d829800] WARN - LPE: falling back to slow path evaluation: /library/metadata/14256/children//library/metadata/14273/all
Jun 27, 2017 14:11:47.769 [0x80d829800] WARN - LPE: falling back to slow path evaluation: /library/metadata/5757/children//library/metadata/5783/all
Jun 27, 2017 16:17:53.049 [0x80bc54800] ERROR - get - invalid frameRate value: 59.940
Jun 27, 2017 16:18:33.454 [0x809c0f000] ERROR - get - invalid frameRate value: 59.940
Jun 27, 2017 16:19:27.280 [0x80aea9000] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
----After updating to latest from ports and restarting daemon----
Jun 28, 2017 03:23:25.587 [0x809c07000] INFO - Plex Media Server v1.7.4.4017-3886b5c85 - FreeBSD PC amd64 - build: freebsd-x86_64 freebsd - GMT -07:00
Jun 28, 2017 03:23:25.587 [0x809c07000] ERROR - Failed to determine system locale: locale::facet::_S_create_c_locale name not valid. Defaulting to en-US.
Jun 28, 2017 03:23:25.587 [0x809c07000] INFO - FreeBSD version: 10.3-STABLE (FreeBSD 10.3-STABLE #0 r295946+21897e6695f(9.10.2-STABLE): Thu Apr 20 17:58:31 UTC 2017 root@gauntlet:/freenas-9.10-releng/_BE/objs/freenas-9.10-releng/_BE/os/sys/FreeNAS.amd64), language: en-US
Jun 28, 2017 03:23:25.587 [0x809c07000] INFO - Processor Intel® Xeon® CPU E3-1230 v5 @ 3.40GHz
Jun 28, 2017 03:23:25.587 [0x809c07000] INFO - /usr/local/share/plexmediaserver-plexpass/Plex_Media_Server
Jun 28, 2017 03:23:25.572 [0x809c06400] WARN - Default locale is invalid; falling back on classic+UTF-8
Jun 28, 2017 03:23:25.598 [0x809c06400] INFO - SQLITE3:0x10, 283, recovered 975 frames from WAL file /usr/local/plexdata-plexpass/Plex Media Server/Plug-in Support/Databases/com.plexapp.plugins.library.db-wal
Jun 28, 2017 03:23:25.672 [0x809c07c00] INFO - CodecManager: obtaining decoder ‘aac’
Jun 28, 2017 03:23:25.847 [0x809c0f000] INFO - Successfully retrieved OCSP response
Jun 28, 2017 03:23:27.418 [0x80c84e400] INFO - SQLITE3:0x10, 283, recovered 104 frames from WAL file /usr/local/plexdata-plexpass/Plex Media Server/Plug-in Support/Databases/tv.plex.providers.epg.onconnect-cfab2bf3-62d1-4369-9dc8-c44a9e483e2e.db-wal
Jun 28, 2017 03:23:27.514 [0x80f02b400] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Jun 28, 2017 03:23:27.516 [0x80f02b400] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
snip
Jun 28, 2017 03:23:27.616 [0x80f02b400] ERROR - SQLITE3:0x10, 1, duplicate column name: grandparent_guid
Jun 28, 2017 03:23:27.617 [0x80f02b400] ERROR - SQLITE3:0x10, 1, duplicate column name: originally_available_at
Jun 28, 2017 03:23:27.627 [0x80f02b400] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
snip
Jun 28, 2017 03:23:27.998 [0x80c84e400] ERROR - downloadContainer: expected MediaContainer element, found html
Jun 28, 2017 03:23:27.998 [0x80c84e400] WARN - DVR:Subscription: No container available for /tv.plex.providers.epg.onconnect:4/metadata/com%2Egracenote%2Eonconnect%3A%2F%2Fshow%2F12051214
Jun 28, 2017 03:23:28.016 [0x80f02b400] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
snip
Jun 28, 2017 03:24:00.943 [0x809c11400] ERROR - Couldn’t get size of file : boost::filesystem::file_size: No such file or directory
Jun 28, 2017 03:24:00.956 [0x80d4d6000] ERROR - Couldn’t get size of file : boost::filesystem::file_size: No such file or directory
Jun 28, 2017 03:24:00.996 [0x80d4d6000] ERROR - Couldn’t get size of file : boost::filesystem::file_size: No such file or directory
Jun 28, 2017 03:24:01.029 [0x80d824800] ERROR - Couldn’t get size of file : boost::filesystem::file_size: No such file or directory
snip
Jun 28, 2017 03:36:19.190 [0x80d4d6c00] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Jun 28, 2017 03:36:19.192 [0x80d4d6c00] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Jun 28, 2017 03:36:19.194 [0x80d4d6c00] ERROR - SQLITE3:0x10, 1, duplicate column name: grandparent_guid
Jun 28, 2017 03:36:19.194 [0x80d4d6c00] ERROR - SQLITE3:0x10, 1, duplicate column name: originally_available_at
Jun 28, 2017 03:36:19.198 [0x80d4d6c00] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Jun 28, 2017 03:36:19.200 [0x80d4d6c00] INFO - SQLITE3:0x10, 17, statement aborts at 57: [select * from metadata_items limit 1] database schema has changed
Ive truncated some duplicate log entries to try and focus on the error statements and odd info. I see the database schema change message quite a lot when updating the EPG.
