Jan 02, 2019 03:36:43.522 [0x7fe3d7bfe700] INFO - Plex Media Server v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 ubuntu - GMT 00:00
Jan 02, 2019 03:36:43.522 [0x7fe3d7bfe700] INFO - Linux version: 4.15.0-43-generic (#46-Ubuntu SMP Thu Dec 6 14:45:28 UTC 2018), language: en-US
Jan 02, 2019 03:36:43.523 [0x7fe3d7bfe700] INFO - Processor        Intel(R) Xeon(R) CPU E5-2690 0 @ 2.90GHz
Jan 02, 2019 03:36:43.523 [0x7fe3d7bfe700] INFO - /usr/lib/plexmediaserver/Plex Media Server
Jan 02, 2019 03:36:43.522 [0x7fe3e3c05840] DEBUG - BPQ: [Idle] -> [Starting]
Jan 02, 2019 03:36:43.523 [0x7fe3e3c05840] DEBUG - FeatureManager: Using cached data for features list
Jan 02, 2019 03:36:43.524 [0x7fe3e3c05840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 02, 2019 03:36:43.600 [0x7fe3e3c05840] DEBUG - Running migrations. (EPG 0)
Jan 02, 2019 03:36:43.726 [0x7fe3e3c05840] DEBUG - ChangestampAllocator: initialized to 242802
Jan 02, 2019 03:36:43.726 [0x7fe3e3c05840] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.13.0, threadsafe=1
Jan 02, 2019 03:36:43.734 [0x7fe3e3c05840] DEBUG - Running migrations. (EPG 0)
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] DEBUG - CERT: Installed certificate with fingerprint e4:11:31:e2:3b:29:83:9d:61:7a:f8:8e:a0:c6:85:ad:ba:20:fa:b5.
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] DEBUG - CERT: Installed new private key.
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.a1e66f15791e4dfbba7b0408b29e4e61.plex.direct
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] INFO - OCSP: Successfully retrieved response from cache.
Jan 02, 2019 03:36:43.746 [0x7fe3e3c05840] DEBUG - CERT: Installed intermediate certificate.
Jan 02, 2019 03:36:43.747 [0x7fe3e3c05840] DEBUG - HttpServer: Listening on port 32400 with queue of 128 connections.
Jan 02, 2019 03:36:43.747 [0x7fe3e3c05840] DEBUG - HttpServer: Listening on port 32401 with queue of 128 connections.
Jan 02, 2019 03:36:43.755 [0x7fe3d3bff700] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Jan 02, 2019 03:36:43.756 [0x7fe3e3c05840] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Jan 02, 2019 03:36:43.756 [0x7fe3e3c05840] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:36:43.756 [0x7fe3e3c05840] DEBUG - Relay: read 10 cached entries from hosts file
Jan 02, 2019 03:36:43.756 [0x7fe3e3c05840] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1
Jan 02, 2019 03:36:43.756 [0x7fe3d2bff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:43.757 [0x7fe3e3c05840] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:36:43.760 [0x7fe3ce3ff700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:36:43.760 [0x7fe3ce3ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1
Jan 02, 2019 03:36:43.761 [0x7fe3d3bff700] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Jan 02, 2019 03:36:43.762 [0x7fe3c87fe700] DEBUG - File "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476" changed: -1 => 2019-01-01 03:09:16 (1546312156).
Jan 02, 2019 03:36:43.762 [0x7fe3c87fe700] DEBUG - File "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins" changed: -1 => 2019-01-01 03:17:42 (1546312662).
Jan 02, 2019 03:36:43.762 [0x7fe3c87fe700] DEBUG - Scanning for plug-ins in "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476"
Jan 02, 2019 03:36:43.791 [0x7fe3c87fe700] DEBUG - Scanning for plug-ins in "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins"
Jan 02, 2019 03:36:43.792 [0x7fe3c87fe700] DEBUG - PluginRepository::setStartState: 1, startingSystem
Jan 02, 2019 03:36:43.792 [0x7fe3c87fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/System.bundle.
Jan 02, 2019 03:36:43.793 [0x7fe3c87fe700] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Jan 02, 2019 03:36:43.795 [0x7fe3c87fe700] DEBUG - Starting file watcher for com.plexapp.system
Jan 02, 2019 03:36:43.796 [0x7fe3c87fe700] DEBUG - Spawned plug-in com.plexapp.system with PID 32469
Jan 02, 2019 03:36:43.796 [0x7fe3c87fe700] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes
Jan 02, 2019 03:36:44.399 [0x7fe3d2bff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:45.031 [0x7fe3ce3ff700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1
Jan 02, 2019 03:36:45.031 [0x7fe3ce3ff700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1
Jan 02, 2019 03:36:45.032 [0x7fe3ce3ff700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:36:45.033 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:45.644 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:45.645 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:45.774 [0x7fe3c87fe700] DEBUG - Reading 486 bytes in the body, code is 200
Jan 02, 2019 03:36:45.774 [0x7fe3c87fe700] DEBUG - [com.plexapp.system] Plug-in running on port 35933.
Jan 02, 2019 03:36:45.774 [0x7fe3c87fe700] DEBUG -  * Plug-in handles prefix: /system
Jan 02, 2019 03:36:45.774 [0x7fe3c87fe700] DEBUG -  * Plug-in handles prefix: /player
Jan 02, 2019 03:36:45.774 [0x7fe3c87fe700] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Jan 02, 2019 03:36:45.791 [0x7fe3c87fe700] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Jan 02, 2019 03:36:45.791 [0x7fe3c87fe700] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Jan 02, 2019 03:36:45.791 [0x7fe3c87fe700] DEBUG - Updating the list of agents known by the system.
Jan 02, 2019 03:36:45.791 [0x7fe3e3c05840] DEBUG - Image transcode cache directory: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder"
Jan 02, 2019 03:36:45.791 [0x7fe3c87fe700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Jan 02, 2019 03:36:45.791 [0x7fe3e3c05840] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 02, 2019 03:36:45.791 [0x7fe3c87fe700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/system/agents
Jan 02, 2019 03:36:45.792 [0x7fe3e3c05840] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1
Jan 02, 2019 03:36:45.793 [0x7fe3e3c05840] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:36:45.795 [0x7fe3cf3fc700] DEBUG - Job running: '/usr/lib/plexmediaserver/CrashUploader' '--directory=/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Crash Reports/1.14.1.5488-cc260c476' '--version=1.14.1.5488-cc260c476' '--platform=linux-ubuntu-x86_64 (4.15.0-43-generic (#46-Ubuntu SMP Thu Dec 6 14:45:28 UTC 2018))' '--serverUuid=e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f' '--userId=orbsa' '--url=https://crashreport.plexapp.com'
Jan 02, 2019 03:36:45.797 [0x7fe3cf3fc700] DEBUG - Jobs: Starting child process with pid 32511
Jan 02, 2019 03:36:45.798 [0x7fe3e3c05840] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=REDACTED&includeGeolocation=1
Jan 02, 2019 03:36:45.800 [0x7fe3e3c05840] DEBUG - MyPlex: username is orbsa, login is orbsa, home is 1, has pin
Jan 02, 2019 03:36:45.801 [0x7fe3e3c05840] DEBUG - PublicAddressManager: Starting.
Jan 02, 2019 03:36:45.801 [0x7fe3e3c05840] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 02, 2019 03:36:45.801 [0x7fe3e3c05840] DEBUG - NetworkInterface: Starting watch thread.
Jan 02, 2019 03:36:45.802 [0x7fe3e3c05840] DEBUG - Network change.
Jan 02, 2019 03:36:45.802 [0x7fe3ce3ff700] DEBUG - PublicAddressManager: Obtaining public IP.
Jan 02, 2019 03:36:45.802 [0x7fe3e3c05840] DEBUG - NetworkInterface: Notified of network changed (force=0)
Jan 02, 2019 03:36:45.802 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=REDACTED&includeGeolocation=1
Jan 02, 2019 03:36:45.802 [0x7fe3ce3ff700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Jan 02, 2019 03:36:45.802 [0x7fe3c43ff700] DEBUG - NetworkInterface: Watching for changes on the interfaces.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - Detected primary interface: 256.256.256.256
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - Network interfaces:
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG -  * 2 eno1 (256.256.256.256) (loopback: 0)
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - Creating NetworkServices singleton.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkServices: Initializing...
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.803 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.803 [0x7fe3c33ff700] DEBUG - Network change for advertiser.
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.804 [0x7fe3d63ff700] DEBUG - NAT: UPnP, attempting port mapping.
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Creating new service.
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 02, 2019 03:36:45.804 [0x7fe3e3c05840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - Network change for advertiser.
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - Network change for advertiser.
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Jan 02, 2019 03:36:45.804 [0x7fe3c33ff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Browsing on interface 256.256.256.256 on broadcast address 50.115.121.199 (index: 1)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Browsing on interface 256.256.256.256 on broadcast address 50.115.121.199 (index: 1)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Jan 02, 2019 03:36:45.805 [0x7fe3c33ff700] DEBUG - NetworkService: Browsing on interface 256.256.256.256 on broadcast address 239.255.255.250 (index: 0)
Jan 02, 2019 03:36:45.814 [0x7fe3c8fff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/CrashUploader' exit code for process 32511 is 0 (success)
Jan 02, 2019 03:36:45.858 [0x7fe3e3c05840] DEBUG - Gracenote Version 3.07.7.3686 (build: 2015-07-05 11:45-0700)
Jan 02, 2019 03:36:45.859 [0x7fe3e3c05840] DEBUG - Gracenote: Using SQLite 3.13.0
Jan 02, 2019 03:36:45.859 [0x7fe3e3c05840] DEBUG - Starting HTTP server.
Jan 02, 2019 03:36:45.859 [0x7fe3e3c05840] DEBUG - Running server...
Jan 02, 2019 03:36:45.859 [0x7fe3e3c05840] DEBUG - Waiting for server condition to be signaled.
Jan 02, 2019 03:36:45.859 [0x7fe3c1fff700] DEBUG - Plex Tuner Service starting.
Jan 02, 2019 03:36:45.859 [0x7fe3c1fff700] DEBUG - Job running: '/usr/lib/plexmediaserver/Plex Tuner Service' '/usr/lib/plexmediaserver/Resources/Tuner/Private' '/usr/lib/plexmediaserver/Resources/Tuner/Shared' '1.14.1.5488-cc260c476' '32600' '/waitmutex'
Jan 02, 2019 03:36:45.861 [0x7fe3c1fff700] DEBUG - Jobs: Starting child process with pid 32518
Jan 02, 2019 03:36:45.861 [0x7fe3c17fe700] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Jan 02, 2019 03:36:45.861 [0x7fe3c17fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:45.862 [0x7fe3bfbff700] DEBUG - Request: [127.0.0.1:44930 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.862 [0x7fe3bfbff700] DEBUG - Translator: Found locale he
Jan 02, 2019 03:36:45.862 [0x7fe3bfbff700] DEBUG - Translator: Found locale ko
Jan 02, 2019 03:36:45.862 [0x7fe3bfbff700] DEBUG - Translator: Found locale en
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale cs_CZ
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale hu_HU
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale ja_JP
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale lt
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale pt_BR
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale hr_HR
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale sv
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale zh_CN
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale nl_BE
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale et_EE
Jan 02, 2019 03:36:45.863 [0x7fe3bebff700] DEBUG - Request: [127.0.0.1:44932 (Loopback)] GET / (7 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale ro
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale ar
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale no
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale it
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale de
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale da
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale ru
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale fr
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale sr
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale es
Jan 02, 2019 03:36:45.863 [0x7fe3bdbff700] DEBUG - Request: [127.0.0.1:44938 (Loopback)] GET / (7 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.863 [0x7fe3bfbff700] DEBUG - Translator: Found locale nl
Jan 02, 2019 03:36:45.864 [0x7fe3bcbff700] DEBUG - Request: [127.0.0.1:44940 (Loopback)] GET /servers (7 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.871 [0x7fe3bfbff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:45.871 [0x7fe3bfbff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:45.872 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44940] 200 GET /servers (7 live) GZIP 9ms 492 bytes
Jan 02, 2019 03:36:45.873 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44938] 200 GET / (7 live) GZIP 9ms 2749 bytes
Jan 02, 2019 03:36:45.873 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:44932] 200 GET / (7 live) GZIP 10ms 2749 bytes
Jan 02, 2019 03:36:45.896 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.896 [0x7fe3bcbff700] DEBUG - Request: [73.20.48.4:54713 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.905 [0x7fe3d53ff700] DEBUG - Completed: [73.20.48.4:54713] 200 GET /media/providers (6 live) TLS GZIP 8ms 3230 bytes (pipelined: 1)
Jan 02, 2019 03:36:45.910 [0x7fe3c87fe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/system/agents
Jan 02, 2019 03:36:45.911 [0x7fe3c87fe700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9134 bytes of content.
Jan 02, 2019 03:36:45.916 [0x7fe3c87fe700] DEBUG - The system knows about 14 agents.
Jan 02, 2019 03:36:45.916 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LyricFind.bundle.
Jan 02, 2019 03:36:45.916 [0x7fe3c87fe700] DEBUG - Waiting for 19 plug-ins to finish starting.
Jan 02, 2019 03:36:45.916 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexMusic.bundle.
Jan 02, 2019 03:36:45.916 [0x7fe3ba3ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexThemeMusic.bundle.
Jan 02, 2019 03:36:45.917 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Jan 02, 2019 03:36:45.917 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/TheMovieDB.bundle.
Jan 02, 2019 03:36:45.917 [0x7fe3bb3fe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Jan 02, 2019 03:36:45.917 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Fanart-TV.bundle.
Jan 02, 2019 03:36:45.918 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Jan 02, 2019 03:36:45.918 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LocalMedia.bundle.
Jan 02, 2019 03:36:45.918 [0x7fe3bb3fe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Jan 02, 2019 03:36:45.918 [0x7fe3ba3ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Jan 02, 2019 03:36:45.918 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Framework.bundle.
Jan 02, 2019 03:36:45.918 [0x7fe3ba3ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Media-Flags.bundle.
Jan 02, 2019 03:36:45.918 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Jan 02, 2019 03:36:45.919 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexMovie.bundle.
Jan 02, 2019 03:36:45.919 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/OpenSubtitles.bundle.
Jan 02, 2019 03:36:45.919 [0x7fe3ba3ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Scanners.bundle.
Jan 02, 2019 03:36:45.919 [0x7fe3bb3fe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Jan 02, 2019 03:36:45.919 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle.
Jan 02, 2019 03:36:45.919 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Jan 02, 2019 03:36:45.919 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/HTbackdrops.bundle.
Jan 02, 2019 03:36:45.920 [0x7fe3ba3ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LastFM.bundle.
Jan 02, 2019 03:36:45.920 [0x7fe3bb3fe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/MoviePosterDB.bundle.
Jan 02, 2019 03:36:45.920 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Jan 02, 2019 03:36:45.920 [0x7fe3bbbff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/TheTVDB.bundle.
Jan 02, 2019 03:36:45.920 [0x7fe3ba3ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Jan 02, 2019 03:36:45.920 [0x7fe3ba3ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PersonalMedia.bundle.
Jan 02, 2019 03:36:45.921 [0x7fe3bb3fe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Jan 02, 2019 03:36:45.921 [0x7fe3bb3fe700] DEBUG - Starting plug-in /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins/Moviemania.bundle.
Jan 02, 2019 03:36:45.921 [0x7fe3bbbff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Jan 02, 2019 03:36:45.921 [0x7fe3bbbff700] DEBUG - Starting plug-in /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins/Services.bundle.
Jan 02, 2019 03:36:45.921 [0x7fe3ba3ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Jan 02, 2019 03:36:45.921 [0x7fe3bb3fe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.moviemania] yet.
Jan 02, 2019 03:36:45.922 [0x7fe3c87fe700] DEBUG - Starting scheduled updates, every 3600 seconds
Jan 02, 2019 03:36:45.922 [0x7fe3c87fe700] INFO - Refreshing paths watched by LibraryUpdateManager
Jan 02, 2019 03:36:45.924 [0x7fe3c87fe700] DEBUG - [Notify] Initializing.
Jan 02, 2019 03:36:45.924 [0x7fe3c87fe700] DEBUG - Done waiting for all plug-ins to start.
Jan 02, 2019 03:36:45.924 [0x7fe3c87fe700] DEBUG - PluginRepository::setStartState: 3, complete
Jan 02, 2019 03:36:45.924 [0x7fe3b8fff700] DEBUG - [Notify] Waiting for inotify notifications...
Jan 02, 2019 03:36:45.925 [0x7fe3b87fe700] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Jan 02, 2019 03:36:45.926 [0x7fe3b87fe700] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Jan 02, 2019 03:36:45.926 [0x7fe3b87fe700] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 32542
Jan 02, 2019 03:36:45.926 [0x7fe3b87fe700] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes
Jan 02, 2019 03:36:45.948 [0x7fe3bfbff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:45.948 [0x7fe3bfbff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59992 bytes of content.
Jan 02, 2019 03:36:45.953 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.953 [0x7fe3bdbff700] DEBUG - Request: [73.20.48.4:54713 (WAN)] GET /music/iTunes (5 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.953 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44930] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 91ms 19417 bytes
Jan 02, 2019 03:36:45.954 [0x7fe3bebff700] DEBUG - Request: [127.0.0.1:44952 (Loopback)] GET / (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.954 [0x7fe3d53ff700] DEBUG - Completed: [73.20.48.4:54713] 404 GET /music/iTunes (5 live) TLS GZIP 1ms 452 bytes (pipelined: 2)
Jan 02, 2019 03:36:45.955 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:44952] 200 GET / (5 live) GZIP 1ms 2737 bytes
Jan 02, 2019 03:36:45.959 [0x7fe3bcbff700] DEBUG - Request: [127.0.0.1:44954 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.962 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:44954] 200 GET /library/sections (6 live) GZIP 3ms 1133 bytes
Jan 02, 2019 03:36:45.972 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.973 [0x7fe3bfbff700] DEBUG - Request: [73.20.48.4:54715 (WAN)] GET /library/sections (10 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.977 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54715] 200 GET /library/sections (10 live) TLS GZIP 4ms 1209 bytes (pipelined: 1)
Jan 02, 2019 03:36:45.991 [0x7fe3ccffd700] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Jan 02, 2019 03:36:45.992 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.992 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.992 [0x7fe3bdbff700] DEBUG - Request: [73.20.48.4:54719 (WAN)] GET /hubs/home/recentlyAdded?type=2 (10 live) TLS Page 16-50 GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.993 [0x7fe3bebff700] DEBUG - Request: [73.20.48.4:54720 (WAN)] GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (9 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.993 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.993 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 02, 2019 03:36:45.993 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 02, 2019 03:36:45.993 [0x7fe3bcbff700] DEBUG - Request: [73.20.48.4:54713 (WAN)] GET /updater/status (8 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.993 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:45.994 [0x7fe3bb3fe700] DEBUG - Request: [73.20.48.4:54718 (WAN)] GET /media/providers (8 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:45.995 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54713] 200 GET /updater/status (8 live) TLS GZIP 1ms 506 bytes (pipelined: 3)
Jan 02, 2019 03:36:45.996 [0x7fe3d53ff700] DEBUG - Completed: [73.20.48.4:54718] 200 GET /media/providers (8 live) TLS GZIP 2ms 3216 bytes (pipelined: 1)
Jan 02, 2019 03:36:46.010 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 17 ms.
Jan 02, 2019 03:36:46.010 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 17 ms.
Jan 02, 2019 03:36:46.010 [0x7fe3bebff700] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 02, 2019 03:36:46.011 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 02, 2019 03:36:46.011 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:46.011 [0x7fe3bfbff700] DEBUG - Request: [73.20.48.4:54721 (WAN)] GET /:/websockets/notifications (8 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.012 [0x7fe3bfbff700] DEBUG - WebSocket: Performing handshake from origin http://app.plex.tv
Jan 02, 2019 03:36:46.012 [0x7fe3bfbff700] DEBUG - Beginning read from WebSocket
Jan 02, 2019 03:36:46.027 [0x7fe3bdbff700] DEBUG - It took 120.000000 ms to retrieve 200 items.
Jan 02, 2019 03:36:46.048 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:46.048 [0x7fe3bcbff700] DEBUG - Request: [73.20.48.4:54713 (WAN)] PUT /updater/check?download=0 (8 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.048 [0x7fe3bcbff700] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=ubuntu&version=1.14.1.5488-cc260c476, download: NO
Jan 02, 2019 03:36:46.049 [0x7fe3bcbff700] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=ubuntu&version=1.14.1.5488-cc260c476
Jan 02, 2019 03:36:46.049 [0x7fe3bcbff700] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=ubuntu&version=1.14.1.5488-cc260c476
Jan 02, 2019 03:36:46.050 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 39 ms.
Jan 02, 2019 03:36:46.050 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 02, 2019 03:36:46.051 [0x7fe3bebff700] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Jan 02, 2019 03:36:46.057 [0x7fe3bb3fe700] DEBUG - Request: [127.0.0.1:44960 (Loopback)] GET /channels/all (9 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.058 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44960] 200 GET /channels/all (9 live) GZIP 0ms 387 bytes
Jan 02, 2019 03:36:46.062 [0x7fe3bfbff700] DEBUG - Request: [127.0.0.1:44962 (Loopback)] GET /library/sections (10 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.064 [0x7fe3bdbff700] DEBUG - It took 110.000000 ms to retrieve 50 items.
Jan 02, 2019 03:36:46.065 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44962] 200 GET /library/sections (10 live) GZIP 3ms 1133 bytes
Jan 02, 2019 03:36:46.068 [0x7fe3d0bfd700] ERROR - [Notify] Failed to add watch for /mnt/reds/media/movies/They Shall Not Grow Old (2018) (13: Permission denied)
Jan 02, 2019 03:36:46.068 [0x7fe3d0bfd700] DEBUG - [Notify] Caught exception on directory.
Jan 02, 2019 03:36:46.072 [0x7fe3bb3fe700] DEBUG - Request: [127.0.0.1:44964 (Loopback)] GET /channels/all (10 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.072 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:44964] 200 GET /channels/all (10 live) GZIP 0ms 387 bytes
Jan 02, 2019 03:36:46.078 [0x7fe3ce3ff700] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Jan 02, 2019 03:36:46.079 [0x7fe3ce3ff700] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 256.256.256.256
Jan 02, 2019 03:36:46.082 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:46.082 [0x7fe3bebff700] DEBUG - It took 110.000000 ms to retrieve 200 items.
Jan 02, 2019 03:36:46.083 [0x7fe3bfbff700] DEBUG - Request: [73.20.48.4:54718 (WAN)] GET /activities (9 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.084 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54718] 200 GET /activities (9 live) TLS GZIP 1ms 423 bytes (pipelined: 2)
Jan 02, 2019 03:36:46.085 [0x7fe3bdbff700] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Jan 02, 2019 03:36:46.118 [0x7fe3bebff700] DEBUG - It took 110.000000 ms to retrieve 50 items.
Jan 02, 2019 03:36:46.122 [0x7fe3d0bfd700] ERROR - [Notify] Failed to add watch for /mnt/reds/media/movies/The Experiment (2010) (13: Permission denied)
Jan 02, 2019 03:36:46.122 [0x7fe3d0bfd700] DEBUG - [Notify] Caught exception on directory.
Jan 02, 2019 03:36:46.139 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 89 ms.
Jan 02, 2019 03:36:46.140 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=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&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 02, 2019 03:36:46.140 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 02, 2019 03:36:46.140 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=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&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 02, 2019 03:36:46.141 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 02, 2019 03:36:46.141 [0x7fe3bebff700] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=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&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 02, 2019 03:36:46.142 [0x7fe3bebff700] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 02, 2019 03:36:46.171 [0x7fe3d0bfd700] ERROR - [Notify] Failed to add watch for /mnt/reds/media/movies/The Old Man & the Gun (2018) (13: Permission denied)
Jan 02, 2019 03:36:46.171 [0x7fe3d0bfd700] DEBUG - [Notify] Caught exception on directory.
Jan 02, 2019 03:36:46.173 [0x7fe3d0bfd700] ERROR - [Notify] Failed to add watch for /mnt/reds/media/movies/The Experiment (2001) (13: Permission denied)
Jan 02, 2019 03:36:46.173 [0x7fe3d0bfd700] DEBUG - [Notify] Caught exception on directory.
Jan 02, 2019 03:36:46.176 [0x7fe3d0bfd700] ERROR - [Notify] Failed to add watch for /mnt/reds/media/movies/Bird Box (2018) (13: Permission denied)
Jan 02, 2019 03:36:46.176 [0x7fe3d0bfd700] DEBUG - [Notify] Caught exception on directory.
Jan 02, 2019 03:36:46.230 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.230 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 5153 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.230 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.230 [0x7fe3bdbff700] DEBUG - Audio Stream: 7060, Subtitle Stream: -1
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 19980 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - Audio Stream: 48570, Subtitle Stream: -1
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 13324 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.232 [0x7fe3bdbff700] DEBUG - Audio Stream: 33391, Subtitle Stream: -1
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 25549 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - Audio Stream: 62970, Subtitle Stream: -1
Jan 02, 2019 03:36:46.233 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.233 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 14727 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.233 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.233 [0x7fe3bdbff700] DEBUG - Audio Stream: 36683, Subtitle Stream: -1
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 6721 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.233 [0x7fe3bebff700] DEBUG - Audio Stream: 12996, Subtitle Stream: -1
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 24047 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - Audio Stream: 59299, Subtitle Stream: -1
Jan 02, 2019 03:36:46.234 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.234 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 5397 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.234 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.234 [0x7fe3bebff700] DEBUG - Audio Stream: 9279, Subtitle Stream: -1
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 5158 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.234 [0x7fe3bdbff700] DEBUG - Audio Stream: 7075, Subtitle Stream: -1
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 23135 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - Audio Stream: 56717, Subtitle Stream: -1
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 24777 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.235 [0x7fe3bdbff700] DEBUG - Audio Stream: 61108, Subtitle Stream: -1
Jan 02, 2019 03:36:46.235 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.235 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 23405 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.235 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.235 [0x7fe3bebff700] DEBUG - Audio Stream: 57378, Subtitle Stream: -1
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 19982 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - Audio Stream: 48576, Subtitle Stream: -1
Jan 02, 2019 03:36:46.236 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.236 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 33101 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.236 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.236 [0x7fe3bdbff700] DEBUG - Audio Stream: 81928, Subtitle Stream: -1
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 13326 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.236 [0x7fe3bebff700] DEBUG - Audio Stream: 33397, Subtitle Stream: -1
Jan 02, 2019 03:36:46.236 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 20820 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - Audio Stream: 50422, Subtitle Stream: -1
Jan 02, 2019 03:36:46.237 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.237 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 24049 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.237 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.237 [0x7fe3bebff700] DEBUG - Audio Stream: 59303, Subtitle Stream: -1
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 21595 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.237 [0x7fe3bdbff700] DEBUG - Audio Stream: 52963, Subtitle Stream: -1
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 11414 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - Audio Stream: 28720, Subtitle Stream: -1
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 31661 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.238 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.238 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 24779 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.238 [0x7fe3bdbff700] DEBUG - Audio Stream: 78357, Subtitle Stream: -1
Jan 02, 2019 03:36:46.238 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.238 [0x7fe3bebff700] DEBUG - Audio Stream: 61113, Subtitle Stream: -1
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 31076 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 13190 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - Audio Stream: 76955, Subtitle Stream: -1
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - Audio Stream: 33088, Subtitle Stream: -1
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 28401 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 13325 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.239 [0x7fe3bebff700] DEBUG - Audio Stream: 33394, Subtitle Stream: -1
Jan 02, 2019 03:36:46.239 [0x7fe3bdbff700] DEBUG - Audio Stream: 70056, Subtitle Stream: -1
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 19979 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - Audio Stream: 48567, Subtitle Stream: -1
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 19981 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - Audio Stream: 48573, Subtitle Stream: -1
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 13323 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.240 [0x7fe3bdbff700] DEBUG - Audio Stream: 33388, Subtitle Stream: -1
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 24048 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.240 [0x7fe3bebff700] DEBUG - Audio Stream: 59301, Subtitle Stream: -1
Jan 02, 2019 03:36:46.241 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.241 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 24778 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 14582 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.241 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.241 [0x7fe3bebff700] DEBUG - Audio Stream: 61110, Subtitle Stream: -1
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - Audio Stream: 36253, Subtitle Stream: -1
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.241 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 15699 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.241 [0x7fe3bebff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.242 [0x7fe3bebff700] DEBUG - Selecting best audio stream for part ID 8122 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - Audio Stream: 38976, Subtitle Stream: -1
Jan 02, 2019 03:36:46.242 [0x7fe3bebff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.242 [0x7fe3bebff700] DEBUG - Audio Stream: 18049, Subtitle Stream: -1
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 14726 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.242 [0x7fe3bdbff700] DEBUG - Audio Stream: 36680, Subtitle Stream: -1
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 24858 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - Audio Stream: 61291, Subtitle Stream: -1
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 24046 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.243 [0x7fe3bdbff700] DEBUG - Audio Stream: 59297, Subtitle Stream: -1
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 24776 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - Audio Stream: 61104, Subtitle Stream: -1
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 33379 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.244 [0x7fe3bdbff700] DEBUG - Audio Stream: 82822, Subtitle Stream: -1
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 23134 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - Audio Stream: 56715, Subtitle Stream: -1
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 20819 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.245 [0x7fe3bdbff700] DEBUG - Audio Stream: 50420, Subtitle Stream: -1
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 13189 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - Audio Stream: 33087, Subtitle Stream: -1
Jan 02, 2019 03:36:46.246 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54720] 200 GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (9 live) TLS GZIP 253ms 10278 bytes (pipelined: 1)
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 18925 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.246 [0x7fe3bdbff700] DEBUG - Audio Stream: 46182, Subtitle Stream: -1
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 12807 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - Audio Stream: 32276, Subtitle Stream: -1
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 14581 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.247 [0x7fe3bdbff700] DEBUG - Audio Stream: 36251, Subtitle Stream: -1
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 12863 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - Audio Stream: 32390, Subtitle Stream: -1
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 11413 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.248 [0x7fe3bdbff700] DEBUG - Audio Stream: 28718, Subtitle Stream: -1
Jan 02, 2019 03:36:46.249 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 02, 2019 03:36:46.249 [0x7fe3bdbff700] DEBUG - Selecting best audio stream for part ID 21594 (autoselect: 0 language: en)
Jan 02, 2019 03:36:46.249 [0x7fe3bdbff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 02, 2019 03:36:46.249 [0x7fe3bdbff700] DEBUG - Audio Stream: 52961, Subtitle Stream: -1
Jan 02, 2019 03:36:46.256 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54719] 200 GET /hubs/home/recentlyAdded?type=2 (9 live) TLS GZIP Page 16-50 263ms 15407 bytes (pipelined: 1)
Jan 02, 2019 03:36:46.260 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.261 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.442 [0x7fe3c17fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.444 [0x7fe3c17fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.516 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=REDACTED&includeGeolocation=1
Jan 02, 2019 03:36:46.518 [0x7fe3c53ff700] DEBUG - MyPlex: username is orbsa, login is orbsa, home is 1, has pin
Jan 02, 2019 03:36:46.519 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.589 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:46.589 [0x7fe3bb3fe700] DEBUG - Request: [73.20.48.4:54719 (WAN)] PUT /myplex/refreshReachability (8 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.589 [0x7fe3bb3fe700] DEBUG - MyPlex: Requesting reachability check.
Jan 02, 2019 03:36:46.590 [0x7fe3bb3fe700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=dc771ce3-9665-42ce-930d-f48832011bcd
Jan 02, 2019 03:36:46.601 [0x7fe3bfbff700] DEBUG - Request: [127.0.0.1:44974 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (9 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.601 [0x7fe3bfbff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Jan 02, 2019 03:36:46.601 [0x7fe3bfbff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 02, 2019 03:36:46.640 [0x7fe3bfbff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 02, 2019 03:36:46.640 [0x7fe3bfbff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 02, 2019 03:36:46.641 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44974] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (9 live) GZIP 39ms 330 bytes
Jan 02, 2019 03:36:46.644 [0x7fe3bcbff700] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=ubuntu&version=1.14.1.5488-cc260c476
Jan 02, 2019 03:36:46.645 [0x7fe3bcbff700] INFO - AutoUpdate: no updates available
Jan 02, 2019 03:36:46.645 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54713] 200 PUT /updater/check?download=0 (9 live) TLS GZIP 597ms 268 bytes (pipelined: 4)
Jan 02, 2019 03:36:46.752 [0x7fe3bebff700] DEBUG - Request: [127.0.0.1:44978 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (10 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.752 [0x7fe3bebff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Jan 02, 2019 03:36:46.752 [0x7fe3bebff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/resourceHashes
Jan 02, 2019 03:36:46.789 [0x7fe3bebff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/resourceHashes
Jan 02, 2019 03:36:46.789 [0x7fe3bebff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Jan 02, 2019 03:36:46.791 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44978] 200 GET /:/plugins/com.plexapp.system/resourceHashes (10 live) GZIP 38ms 3498 bytes
Jan 02, 2019 03:36:46.808 [0x7fe3bdbff700] DEBUG - Request: [127.0.0.1:44984 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (11 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.808 [0x7fe3bdbff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:46.808 [0x7fe3bdbff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:46.809 [0x7fe3bfbff700] DEBUG - Request: [127.0.0.1:44986 (Loopback)] GET / (11 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:46.810 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:44986] 200 GET / (11 live) GZIP 1ms 2748 bytes
Jan 02, 2019 03:36:46.833 [0x7fe3bdbff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 02, 2019 03:36:46.834 [0x7fe3bdbff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59992 bytes of content.
Jan 02, 2019 03:36:46.839 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:44984] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (11 live) GZIP 30ms 19417 bytes
Jan 02, 2019 03:36:46.869 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:46.869 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.080 [0x7fe3c17fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.082 [0x7fe3c17fe700] DEBUG - Running database fix-ups.
Jan 02, 2019 03:36:47.085 [0x7fe3c17fe700] DEBUG - Database Fixups: Destroyed 0 transient items.
Jan 02, 2019 03:36:47.087 [0x7fe3c17fe700] DEBUG - Collections: Found 0 collections tags in section 2.
Jan 02, 2019 03:36:47.087 [0x7fe3c17fe700] DEBUG - Collections: We had 0 collections in the library already.
Jan 02, 2019 03:36:47.087 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 02, 2019 03:36:47.087 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Jan 02, 2019 03:36:47.089 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 2 in 2ms.
Jan 02, 2019 03:36:47.090 [0x7fe3c17fe700] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 02, 2019 03:36:47.090 [0x7fe3c17fe700] DEBUG - Collections: We had 0 collections in the library already.
Jan 02, 2019 03:36:47.090 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 02, 2019 03:36:47.090 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 02, 2019 03:36:47.092 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 3 in 1ms.
Jan 02, 2019 03:36:47.092 [0x7fe3c17fe700] DEBUG - Collections: Found 0 collections tags in section 4.
Jan 02, 2019 03:36:47.092 [0x7fe3c17fe700] DEBUG - Collections: We had 0 collections in the library already.
Jan 02, 2019 03:36:47.092 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 4 in 0ms.
Jan 02, 2019 03:36:47.093 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 0 collections in section 4 in 0ms.
Jan 02, 2019 03:36:47.095 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 4 in 1ms.
Jan 02, 2019 03:36:47.095 [0x7fe3c17fe700] DEBUG - Collections: Found 0 collections tags in section 5.
Jan 02, 2019 03:36:47.095 [0x7fe3c17fe700] DEBUG - Collections: We had 0 collections in the library already.
Jan 02, 2019 03:36:47.095 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 5 in 0ms.
Jan 02, 2019 03:36:47.096 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 0 collections in section 5 in 0ms.
Jan 02, 2019 03:36:47.097 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 5 in 1ms.
Jan 02, 2019 03:36:47.097 [0x7fe3c17fe700] DEBUG - Collections: Found 1 collections tags in section 6.
Jan 02, 2019 03:36:47.097 [0x7fe3c17fe700] DEBUG - Collections: We had 1 collections in the library already.
Jan 02, 2019 03:36:47.097 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 6 in 0ms.
Jan 02, 2019 03:36:47.102 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 1 collections in section 6 in 4ms.
Jan 02, 2019 03:36:47.110 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 6 in 7ms.
Jan 02, 2019 03:36:47.110 [0x7fe3c17fe700] DEBUG - Collections: Found 0 collections tags in section 7.
Jan 02, 2019 03:36:47.110 [0x7fe3c17fe700] DEBUG - Collections: We had 0 collections in the library already.
Jan 02, 2019 03:36:47.110 [0x7fe3c17fe700] DEBUG - Collections: Synced tags in section 7 in 0ms.
Jan 02, 2019 03:36:47.111 [0x7fe3c17fe700] DEBUG - Collections: Refreshed attributes for 0 collections in section 7 in 0ms.
Jan 02, 2019 03:36:47.112 [0x7fe3c17fe700] DEBUG - Collection: Refreshed visibility in section 7 in 0ms.
Jan 02, 2019 03:36:47.112 [0x7fe3c17fe700] DEBUG - Running database fix-ups completed in 0.0 seconds.
Jan 02, 2019 03:36:47.112 [0x7fe3c17fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.160 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.161 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.184 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.185 [0x7fe3c53ff700] DEBUG - Sync: onMyPlexSignIn
Jan 02, 2019 03:36:47.185 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/sync_lists?auth_token=REDACTED
Jan 02, 2019 03:36:47.185 [0x7fe3c63fd700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.204 [0x7fe3bb3fe700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=dc771ce3-9665-42ce-930d-f48832011bcd
Jan 02, 2019 03:36:47.206 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54719] 200 PUT /myplex/refreshReachability (11 live) TLS GZIP 617ms 268 bytes (pipelined: 2)
Jan 02, 2019 03:36:47.434 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.580 [0x7fe3bcbff700] DEBUG - Request: [127.0.0.1:45000 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (13 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:47.580 [0x7fe3bcbff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 02, 2019 03:36:47.580 [0x7fe3bcbff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 02, 2019 03:36:47.591 [0x7fe3bcbff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 02, 2019 03:36:47.591 [0x7fe3bcbff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Jan 02, 2019 03:36:47.592 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:45000] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (13 live) GZIP 12ms 465 bytes
Jan 02, 2019 03:36:47.604 [0x7fe3bebff700] DEBUG - Request: [54.194.178.55:57692 (WAN)] GET /identity (12 live) TLS Signed-in Token (orbsa)
Jan 02, 2019 03:36:47.605 [0x7fe3d53ff700] DEBUG - Completed: [54.194.178.55:57692] 200 GET /identity (12 live) TLS 0ms 386 bytes (pipelined: 1)
Jan 02, 2019 03:36:47.672 [0x7fe3b87fe700] DEBUG - Reading 148 bytes in the body, code is 200
Jan 02, 2019 03:36:47.672 [0x7fe3b87fe700] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 37471.
Jan 02, 2019 03:36:47.673 [0x7fe3b87fe700] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Jan 02, 2019 03:36:47.676 [0x7fe3b87fe700] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Jan 02, 2019 03:36:47.676 [0x7fe3b87fe700] DEBUG - HTTP requesting GET http://127.0.0.1:37471/:/plugins/com.plexapp.agents.imdb/prefs
Jan 02, 2019 03:36:47.702 [0x7fe3b87fe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:37471/:/plugins/com.plexapp.agents.imdb/prefs
Jan 02, 2019 03:36:47.702 [0x7fe3b87fe700] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Jan 02, 2019 03:36:47.703 [0x7fe3b87fe700] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as f54cb7e8313d05674494678e9c30d3ed688385ac
Jan 02, 2019 03:36:47.703 [0x7fe3b87fe700] DEBUG - IVA: Downloading trailer lists.
Jan 02, 2019 03:36:47.780 [0x7fe3c17fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.783 [0x7fe3c17fe700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 02, 2019 03:36:47.783 [0x7fe3d1bff700] DEBUG - DVR: Starting up.
Jan 02, 2019 03:36:47.784 [0x7fe3cafff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.786 [0x7fe3d1bff700] DEBUG - Activity: registered new activity 9808a494-c058-4f93-acb3-605a0849c79e - Processing subscriptions
Jan 02, 2019 03:36:47.790 [0x7fe3b87fe700] DEBUG - IVA: Downloaded 13 trailers for source 1.
Jan 02, 2019 03:36:47.821 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/sync_lists?auth_token=REDACTED
Jan 02, 2019 03:36:47.822 [0x7fe3c53ff700] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Jan 02, 2019 03:36:47.822 [0x7fe3c53ff700] DEBUG - Sync: synchronizing 0 sync lists to generators
Jan 02, 2019 03:36:47.822 [0x7fe3c53ff700] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Jan 02, 2019 03:36:47.822 [0x7fe3c53ff700] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] DEBUG - BPQ: garbageCollect - sync - forced - 10
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] DEBUG - Sync: starting Sync garbage collection: 10, forced
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] DEBUG - Sync: garbage collecting filesystem
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] WARN - Sync: local sync directory "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/Transcode/Sync+" does not exist
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] DEBUG - Sync: garbage collected filesystem
Jan 02, 2019 03:36:47.823 [0x7fe3ba3ff700] DEBUG - Sync: finished Sync garbage collection: 10
Jan 02, 2019 03:36:47.823 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:47.887 [0x7fe3b87fe700] DEBUG - IVA: Downloaded 26 trailers for source 2.
Jan 02, 2019 03:36:48.408 [0x7fe3cafff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:48.470 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:48.473 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:49.100 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:49.104 [0x7fe3c53ff700] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=REDACTED
Jan 02, 2019 03:36:49.104 [0x7fe3d0bfd700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:49.718 [0x7fe3d0bfd700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:49.939 [0x7fe3c53ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=REDACTED
Jan 02, 2019 03:36:49.941 [0x7fe3ccffd700] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Jan 02, 2019 03:36:49.941 [0x7fe3c53ff700] DEBUG - EventSource: Resolving 184.105.148.97 port 443s
Jan 02, 2019 03:36:49.942 [0x7fe3c53ff700] DEBUG - MyPlex: Updating device connections (from timer: 0)
Jan 02, 2019 03:36:49.942 [0x7fe3d4bfe700] DEBUG - EventSource: Resolved 184.105.148.97 to 184.105.148.97
Jan 02, 2019 03:36:49.942 [0x7fe3c53ff700] DEBUG - HTTP requesting PUT https://plex.tv/devices/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f?Connection[][uri]=http://256.256.256.256:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=REDACTED
Jan 02, 2019 03:36:49.947 [0x7fe3b6fff700] DEBUG - MyPlex: Sending Server Info to myPlex (user=orbsa, ip=, port=0)
Jan 02, 2019 03:36:49.949 [0x7fe3b6fff700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=REDACTED&async=1&asyncIdentifier=0d6ee1f5-b88c-49cc-a2bb-02df0b52904f
Jan 02, 2019 03:36:50.039 [0x7fe3d53ff700] DEBUG - EventSource: Connected in 66 ms.
Jan 02, 2019 03:36:50.039 [0x7fe3d53ff700] DEBUG - EventSource: Wrote data, reading reply.
Jan 02, 2019 03:36:50.621 [0x7fe3c53ff700] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f?Connection[][uri]=http://256.256.256.256:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=REDACTED
Jan 02, 2019 03:36:50.627 [0x7fe3c53ff700] DEBUG - CERT: Certificate will not expire soon.
Jan 02, 2019 03:36:50.638 [0x7fe3d53ff700] DEBUG - EventSource: Read HTTP reply header.
Jan 02, 2019 03:36:50.638 [0x7fe3d53ff700] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Jan 02, 2019 03:36:50.638 [0x7fe3d53ff700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 02, 2019 03:36:50.638 [0x7fe3d53ff700] DEBUG - EventSource: Successfully connected to 184.105.148.97.
Jan 02, 2019 03:36:50.663 [0x7fe3b6fff700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=REDACTED&async=1&asyncIdentifier=0d6ee1f5-b88c-49cc-a2bb-02df0b52904f
Jan 02, 2019 03:36:50.664 [0x7fe3b6fff700] DEBUG - MyPlex: Published Mapping State response was 201
Jan 02, 2019 03:36:50.665 [0x7fe3b6fff700] DEBUG - MyPlex: Got response for e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f ~ registered 256.256.256.256:32400
Jan 02, 2019 03:36:50.700 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:50.700 [0x7fe3bfbff700] DEBUG - Request: [73.20.48.4:54719 (WAN)] PUT /myplex/refreshReachability (11 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:50.701 [0x7fe3bfbff700] DEBUG - MyPlex: Requesting reachability check.
Jan 02, 2019 03:36:50.702 [0x7fe3bfbff700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=e58be88f-dd6f-40dd-b9ab-677cf72922c1
Jan 02, 2019 03:36:50.710 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message host="184.105.148.105" port="443" command="startRelay"/>'
Jan 02, 2019 03:36:50.710 [0x7fe3d53ff700] DEBUG - HTTP requesting GET https://downloads.plex.tv/relay/relay_v1.pub
Jan 02, 2019 03:36:50.789 [0x7fe3d53ff700] DEBUG - HTTP 200 response from GET https://downloads.plex.tv/relay/relay_v1.pub
Jan 02, 2019 03:36:50.790 [0x7fe3d53ff700] DEBUG - Relay: refreshed host key from https://downloads.plex.tv/relay/relay_v1.pub
Jan 02, 2019 03:36:50.791 [0x7fe3d53ff700] DEBUG - Relay: starting relay.
Jan 02, 2019 03:36:50.791 [0x7fe3d53ff700] DEBUG - Job running: PLEXTOKEN='REDACTED' '/usr/lib/plexmediaserver/Plex Relay' '-p' '443' '-N' '-R' '0:127.0.0.1:32401' '-o' 'StrictHostKeyChecking=yes' '-o' 'UserKnownHostsFile=/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/relayHostKey.txt' '-o' 'NoneSwitch=yes' '-o' 'NoneEnabled=yes' '-o' 'LogLevel=VERBOSE' '-o' 'PreferredAuthentications=password' '-o' 'PubkeyAuthentication=no' '-l' 'orbsa' '-F' '/dev/null' '184.105.148.105'
Jan 02, 2019 03:36:50.795 [0x7fe3d53ff700] DEBUG - Jobs: Starting child process with pid 32571
Jan 02, 2019 03:36:50.796 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message address="256.256.256.256" port="32400" asyncIdentifier="dc771ce3-9665-42ce-930d-f48832011bcd" connectivity="1" command="notifyConnectivity"/>'
Jan 02, 2019 03:36:50.804 [0x7fe3c33ff700] DEBUG - NetworkServiceBrowser: SERVER arrived: 127.0.0.1 (Thyrsos)
Jan 02, 2019 03:36:50.804 [0x7fe3c33ff700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=127.0.0.1
Jan 02, 2019 03:36:50.804 [0x7fe3c33ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=127.0.0.1
Jan 02, 2019 03:36:50.804 [0x7fe3c33ff700] DEBUG - HTTP requesting GET http://127.0.0.1:35933/system/notify/serverUpdated?host=127.0.0.1
Jan 02, 2019 03:36:50.812 [0x7fe3bdbff700] DEBUG - Request: [127.0.0.1:45036 (Loopback)] GET /servers (11 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:50.813 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45036] 200 GET /servers (11 live) GZIP 0ms 492 bytes
Jan 02, 2019 03:36:50.817 [0x7fe3bb3fe700] DEBUG - Request: [127.0.0.1:45038 (Loopback)] GET /library/sections (12 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:50.826 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45038] 200 GET /library/sections (12 live) GZIP 9ms 1133 bytes
Jan 02, 2019 03:36:50.832 [0x7fe3bcbff700] DEBUG - Request: [127.0.0.1:45040 (Loopback)] GET /channels/all (13 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:50.833 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45040] 200 GET /channels/all (13 live) GZIP 0ms 387 bytes
Jan 02, 2019 03:36:50.835 [0x7fe3c33ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:35933/system/notify/serverUpdated?host=127.0.0.1
Jan 02, 2019 03:36:50.835 [0x7fe3c33ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 02, 2019 03:36:51.107 [0x7fe3bebff700] DEBUG - Request: [52.49.250.227:55840 (WAN)] GET /identity (13 live) TLS Signed-in Token (orbsa)
Jan 02, 2019 03:36:51.108 [0x7fe3d4bfe700] DEBUG - Completed: [52.49.250.227:55840] 200 GET /identity (13 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 02, 2019 03:36:51.346 [0x7fe3bfbff700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=e58be88f-dd6f-40dd-b9ab-677cf72922c1
Jan 02, 2019 03:36:51.347 [0x7fe3d53ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (dc771ce3-9665-42ce-930d-f48832011bcd, expected e58be88f-dd6f-40dd-b9ab-677cf72922c1)
Jan 02, 2019 03:36:51.349 [0x7fe3d53ff700] DEBUG - Completed: [73.20.48.4:54719] 200 PUT /myplex/refreshReachability (13 live) TLS GZIP 648ms 268 bytes (pipelined: 3)
Jan 02, 2019 03:36:51.438 [0x7fe3d4bfe700] DEBUG - EventSource: Got event [data] '<Message address="256.256.256.256" port="32400" asyncIdentifier="0d6ee1f5-b88c-49cc-a2bb-02df0b52904f" connectivity="1" command="notifyConnectivity"/>'
Jan 02, 2019 03:36:51.438 [0x7fe3d4bfe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (0d6ee1f5-b88c-49cc-a2bb-02df0b52904f, expected e58be88f-dd6f-40dd-b9ab-677cf72922c1)
Jan 02, 2019 03:36:51.735 [0x7fe3bdbff700] DEBUG - [PlexRelay] Authenticated to 184.105.148.105 ([184.105.148.105]:443).
Jan 02, 2019 03:36:51.772 [0x7fe3bb3fe700] DEBUG - Request: [34.245.172.51:53440 (WAN)] GET /identity (13 live) TLS Signed-in Token (orbsa)
Jan 02, 2019 03:36:51.774 [0x7fe3d4bfe700] DEBUG - Completed: [34.245.172.51:53440] 200 GET /identity (13 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 02, 2019 03:36:51.906 [0x7fe3bcbff700] ERROR - [PlexRelay] kex protocol error: type 7 seq 11
Jan 02, 2019 03:36:52.102 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message address="256.256.256.256" port="32400" asyncIdentifier="e58be88f-dd6f-40dd-b9ab-677cf72922c1" connectivity="1" command="notifyConnectivity"/>'
Jan 02, 2019 03:36:52.102 [0x7fe3d53ff700] DEBUG - PubSub: Got notified of reachability: 1 for 256.256.256.256:32400
Jan 02, 2019 03:36:52.119 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:52.120 [0x7fe3bebff700] DEBUG - Request: [73.20.48.4:54719 (WAN)] GET /myplex/account (13 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:52.123 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54719] 200 GET /myplex/account (13 live) TLS GZIP 3ms 2087 bytes (pipelined: 4)
Jan 02, 2019 03:36:52.185 [0x7fe3d13fe700] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Jan 02, 2019 03:36:52.535 [0x7fe3bfbff700] INFO - [PlexRelay] Allocated port 19709 for remote forward to 127.0.0.1:32401
Jan 02, 2019 03:36:52.822 [0x7fe3d13fe700] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 184.105.148.97 was 65 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 184.105.148.98 was 66 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 45.33.118.95 was 68 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 50.116.44.223 was 113 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 45.33.75.206 was 121 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 252 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 151.236.217.85 was 254 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 82.94.168.54 was 267 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 172.104.133.220 was 290 ms.
Jan 02, 2019 03:36:53.500 [0x7fe3d13fe700] DEBUG - PubSubManager: Time to connect to 103.3.62.6 was 450 ms.
Jan 02, 2019 03:36:53.501 [0x7fe3d13fe700] DEBUG - PubSubManager: Updating best ping time for 184.105.148.97 to 65 ms.
Jan 02, 2019 03:36:53.755 [0x7fe3cdbfe700] DEBUG - DVR:Device: Discovering and refreshing all devices.
Jan 02, 2019 03:36:53.756 [0x7fe3cdbfe700] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Jan 02, 2019 03:36:53.756 [0x7fe3cdbfe700] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Jan 02, 2019 03:36:55.278 [0x7fe3c63fd700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=REDACTED
Jan 02, 2019 03:36:56.171 [0x7fe3cdbfe700] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Jan 02, 2019 03:36:57.787 [0x7fe3ccffd700] DEBUG - Activity: registered new activity 031f59fd-2788-4673-90b8-775ef7538606 - Processing subscriptions
Jan 02, 2019 03:36:57.788 [0x7fe3ccffd700] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - DVR:Subscription: Initialized 0 schedulers.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - Activity: Ended activity 031f59fd-2788-4673-90b8-775ef7538606.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - Activity: Ended activity 9808a494-c058-4f93-acb3-605a0849c79e.
Jan 02, 2019 03:36:57.789 [0x7fe3ccffd700] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Jan 02, 2019 03:36:57.817 [0x7fe3d63ff700] DEBUG - NAT: UPnP, didn't find usable IGD.
Jan 02, 2019 03:36:57.817 [0x7fe3d63ff700] DEBUG - NAT: PMP, attempting mapping.
Jan 02, 2019 03:36:58.067 [0x7fe3d63ff700] WARN - NAT: PMP, got an error: Not Supported by gateway.
Jan 02, 2019 03:36:58.068 [0x7fe3d63ff700] DEBUG - NAT: UPnP, attempting port mapping.
Jan 02, 2019 03:36:58.091 [0x7fe3ce3ff700] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
Jan 02, 2019 03:36:58.342 [0x7fe3ce3ff700] WARN - NAT: PMP, got an error: Not Supported by gateway.
Jan 02, 2019 03:36:58.348 [0x7fe3ce3ff700] DEBUG - MyPlex: Sending Server Info to myPlex (user=orbsa, ip=, port=32400)
Jan 02, 2019 03:36:58.350 [0x7fe3ce3ff700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=REDACTED&async=1&asyncIdentifier=eed858b9-c09a-4ab8-9b5a-0221144e5c01
Jan 02, 2019 03:36:59.314 [0x7fe3ce3ff700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=REDACTED&async=1&asyncIdentifier=eed858b9-c09a-4ab8-9b5a-0221144e5c01
Jan 02, 2019 03:36:59.315 [0x7fe3ce3ff700] DEBUG - MyPlex: Published Mapping State response was 201
Jan 02, 2019 03:36:59.315 [0x7fe3ce3ff700] DEBUG - MyPlex: Got response for e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f ~ registered 256.256.256.256:32400
Jan 02, 2019 03:36:59.316 [0x7fe3ce3ff700] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 02, 2019 03:36:59.337 [0x7fe3d4bfe700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:36:59.338 [0x7fe3bdbff700] DEBUG - Request: [73.20.48.4:54719 (WAN)] PUT /myplex/refreshReachability (12 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:36:59.338 [0x7fe3bdbff700] DEBUG - MyPlex: Requesting reachability check.
Jan 02, 2019 03:36:59.340 [0x7fe3bdbff700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=c3f5b8e2-4fda-49f0-bb9f-3c23f98ff6b7
Jan 02, 2019 03:36:59.758 [0x7fe3bb3fe700] DEBUG - Request: [34.248.59.52:41634 (WAN)] GET /identity (13 live) TLS Signed-in Token (orbsa)
Jan 02, 2019 03:36:59.759 [0x7fe3d4bfe700] DEBUG - Completed: [34.248.59.52:41634] 200 GET /identity (13 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 02, 2019 03:36:59.949 [0x7fe3bdbff700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/connectivity?X-Plex-Token=REDACTED&asyncIdentifier=c3f5b8e2-4fda-49f0-bb9f-3c23f98ff6b7
Jan 02, 2019 03:36:59.951 [0x7fe3d4bfe700] DEBUG - Completed: [73.20.48.4:54719] 200 PUT /myplex/refreshReachability (13 live) TLS GZIP 613ms 268 bytes (pipelined: 5)
Jan 02, 2019 03:37:00.086 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message address="256.256.256.256" port="32400" asyncIdentifier="eed858b9-c09a-4ab8-9b5a-0221144e5c01" connectivity="1" command="notifyConnectivity"/>'
Jan 02, 2019 03:37:00.087 [0x7fe3d53ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (eed858b9-c09a-4ab8-9b5a-0221144e5c01, expected c3f5b8e2-4fda-49f0-bb9f-3c23f98ff6b7)
Jan 02, 2019 03:37:00.368 [0x7fe3bcbff700] DEBUG - Request: [52.49.250.227:52854 (WAN)] GET /identity (13 live) TLS Signed-in Token (orbsa)
Jan 02, 2019 03:37:00.369 [0x7fe3d4bfe700] DEBUG - Completed: [52.49.250.227:52854] 200 GET /identity (13 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 02, 2019 03:37:00.698 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message address="256.256.256.256" port="32400" asyncIdentifier="c3f5b8e2-4fda-49f0-bb9f-3c23f98ff6b7" connectivity="1" command="notifyConnectivity"/>'
Jan 02, 2019 03:37:00.698 [0x7fe3d53ff700] DEBUG - PubSub: Got notified of reachability: 1 for 256.256.256.256:32400
Jan 02, 2019 03:37:00.714 [0x7fe3d53ff700] DEBUG - Auth: authenticated user 1 as orbsa
Jan 02, 2019 03:37:00.715 [0x7fe3bebff700] DEBUG - Request: [73.20.48.4:54719 (WAN)] GET /myplex/account (13 live) TLS GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:37:00.718 [0x7fe3d53ff700] DEBUG - Completed: [73.20.48.4:54719] 200 GET /myplex/account (13 live) TLS GZIP 2ms 2087 bytes (pipelined: 6)
Jan 02, 2019 03:37:10.080 [0x7fe3d63ff700] DEBUG - NAT: UPnP, didn't find usable IGD.
Jan 02, 2019 03:37:10.080 [0x7fe3d63ff700] DEBUG - PublicAddressManager: Mapping failed.
Jan 02, 2019 03:37:10.081 [0x7fe3d63ff700] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 02, 2019 03:37:16.053 [0x7fe3d53ff700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:37:16.053 [0x7fe3d53ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:17.425 [0x7fe3d53ff700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:17.426 [0x7fe3d53ff700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:17.428 [0x7fe3d53ff700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:37:17.431 [0x7fe3d0bfd700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:17.431 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:17.432 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61125 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:17.432 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61125] 401 GET /media/providers (7 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:18.053 [0x7fe3d0bfd700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:18.128 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:18.128 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33482 (WAN)] GET /media/providers (8 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:18.130 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33482] 401 GET /media/providers (8 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:19.817 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:19.818 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61133 (WAN)] GET /media/providers (8 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:19.819 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61133] 401 GET /media/providers (8 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:20.087 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:20.087 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33484 (WAN)] GET /media/providers (9 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:20.088 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33484] 401 GET /media/providers (9 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:20.805 [0x7fe3c63fd700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:21.424 [0x7fe3c63fd700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:46.349 [0x7fe3d4bfe700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:37:46.349 [0x7fe3d4bfe700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:47.608 [0x7fe3d4bfe700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:47.609 [0x7fe3d4bfe700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:47.611 [0x7fe3d4bfe700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:37:47.613 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:47.613 [0x7fe3d4bfe700] DEBUG - Request: [MY-Friends-IP:61140 (WAN)] GET /media/providers (5 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:47.613 [0x7fe3d0bfd700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:47.614 [0x7fe3d4bfe700] DEBUG - Completed: [MY-Friends-IP:61140] 401 GET /media/providers (5 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:47.896 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:47.896 [0x7fe3d4bfe700] DEBUG - Request: [127.0.0.1:33494 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:47.898 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:33494] 401 GET /media/providers (6 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:37:48.236 [0x7fe3d0bfd700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:48.427 [0x7fe3cafff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:48.921 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:48.922 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61142 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:48.923 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61142] 401 GET /media/providers (6 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:49.036 [0x7fe3cafff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:49.310 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:49.310 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33498 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:49.312 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33498] 401 GET /media/providers (7 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:37:50.637 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:50.637 [0x7fe3d4bfe700] DEBUG - Request: [MY-Friends-IP:61144 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:50.639 [0x7fe3d4bfe700] DEBUG - Completed: [MY-Friends-IP:61144] 401 GET /media/providers (7 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:50.923 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:50.923 [0x7fe3d4bfe700] DEBUG - Request: [127.0.0.1:33500 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:50.925 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:33500] 401 GET /media/providers (7 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:37:53.979 [0x7fe3d53ff700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:37:53.979 [0x7fe3d53ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:55.640 [0x7fe3d53ff700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:55.641 [0x7fe3d53ff700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:37:55.643 [0x7fe3d53ff700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:37:55.646 [0x7fe3c63fd700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:55.646 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:55.646 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61146 (WAN)] GET /media/providers (5 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:55.647 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61146] 401 GET /media/providers (5 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:55.926 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:55.927 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33506 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:55.928 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33506] 401 GET /media/providers (6 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:37:56.269 [0x7fe3c63fd700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:56.427 [0x7fe3d2bff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:57.055 [0x7fe3d2bff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:37:57.359 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:57.359 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61149 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:57.360 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61149] 401 GET /media/providers (6 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:37:57.627 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:37:57.627 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33510 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:37:57.629 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33510] 401 GET /media/providers (7 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:38:43.523 [0x7fe3cbfff700] DEBUG - BPQ: [Starting] -> [Processing]
Jan 02, 2019 03:38:43.527 [0x7fe3cbfff700] DEBUG - BPQ: generating queue items from 0 generator(s)
Jan 02, 2019 03:38:43.527 [0x7fe3cbfff700] DEBUG - BPQ: generated 0 item(s) for queue
Jan 02, 2019 03:38:43.527 [0x7fe3cbfff700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 02, 2019 03:38:43.527 [0x7fe3cbfff700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 02, 2019 03:38:43.534 [0x7fe3cbfff700] DEBUG - BPQ: [Processing] -> [Idle]
Jan 02, 2019 03:38:45.762 [0x7fe3ccffd700] DEBUG - Request: [127.0.0.1:45112 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:38:45.763 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45112] 200 GET /servers (5 live) GZIP 1ms 492 bytes
Jan 02, 2019 03:38:45.768 [0x7fe3bebff700] DEBUG - Request: [127.0.0.1:45114 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:38:45.773 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:45114] 200 GET /library/sections (5 live) GZIP 4ms 1133 bytes
Jan 02, 2019 03:38:45.777 [0x7fe3ccffd700] DEBUG - Request: [127.0.0.1:45116 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:38:45.778 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45116] 200 GET /channels/all (5 live) GZIP 1ms 387 bytes
Jan 02, 2019 03:38:45.787 [0x7fe3bebff700] DEBUG - Request: [127.0.0.1:45118 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:38:45.791 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45118] 200 GET /library/sections (5 live) GZIP 4ms 1133 bytes
Jan 02, 2019 03:38:45.796 [0x7fe3ccffd700] DEBUG - Request: [127.0.0.1:45120 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (orbsa)
Jan 02, 2019 03:38:45.797 [0x7fe3d4bfe700] DEBUG - Completed: [127.0.0.1:45120] 200 GET /channels/all (5 live) GZIP 1ms 387 bytes
Jan 02, 2019 03:38:58.678 [0x7fe3d53ff700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:38:58.679 [0x7fe3d53ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:38:59.939 [0x7fe3d53ff700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:38:59.940 [0x7fe3d53ff700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:38:59.942 [0x7fe3d53ff700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:38:59.945 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:38:59.945 [0x7fe3d53ff700] DEBUG - Request: [MY-Friends-IP:61172 (WAN)] GET /media/providers (5 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:38:59.945 [0x7fe3cdbfe700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:38:59.945 [0x7fe3d53ff700] DEBUG - Completed: [MY-Friends-IP:61172] 401 GET /media/providers (5 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:39:00.572 [0x7fe3cdbfe700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:00.809 [0x7fe3ce3ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:01.436 [0x7fe3ce3ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:02.374 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:39:02.374 [0x7fe3d4bfe700] DEBUG - Request: [MY-Friends-IP:61179 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:39:02.376 [0x7fe3d4bfe700] DEBUG - Completed: [MY-Friends-IP:61179] 401 GET /media/providers (6 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:39:02.644 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:39:02.644 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33530 (WAN)] GET /media/providers (7 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:39:02.646 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33530] 401 GET /media/providers (7 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:39:05.366 [0x7fe3d4bfe700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 02, 2019 03:39:05.366 [0x7fe3d4bfe700] DEBUG - HTTP requesting GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:39:06.770 [0x7fe3d4bfe700] DEBUG - HTTP 500 response from GET https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:39:06.771 [0x7fe3d4bfe700] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/e95cbb18fb1faf4aa2c74e41cd5dbca962766c4f/access_tokens.xml?auth_token=REDACTED&includeProfiles=1&includeProviders=1&unknownToken=ptpxNXnf5xLdPqsdoJBe
Jan 02, 2019 03:39:06.772 [0x7fe3d4bfe700] DEBUG - MyPlex: updating with 33 access tokens
Jan 02, 2019 03:39:06.776 [0x7fe3cafff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:06.776 [0x7fe3d4bfe700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:39:06.776 [0x7fe3d4bfe700] DEBUG - Request: [MY-Friends-IP:61181 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:39:06.777 [0x7fe3d4bfe700] DEBUG - Completed: [MY-Friends-IP:61181] 401 GET /media/providers (6 live) TLS GZIP 0ms 411 bytes
Jan 02, 2019 03:39:06.840 [0x7fe3d53ff700] DEBUG - Auth: authenticating user as guest
Jan 02, 2019 03:39:06.840 [0x7fe3d53ff700] DEBUG - Request: [127.0.0.1:33526 (WAN)] GET /media/providers (6 live) TLS GZIP Signed-in Token ()
Jan 02, 2019 03:39:06.841 [0x7fe3d53ff700] DEBUG - Completed: [127.0.0.1:33526] 401 GET /media/providers (6 live) TLS GZIP 1ms 411 bytes
Jan 02, 2019 03:39:07.404 [0x7fe3cafff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:07.810 [0x7fe3d2bff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:08.479 [0x7fe3d2bff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=REDACTED
Jan 02, 2019 03:39:13.794 [0x7fe3c63fd700] DEBUG - Statistics: Flushing 17 expired bandwidth entries, 0 expired media entries.
Jan 02, 2019 03:39:42.307 [0x7fe3d53ff700] DEBUG - EventSource: Got event [data] '<Message host="184.105.148.109" port="443" command="startRelay"/>'
Jan 02, 2019 03:39:42.307 [0x7fe3d53ff700] DEBUG - Relay: reused an existing key for https://downloads.plex.tv/relay/relay_v1.pub
Jan 02, 2019 03:39:42.307 [0x7fe3d53ff700] DEBUG - Relay: starting relay.
Jan 02, 2019 03:39:42.307 [0x7fe3d53ff700] DEBUG - Job running: PLEXTOKEN='REDACTED' '/usr/lib/plexmediaserver/Plex Relay' '-p' '443' '-N' '-R' '0:127.0.0.1:32401' '-o' 'StrictHostKeyChecking=yes' '-o' 'UserKnownHostsFile=/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/relayHostKey.txt' '-o' 'NoneSwitch=yes' '-o' 'NoneEnabled=yes' '-o' 'LogLevel=VERBOSE' '-o' 'PreferredAuthentications=password' '-o' 'PubkeyAuthentication=no' '-l' 'orbsa' '-F' '/dev/null' '184.105.148.109'
Jan 02, 2019 03:39:42.312 [0x7fe3d53ff700] DEBUG - Jobs: Starting child process with pid 32584
Jan 02, 2019 03:39:43.260 [0x7fe3ccffd700] DEBUG - [PlexRelay] Authenticated to 184.105.148.109 ([184.105.148.109]:443).
Jan 02, 2019 03:39:43.427 [0x7fe3bebff700] ERROR - [PlexRelay] kex protocol error: type 7 seq 11
Jan 02, 2019 03:39:43.805 [0x7fe3cafff700] DEBUG - Statistics: Flushing 3 expired bandwidth entries, 0 expired media entries.
Jan 02, 2019 03:39:44.045 [0x7fe3ccffd700] INFO - [PlexRelay] Allocated port 1115 for remote forward to 127.0.0.1:32401