Plex Working but throwing console errors

[wah00kid]

FreeNas 9.10.2-U5 here
I have Plex running fine Version 1.5.7.4016.
I uesd the plugin and it currently says Plexmediaserver 1.1.4.2757 plexmediaserver-1.1.4.2757-amd64 plexmediaserver_1 and the service is not enabled in the GUI.

I have done many updates both to the Plex Version and through the various 9.10.2 versions without problems with Plex... however my console is constantly being filled up with this:

Anyone in a similar boat?

 

[SweetAndLow]

FreeNas 9.10.2-U5 here
I have Plex running fine Version 1.5.7.4016.
I uesd the plugin and it currently says Plexmediaserver 1.1.4.2757 plexmediaserver-1.1.4.2757-amd64 plexmediaserver_1 and the service is not enabled in the GUI.

I have done many updates both to the Plex Version and through the various 9.10.2 versions without problems with Plex... however my console is constantly being filled up with this:

Anyone in a similar boat?

service plexmediaserver onestart

What does the output of that look like if you run it in your jail?

Sent from my Nexus 5X using Tapatalk

 

[wah00kid]

service plexmediaserver onestart

What does the output of that look like if you run it in your jail?

Sent from my Nexus 5X using Tapatalk

Code:

root@plexmediaserver_1:/ # service plexmediaserver onestart
plexmediaserver already running?  (pid=60894).
root@plexmediaserver_1:/ #

 

[fracai]

Check out the Plex log files:
/var/db/plexdata/Plex Media Server/Logs

Anything interesting in there?

 

[wah00kid]

Looking through the last 10 minutes or so, with no activity otherwise, I see this loop repeating:

Code:

