Remote access enabled, but no remote plays work anymore

Server Version#: 1.19.1.2645
Player Version#: Android 8.0.0.17276 (cef199a0)

For years I’ve been able to stream remotely. I have not been away from home for a while, but a family member who lives remotely was unable to stream anything last night. I have been debugging by turning off WiFi on my cellphone, and can reproduce the problem. I can see my server, all my libraries, etc, but no movies stream.

They don’t appear on my Dashboard, but I do see a Transcode process start (watching “top”) as soon as I hit play on the remote player. It’s not at 100% of my (very capable) CPU; again, this all worked before.Plex Media Server Logs_2020-05-03_10-38-00.txt (4.5 MB)

Remote access is fully enabled:

and my firewall is correctly configured
image

I trolled the logs (redacted version attached) but I really don’t understand the format at all. The one error that cropped up (I filtered the live logs) was this (not in this extract, but there’s a similar log line) - I don’t know if it’s a true error, however

TimeMay 03, 2020 10:59:56.671

LevelDebug

Thread0x81b069300

MessageJob running: EAE_ROOT='/tmp/pms-aa0d6acb-574f-451b-a674-6e521dc4ad21/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/Plex\ Media\ Server/Codecs/99c90e0-3095-freebsd-x86_64/' XDG_CACHE_HOME='/Plex Media Server/Cache' XDG_DATA_HOME='/usr/local/share/plexmediaserver-plexpass/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/local/share/plexmediaserver-plexpass/Plex Transcoder' '-codec:#0x01' 'h264' '-codec:#0x02' 'aac' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/media/Movies01/00-Music/PinkFloyd-LiveAtPompeii-Live.m4v' '-filter_complex' '[0:#0x01]scale=w=426:h=320[0];[0]format=pix_fmts=yuv420p|nv12[1]' '-filter_complex' '[0:#0x02] aresample=async=1:ocl='\''stereo'\'':osr=48000[2]' '-map' '[1]' '-codec:0' 'libx264' '-crf:0' '23' '-maxrate:0' '562k' '-bufsize:0' '1124k' '-r:0' '29.969000000000001' '-preset:0' 'veryfast' '-x264opts:0' 'subme=6:me_range=4:rc_lookahead=10:me=hex:8x8dct=1' '-force_key_frames:0' 'expr:gte(t,0+n_forced*3)' '-map' '[2]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '122k' '-segment_format' 'matroska' '-f' 'ssegment' '-individual_header_trailer' '0' '-flags' '+global_header' '-segment_format_options' 'output_ts_offset=10' '-segment_header_filename' 'header' '-segment_time' '3' '-segment_start_number' '0' '-segment_copyts' '1' '-segment_time_delta' '0.0625' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/0d7bab6f1eb92245-com-plexapp-android/9d5b2f78-413e-4aa7-81ed-61b5f8724f1c/seglist?X-Plex-Http-Pipeline=infinite' '-segment_list_type' 'csv' '-segment_list_size' '5' '-segment_list_separate_stream_times' '1' '-segment_list_unfinished' '1' '-max_delay' '5000000' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/0d7bab6f1eb92245-com-plexapp-android/9d5b2f78-413e-4aa7-81ed-61b5f8724f1c/progress'

I’ve rebooted my FreeNAS server, restarted the Plex plugin, jail, even rebooted my firewall. This all used to work perfectly, but I honestly don’t know the date on which it stopped.

Only local network streams are working.

I am pretty sure I was able to stream music earlier this morning, but now even that is failing.

Really appreciate any help.

I can now add that this has nothing to do with my firewall or network setup; I also have an nVidia Shield, that automatically acts as a PleX server; I am able to stream remotely from the Shield, but not from my PMS on my FreeNAS - so I have got some kind of config error, but I am completely lost as to where to look next.

Forum Admins; isn’t there someone here with some expertise? Logs posted, and all. Still down a week later :frowning:

So your actually able to connect remotely – you’ll see your libraries and can navigate around within a client. However when you try streaming, things go sideways.

Does it actually start playing? Any error messages?
The logs show a number of network errors. At some point, Plex seems to establish a relayed / indirect connection.

Have you already checked things within your home network? Issue doesn’t seem to be with the remote setup as such… seems to be more basic.

  • DNS
  • any firewalls / restrictions that can impact the communication of your PMS with the network’s router?

Thanks for looking at it Tom; local plays from the FreeNAS hosted server are fine. Local and remote plays from the Shield server are fine.

One thing I suspected that might have been at play, had been the addition of a second NIC to eh FeeNAS server, but I disabled the interface entirely without a change.

Because I don’t understand the log format, can you point me at some timestamps where you saw the network errors? Is this one example?

May 03, 2020 10:05:57.177 [0x81d6eb000] DEBUG - NAT: PMP, attempting mapping.
May 03, 2020 10:05:57.177 [0x81d6eb000] ERROR - NAT: PMP, map, couldn't initialize: -3:Unable to get Gateway
May 03, 2020 10:05:57.177 [0x81d6eb000] DEBUG - NAT: UPnP, attempting port mapping.
May 03, 2020 10:05:57.177 [0x817aeb700] DEBUG - NetworkInterface: PF_ROUTE socket received message. Version 5, Type 0x4, Len 184.
May 03, 2020 10:05:57.486 [0x818a3b700] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
May 03, 2020 10:05:57.486 [0x818a3b700] ERROR - NAT: PMP::getPublicIP, couldn't initialize: -3:Unable to get Gateway
May 03, 2020 10:05:57.486 [0x817aeb700] DEBUG - NetworkInterface: PF_ROUTE socket received message. Version 5, Type 0x4, Len 184.
May 03, 2020 10:06:09.182 [0x81d6eb000] DEBUG - NAT: UPnP, didn't find usable IGD.
May 03, 2020 10:06:09.183 [0x81d6eb000] DEBUG - PublicAddressManager: Mapping failed.
May 03, 2020 10:06:09.183 [0x81b24d800] DEBUG - Destroying public address manager

Any clues on how to debug further? I can open a shell in the jail and check things from there…

I can ping my gateway, external IPs etc

tera% sudo iocage console knox
...
Welcome to FreeBSD!
...
root@knox:~ # netstat -rn
Routing tables

Internet:
Destination        Gateway            Flags     Netif Expire
192.168.10.7       link#1             UHS         lo0

root@knox:~ # ping 192.168.10.1
PING 192.168.10.1 (192.168.10.1): 56 data bytes
64 bytes from 192.168.10.1: icmp_seq=0 ttl=64 time=0.379 ms
64 bytes from 192.168.10.1: icmp_seq=1 ttl=64 time=0.345 ms
^C
--- 192.168.10.1 ping statistics ---
2 packets transmitted, 2 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 0.345/0.362/0.379/0.017 ms
root@knox:~ # 

root@knox:~ # ping 8.8.8.8
PING 8.8.8.8 (8.8.8.8): 56 data bytes
64 bytes from 8.8.8.8: icmp_seq=0 ttl=54 time=11.546 ms
64 bytes from 8.8.8.8: icmp_seq=1 ttl=54 time=10.290 ms
^C
--- 8.8.8.8 ping statistics ---
2 packets transmitted, 2 packets received, 0.0% packet loss
round-trip min/avg/max/stddev = 10.290/10.918/11.546/0.628 ms
root@knox:~ #

root@knox:~ # ifconfig -a
igb0: flags=8943<UP,BROADCAST,RUNNING,PROMISC,SIMPLEX,MULTICAST> metric 0 mtu 1500
	description: igb0
	options=2400b9<RXCSUM,VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,VLAN_HWTSO,RXCSUM_IPV6>
	ether 0c:c4:7a:e6:bc:c4
	hwaddr 0c:c4:7a:e6:bc:c4
	inet 192.168.10.7 netmask 0xffffff00 broadcast 192.168.10.255
	media: Ethernet autoselect (1000baseT <full-duplex>)
	status: active
igb1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
	description: member of lagg0
	options=6403bb<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,TSO4,TSO6,VLAN_HWTSO,RXCSUM_IPV6,TXCSUM_IPV6>
	ether 0c:c4:7a:e6:bc:c5
	hwaddr 0c:c4:7a:e6:bc:c5
	media: Ethernet autoselect (1000baseT <full-duplex>)
	status: active
bxe0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 9000
	description: 10G-2
	options=527bb<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,TSO4,TSO6,LRO,WOL_MAGIC,VLAN_HWFILTER,VLAN_HWTSO>
	ether 00:10:18:ce:02:84
	hwaddr 00:10:18:ce:02:84
	media: Ethernet autoselect (none)
	status: no carrier
bxe1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 9000
	description: 10G
	options=527bb<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,TSO4,TSO6,LRO,WOL_MAGIC,VLAN_HWFILTER,VLAN_HWTSO>
	ether 00:10:18:ce:02:86
	hwaddr 00:10:18:ce:02:86
	media: Ethernet autoselect (none)
	status: no carrier
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
	options=680003<RXCSUM,TXCSUM,LINKSTATE,RXCSUM_IPV6,TXCSUM_IPV6>
	groups: lo
lagg0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
	description: lagg0
	options=6403bb<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,TSO4,TSO6,VLAN_HWTSO,RXCSUM_IPV6,TXCSUM_IPV6>
	ether 0c:c4:7a:e6:bc:c5
	media: Ethernet autoselect
	status: active
	groups: lagg
	laggproto lacp lagghash l2,l3,l4
	laggport: igb1 flags=1c<ACTIVE,COLLECTING,DISTRIBUTING>
bridge0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
	ether 02:d6:e2:e7:ca:00
	groups: bridge
	id 00:00:00:00:00:00 priority 32768 hellotime 2 fwddelay 15
	maxage 20 holdcnt 6 proto rstp maxaddr 2000 timeout 1200
	root id 00:00:00:00:00:00 priority 32768 ifcost 0 port 0
	member: vnet0.3 flags=143<LEARNING,DISCOVER,AUTOEDGE,AUTOPTP>
	        ifmaxaddr 0 port 9 priority 128 path cost 2000
	member: vnet0.1 flags=143<LEARNING,DISCOVER,AUTOEDGE,AUTOPTP>
	        ifmaxaddr 0 port 8 priority 128 path cost 2000
	member: igb0 flags=143<LEARNING,DISCOVER,AUTOEDGE,AUTOPTP>
	        ifmaxaddr 0 port 1 priority 128 path cost 20000
vnet0.1: flags=8943<UP,BROADCAST,RUNNING,PROMISC,SIMPLEX,MULTICAST> metric 0 mtu 1500
	description: associated with jail: OpenVPN as nic: epair0b
	options=8<VLAN_MTU>
	ether 0c:c4:7a:9b:c8:7f
	hwaddr 02:92:d0:00:08:0a
	media: Ethernet 10Gbase-T (10Gbase-T <full-duplex>)
	status: active
	groups: epair
vnet0.3: flags=8943<UP,BROADCAST,RUNNING,PROMISC,SIMPLEX,MULTICAST> metric 0 mtu 1500
	description: associated with jail: tautulli as nic: epair0b
	options=8<VLAN_MTU>
	ether 0c:c4:7a:46:b0:16
	hwaddr 02:92:d0:00:09:0a
	media: Ethernet 10Gbase-T (10Gbase-T <full-duplex>)
	status: active
	groups: epair
root@knox:~ # 

I can run other commands as needed; eg DNS appears to work from inside the jail

root@knox:~ # host google.com
google.com has address 172.217.5.110
google.com has IPv6 address 2607:f8b0:4005:802::200e
google.com mail is handled by 10 aspmx.l.google.com.
google.com mail is handled by 40 alt3.aspmx.l.google.com.
google.com mail is handled by 50 alt4.aspmx.l.google.com.
google.com mail is handled by 30 alt2.aspmx.l.google.com.
google.com mail is handled by 20 alt1.aspmx.l.google.com.
root@knox:~ # 

Thanks for any more assistance.

I’m totally stumped :frowning:

Kuyper

in regards to this; at the time that I took this log, I’m pretty sure I was not using UPnP - I was specifying port 32400 externally (I also tried 32401; in fact, I used 32401 to point at my Shield, and then found that to be working; since then, I’ve flipped my Shield to UPnP; and I’ve also tried the FreeNAS PMS on UPnP - still no go…

My PMS is able to get out and see the PleX servers:

May 03, 2020 10:10:17.425 [0x81f0f7000] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 03, 2020 10:10:17.425 [0x81f0f7000] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 73.170.xx.yy
May 03, 2020 10:10:17.534 [0x818ceb500] DEBUG - It took 1210.937500 ms to retrieve 357 items.

I’ve just rebooted my FreeNAS Server and completely removed the dual 10G NIC in case that was an issue; no change; As for remote traffic; there is plenty, but it’s only when I connect from (say) my cellphone; there’s a burst of data as it populates the list of videos, and sends it to my phone (6Mibps is my outbound capacity) but nothing streams; CPU shoots up in response to attempt to transcode, but never sends any video, and dash shows “nothing playing”:

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