Remote access failure

Logs shows a number of connectivity tests carried out and none of them got a remote request to the Plex Media Server

These were the last failed connectivity tests

Apr 14, 2020 17:15:38.045 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="d31300a6-3444-4043-a5de-bc8e8ab22bdd" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:15:40.196 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="aeb61c39-a922-43d7-97d1-3b7b2b4497b7" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:16:54.052 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="c3f5be17-9646-458e-a1dd-a38d1f32e1f1" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:17:01.088 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="f917bae2-b5f8-4afd-9c78-eaadce9ab192" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:17:26.357 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="5536011a-766e-4483-a9fd-a130c6a6209b" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:17:54.371 [3120] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="5e09f9cc-e8fb-445f-a2cc-208b84a40d76" connectivity="0" command="notifyConnectivity"/>'
Apr 14, 2020 17:18:02.852 [4448] DEBUG - EventSource: Got event [data] '<Message address="1.159.xxx.xxx" port="32400" asyncIdentifier="0c6b8f24-7c73-492a-9af4-acf8941025b1" connectivity="0" command="notifyConnectivity"/>'

As this windows, have you checked to see if the network adaptor switched to being a “Public Network” or “Unknown Network” - that happens sometimes on windows and would need user intervention to set it back to “Private Network”

Also if you are still having the problem, see if the port forward to 192.168.0.156 for wan port 32400 is still in place and forwarding to local port 32400

You could test the port forward (whilst plex media server is running) by trying http://1.159.xxx.xxx:32400/web in browser from outside the local network

What firewall do you have? Any additional security product?

Thanks - I turned on Windows Firewall again (turned it off before) and i can see the Plex server while at work. As soon as i turned it back on it become visible? Network settings were set to Private.

Also, one of my kids is watching a movie from another room and after 40mins it seems fine.

However, it still is showing that it is not accessible remotely - but it all is working?

This indicator is based on the results of the connectivity tests - previous evidence showed none of them were reaching the server.

Does the firewall have any specific rules that may blocking some remote IP addresses ?

If I read this right and the URL works from my device connects on cellular, it means the red exclamation is a bug then? I was working well when using my Synology NAS as a server last week, but I recently fired up a older PC (Win 7) to handle the TV turner performance and I’ve had this error from the moment I installed. The Commercial Skip app is also crashing, but that’s for another message thread. For the record, I have had access without issue once I setup proper port forwarding to the correct device on my firewall. I just have a red exclamation point.

No nothing i can think of - it has been working fine again - but still giving me the red light…

So i guess i just keep going!

Unlike @jmosky my logs are showing successful connectivity tests, followed pretty quickly by unsuccessful tests which correspond to when the remote access indicator changes from green to red.

I can also access my plex server remotely, the remote access indicator just shows an incorrect status

