Can’t play most of my videos in Video media library. They all worked ok some time back now only a few can be played. Music files from Music library all play ok. It is the same on any player (web, desktop or mobile).Plex Media Server Logs_2019-09-12_18-00-52.zip (2.8 MB)
Server Version#: 1.16.5.1554 plexpass
Player Version#: 7.21.0
Logs attached.
Yeah that sounds like a problem that we can make better.
Your PMS is on FreeBSD. Describe where you media is stored.
Describe you basic network layout, and if you use Plex Remote Access.
Let’s have you use the desktop player app. Tell us the version you’re on.
Please create a test case for me.
Although you did some of the above, let’s do it again this way
so that we capture more detail.
Thank you sir
Here it is:
Network layout is simple:
Server connected to a switch <-> router <-> Fiber Channel modem <-> Internet
Port forwarding on router and modem accepting traffic on port 32400 forwarded to the plex server IP. Playback of music works ok both on internal network (mobile app, web app, DLNA etc) as well as from Internet on mobile, desktop and web apps. The problem is only with most of the video files and I have noticed that since a while only, for long time all was working ok.
I’ve followed your procedure and here is the log.
Please note that those video files won’t play on any player, mobile, web and desktop, regardless of the location (locally or via internet). Anyway the desktop player app version is 1.3.0.910-77ef46d6
I’ve tried it now on both remote location and locally.
Logs attached.Plex Media Server Logs_2019-09-13_14-18-53.zip (2.8 MB)
Thanks I’ll have a look now.
It didn’t make sense to me, partly because I guess PMS decided to generate a bunch of thumbnails and subtitle downloads, which filled the logs. But I saw several instances of HTTP 401 or 404 responses
Sep 13, 2019 14:16:00.725 [0x80ccad900] DEBUG - HTTP 401 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=1&force=1&guid=local%3A%2F%2F163857&id=163857&agent=com.plexapp.agents.localmedia&async=0
Sep 13, 2019 14:17:42.072 [0x80d026300] DEBUG - HTTP 404 response from GET http://127.0.0.1:32399/system/agents/attribution?identifier=com.plexapp.agents.localmedia
Please do the steps again when it’s past server automated tasks hour. Sorry.
And only do one example of the error, your local desktop player connecting locally.
OK, I’ve done that again. Logs attached, using only the web player on a laptop in the local network.
In the meantime I’ve noticed that the same videos play all ok on an iPhone, and only on an iPhone…Plex Media Server Logs_2019-09-13_22-29-27.zip (2.4 MB)
There are a lot of network problems. Have you rebooted all your hardware?
So you’re on FreeBSD 11.2-STABLE en-US. Does the following apply to you?
Note : If running in a FreeBSD “jail”, you’ll want to make sure that VNET is enabled.
FreeNAS has instructions.
No, I’ve restarted the PLEX jail only. I’ve repeated the steps after rebooting entire FreeNAS hardware, logs attached.
Not sure what network errors there are, PLEX works all fine with music and actually works for all files on iPhone app. But not on web, desktop or Android apps. Any clue?
Plex Media Server Logs_2019-09-14_08-21-57.zip (1.2 MB)
We have to rope in a FreeBSD expert, but I’ll have a look at them over coffee.
Your logs look very clean now that you rebooted everything. It shouldn’t be too
hard to spot for someone who knows what to look for. Below are just some things
that stood out as being different, but not necessarily bad, as I just don’t know.
What I can see from reading the logs is that the transcoder session starts and
the video plays and there’s some sync issues maybe, then you stop playing.
I should ask if you went thru the VNET docs.
Sep 14, 2019 08:05:27.703 [0x80c8a8300] WARN - HTTP error requesting POST http://127.0.0.1:32600/devices/discover (0, No error) (Failed to connect to 127.0.0.1 port 32600: Connection refused)
...
Sep 14, 2019 08:05:29.375 [0x80c8aa100] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 14, 2019 08:05:29.898 [0x80d024a00] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 14, 2019 08:05:29.898 [0x80d024a00] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901
Sep 14, 2019 08:05:29.898 [0x80d024a00] DEBUG - NetworkService: Browsing on interface 10.144.1.2 on broadcast address 239.255.255.250 (index: 0)
Sep 14, 2019 08:05:29.898 [0x80d024a00] ERROR - Network Service: Error in browser handle read: 85 (Operation canceled) socket=-1
...
Sep 14, 2019 08:05:31.103 [0x80cf13500] DEBUG - NAT: UPnP, found device <http://10.144.1.1:41643/rootDesc.xml> with private address <10.144.1.2>
Sep 14, 2019 08:05:31.107 [0x80cf13500] DEBUG - NAT: UPnP, usable device <http://10.144.1.1:41643/rootDesc.xml> with private address <10.144.1.2>.
Sep 14, 2019 08:05:31.109 [0x80cf13500] DEBUG - NAT: UPnP, public address is 192.168.1.3
Sep 14, 2019 08:05:31.109 [0x80cf13500] DEBUG - PublicAddressManager: got WAN IP 192.168.1.3 from router
Sep 14, 2019 08:05:31.109 [0x80cf13500] WARN - PublicAddressManager: WAN IP is a private IP address
...
Sep 14, 2019 08:21:30.180 [0x80d874000] WARN - Timed out waiting for segment.
Sep 14, 2019 08:21:30.202 [0x80c8a9200] WARN - Timed out waiting for segment.
Yes, I’ve tried to enable VNET but it actually makes my PLEX jail inaccessible. I would need to dig in this deeper to understand and make it work… Anyway PLEX worked all good without enabling the VNET on its jail.
And by all good you mean you can see libraries, but you still have the issue below?
Would you again follow the steps please, going through them all to make another test case, but also enable debug logging on Plex-1.3.1 and zip that log file too? This is the idea:
Settings > Show Advanced > Debug > Debug Level (Enabled) > SAVE
Once you’ve made the issue occur and you’ve done step (7), exit Plex-1.3.1 to make it save the Plex.log file also.
With both Player and Sever logs showing the same, we should have enough for a ninja to go on.
Here are both logs. Hope this helps 
plex web player debug.log (64.4 KB)
Plex Media Server Logs_2019-09-16_21-39-13.zip (2.0 MB)
It looks like networking problems and then authorization problems are the result.
Reboot all your hardware, check you network setup, screenshot some things
if you like, such as Settings > Show Advanced > Network
"message": "[Connections] [Bundled] is unauthorized at http://10.144.1.2:32400/media/providers (Status 401)",
"time": "2019-09-16T19:37:00.805Z"
"message": "[Identity] Machine identifier found for [Bundled]: 6370c58fbed9223108d7e8ebf583c97970a97143",
"time": "2019-09-16T19:37:01.003Z"
"message": "[Identity] [Bundled] and Plex (cached) collide",
"time": "2019-09-16T19:37:01.006Z"
"message": "[Identity] Merging [Bundled] into Plex (cached)",
"time": "2019-09-16T19:37:01.006Z"
"message": "[Connections] Added connection http://10.144.1.2:32400 for Plex",
"time": "2019-09-16T19:37:01.013Z"
"message": "[Commands] Executing testServerConnection",
"time": "2019-09-16T19:37:01.014Z"
"message": "[MediaServerEventManager] Opening server event connection to Plex at wss://10-144-1-2.f36a06fbc3544cb582a9a7874a03ad76.plex.direct:32400/:/websockets/notifications?X-Plex-Token=REDACTED",
"time": "2019-09-16T19:37:01.017Z"
"message": "[Servers] [Bundled] was removed",
"time": "2019-09-16T19:37:01.017Z"
"message": "[Connections] Aborting all pending connection tests for [Bundled]",
"time": "2019-09-16T19:37:01.018Z"
"message": "[Connections] [Loopback] is unavailable at http://127.0.0.1:32400/media/providers (Status 0)",
"time": "2019-09-16T19:37:02.985Z"
"message": "[Connections] All connections to [Loopback] failed",
"time": "2019-09-16T19:37:02.990Z"
"message": "[Connections] Added connection http://80.49.213.48:32400 for Plex",
"time": "2019-09-16T19:37:11.850Z"
"message": "[Connections] Testing connection for Plex at http://80.49.213.48:32400/media/providers",
"time": "2019-09-16T19:37:11.854Z"
"statusText": "timeout",
"time": "2019-09-16T19:37:11.865Z"
"message": "[Connections] Plex is unavailable at http://80.49.213.48:32400/media/providers (Status 0)",
"time": "2019-09-16T19:37:21.865Z"
and then there’s the
2019-09-16 21:17:35,107 (80764f000) : DEBUG (networking:166) - Requesting 'https://plugins.plex.tv/apps/all.json?frameworkVersion=2.6.3&machineIdentifier=6370c58fbed9223108d7e8ebf583c97970a97143&os=FreeBSD&osVersion=11.2-STABLE&cpu=i386&serverVersion=1.16.5.1554-1e5ff713d&systemRevision=1e5ff71'
2019-09-16 21:17:35,172 (80764f000) : INFO (storeservice:509) - Failed to fetch Plex Online data - the store probably won't work
2019-09-16 21:17:35,174 (80764f000) : CRITICAL (storeservice:510) - Error info (most recent call last):
... URLError: <urlopen error [Errno 65] No route to host>
and maybe some
Sep 16, 2019 21:17:31.386 [0x80d41c000] WARN - HTTP error requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) ()
Sep 16, 2019 21:17:31.386 [0x80d41c000] ERROR - Error parsing content.
Sep 16, 2019 21:17:31.386 [0x80d41c000] ERROR - Error parsing XML: Error parsing file.
Sep 16, 2019 21:17:31.391 [0x80d41c000] ERROR - Error issuing curl_easy_perform(handle): 7
Sep 16, 2019 21:17:31.391 [0x80d41c000] WARN - HTTP error requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) ()
Sep 16, 2019 21:17:31.391 [0x80d41c000] ERROR - Error parsing content.
Sep 16, 2019 21:17:31.391 [0x80d41c000] ERROR - Error parsing XML: Error parsing file.
Sep 16, 2019 21:17:34.905 [0x80c8aa100] ERROR - Error broadcasting message: send_to: No route to host
Sep 16, 2019 21:17:34.907 [0x80c8aa100] ERROR - Error broadcasting message: send_to: No route to host
Sep 16, 2019 21:17:34.955 [0x80d010900] ERROR - Error issuing curl_easy_perform(handle): 7
Well, some of the above errors don’t make any sense to me. Also other errors seem just temporary as later similar requests went ok later on.
Also this does not explain why iPhone app opens all video files without any problem but none of other players does (web, Android, desktop, Chromecast etc). That iPhone is connected to the same wifi network as the other players and faces no issues at all.
I couldn’t leave it like that so I’ve installed a fresh PLEX plugin on my FreeNAS and added a single library with videos pointing to the same files. And bingo it works! 
I’ll still try to move my plex db to see if it was the plugin/jail setup that was causing this issue or the db itself got corrupted. Will post back here.
Did your jail have a proper loopback address (127.0.0.1) ?
Since according to PMS logs, it never showed
And no database errors showed in the logs, btw
Well obviously this is missing. But I can’t figure out how to configure it…
And btw I’ve migrated the db from the faulty jail and seems that it works now and the migration is quite transparent (just IP address has changed of the PLEX server).
Sadly makes two of us, since jail and distro is unknown to me, but most likely the reason it failed though
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
