What did I do to mess up plex?

Status
Not open for further replies.
Roydub

Roydub

Dabbler
Joined
May 24, 2018
Messages
15
Plex no longer applies metadata to media files on the server. I know the issue is a networking problem but I just cant figure it out. It started last week when I started tinkering with pfsense on my home network. I no longer have the PFsense machine plugged into the network but it seems that something has changed. I used to have Plex set as a static IP but now when I set that up and try to access the WebUI it just times out. Setting Plex on DHCP allows everything to work (can ping the server, can ping google, can play movies over the network) I just get no metadata applied to the movies. As a side note, if I go into edit the movies and manually add poster art and backgrounds they are actually downloaded, the artwork just isnt being applied to the movies automatically the way it should. When I look at Plex system logs it says failed to determine system locale, and it shows 2 IPs that ive never set up for anything, 192.168.0.13 and 192.168.0.78, I just don't understand why or whats going on. I have tried uninstalling and reinstalling several times using both static and DHCP but nothing works.

Sorry for the long explanation and thankyou for any help

FreeNAS 11.2-RC1
Plex 11.2-RELEASE-p4 (3.67.1)

Network Setup:
FreeNAS: 192.168.0.21
Plex: DHCP (192.168.0.85)

Router DHCP Range: 192.168.0.10 - 192.168.0.75

IFCONFIG
Code:
root@plex:~ # ifconfig
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
		options=600003<RXCSUM,TXCSUM,RXCSUM_IPV6,TXCSUM_IPV6>
		inet6 ::1 prefixlen 128
		inet6 fe80::1%lo0 prefixlen 64 scopeid 0x1
		inet 127.0.0.1 netmask 0xff000000
		nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
		groups: lo
epair0b: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
		options=8<VLAN_MTU>
		ether 02:ff:60:14:fa:0a
		hwaddr 02:fa:d0:00:08:0b
		inet 192.168.0.85 netmask 0xffffff00 broadcast 192.168.0.255
		nd6 options=1<PERFORMNUD>
		media: Ethernet 10Gbase-T (10Gbase-T <full-duplex>)
		status: active
		groups: epair


