PMS v1.13.8.5395 and Docker Woes - Essentially non functional

Plex Media Server NAS & Devices
1

I’ve tried both the linuxserver.io and Plex images, and both pull this version down. Is there a way to downgrade and NOT update to this version? There’s not a lot of errors in these debug logs, and one might think that it’s no big deal, but Plex loses connectivity routinely enough that nothing actually works.

Server Version#: Version 1.13.8.5395
Player Version#: Version 3.67.1

Sep 27, 2018 02:05:57.265 [0x7f707e3ff700] DEBUG - Jobs: Starting child process with pid 1395
Sep 27, 2018 02:06:04.559 [0x7f709e7fd700] DEBUG - NAT: UPnP, not an IGD: <http://192.168.20.5:5000/ssdp/desc-DSM-bond1.xml>.
Sep 27, 2018 02:06:04.559 [0x7f709e7fd700] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
Sep 27, 2018 02:06:14.562 [0x7f709e7fd700] WARN - NAT: PMP, timed out waiting for response.
Sep 27, 2018 02:06:14.562 [0x7f70a2fff700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 27, 2018 02:06:14.562 [0x7f709f7ff700] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 27, 2018 02:06:14.562 [0x7f70a2fff700] DEBUG - EventSource: Successfully connected to 66.228.52.149.
Sep 27, 2018 02:06:14.562 [0x7f709f7ff700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Sep 27, 2018 02:06:14.562 [0x7f70a2fff700] DEBUG - EventSource: Failure in IdleTimeout (0 - Success).
Sep 27, 2018 02:06:14.562 [0x7f70a2fff700] DEBUG - MyPlex: We appear to have lost Internet connectivity, resetting device URL cache.
Sep 27, 2018 02:06:14.562 [0x7f70a2fff700] ERROR - EventSource: Retrying in 15 seconds.
Sep 27, 2018 02:06:14.565 [0x7f709e7fd700] DEBUG - MyPlex: Sending Server Info to myPlex (user=kars85@gmail.com, ip=, port=32400)
Sep 27, 2018 02:06:14.566 [0x7f709e7fd700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=4e1569e9-a55a-4c2d-967b-644eda930b35
Sep 27, 2018 02:06:15.222 [0x7f709e7fd700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=4e1569e9-a55a-4c2d-967b-644eda930b35
Sep 27, 2018 02:06:15.223 [0x7f709e7fd700] DEBUG - MyPlex: Published Mapping State response was 201
Sep 27, 2018 02:06:15.223 [0x7f709e7fd700] DEBUG - MyPlex: Got response for 4c1d95a2fcdbeacc9e401949638d8e8f79a3a96f ~ registered 173.17.63.8:32400
Sep 27, 2018 02:06:15.224 [0x7f709e7fd700] DEBUG - MyPlex: Updating device connections (from timer: 0)
Sep 27, 2018 02:06:15.224 [0x7f709e7fd700] DEBUG - HTTP requesting PUT https://plex.tv/devices/4c1d95a2fcdbeacc9e401949638d8e8f79a3a96f?Connection[][uri]=http://192.168.1.49:32400&Connection[][uri]=http://172.17.0.1:32400&Connection[][uri]=http://172.18.0.1:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 27, 2018 02:06:15.386 [0x7f709f7ff700] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Sep 27, 2018 02:06:15.387 [0x7f709f7ff700] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 172.17.0.1
Sep 27, 2018 02:06:15.638 [0x7f70877fe700] DEBUG - Request: [34.244.6.8:58156 (WAN)] GET /identity (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:15.638 [0x7f70a2fff700] DEBUG - Completed: [34.244.6.8:58156] 200 GET /identity (5 live) TLS 1ms 357 bytes
Sep 27, 2018 02:06:15.867 [0x7f709e7fd700] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/4c1d95a2fcdbeacc9e401949638d8e8f79a3a96f?Connection[][uri]=http://192.168.1.49:32400&Connection[][uri]=http://172.17.0.1:32400&Connection[][uri]=http://172.18.0.1:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 27, 2018 02:06:22.035 [0x7f70a2fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:22.036 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58959 (Subnet)] GET /photo/:/transcode?height=2048&url=%2flibrary%2fmetadata%2f31909%2fart%2f1537343553&width=2048 (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:22.036 [0x7f707dbfe700] DEBUG - Photo transcoder: Request for url [/library/metadata/31909/art/1537343553] (is local: 1 upscaled: 0)
Sep 27, 2018 02:06:22.036 [0x7f707dbfe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:22.039 [0x7f707dbfe700] DEBUG - Calculated media file path for item 31909: "/config/Library/Application Support/Plex Media Server/Media/localhost/e/a9a7559ffedbd2265593e9853769f489919636f.bundle/Contents/Art/art3.jpg"
Sep 27, 2018 02:06:22.039 [0x7f707dbfe700] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Sep 27, 2018 02:06:22.040 [0x7f707dbfe700] DEBUG - Photo cache obtained 106836 bytes from /library/metadata/31909/art/1537343553
Sep 27, 2018 02:06:22.040 [0x7f707dbfe700] DEBUG - Saving original media file to /config/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/fa/fadaeea41f798cfccf969d5fd32f5dc287ad577e.jpg
Sep 27, 2018 02:06:22.057 [0x7f707dbfe700] DEBUG - Created thumbnail of size 1280x720, has pixels: 1
Sep 27, 2018 02:06:22.107 [0x7f70a27fe700] DEBUG - Completed: [192.168.1.157:58959] 206 GET /photo/:/transcode?height=2048&url=%2flibrary%2fmetadata%2f31909%2fart%2f1537343553&width=2048 (5 live) TLS 71ms 121153 bytes (pipelined: 1) (range: bytes=0-) 
Sep 27, 2018 02:06:24.401 [0x7f709f7ff700] DEBUG - NAT: UPnP, found device <http://192.168.1.186:8008/ssdp/device-desc.xml> with private address <192.168.1.49>
Sep 27, 2018 02:06:24.401 [0x7f709f7ff700] DEBUG - NAT: UPnP, not an IGD: <http://192.168.1.186:8008/ssdp/device-desc.xml>.
Sep 27, 2018 02:06:24.401 [0x7f709f7ff700] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
Sep 27, 2018 02:06:29.562 [0x7f70a2fff700] DEBUG - EventSource: Resolving 66.228.52.149 port 443s
Sep 27, 2018 02:06:29.563 [0x7f70a2fff700] DEBUG - EventSource: Resolved 66.228.52.149 to 66.228.52.149
Sep 27, 2018 02:06:29.695 [0x7f70a2fff700] DEBUG - EventSource: Connected in 90 ms.
Sep 27, 2018 02:06:29.695 [0x7f70a2fff700] DEBUG - EventSource: Wrote data, reading reply.
Sep 27, 2018 02:06:30.242 [0x7f70a27fe700] DEBUG - EventSource: Read HTTP reply header.
Sep 27, 2018 02:06:30.242 [0x7f70a27fe700] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Sep 27, 2018 02:06:34.403 [0x7f709f7ff700] WARN - NAT: PMP, timed out waiting for response.
Sep 27, 2018 02:06:34.403 [0x7f70a27fe700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 27, 2018 02:06:34.403 [0x7f70a27fe700] DEBUG - EventSource: Successfully connected to 66.228.52.149.
Sep 27, 2018 02:06:34.403 [0x7f707a7f3700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 27, 2018 02:06:34.403 [0x7f707aff4700] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 27, 2018 02:06:34.403 [0x7f707aff4700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Sep 27, 2018 02:06:34.406 [0x7f709f7ff700] DEBUG - MyPlex: Sending Server Info to myPlex (user=kars85@gmail.com, ip=, port=32400)
Sep 27, 2018 02:06:34.407 [0x7f709f7ff700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ba49a9ac-c051-4cbc-be2c-47c45c4ae7ad
Sep 27, 2018 02:06:35.068 [0x7f709f7ff700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ba49a9ac-c051-4cbc-be2c-47c45c4ae7ad
Sep 27, 2018 02:06:35.068 [0x7f709f7ff700] DEBUG - MyPlex: Published Mapping State response was 201
Sep 27, 2018 02:06:35.069 [0x7f709f7ff700] DEBUG - MyPlex: Got response for 4c1d95a2fcdbeacc9e401949638d8e8f79a3a96f ~ registered 173.17.63.8:32400
Sep 27, 2018 02:06:35.069 [0x7f709f7ff700] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 27, 2018 02:06:35.195 [0x7f707aff4700] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Sep 27, 2018 02:06:35.196 [0x7f707aff4700] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 172.17.0.1
Sep 27, 2018 02:06:35.478 [0x7f707dbfe700] DEBUG - Request: [34.244.6.8:64852 (WAN)] GET /identity (6 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:35.479 [0x7f70a2fff700] DEBUG - Completed: [34.244.6.8:64852] 200 GET /identity (6 live) TLS 1ms 357 bytes
Sep 27, 2018 02:06:35.909 [0x7f70a27fe700] DEBUG - EventSource: Got event [data] '<Message address="173.17.63.8" port="32400" asyncIdentifier="ba49a9ac-c051-4cbc-be2c-47c45c4ae7ad" connectivity="1" command="notifyConnectivity"/>'
Sep 27, 2018 02:06:35.909 [0x7f70a27fe700] DEBUG - PubSub: Got notified of reachability: 1 for 173.17.63.8:32400
Sep 27, 2018 02:06:44.219 [0x7f707aff4700] DEBUG - NAT: UPnP, found device <http://192.168.1.193:8008/ssdp/device-desc.xml> with private address <192.168.1.49>
Sep 27, 2018 02:06:44.219 [0x7f707aff4700] DEBUG - NAT: UPnP, not an IGD: <http://192.168.1.193:8008/ssdp/device-desc.xml>.
Sep 27, 2018 02:06:44.219 [0x7f707aff4700] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
Sep 27, 2018 02:06:54.221 [0x7f707aff4700] WARN - NAT: PMP, timed out waiting for response.
Sep 27, 2018 02:06:54.225 [0x7f707aff4700] DEBUG - MyPlex: Sending Server Info to myPlex (user=kars85@gmail.com, ip=, port=32400)
Sep 27, 2018 02:06:54.226 [0x7f707aff4700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=5daa2e0b-bf47-47f9-b764-e64a182b777b
Sep 27, 2018 02:06:55.336 [0x7f707aff4700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=5daa2e0b-bf47-47f9-b764-e64a182b777b
Sep 27, 2018 02:06:55.337 [0x7f707aff4700] DEBUG - MyPlex: Published Mapping State response was 201
Sep 27, 2018 02:06:55.337 [0x7f707aff4700] DEBUG - MyPlex: Got response for 4c1d95a2fcdbeacc9e401949638d8e8f79a3a96f ~ registered 173.17.63.8:32400
Sep 27, 2018 02:06:55.338 [0x7f707aff4700] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 27, 2018 02:06:55.792 [0x7f70877fe700] DEBUG - Request: [54.246.141.109:54421 (WAN)] GET /identity (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:55.793 [0x7f70a2fff700] DEBUG - Completed: [54.246.141.109:54421] 200 GET /identity (5 live) TLS 1ms 357 bytes
Sep 27, 2018 02:06:56.230 [0x7f70a27fe700] DEBUG - EventSource: Got event [data] '<Message address="173.17.63.8" port="32400" asyncIdentifier="5daa2e0b-bf47-47f9-b764-e64a182b777b" connectivity="1" command="notifyConnectivity"/>'
Sep 27, 2018 02:06:56.230 [0x7f70a27fe700] DEBUG - PubSub: Got notified of reachability: 1 for 173.17.63.8:32400
Sep 27, 2018 02:06:57.136 [0x7f70a2fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.136 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.140 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections (5 live) TLS 4ms 2815 bytes (pipelined: 1) (range: bytes=0-) 
Sep 27, 2018 02:06:57.146 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.147 [0x7f70877fe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /playlists/all (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.149 [0x7f70a27fe700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /playlists/all (5 live) TLS 2ms 355 bytes (pipelined: 2) (range: bytes=0-) 
Sep 27, 2018 02:06:57.188 [0x7f70a2fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.189 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections/5/prefs (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.190 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections/5/prefs (5 live) TLS 1ms 1426 bytes (pipelined: 3) (range: bytes=0-) 
Sep 27, 2018 02:06:57.194 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.194 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections/1/prefs (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.196 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections/1/prefs (5 live) TLS 1ms 1426 bytes (pipelined: 4) (range: bytes=0-) 
Sep 27, 2018 02:06:57.199 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.199 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections/3/prefs (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.201 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections/3/prefs (5 live) TLS 1ms 1358 bytes (pipelined: 5) (range: bytes=0-) 
Sep 27, 2018 02:06:57.204 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.205 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections/4/prefs (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.206 [0x7f70a27fe700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections/4/prefs (5 live) TLS 1ms 1363 bytes (pipelined: 6) (range: bytes=0-) 
Sep 27, 2018 02:06:57.210 [0x7f70a2fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.210 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /library/sections/2/prefs (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.212 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /library/sections/2/prefs (5 live) TLS 1ms 1666 bytes (pipelined: 7) (range: bytes=0-) 
Sep 27, 2018 02:06:57.217 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:06:57.217 [0x7f707dbfe700] DEBUG - Request: [192.168.1.157:58960 (Subnet)] GET /channels/all (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:06:57.218 [0x7f70a27fe700] DEBUG - Completed: [192.168.1.157:58960] 206 GET /channels/all (5 live) TLS 1ms 377 bytes (pipelined: 8) (range: bytes=0-) 
Sep 27, 2018 02:07:29.161 [0x7f709b7ff700] WARN - [Analytics] Attempting to send unrecognized metric (imagga:service_throttled). Skipping.
Sep 27, 2018 02:07:34.706 [0x7f7079ff2700] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.232708 seconds: 192.168.1.115 (Master Bedroom Roku)
Sep 27, 2018 02:07:46.029 [0x7f7095bff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.115:8060/
Sep 27, 2018 02:07:46.030 [0x7f7095bff700] DEBUG - HTTP requesting GET http://192.168.1.115:8060/
Sep 27, 2018 02:07:46.038 [0x7f7095bff700] DEBUG - HTTP 200 response from GET http://192.168.1.115:8060/
Sep 27, 2018 02:07:46.039 [0x7f7095bff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.1.115:8060/
Sep 27, 2018 02:07:46.039 [0x7f7095bff700] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.115 (Master Bedroom Roku)
Sep 27, 2018 02:07:52.258 [0x7f70a2fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:07:52.259 [0x7f70a3fff700] DEBUG - Request: [192.168.1.157:58964 (Subnet)] GET /photo/:/transcode?height=2048&url=%2flibrary%2fparts%2f17773%2f20170102_122744_006.jpg&width=2048 (5 live) TLS Signed-in Token (kars85)
Sep 27, 2018 02:07:52.259 [0x7f70a3fff700] DEBUG - Photo transcoder: Request for url [/library/parts/17773/20170102_122744_006.jpg] (is local: 1 upscaled: 0)
Sep 27, 2018 02:07:52.260 [0x7f70a3fff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:07:52.324 [0x7f70a3fff700] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Sep 27, 2018 02:07:52.402 [0x7f70a3fff700] DEBUG - Photo cache obtained 1213218 bytes from /library/parts/17773/20170102_122744_006.jpg
Sep 27, 2018 02:07:52.838 [0x7f70a3fff700] DEBUG - Created thumbnail of size 2048x1152, has pixels: 1
Sep 27, 2018 02:07:52.941 [0x7f70a2fff700] DEBUG - Completed: [192.168.1.157:58964] 206 GET /photo/:/transcode?height=2048&url=%2flibrary%2fparts%2f17773%2f20170102_122744_006.jpg&width=2048 (5 live) TLS 682ms 311354 bytes (pipelined: 1) (range: bytes=0-) 
Sep 27, 2018 02:08:37.347 [0x7f70a27fe700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 02:08:37.347 [0x7f70877fe700] DEBUG - Request: [192.168.1.157:58965 (Subnet)] GET /library/arts?sort=random (5 live) TLS Page 0-49 Signed-in Token (kars85)
Sep 27, 2018 02:08:37.543 [0x7f70877fe700] DEBUG - Setting container serialization range to [0, 49] (total=-1)
Sep 27, 2018 02:08:37.546 [0x7f70a27fe700] DEBUG - Completed: [192.168.1.157:58965] 206 GET /library/arts?sort=random (5 live) TLS Page 0-49 199ms 10071 bytes (pipelined: 1) (range: bytes=0-) 
Sep 27, 2018 02:09:40.301 [0x7f709a3ff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 1395 is 0 (success)
Sep 27, 2018 02:09:40.301 [0x7f707e3ff700] DEBUG - Butler: Scheduling deep analysis for: Sto

Later on…

Sep 27, 2018 07:29:32.032 [0x7fee8abff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 07:29:32.032 [0x7fee6f3ff700] DEBUG - Request: [192.168.1.105:2761 (Subnet)] GET /photo/:/transcode?width=500&height=282&minSize=1&url=%2Flibrary%2Fmetadata%2F237%2Fart%2F1537343528%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) TLS GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:32.032 [0x7fee77fff700] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&onlyTransient=1' in 9 ms.
Sep 27, 2018 07:29:32.032 [0x7fee77fff700] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&onlyTransient=1' to the cache (1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&onlyTransient=1).
Sep 27, 2018 07:29:32.033 [0x7fee6f3ff700] DEBUG - Photo transcoder: Request for url [/library/metadata/237/art/1537343528?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Sep 27, 2018 07:29:32.033 [0x7fee6f3ff700] DEBUG - Content-Length of /config/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/1f/1f1667886c11d1bd0098808651f11a03f86f7f4f.jpg is 22965.
Sep 27, 2018 07:29:32.033 [0x7fee8abff700] DEBUG - Failed to stream media, client probably disconnected after 0 bytes: 32 - Broken pipe
Sep 27, 2018 07:29:32.033 [0x7fee8abff700] DEBUG - Completed: [192.168.1.105:2761] 200 GET /photo/:/transcode?width=500&height=282&minSize=1&url=%2Flibrary%2Fmetadata%2F237%2Fart%2F1537343528%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) TLS GZIP 0ms 0 bytes (pipelined: 5)
Sep 27, 2018 07:29:32.034 [0x7fee77fff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Sep 27, 2018 07:29:32.065 [0x7fee77fff700] DEBUG - It took 30.000000 ms to retrieve 200 items.
Sep 27, 2018 07:29:32.090 [0x7fee77fff700] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&onlyTransient=1' in 57 ms.
Sep 27, 2018 07:29:32.090 [0x7fee77fff700] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Sep 27, 2018 07:29:32.094 [0x7fee77fff700] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 3 ms.
Sep 27, 2018 07:29:32.094 [0x7fee77fff700] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Sep 27, 2018 07:29:32.184 [0x7fee77fff700] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 89 ms.
Sep 27, 2018 07:29:32.184 [0x7fee77fff700] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Sep 27, 2018 07:29:32.188 [0x7fee77fff700] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 3 ms.
Sep 27, 2018 07:29:32.188 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.188 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39097 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.188 [0x7fee77fff700] DEBUG - Audio Stream: 38969, Subtitle Stream: -1
Sep 27, 2018 07:29:32.189 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.189 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39126 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.189 [0x7fee77fff700] DEBUG - Audio Stream: 39114, Subtitle Stream: -1
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39117 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Audio Stream: 39087, Subtitle Stream: -1
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39112 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Audio Stream: 39073, Subtitle Stream: -1
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39111 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Audio Stream: 39071, Subtitle Stream: -1
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 39097 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.190 [0x7fee77fff700] DEBUG - Audio Stream: 38969, Subtitle Stream: -1
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 37812 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Audio Stream: 35713, Subtitle Stream: -1
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 37811 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Audio Stream: 35711, Subtitle Stream: -1
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8253 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Audio Stream: 16582, Subtitle Stream: -1
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8252 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.191 [0x7fee77fff700] DEBUG - Audio Stream: 16580, Subtitle Stream: -1
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8209 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Audio Stream: 16476, Subtitle Stream: -1
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8251 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Audio Stream: 16578, Subtitle Stream: -1
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 11587 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Audio Stream: 26527, Subtitle Stream: -1
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8250 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.192 [0x7fee77fff700] DEBUG - Audio Stream: 16576, Subtitle Stream: -1
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8249 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Audio Stream: 16574, Subtitle Stream: -1
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8248 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Audio Stream: 16570, Subtitle Stream: -1
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Selecting best audio stream for part ID 8247 (autoselect: 1 language: en)
Sep 27, 2018 07:29:32.193 [0x7fee77fff700] DEBUG - Audio Stream: 16568, Subtitle Stream: -1
Sep 27, 2018 07:29:32.199 [0x7fee8abff700] DEBUG - Completed: [192.168.1.105:2784] 200 GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&excludePlaylists=1&onlyTransient=1 (11 live) TLS GZIP 196ms 13549 bytes (pipelined: 2)
Sep 27, 2018 07:29:33.398 [0x7fee8abff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 07:29:33.398 [0x7fee707ff700] DEBUG - Request: [192.168.1.105:2788 (Subnet)] GET / (12 live) GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:39.371 [0x7fee8abff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 07:29:39.372 [0x7fee6f3ff700] DEBUG - Request: [192.168.1.105:2784 (Subnet)] GET /:/timeline?ratingKey=237&key=%2Flibrary%2Fmetadata%2F237&playQueueItemID=646&state=paused&hasMDE=1&time=422000&duration=5535000 (11 live) TLS GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:39.373 [0x7fee6f3ff700] DEBUG - Client [4mfg26yem02lnsdmbg8y59eg] reporting timeline state paused, progress of 422000/5535000ms for guid=, ratingKey=237 url=, key=/library/metadata/237, containerKey=, metadataId=237
Sep 27, 2018 07:29:39.381 [0x7fee6f3ff700] DEBUG - Play progress on 237 'Monsters, Inc.' - got played 422000 ms by account 1!
Sep 27, 2018 07:29:39.382 [0x7fee6f3ff700] DEBUG - [Now] User is kars85 (ID: 1)
Sep 27, 2018 07:29:39.382 [0x7fee6f3ff700] DEBUG - [Now] Device is Firefox (Firefox).
Sep 27, 2018 07:29:39.382 [0x7fee6f3ff700] DEBUG - [Now] Profile is Firefox
Sep 27, 2018 07:29:39.382 [0x7fee6f3ff700] DEBUG - [Now] Updated play state for /library/metadata/237.
Sep 27, 2018 07:29:39.383 [0x7fee6f3ff700] DEBUG - HubCache: Expiring '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeFeaturedTags=1&onlyTransient=1' because event plex.event.play.session.state.update referenced an item the hub contained
Sep 27, 2018 07:29:39.384 [0x7fee6f3ff700] DEBUG - Statistics: (qkb1vovb6i3e7zlaea14i2v2) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Sep 27, 2018 07:29:39.389 [0x7fee8abff700] DEBUG - Completed: [192.168.1.105:2784] 200 GET /:/timeline?ratingKey=237&key=%2Flibrary%2Fmetadata%2F237&playQueueItemID=646&state=paused&hasMDE=1&time=422000&duration=5535000 (12 live) TLS GZIP 17ms 527 bytes (pipelined: 3)
Sep 27, 2018 07:29:39.389 [0x7fee8abff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 07:29:39.390 [0x7fee77fff700] DEBUG - Request: [172.18.0.15:37744 (WAN)] GET /status/sessions (12 live) Signed-in Token (kars85)
Sep 27, 2018 07:29:39.390 [0x7fee77fff700] DEBUG - [Now] Adding 1 sessions.
Sep 27, 2018 07:29:39.390 [0x7fee8abff700] DEBUG - Completed: [172.18.0.15:37744] 200 GET /status/sessions (12 live) 1ms 292 bytes (pipelined: 1)
Sep 27, 2018 07:29:40.553 [0x7fee8abff700] DEBUG - handleStreamRead code 335544539: short read
Sep 27, 2018 07:29:40.558 [0x7fee8abff700] DEBUG - Auth: authenticated user 1 as kars85
Sep 27, 2018 07:29:40.558 [0x7fee6f3ff700] DEBUG - Request: [192.168.1.105:2784 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (11 live) TLS GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:40.559 [0x7fee6f3ff700] DEBUG - Beginning read from two-way stream.
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] ERROR - Error issuing curl_easy_perform(handle): 28
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] DEBUG - HTTP simulating 408 after curl timeout
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: found 0 SSDP devices via http://192.168.20.5:5000/ssdp/desc-DSM-bond1.xml
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] DEBUG - NetworkService: Replacing an old server at index 0 and address 127.0.0.1 (we just got packet from 192.168.1.49, index 1)
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: SERVER updated: 192.168.1.49 (Van Meeteren Family)
Sep 27, 2018 07:29:45.492 [0x7fee7c3ff700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.1.49
Sep 27, 2018 07:29:45.493 [0x7fee7c3ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.1.49
Sep 27, 2018 07:29:45.493 [0x7fee7c3ff700] DEBUG - HTTP requesting GET http://127.0.0.1:44481/system/notify/serverUpdated?host=192.168.1.49
Sep 27, 2018 07:29:45.499 [0x7fee77fff700] DEBUG - Request: [127.0.0.1:44398 (Loopback)] GET /servers (11 live) GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:45.500 [0x7fee8abff700] DEBUG - Completed: [127.0.0.1:44398] 200 GET /servers (11 live) GZIP 1ms 498 bytes
Sep 27, 2018 07:29:45.505 [0x7fee6f3ff700] DEBUG - Request: [127.0.0.1:44400 (Loopback)] GET /library/sections (12 live) GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:45.508 [0x7fee8abff700] DEBUG - Completed: [127.0.0.1:44400] 200 GET /library/sections (12 live) GZIP 3ms 1102 bytes
Sep 27, 2018 07:29:45.512 [0x7fee77fff700] DEBUG - Request: [127.0.0.1:44402 (Loopback)] GET /channels/all (12 live) GZIP Signed-in Token (kars85)
Sep 27, 2018 07:29:45.513 [0x7fee8abff700] DEBUG - Completed: [127.0.0.1:44402] 200 GET /channels/all (12 live) GZIP 1ms 387 bytes
Sep 27, 2018 07:29:45.516 [0x7fee7c3ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:44481/system/notify/serverUpdated?host=192.168.1.49
Sep 27, 2018 07:29:45.517 [0x7fee7c3ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Sep 27, 2018 07:29:45.517 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.1.6:5000/ssdp/desc-DSM-bond0.xml
Sep 27, 2018 07:29:45.517 [0x7fee7c3ff700] DEBUG - HTTP requesting GET http://192.168.1.6:5000/ssdp/desc-DSM-bond0.xml
Sep 27, 2018 07:29:45.523 [0x7fee7c3ff700] DEBUG - HTTP 200 response from GET http://192.168.1.6:5000/ssdp/desc-DSM-bond0.xml
Sep 27, 2018 07:29:45.524 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.1.6:5000/ssdp/desc-DSM-bond0.xml
Sep 27, 2018 07:29:45.524 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.6 (DiskStation (DS1515+))
Sep 27, 2018 07:29:45.524 [0x7fee7c3ff700] DEBUG - NetworkService: Replacing an old server at index 0 and address 127.0.0.1 (we just got packet from 192.168.1.49, index 2)
Sep 27, 2018 07:29:45.524 [0x7fee7c3ff700] DEBUG - NetworkServiceBrowser: PLAYER updated: 1

This seems somewhat relevant, but not a lot of action.

2

I’m having the same problem - when running the latest version(s) of plex (basically any version with the new settings menu from what I can see) the docker container starts up, but then kills the underlying VM and all other containers.

Whatever it’s doing, it’s doing a damn good job at it!

@ChuckPa is this common across Docker containers at the moment? There seems to be a few of us with this issue now.

3

Previous versions are still on docker hub. You can simply change from the latest to one of the explicit version tags. As per errors in your logs, nothing really stands out except for 2 network timeouts and a timeout waiting on the client to read data. These usually speak of network conditions outside of Plex’s control (the OS, the docker network virtualization layer, the networking hardware, etc). The timeout waiting on the client occurred 1ms after the request was received so this may just be the client aborting the request (b/c it no longer needs it).

Anyway, I personally run the docker container on my primary PMS and I’ve been on 1.13.8 for several days now with zero issues. This is certainly not a widespread issue.

4

@gbooker02 could you elaborate on your docker setup?

My particular environment is unRAID, and I’ve been unsuccessful, as many others too, in getting any further with this. I’ve been stuck at 1.13.2.5154 and cannot go any newer…

Any tips, tricks or settings ideas would, and could be, very helpful.

All we seem to see is that every 5 minutes or so, the server shows up for about 30 seconds, and then it goes offline for another 5 minutes.

We had a bit of dialog with @ChuckPa, and for that we’re thankful, but he’s bowed out as he seems to be a bare metal guy and a NAS expert. I’d really rather not go back to ESXi just to host a Plex server, when up until now, unRAID’s been pretty amazing.

It’s not everyone, just some users, not really sure where to look.

Tried using the new “fixed/set” IP address to no avail, too.

5

Here’s my setup:

  • I use FreeNAS Corral (Boot2Docker) with Plex in a container (I know, I know, I need to upgrade).
  • I’ve tried both the freenas/plex and plexinc/plex images
  • I’ve tried with both my original config files, and with brand new config files.

In all the permutations above the VM hosting my containers reboots once it auto-updates to the latest version (it takes down my other containers that are running at the same time too). I sometimes get lucky and can get into the web interface for a few seconds, but I can’t really achieve much during that time.

6

Ubuntu 18.04 running on top of ESXi with ZFS datasets with the standard docker engine included in the distro. My PMS container uses host networking.

Do you have any other containers on this same machine? Do they exhibit any such issues?

7

@gbooker02,

I have about 12 other containers, none of them have this issue. But none of them are “Plex” either. :slight_smile:

The issue appears in the systems logs where it thinks it’s “Lost Internet connectivity” and it never has. I tried setting the fixed IP address of the Docker host (which it normally is) for the server address to listen on.

Initially I had the same issues with my ESXi box with Ubuntu 16.04 when it first came out, it’s since been running fine.

8

Sounds similar as me (I’m on unraid too).

Plex is stable when using the version tag “1.13.4.5271-200287a06” any higher build and PMS can’t be connected too. It started when the network interface changes came in.

https://forums.plex.tv/t/plex-server-not-worked-since-1-13-4-5271-200287a06/312196/11

9

Thank you for this…I’m running Unraid as well and the version listed in the thread title has been giving me remote access problems since installed.
And frankly, I didn’t like that they moved the setting menu to the side like that. I like that with the version you listed I can just click on the server heading and can see the green circle next to remote access rather than the new version where you have to actually click on the link on the side to see if remote access is working.

10

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