Apr 19, 2020 15:55:34.365 [0x7fc55e1ff700] INFO - Plex Media Server v1.19.1.2645-ccb6eb67e - Docker Docker Container x86_64 - build: linux-x86_64 debian - GMT 10:00
Apr 19, 2020 15:56:20.564 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="5e643fb2-e789-452f-b3c4-e85bed008fb6" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:56:27.836 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="4c45a4a4-42e4-4606-8ebb-c56cd484d32f" connectivity="1" command="notifyConnectivity"/>'
Apr 19, 2020 15:56:29.519 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="312c1ea7-9c00-4def-a7ce-007a7017685c" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:56:38.961 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="8a15b437-5d00-4686-b806-96208ce03834" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:57:39.357 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="1fa6ee09-767f-4b7f-9a50-21e5a6264fee" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:57:41.853 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="bbdb0c2f-dc19-4cda-bb7a-fa0be1db93ea" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:57:51.838 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="7e3fca94-e5c4-4627-bc78-ba634d0e9416" connectivity="1" command="notifyConnectivity"/>'
Apr 19, 2020 15:59:02.647 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="b15244d2-e095-422e-b1be-ba6bbf289e60" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 15:59:07.417 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="6af5e0e4-c3d8-4fea-b236-8e664dffb3ab" connectivity="1" command="notifyConnectivity"/>'
Apr 19, 2020 15:59:55.090 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="01013429-42e6-467e-9d1e-2ebd9953b9e9" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:00:49.353 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="f694958f-3c74-4850-8b8c-8338b125cc78" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:00:58.518 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="fcc3e3b2-0ccf-47f8-8878-f7ba78faf875" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:01:31.647 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="be3119c3-58dd-4d93-9bcd-fd1d3f14a061" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:02:20.519 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="cd4bbb38-59b0-4b74-86d2-8315a1cd2911" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:02:29.604 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="8bf6b989-3c1c-4e34-967d-24a9c3174fe8" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:02:50.672 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="0f9ba10f-0f9e-4416-a46d-c3e5a359dd87" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:02:52.480 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="f1c92f91-915d-4d4b-bf3f-f2aa9827db60" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:03:05.915 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="f17d198c-c64e-4afd-8a84-a6ae6267a062" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:03:12.585 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="d8749df5-b4ce-4e3f-9978-af3af22e1ef7" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:03:24.540 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="8861503d-d790-473b-a23c-57462502b1b9" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:03:24.993 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="c9a05c4c-551c-4f2a-b8cb-827f63bfffa2" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:03:34.525 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="a5208eb0-37da-498d-944b-2b7ed7a627eb" connectivity="1" command="notifyConnectivity"/>'
Apr 19, 2020 16:05:04.909 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="328e8707-dcb3-4fe3-8fb0-9db1d55ac9ea" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:05:21.530 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="6ddfcb3b-4745-4dcb-8b84-685e74faf038" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:05:55.591 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="9959c2fe-e160-48b3-bd39-2970c292e053" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:05:57.105 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="9e388e52-3ffb-467b-8919-ae6996edc123" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:10.219 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="2ace60b5-17da-4bd4-b86c-c8229d584338" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:19.225 [0x7fc554805700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="6476744c-032a-4a06-a536-3e02b1493210" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:24.310 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="51344b54-9377-469d-8500-b029d286e4da" connectivity="1" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:28.747 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="ea5bd169-7a52-4761-997f-2bbda1930bc1" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:45.755 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="e868e139-e0dc-499a-9413-b946681bb503" connectivity="0" command="notifyConnectivity"/>'
Apr 19, 2020 16:06:56.628 [0x7fc555006700] DEBUG - EventSource: Got event [data] '<Message address="X.X.X.X" port="32400" asyncIdentifier="d603ed3a-08d4-4816-97ea-a2c7f44ad4f0" connectivity="0" command="notifyConnectivity"/>'

Yes we are losing connectivity test requests and that is when you see the connectivity="0" events - I have referred this to our plex.tv team to look into possible loss of requests from the back end system. If there is any router / firewall log that confirm these requests are not coming through when others are then that would help.

Need to establish if there is evidence of any overload at the back end test systems - the full log would show me if there are any delays in receiving the events and if any rate limiting on tests is reached

Due to the way my firewall is configured, I’m only able to log passed packets and not rejected or blocked packets. However, comparing the timestamps of the notifyConnectivity message with the firewall time stamps it appears that both the connectivity="0" and connectivity="1" appear to be getting passed through to PMS.

I also noticed that the sometimes the Remote Access toggle swtich would changed from enabled to disabled and I would need to reactivate it again. It dropped out and changed to relay while I was doing the test. Hopefully that shows up in the logs.

Have sent logs via PM to you.

Thanks for the logs and also the firewall log which is very useful - but the number of requests in the firewall log do not match what is in the server log.

The server log shows 240 WAN requests coming in during the period but the firewall log only has 67

I need to match requests

In the last server log in the zip which covers period:
Apr 19, 2020 21:29:06 to Apr 19, 2020 21:41:00
The firewall log covers:
Apr 19 21:29:33 to Apr 19 21:40:55

And I would like to establish somehow if you have a duplicate IP on the network. Could you explore that - the IP of interest being 192.168.2.230

There should be network tools available to detect that.
I can see requests in the firewall log but not in the server log (and vice versa - but that is different)

And one more thing - please leave some time between disabling/re-enabling remote access and then repeating it again, so that the diagnostics can be clear - there are time delays and it would be good if you leave remote access enabled for a number of minutes before disabling/re-enabling or clicking on reconnect.

You can see from here that the only good period I have for diagnostics is between 21:32:58 and 21:35:26 where we have a few minutes to look at.

Date Time - Disabled Time - Re-enabled
Apr 19, 2020 21:30:08.420 21:30:09.862
Apr 19, 2020 21:30:40.003 21:30:41.450
Apr 19, 2020 21:31:15.295 21:31:16.824
Apr 19, 2020 21:31:57.087 21:31:58.546
Apr 19, 2020 21:32:46.397 21:32:58.315
Apr 19, 2020 21:35:26.156 21:35:27.675
Apr 19, 2020 21:36:29.738 21:40:08.922
Apr 19, 2020 21:40:45.029 21:40:46.557

A reconnect would be like disable/re-enable

Have checked the ARP table on my managed switch and firewall. Have also checked networking configuration on my docker host. There are no duplicate entries.

I belive the firewall is only logging the initial connection not each packet. I’ve run a packet capture on all WAN traffic with prt 32400 and will send that to you togther with the PMS logs.

I was not manually disabling then renabling remote access. I enabled it once, and then PMS was changing the status on it’s own. The only time I enabled it manually was at the start and then when it disabled on its own.

Playing around with it this morning, the remote access status stays red when I enable it from a locally based browser (i.e. a PC in my LAN(. If I then use a remote web browser to access PMS (in this instance my mobile phone), the remote status will switch to green. Sometimes I need to use the manual retry button next to the port setting from the remote browser. Provided I’m accessing PMS through my remote browser the status stays green. If I then access PMS through my local browser the status will switch to red again. I also noticed if I used the Plex app on my Android mobile, the status in the remote browser would switch to indirect, and remote access was showing as disabled rather than just not available. This also began to happen with my remote browser as well. The change is status also happened when I used the Plex appp on my local PC.

So the takeaway point for me is that the remote access status seems to change depending on whether I’m access my PMS locally or remotely.

I’ll forward the detailed logs to you via PM shortly.

Thanks

At the moment from what I have seen, this is the only feasible explanation. some of the requests getting routed to a different host from the port forward. I do not expect there to be a Plex Media Server bug where we would lose tcp requests coming in. Could you also send me your docker configuration file.

It should not do it iself - were you perhaps clicking on the “RETRY” button on the manually specify public port line in remote access advanced settings ? The “RETRY” simulates a disable/re-enable.

I’ve forwarded the information. My docker is running plex using ipvlan rather than bridged networking so it should be exposed directly to my LAN subnet rather than relying on a port forward accross the docker bridge. Docker is running on CentOS which is within a VM on a physical Hyper-V host.

In some instances I was clicking retry when it changed, in others it changed status or disabled by itself without my manual intervention. I’ve just sent you detailed logs via PM with the logs, firewall packet capture and list of actions that I took. These were through a web browser via plex.tv/web:

  • 7:24 Started plex - Remote access status red
  • 7:33 Attempted Retry buttom from a web brower on my LAN - Remote access status remained red
  • 7:37 Connected from brower on my mobile via WAN interface to PMS. Went to remote access screen and status changed green (did not select retry or change any other configuration settings
  • 7:43 On my LAN broswer changed from remote access screen and back to PMS home screen – remote access status changed to red.
  • 7:45 Connected from mobile broswer to WAN - Remote access stayed red
  • 7:46 Attempted Retry buttom from mobile web brower on WAN – Status went green
  • 7:50 On my Lan broswer changed from remote access screen and back to home – remote access status changed to red.
  • 8:00 Used Plex App on mobile, noticed that connection type on my mobile brower connecting from WAN changed to indirect
  • 8:02 Retried connection from mobile browser on WAN, no connection available
  • 8:03 Checked status from browser on LAN and remote access has been disabled (I did not disable it manually).
  • 8:08 Enabled Remote Access again from LAN browser – Remote access status remained red
  • 8:14 Re-entered plex.tv/web in my mobile brower and it reconnected from WAN. Connection status switched from Indirect to Remote, but still shows remote access as red.
  • 8:15 Navigated on mobile browser on WAN to PMS home then back to remote access configuration screen and status changed to green.
  • 8:20 Navigated on mobile browser on WAN to PMS home then back to remote access configuration screen and status stayed green.
  • 8:23 Started Plex Windows App on same local PC - No immediate change in remote access status, but then changed to red shortly after.
  • 8:29 From browser on LAN hit retry for remote access - Status changed to green
  • 8:43 From mobile browser on WAN accessed PMS - Status changed to red
  • 8:49 From browser on LAN hit retry for remote access - Status remained red
  • 8:53 From mobile broawed on WAN hit retry for remote access – Remote access status red with PMS connection status now showing as indirect for the mobile browser
  • 8:57 Re-entered plex.tv/web in my mobile brower and it reconnected from WAN. Connection status switched from Indirect to Remote, but still shows remote access as red.
  • 9:08 From mobile broawed on WAN hit retry for remote access – Remote access status red

The actual network packet capture pcap file with the server logs was very useful. We are not losing connectivity test requests but some are aborting during TLS 1.2 dialogue with the server. I have referred this to our plex.tv team. Thank you for all then diagnostics and I will let you know if we need any more tests

Thanks

1 Like

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