Jul 06, 2017 10:49:01.079 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.413039 seconds: 192.168.1.107
Jul 06, 2017 10:49:20.694 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.107 (http://192.168.1.107:8008/ssdp/device-desc.xml)
Jul 06, 2017 10:49:20.695 [0x80b08d000] DEBUG - HTTP requesting GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:49:20.702 [0x80b08d000] DEBUG - HTTP 200 response from GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:49:28.896 [0x80a503800] DEBUG - Request: [127.0.0.1:15104 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 10:49:28.896 [0x80a503800] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 10:49:28.896 [0x80a503800] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:49:28.896 [0x80a503800] DEBUG - Completed: [127.0.0.1:15104] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:49:58.980 [0x80ac72c00] DEBUG - Request: [127.0.0.1:31716 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 10:49:58.980 [0x80ac72c00] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 10:49:58.980 [0x80ac72c00] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:49:58.980 [0x80ac72c00] DEBUG - Completed: [127.0.0.1:31716] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:50:01.076 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.352275 seconds: 192.168.1.107

There are no other log files that I see that are being written during the immediate time frame.

 

[wah00kid]

Here is a two minute chunk of logs as it looks like the period from my console is about a minute and a half:

Code:

Jul 06, 2017 10:58:00.002 [0x80c0bf400] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:58:00.003 [0x80c0bf400] DEBUG - Completed: [127.0.0.1:12638] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:58:06.737 [0x80b08c400] DEBUG - BPQ: garbageCollect - sync - not forced - -1
Jul 06, 2017 10:58:06.737 [0x80b08c400] DEBUG - Sync: starting Sync garbage collection: -1, not forced
Jul 06, 2017 10:58:06.738 [0x80b08c400] DEBUG - Sync: garbage collecting database for sync list 14156936
Jul 06, 2017 10:58:06.741 [0x80b08c400] DEBUG - Sync: garbage collected database for sync list 14156936 in 0.0 seconds
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Sync: garbage collecting filesystem
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Sync: garbage collected filesystem
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Sync: scanning for items to reprocess in sync list 14156936
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Sync: scanned for items to reprocess in sync list 14156936
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Sync: finished Sync garbage collection: -1
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - BPQ: garbageCollect - version - not forced - -1
Jul 06, 2017 10:58:06.742 [0x80b08c400] DEBUG - Versions: garbage collecting
Jul 06, 2017 10:58:06.743 [0x80b08c400] DEBUG - Versions: garbage collected in 0.0 seconds
Jul 06, 2017 10:58:07.547 [0x80b08d000] DEBUG - Sync: uploadStatus
Jul 06, 2017 10:58:11.090 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.390833 seconds: 192.168.1.107
Jul 06, 2017 10:58:20.660 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.107 (http://192.168.1.107:8008/ssdp/device-desc.xml)
Jul 06, 2017 10:58:20.660 [0x80b08d000] DEBUG - HTTP requesting GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:58:20.674 [0x80b08d000] DEBUG - HTTP 200 response from GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:58:30.155 [0x80cd41800] DEBUG - Request: [127.0.0.1:64601 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 10:58:30.155 [0x80cd41800] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 10:58:30.155 [0x80cd41800] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:58:30.155 [0x80cd41800] DEBUG - Completed: [127.0.0.1:64601] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:58:41.077 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.416678 seconds: 192.168.1.107
Jul 06, 2017 10:59:00.201 [0x80ac73800] DEBUG - Request: [127.0.0.1:46943 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 10:59:00.201 [0x80ac73800] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 10:59:00.201 [0x80ac73800] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:59:00.201 [0x80ac73800] DEBUG - Completed: [127.0.0.1:46943] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:59:19.699 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.107 (http://192.168.1.107:8008/ssdp/device-desc.xml)
Jul 06, 2017 10:59:19.699 [0x80a502800] DEBUG - HTTP requesting GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:59:19.707 [0x80a502800] DEBUG - HTTP 200 response from GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 10:59:30.297 [0x80ac6f000] DEBUG - Request: [127.0.0.1:58105 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 10:59:30.297 [0x80ac6f000] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 10:59:30.297 [0x80ac6f000] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 10:59:30.297 [0x80ac6f000] DEBUG - Completed: [127.0.0.1:58105] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 10:59:41.096 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.396066 seconds: 192.168.1.107
Jul 06, 2017 11:00:00.405 [0x80ac74c00] DEBUG - Request: [127.0.0.1:23306 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 11:00:00.405 [0x80ac74c00] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 11:00:00.405 [0x80ac74c00] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 11:00:00.405 [0x80ac74c00] DEBUG - Completed: [127.0.0.1:23306] 200 HEAD /web/index.html (2 live) 0ms 0 bytes
Jul 06, 2017 11:00:10.668 [0x80a503400] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.107 (http://192.168.1.107:8008/ssdp/device-desc.xml)
Jul 06, 2017 11:00:10.668 [0x80b08d000] DEBUG - HTTP requesting GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 11:00:10.676 [0x80b08d000] DEBUG - HTTP 200 response from GET http://192.168.1.107:8008/ssdp/device-desc.xml
Jul 06, 2017 11:00:30.482 [0x80a501000] DEBUG - Request: [127.0.0.1:32561 (Loopback)] HEAD /web/index.html (2 live) Signed-in
Jul 06, 2017 11:00:30.482 [0x80a501000] DEBUG - Final path: /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html
Jul 06, 2017 11:00:30.483 [0x80a501000] DEBUG - Content-Length of /usr/pbi/plexmediaserver-amd64/share/plexmediaserver/Resources/Plug-ins-313f93718/WebClient.bundle/Contents/Resources/index.html is 4223.
Jul 06, 2017 11:00:30.483 [0x80a501000] DEBUG - Completed: [127.0.0.1:32561] 200 HEAD /web/index.html (2 live) 0ms 0 bytes

 

[fracai]

The console indicates that the Plex server is starting and dieing, but the Plex logs don't seem to show any issues that I can see. I'll try to remember to check my logs this evening to compare. Which one did you post? Are there others?

 

[wah00kid]

I posted just Plex Media Server.log

as when I do an ls -all that is the only one that has a date right up to the minute

Code:

root@plexmediaserver_1:/var/db/plexdata/Plex Media Server/Logs # ls -all
total 2967
drwxrwxrwx  3 plex  plex	   53 Jul  6 22:59 .
drwxr-xr-x  9 plex  plex	   10 Jul  6 22:58 ..
drwxrwxrwx  2 plex  plex	   87 Jul  6 22:58 PMS Plugin Logs
-rw-r--r--  1 plex  plex	 2295 Mar 30 20:19 Plex (anonymous).1.log
-rw-r--r--  1 plex  plex	 2295 Mar 30 20:18 Plex (anonymous).2.log
-rw-r--r--  1 plex  plex	 2295 Mar 30 20:16 Plex (anonymous).3.log
-rw-r--r--  1 plex  plex	 2295 Mar 30 20:15 Plex (anonymous).4.log
-rw-r--r--  1 plex  plex	 2295 Mar 30 20:12 Plex (anonymous).5.log
-rw-r--r--  1 plex  plex	 2177 Mar 30 20:21 Plex (anonymous).log
-rw-r--r--  1 plex  plex		0 Nov 22  2016 Plex DLNA Server Neptune.log
-rw-r--r--  1 plex  plex		0 Nov 15  2016 Plex DLNA Server Neptune.old.log
-rw-r--r--  1 plex  plex  2046955 Nov 15  2016 Plex DLNA Server.1.log
-rw-r--r--  1 plex  plex	 1417 Nov 13  2016 Plex DLNA Server.2.log
-rw-rw-rw-  1 plex  plex   939948 Nov 13  2016 Plex DLNA Server.3.log
-rw-rw-rw-  1 plex  plex  5242922 Nov 13  2016 Plex DLNA Server.4.log
-rw-rw-rw-  1 plex  plex	 1956 Nov 10  2016 Plex DLNA Server.5.log
-rw-r--r--  1 plex  plex  4353181 Nov 27  2016 Plex DLNA Server.log
-rw-r--r--  1 plex  plex	14852 Jun  2 04:02 Plex Media Scanner Analysis.1.log
-rw-r--r--  1 plex  plex	 7765 May 10 04:04 Plex Media Scanner Analysis.2.log
-rw-r--r--  1 plex  plex	14580 Apr 10 04:00 Plex Media Scanner Analysis.3.log
-rw-r--r--  1 plex  plex	 7760 Apr  2 04:04 Plex Media Scanner Analysis.4.log
-rw-r--r--  1 plex  plex	19665 Apr  1 19:03 Plex Media Scanner Analysis.5.log
-rw-r--r--  1 plex  plex	 8226 Jun  5 04:02 Plex Media Scanner Analysis.log
-rw-r--r--  1 plex  plex	 5632 Jun  3 04:02 Plex Media Scanner Chapter Thumbnails.1.log
-rw-r--r--  1 plex  plex	 5629 Jun  3 04:02 Plex Media Scanner Chapter Thumbnails.2.log
-rw-r--r--  1 plex  plex	64517 Jun  2 04:02 Plex Media Scanner Chapter Thumbnails.3.log
-rw-r--r--  1 plex  plex	72485 Jun  2 04:02 Plex Media Scanner Chapter Thumbnails.4.log
-rw-r--r--  1 plex  plex	51693 May 10 04:05 Plex Media Scanner Chapter Thumbnails.5.log
-rw-r--r--  1 plex  plex	49205 Jun  5 04:02 Plex Media Scanner Chapter Thumbnails.log
-rw-r--r--  1 plex  plex  1021298 Jul  1 04:00 Plex Media Scanner Deep Analysis.1.log
-rw-r--r--  1 plex  plex  5242922 Jul  1 04:00 Plex Media Scanner Deep Analysis.2.log
-rw-r--r--  1 plex  plex	 7391 Jul  1 04:00 Plex Media Scanner Deep Analysis.3.log
-rw-r--r--  1 plex  plex	 4704 Jul  1 04:00 Plex Media Scanner Deep Analysis.4.log
-rw-r--r--  1 plex  plex	 5372 Jun 28 04:04 Plex Media Scanner Deep Analysis.5.log
-rw-r--r--  1 plex  plex  5137257 Jul  3 04:00 Plex Media Scanner Deep Analysis.log
-rw-r--r--  1 plex  plex	47795 Jul  6 22:59 Plex Media Scanner.1.log
-rw-r--r--  1 plex  plex   108867 Jul  6 22:58 Plex Media Scanner.2.log
-rw-r--r--  1 plex  plex	47795 Jul  6 22:58 Plex Media Scanner.3.log
-rw-r--r--  1 plex  plex   108843 Jul  5 07:40 Plex Media Scanner.4.log
-rw-r--r--  1 plex  plex	47771 Jul  5 07:40 Plex Media Scanner.5.log
-rw-r--r--  1 plex  plex   108867 Jul  6 22:59 Plex Media Scanner.log
-rw-r--r--  1 plex  plex	 2739 Jul  5 22:56 Plex Media Server.1.log
-rw-r--r--  1 plex  plex	 2739 Jul  5 22:55 Plex Media Server.2.log
-rw-r--r--  1 plex  plex	 2739 Jul  5 22:53 Plex Media Server.3.log
-rw-r--r--  1 plex  plex	 2739 Jul  5 22:52 Plex Media Server.4.log
-rw-r--r--  1 plex  plex	 2306 Jul  5 22:51 Plex Media Server.5.log
-rw-r--r--  1 plex  plex  4798009 Jul  7 12:39 Plex Media Server.log
-rw-r--r--  1 plex  plex	 2534 Jul  5 22:58 Plex Tuner Service.1.log
-rw-r--r--  1 plex  plex	 2534 Jul  5 12:55 Plex Tuner Service.2.log
-rw-r--r--  1 plex  plex	 2534 Jul  5 11:03 Plex Tuner Service.3.log
-rw-r--r--  1 plex  plex	 2534 Jun 29 07:35 Plex Tuner Service.4.log
-rw-r--r--  1 plex  plex	 2534 Jun 26 20:34 Plex Tuner Service.5.log
-rw-r--r--  1 plex  plex	 1962 Jul  5 22:58 Plex Tuner Service.log

 

[nightshade00013]

How long has the FreeNAS been up? Was it a fresh install or an upgrade from a previous version? If an upgrade what version was it upgraded from?

Right now the latest version of plex is around 1.7.5 so it is out of date and I suspect that you have upgraded your FreeNAS but not removed the jail dataset and created a new one so upgrades can come through. It may be easiest to just kill or shut off the current jail/plugin and go for a new install and see if the problem persists IMHO. Sometimes it's easier to start fresh than to fix something that could be corrupted especially when a jail is so easy to make for plex.

Hardware specs could also be useful as there could even be something there that is not recommended and causing some sort of issue.

 

[wah00kid]

Thanks for the interest and help. Agreed that rebuilding PLEX from scratch is the way to go. I clearly have a disconnect between the original plugin I used to install PLEX on 9.3 (I cant remember where I started exactly) and what I have now where the GUI shows one thing but under the hood it is the latest version which I manually update.

Oh yeah it doesn't matter how long I've been up it starts throwing the errors immediately on a reboot. I do not do anything to start PLEX manually though... it all starts up fine on its own on a reboot.

My hardware is x9scm (sas controller flashed to IT mode)
4x3tb in raidz2
2x160gb in a mirror
intel e1230v2
24 GB ECC Ram (2x8gb Kingston + 2x4gb Hynix)

 

[nightshade00013]

So you installed the plugin but manually updated after that. I suspect that could be the issue then.

Make a new jail in the UI, not a plugin this time a jail. Then just use this as an instruction to set things up. Also if you have been updating since 9.3 you will need to make a new jail dataset so the old templates are not used and then update the jail configuration so that the new dataset is the location for the jail root. You should probably stop all the old jails before doing this and for good measure reboot the FreeNAS. Once that is done you will create a new jail, all of the old ones should be gone, and then install Plex https://forums.freenas.org/index.ph...to-install-plex-in-a-freenas-9-10-jail.19412/

 

[SweetAndLow]

So you installed the plugin but manually updated after that. I suspect that could be the issue then.

Make a new jail in the UI, not a plugin this time a jail. Then just use this as an instruction to set things up. Also if you have been updating since 9.3 you will need to make a new jail dataset so the old templates are not used and then update the jail configuration so that the new dataset is the location for the jail root. You should probably stop all the old jails before doing this and for good measure reboot the FreeNAS. Once that is done you will create a new jail, all of the old ones should be gone, and then install Plex https://forums.freenas.org/index.ph...to-install-plex-in-a-freenas-9-10-jail.19412/

Freenas will download the new jail template. No need to change the root directory.


Sent from my Nexus 5X using Tapatalk