PLEX LOG
Code:
Oct 31, 2018 10:54:58.700 [0x80a6cb000] INFO - Plex Media Server v1.13.8.5395-10d48da0d - FreeBSD PC amd64 - build: freebsd-x86_64 freebsd - GMT -04:00
Oct 31, 2018 10:54:58.700 [0x80a6cb000] ERROR - Failed to determine system locale: locale::facet::_S_create_c_locale name not valid. Defaulting to en-US.
Oct 31, 2018 10:54:58.700 [0x80a6cb000] INFO - FreeBSD version: 11.2-STABLE (FreeBSD 11.2-STABLE #0 r325575+97f4f541349(freenas/11.2-stable): Wed Oct 17 18:06:49 EDT 2018	 root@nemesis.tn.ixsystems.com:/freenas-11.2-releng/freenas/_BE/objs/freenas-11.2-releng/freenas/_BE/os/sys/FreeNAS.amd64), language: en-US
Oct 31, 2018 10:54:58.700 [0x80a6cb000] INFO - Processor	   Intel(R) Xeon(R) CPU E5-1650 v2 @ 3.50GHz
Oct 31, 2018 10:54:58.700 [0x80a6cb000] INFO - /usr/local/share/plexmediaserver/Plex_Media_Server
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - HTTP requesting GET http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80afffb00] ERROR - Error issuing curl_easy_perform(handle): 7
Oct 31, 2018 10:54:58.700 [0x80afffb00] WARN - HTTP error requesting GET http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml (0, No error) (Failed to connect to 192.168.0.13: Host is down)
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - NetworkServiceBrowser: found 0 SSDP devices via http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - HTTP requesting GET http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80afffb00] ERROR - Error issuing curl_easy_perform(handle): 7
Oct 31, 2018 10:54:58.700 [0x80afffb00] WARN - HTTP error requesting GET http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml (0, No error) (Failed to connect to 192.168.0.13: Host is down)
Oct 31, 2018 10:54:58.700 [0x80afffb00] DEBUG - NetworkServiceBrowser: found 0 SSDP devices via http://192.168.0.13:1990/9540efb4-6b0e-4464-a70e-6e85ddbd7a2a/WFADevice.xml
Oct 31, 2018 10:54:58.700 [0x80f8ba800] DEBUG - Streaming Resource: Usage: 0kbps of WAN bandwidth, 0 streaming transcode slots, and 0 static transcode slots across 0 sessions
Oct 31, 2018 10:54:59.521 [0x80af0e100] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.522 [0x80c89b700] DEBUG - Request: [192.168.0.78:60798 (Subnet)] GET /music/iTunes (7 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.522 [0x80af0e100] DEBUG - Completed: [192.168.0.78:60798] 404 GET /music/iTunes (7 live) TLS GZIP 0ms 452 bytes (pipelined: 11)
Oct 31, 2018 10:54:59.525 [0x80af0e600] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.526 [0x80c89b700] DEBUG - Request: [192.168.0.78:60804 (Subnet)] GET /library/sections (7 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.527 [0x80af0e600] DEBUG - Completed: [192.168.0.78:60804] 200 GET /library/sections (7 live) TLS GZIP 1ms 887 bytes (pipelined: 11)
Oct 31, 2018 10:54:59.535 [0x80af0e100] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.535 [0x80b840500] DEBUG - Request: [192.168.0.78:60798 (Subnet)] GET /media/providers (7 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.537 [0x80af0e100] DEBUG - Completed: [192.168.0.78:60798] 200 GET /media/providers (7 live) TLS GZIP 1ms 926 bytes (pipelined: 12)
Oct 31, 2018 10:54:59.544 [0x80af0e100] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.545 [0x80b840500] DEBUG - Request: [192.168.0.78:60804 (Subnet)] GET /library/metadata/62?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (8 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.547 [0x80af0e600] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.547 [0x80c89b700] DEBUG - Request: [192.168.0.78:60798 (Subnet)] GET /hubs/metadata/62/related?excludeFields=summary&count=12 (8 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.547 [0x80b840500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Oct 31, 2018 10:54:59.547 [0x80b840500] DEBUG - Selecting best audio stream for part ID 62 (autoselect: 0 language: en)
Oct 31, 2018 10:54:59.547 [0x80b840500] DEBUG - Audio Stream: 191, Subtitle Stream: -1
Oct 31, 2018 10:54:59.548 [0x80b840500] DEBUG - Activity: registered new activity 12fd6d80-2a46-4d20-8fdb-86ed50331613 - Refreshing
Oct 31, 2018 10:54:59.548 [0x80f8ba800] DEBUG - Activity: updated activity 12fd6d80-2a46-4d20-8fdb-86ed50331613 - completed 0% - Refreshing
Oct 31, 2018 10:54:59.548 [0x80f8ba800] ERROR - Error creating directory "/usr/local/plexdata/Plex Media Server/Media/localhost/b": boost::filesystem::create_directories: Permission denied: "/usr/local/plexdata/Plex Media Server/Media/localhost"
Oct 31, 2018 10:54:59.548 [0x80f8ba800] ERROR - Error creating directory "/usr/local/plexdata/Plex Media Server/Media/localhost/b/587333188a739cb55aae500923e6169ed93edd4.bundle/Contents": boost::filesystem::create_directories: Permission denied: "/usr/local/plexdata/Plex Media Server/Media/localhost/b/587333188a739cb55aae500923e6169ed93edd4.bundle"
Oct 31, 2018 10:54:59.549 [0x80af0e600] DEBUG - Completed: [192.168.0.78:60804] 200 GET /library/metadata/62?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (8 live) TLS GZIP 4ms 1302 bytes (pipelined: 12)
Oct 31, 2018 10:54:59.549 [0x80f8ba800] ERROR - Error creating directory "/usr/local/plexdata/Plex Media Server/Media/localhost/b": boost::filesystem::create_directories: Permission denied: "/usr/local/plexdata/Plex Media Server/Media/localhost"
Oct 31, 2018 10:54:59.549 [0x80f8ba800] ERROR - Error creating directory "/usr/local/plexdata/Plex Media Server/Media/localhost/b/587333188a739cb55aae500923e6169ed93edd4.bundle/Contents": boost::filesystem::create_directories: Permission denied: "/usr/local/plexdata/Plex Media Server/Media/localhost/b/587333188a739cb55aae500923e6169ed93edd4.bundle"
Oct 31, 2018 10:54:59.551 [0x80af0e600] DEBUG - Completed: [192.168.0.78:60798] 200 GET /hubs/metadata/62/related?excludeFields=summary&count=12 (8 live) TLS GZIP 4ms 544 bytes (pipelined: 13)
Oct 31, 2018 10:54:59.586 [0x80f8ba800] DEBUG - Updating part with ID=62 [/usr/local/plexdata/Plex Media Server/Media/Movies/Movies/Action/Hunters Prayer (2017).avi]
Oct 31, 2018 10:54:59.587 [0x80f8ba800] DEBUG - Activity: updated activity 12fd6d80-2a46-4d20-8fdb-86ed50331613 - completed 33% - Refreshing
Oct 31, 2018 10:54:59.587 [0x80f8ba800] DEBUG - Activity: updated activity 12fd6d80-2a46-4d20-8fdb-86ed50331613 - completed 66% - Refreshing
Oct 31, 2018 10:54:59.587 [0x80f8ba800] DEBUG - Activity: Ended activity 12fd6d80-2a46-4d20-8fdb-86ed50331613.
Oct 31, 2018 10:54:59.650 [0x80af0e600] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.650 [0x80d869000] DEBUG - Request: [192.168.0.78:60804 (Subnet)] GET /photo/:/transcode?width=160&height=240&minSize=1&url=%2Flibrary%2Fmetadata%2F62%2Fthumb%2F1540935300%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Roydub)
Oct 31, 2018 10:54:59.651 [0x80d869000] DEBUG - Photo transcoder: Request for url [/library/metadata/62/thumb/1540935300?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Oct 31, 2018 10:54:59.651 [0x80d869000] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.652 [0x80d869000] DEBUG - Calculated media file path for item 62: "/usr/local/plexdata/Plex Media Server/Media/localhost/b/587333188a739cb55aae500923e6169ed93edd4.bundle/Contents/Thumbnails/thumb1.jpg"
Oct 31, 2018 10:54:59.652 [0x80d869000] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 404)
Oct 31, 2018 10:54:59.653 [0x80af0e600] DEBUG - Completed: [192.168.0.78:60804] 404 GET /photo/:/transcode?width=160&height=240&minSize=1&url=%2Flibrary%2Fmetadata%2F62%2Fthumb%2F1540935300%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 2ms 452 bytes (pipelined: 13)
Oct 31, 2018 10:54:59.661 [0x80af0e100] DEBUG - Auth: authenticated user 1 as Roydub
Oct 31, 2018 10:54:59.661 [0x80d869000] DEBUG - Request: [192.168.0.78:60798 (Subnet)] GET /photo/:/transcode?width=640&height=538&opacity=30&background=36383b&format=png&blur=66&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F62%2Fthumb%2F1540935300%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Roydub)
 
kdragon75

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
look at the error lines. Thats your hint.
 
Roydub

Roydub

Dabbler
Joined
May 24, 2018
Messages
15
Ya I see it. Its requesting information from the wrong host right? I just have no idea what to do about it. I don't even know where its pulling the IPs 192.168.0.13 and 192.168.0.78 from?
 
kdragon75

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
Roydub said:
Oct 31, 2018 10:54:59.548 [0x80f8ba800] ERROR - Error creating directory "/usr/local/plexdata/Plex Media Server/Media/localhost/b": boost::filesystem::create_directories: Permission denied: "/usr/local/plexdata/Plex Media Server/Media/localhost"
Click to expand...
 
kdragon75

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
The plex user needs write permission to the folder where the metadata and covers get saved. That's it.
Try the following from inside the plex jail:
chmod -R 755 /usr/local/plexdata/Plex\ Media\ Server
chown -R plex:plex /usr/local/plexdata/Plex\ Media\ Server
 
Roydub

Roydub

Dabbler
Joined
May 24, 2018
Messages
15
Thank you for your help!

The first line:
Code:
chmod -R 755 /usr/local/plexdata/Plex\ Media\ Server

wasn't working as I was getting permission denied errors so I tried just the metadata folder with:
Code:
chmod -R 755 /usr/local/plexdata/Plex\ Media\ Server/Metadata

and it worked perfectly.

With that resolved does this cover why I was also running into an issue trying to get it working on a static IP? or will I need to check the logs when/if I try that?

Thank you again for your help!
 
kdragon75

kdragon75

Wizard
Joined
Aug 7, 2016
Messages
2,457
Roydub said:
Thank you for your help!

The first line:
Code:
chmod -R 755 /usr/local/plexdata/Plex\ Media\ Server

wasn't working as I was getting permission denied errors so I tried just the metadata folder with:
Code:
chmod -R 755 /usr/local/plexdata/Plex\ Media\ Server/Metadata

and it worked perfectly.

With that resolved does this cover why I was also running into an issue trying to get it working on a static IP? or will I need to check the logs when/if I try that?

Thank you again for your help!
Click to expand...
Tell me more about the static IP and how younpla to use it.
 
Status
Not open for further replies.

Important Announcement for the TrueNAS Community.

The TrueNAS Community has now been moved. This forum will now become READ-ONLY for historical purposes. Please feel free to join us on the new TrueNAS Community Forums.

Related topics on forums.truenas.com for thread: "What did I do to mess up plex?"

Unfortunately, no related topics are found on the New Community Forums.

Similar threads

tofagerl
  • Locked
Can't transcode in plex for some reason
Replies
6
Views
9K
tofagerl
tofagerl
C
Plex playback locked up, service won't start
Replies
10
Views
977
Causation9768
C
itskando
plex doesn't work
2
Replies
32
Views
16K
Stephane-Geek
Stephane-Geek
R
Plex - Playback Error
Replies
6
Views
6K
robert88
R
Magnus Svensson
  • Locked
Plex fails to start up.
Replies
6
Views
4K
nightshade00013
nightshade00013
Top