May 09, 2019 12:01:04.601 [5192] INFO - Plex Media Server v1.15.4.994-107756f7e - Microsoft PC x64 - build: windows-x86 windows - GMT 01:00
May 09, 2019 12:01:04.601 [5192] INFO - Windows version: 10.0 (Build 17763), language en-US
May 09, 2019 12:01:04.601 [5192] INFO - 8 3600 MHz processor(s): Architecture=0, Level=6, Revision=40457 Processor Identifier=Intel64 Family 6 Model 158 Stepping 9, GenuineIntel
May 09, 2019 12:01:04.601 [5192] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
May 09, 2019 12:01:04.601 [12740] DEBUG - Plex for Windows Store application is not installed
May 09, 2019 12:01:04.601 [12740] DEBUG - BPQ: [Idle] -> [Starting]
May 09, 2019 12:01:04.601 [12740] DEBUG - FeatureManager: Using cached data for features list
May 09, 2019 12:01:04.601 [12740] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
May 09, 2019 12:01:04.648 [12740] DEBUG - Running migrations. (EPG 0)
May 09, 2019 12:01:04.648 [12740] DEBUG - ChangestampAllocator: initialized to 280147
May 09, 2019 12:01:04.648 [12740] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.26.0, threadsafe=1
May 09, 2019 12:01:04.648 [12740] DEBUG - Running migrations. (EPG 0)
May 09, 2019 12:01:05.241 [12740] DEBUG - CERT: Installed certificate with fingerprint 5c:96:80:23:62:db:f6:12:d6:99:62:34:31:19:2d:42:5f:0d:5e:40.
May 09, 2019 12:01:05.241 [12740] DEBUG - CERT: Installed new private key.
May 09, 2019 12:01:05.241 [12740] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.5520a132865040c59d29b9d38c11dc0d.plex.direct
May 09, 2019 12:01:05.257 [12740] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
May 09, 2019 12:01:05.257 [12740] INFO - OCSP: Successfully retrieved response from cache.
May 09, 2019 12:01:05.257 [12740] DEBUG - CERT: Installed intermediate certificate.
May 09, 2019 12:01:05.257 [12740] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
May 09, 2019 12:01:05.257 [12740] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
May 09, 2019 12:01:05.288 [13024] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
May 09, 2019 12:01:05.288 [12740] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
May 09, 2019 12:01:05.288 [12740] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
May 09, 2019 12:01:05.288 [15252] DEBUG - Relay: read 1 cached entries from hosts file
May 09, 2019 12:01:05.288 [15252] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:05.288 [13024] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
May 09, 2019 12:01:05.288 [12740] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 09, 2019 12:01:05.288 [12740] DEBUG - MyPlex: updating with 7 access tokens
May 09, 2019 12:01:05.288 [12740] DEBUG - Opening 1 database sessions to library (), SQLite 3.26.0, threadsafe=1
May 09, 2019 12:01:05.288 [13300] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
May 09, 2019 12:01:05.288 [13300] DEBUG - HTTP requesting GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 09, 2019 12:01:05.288 [5360] DEBUG - File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e" changed: -1 => 2019-05-09 11:59:20 (1557399560).
May 09, 2019 12:01:05.288 [5360] DEBUG - File "C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2018-12-15 11:18:36 (1544872716).
May 09, 2019 12:01:05.288 [5360] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e"
May 09, 2019 12:01:05.288 [5360] DEBUG - Scanning for plug-ins in "C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins"
May 09, 2019 12:01:05.288 [5360] DEBUG - PluginRepository::setStartState: 1, startingSystem
May 09, 2019 12:01:05.288 [5360] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\System.bundle.
May 09, 2019 12:01:05.288 [5360] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
May 09, 2019 12:01:05.288 [5360] DEBUG - Starting file watcher for com.plexapp.system
May 09, 2019 12:01:05.304 [5360] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files (x86)\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\System.bundle"
May 09, 2019 12:01:05.304 [5360] DEBUG - Spawned plug-in com.plexapp.system with PID 16192
May 09, 2019 12:01:05.304 [5360] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

May 09, 2019 12:01:05.757 [15252] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:05.788 [13300] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 09, 2019 12:01:05.788 [13300] DEBUG - MyPlex: updating with 7 access tokens
May 09, 2019 12:01:05.788 [3880] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:05.944 [3880] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:05.944 [3880] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:05.944 [13024] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.085 [3880] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.085 [3880] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.116 [13024] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.304 [3880] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.600 [15252] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\RICHA\AppData\Local\Plex Media Server\Crash Reports\1.15.4.994-107756f7e" --version=1.15.4.994-107756f7e --platform=Windows "--platformVersion=10.0 (Build 17763)" --serverUuid=9bb5e326fe8035f501ef3fd2c09078924475dfc2 --userId=richard49@gmail.com --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx --vendor=Microsoft --model=x64 --device=PC 
May 09, 2019 12:01:06.600 [15252] DEBUG - Jobs: Starting child process with pid 11464
May 09, 2019 12:01:06.647 [14868] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 11464 is 0 (success)
May 09, 2019 12:01:06.788 [5360] DEBUG - Reading 486 bytes in the body, code is 200
May 09, 2019 12:01:06.788 [5360] DEBUG - [com.plexapp.system] Plug-in running on port 50775.
May 09, 2019 12:01:06.788 [5360] DEBUG -  * Plug-in handles prefix: /system
May 09, 2019 12:01:06.788 [5360] DEBUG -  * Plug-in handles prefix: /player
May 09, 2019 12:01:06.788 [5360] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
May 09, 2019 12:01:06.788 [5360] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
May 09, 2019 12:01:06.788 [5360] DEBUG - PluginRepository::setStartState: 2, startingPlugins
May 09, 2019 12:01:06.788 [5360] DEBUG - Updating the list of agents known by the system.
May 09, 2019 12:01:06.788 [5360] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
May 09, 2019 12:01:06.788 [12740] DEBUG - Image transcode cache directory: "C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
May 09, 2019 12:01:06.788 [5360] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents
May 09, 2019 12:01:06.788 [12740] DEBUG - Transcoder: Cleaning old transcode directories.
May 09, 2019 12:01:06.788 [12740] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 09, 2019 12:01:06.788 [12740] DEBUG - MyPlex: updating with 7 access tokens
May 09, 2019 12:01:06.788 [15252] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.788 [12740] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 09, 2019 12:01:06.788 [12740] DEBUG - MyPlex: username is richard49, login is richard49@gmail.com, home is 0, no pin
May 09, 2019 12:01:06.788 [12740] DEBUG - PublicAddressManager: Starting.
May 09, 2019 12:01:06.788 [12740] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 09, 2019 12:01:06.788 [14580] DEBUG - PublicAddressManager: Obtaining public IP.
May 09, 2019 12:01:06.788 [11000] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 09, 2019 12:01:06.788 [14580] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: ignoring adapter 'Wi-Fi' (fe80::a1f1:bfe7:9ba4:ab95%5,169.254.171.149) because it has no gateway
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: ignoring adapter 'Local Area Connection* 3' (fe80::a97a:50a6:86c1:702a%4,169.254.112.42) because it has no gateway
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: ignoring adapter 'Local Area Connection* 1' (fe80::f8e0:a0f6:33dc:a339%18,169.254.163.57) because it has no gateway
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 2' (fe80::49d2:d60a:7d08:1878%10,10.102.37.150,169.254.24.120) because it has no gateway
May 09, 2019 12:01:06.788 [12740] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
May 09, 2019 12:01:06.788 [12740] DEBUG - Detected primary interface: 192.168.0.32
May 09, 2019 12:01:06.788 [12740] DEBUG - Network interfaces:
May 09, 2019 12:01:06.788 [12740] DEBUG -  * 7 Ethernet {4DDAA004-5AFB-4F9E-B56D-03836F6C5191} (192.168.0.32) (loopback: 0)
May 09, 2019 12:01:06.788 [12740] DEBUG - Creating NetworkServices singleton.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkServices: Initializing...
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [11564] DEBUG - Network change for advertiser.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Creating new service.
May 09, 2019 12:01:06.803 [11564] DEBUG - Network change for advertiser.
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
May 09, 2019 12:01:06.803 [12740] DEBUG - NetworkService: Quick dispatch of network change.
May 09, 2019 12:01:06.803 [11564] DEBUG - Network change for advertiser.
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 192.168.0.255 (index: 0)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 192.168.0.255 (index: 0)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
May 09, 2019 12:01:06.803 [11564] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 239.255.255.250 (index: 0)
May 09, 2019 12:01:06.866 [14580] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:06.866 [14580] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 81.107.172.154
May 09, 2019 12:01:06.882 [12740] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
May 09, 2019 12:01:06.882 [12740] DEBUG - Gracenote: Using SQLite 3.26.0
May 09, 2019 12:01:06.882 [12328] DEBUG - Plex Dlna Server.exe starting.
May 09, 2019 12:01:06.882 [12328] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Dlna Server.exe 
May 09, 2019 12:01:06.882 [12740] DEBUG - Starting HTTP server.
May 09, 2019 12:01:06.882 [12740] DEBUG - Running server...
May 09, 2019 12:01:06.882 [12336] DEBUG - Plex Tuner Service.exe starting.
May 09, 2019 12:01:06.882 [12740] DEBUG - Waiting for server condition to be signaled.
May 09, 2019 12:01:06.882 [12728] DEBUG - HttpServer: Set up a thread pool with 2 threads.
May 09, 2019 12:01:06.882 [12728] DEBUG - Running database fix-ups.
May 09, 2019 12:01:06.882 [12976] DEBUG - Request: [127.0.0.1:50770 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.882 [11976] DEBUG - Request: [127.0.0.1:50771 (Loopback)] GET / (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale ar
May 09, 2019 12:01:06.897 [12028] DEBUG - Request: [127.0.0.1:50773 (Loopback)] GET / (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale cs_CZ
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale da
May 09, 2019 12:01:06.897 [12672] DEBUG - Request: [127.0.0.1:50774 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale de
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale en
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale es
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale et_EE
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale fr
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale he
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale hr_HR
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale hu_HU
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale it
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale ja_JP
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale ko
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale lt
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale nl
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale nl_BE
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale no
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale pt_BR
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale ro
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale ru
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale sr
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale sv
May 09, 2019 12:01:06.897 [12976] DEBUG - Translator: Found locale zh_CN
May 09, 2019 12:01:06.897 [12328] DEBUG - Jobs: Starting child process with pid 12668
May 09, 2019 12:01:06.897 [12336] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Tuner Service.exe "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Private" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Shared" 1.15.4.994-107756f7e 32600 /waitmutex 
May 09, 2019 12:01:06.897 [12728] DEBUG - Database Fixups: Destroyed 0 transient items.
May 09, 2019 12:01:06.913 [5360] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/agents
May 09, 2019 12:01:06.913 [5360] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Found 0 collections tags in section 1.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: We had 0 collections in the library already.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Synced tags in section 1 in 0ms.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collection: Refreshed visibility in section 1 in 1ms.
May 09, 2019 12:01:06.913 [5360] DEBUG - The system knows about 13 agents.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Found 0 collections tags in section 2.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: We had 0 collections in the library already.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Fanart-TV.bundle.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Synced tags in section 2 in 0ms.
May 09, 2019 12:01:06.913 [5360] DEBUG - Waiting for 18 plug-ins to finish starting.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
May 09, 2019 12:01:06.913 [12976] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:06.913 [12976] DEBUG - HTTP requesting GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:06.913 [12728] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Found 0 collections tags in section 5.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: We had 0 collections in the library already.
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Synced tags in section 5 in 0ms.
May 09, 2019 12:01:06.913 [16132] DEBUG - Completed: [127.0.0.1:50774] 200 GET /servers (5 live) GZIP 22ms 496 bytes
May 09, 2019 12:01:06.913 [12728] DEBUG - Collections: Refreshed attributes for 0 collections in section 5 in 0ms.
May 09, 2019 12:01:06.913 [13852] DEBUG - Completed: [127.0.0.1:50773] 200 GET / (5 live) GZIP 23ms 3025 bytes
May 09, 2019 12:01:06.913 [12728] DEBUG - Collection: Refreshed visibility in section 5 in 0ms.
May 09, 2019 12:01:06.913 [12728] DEBUG - Running database fix-ups completed in 0.1 seconds.
May 09, 2019 12:01:06.913 [16132] DEBUG - Completed: [127.0.0.1:50771] 200 GET / (5 live) GZIP 24ms 3025 bytes
May 09, 2019 12:01:06.913 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\HTbackdrops.bundle.
May 09, 2019 12:01:06.913 [12336] DEBUG - Jobs: Starting child process with pid 9672
May 09, 2019 12:01:06.913 [5680] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Framework.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\LastFM.bundle.
May 09, 2019 12:01:06.913 [0832] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
May 09, 2019 12:01:06.913 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\LocalMedia.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
May 09, 2019 12:01:06.913 [5680] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\LyricFind.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Media-Flags.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\MoviePosterDB.bundle.
May 09, 2019 12:01:06.913 [0832] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
May 09, 2019 12:01:06.913 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\OpenSubtitles.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\PersonalMedia.bundle.
May 09, 2019 12:01:06.913 [5680] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
May 09, 2019 12:01:06.913 [0832] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
May 09, 2019 12:01:06.913 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\PlexMovie.bundle.
May 09, 2019 12:01:06.913 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
May 09, 2019 12:01:06.913 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\PlexMusic.bundle.
May 09, 2019 12:01:06.928 [0832] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
May 09, 2019 12:01:06.928 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Scanners.bundle.
May 09, 2019 12:01:06.928 [5680] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\PlexThemeMusic.bundle.
May 09, 2019 12:01:06.928 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\TheMovieDB.bundle.
May 09, 2019 12:01:06.928 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
May 09, 2019 12:01:06.928 [6600] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\TheTVDB.bundle.
May 09, 2019 12:01:06.928 [0832] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
May 09, 2019 12:01:06.928 [0832] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle.
May 09, 2019 12:01:06.928 [5680] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
May 09, 2019 12:01:06.928 [5680] DEBUG - Starting plug-in C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
May 09, 2019 12:01:06.928 [6600] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
May 09, 2019 12:01:06.928 [5360] DEBUG - Starting scheduled updates, every 3600 seconds
May 09, 2019 12:01:06.928 [5360] INFO - LibraryUpdateManager path watching is disabled
May 09, 2019 12:01:06.928 [5360] DEBUG - Done waiting for all plug-ins to start.
May 09, 2019 12:01:06.928 [5360] DEBUG - PluginRepository::setStartState: 3, complete
May 09, 2019 12:01:06.928 [14736] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
May 09, 2019 12:01:06.928 [12672] DEBUG - Request: [127.0.0.1:50783 (Loopback)] GET / (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.928 [14736] DEBUG - Starting file watcher for com.plexapp.agents.imdb
May 09, 2019 12:01:06.928 [12028] DEBUG - Request: [127.0.0.1:50784 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.928 [16132] DEBUG - Completed: [127.0.0.1:50783] 200 GET / (7 live) GZIP 2ms 3013 bytes
May 09, 2019 12:01:06.928 [14736] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files (x86)\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\PlexMovie.bundle"
May 09, 2019 12:01:06.928 [12728] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 09, 2019 12:01:06.928 [16132] DEBUG - Completed: [127.0.0.1:50784] 200 GET /library/sections (7 live) GZIP 3ms 928 bytes
May 09, 2019 12:01:06.928 [12728] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.944 [11976] DEBUG - Request: [127.0.0.1:50786 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.944 [16132] DEBUG - Completed: [127.0.0.1:50786] 200 GET /channels/all (7 live) GZIP 1ms 387 bytes
May 09, 2019 12:01:06.944 [14736] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 3084
May 09, 2019 12:01:06.944 [14736] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

May 09, 2019 12:01:06.944 [11976] DEBUG - Request: [127.0.0.1:50787 (Loopback)] GET /library/sections (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.960 [16132] DEBUG - Completed: [127.0.0.1:50787] 200 GET /library/sections (8 live) GZIP 2ms 928 bytes
May 09, 2019 12:01:06.975 [15252] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:06.975 [12672] DEBUG - Request: [127.0.0.1:50788 (Loopback)] GET /channels/all (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:06.975 [16132] DEBUG - Completed: [127.0.0.1:50788] 200 GET /channels/all (8 live) GZIP 1ms 387 bytes
May 09, 2019 12:01:07.022 [12976] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:07.022 [12976] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
May 09, 2019 12:01:07.022 [16132] DEBUG - Completed: [127.0.0.1:50770] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (8 live) GZIP 139ms 19473 bytes
May 09, 2019 12:01:07.038 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 09, 2019 12:01:07.038 [11000] DEBUG - MyPlex: username is richard49, login is richard49@gmail.com, home is 0, no pin
May 09, 2019 12:01:07.038 [11000] DEBUG - HTTP requesting GET https://plex.tv/api/v2/geoip
May 09, 2019 12:01:07.053 [13300] DEBUG - Gracenote: Loading 29 locales in 0.2 seconds.
May 09, 2019 12:01:07.116 [12728] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.116 [12728] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.194 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/geoip
May 09, 2019 12:01:07.194 [11000] DEBUG - EventSource: Resolving 176.58.127.172 port 443
May 09, 2019 12:01:07.194 [11000] DEBUG - Sync: onMyPlexSignIn
May 09, 2019 12:01:07.194 [13852] DEBUG - EventSource: Resolved 176.58.127.172 to 176.58.127.172
May 09, 2019 12:01:07.194 [11000] DEBUG - HTTP requesting GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.272 [12728] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.272 [13852] DEBUG - EventSource: Connected in 60 ms.
May 09, 2019 12:01:07.272 [16132] DEBUG - EventSource: Wrote data, reading reply.
May 09, 2019 12:01:07.272 [3880] DEBUG - DVR: Starting up.
May 09, 2019 12:01:07.272 [12728] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.272 [13300] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.272 [3880] DEBUG - Activity: registered new activity cd0090f1-7442-42d7-a399-76ab6253a9bb - Processing subscriptions
May 09, 2019 12:01:07.397 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.397 [11000] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
May 09, 2019 12:01:07.397 [11000] DEBUG - Sync: synchronizing 0 sync lists to generators
May 09, 2019 12:01:07.397 [11000] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
May 09, 2019 12:01:07.397 [11000] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
May 09, 2019 12:01:07.397 [15876] DEBUG - BPQ: garbageCollect - sync - forced - 10
May 09, 2019 12:01:07.397 [15876] DEBUG - Sync: starting Sync garbage collection: 10, forced
May 09, 2019 12:01:07.397 [15876] DEBUG - Sync: garbage collecting filesystem
May 09, 2019 12:01:07.397 [11000] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.397 [15876] WARN - Sync: local sync directory "C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
May 09, 2019 12:01:07.397 [15876] DEBUG - Sync: garbage collected filesystem
May 09, 2019 12:01:07.397 [15876] DEBUG - Sync: finished Sync garbage collection: 10
May 09, 2019 12:01:07.460 [12728] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.475 [13300] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.616 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.616 [11000] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.741 [12028] DEBUG - Request: [127.0.0.1:50820 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:07.741 [12028] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
May 09, 2019 12:01:07.741 [12028] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/messaging/clear_events/com.plexapp.agents.imdb
May 09, 2019 12:01:07.772 [12028] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/messaging/clear_events/com.plexapp.agents.imdb
May 09, 2019 12:01:07.772 [12028] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
May 09, 2019 12:01:07.772 [16132] DEBUG - Completed: [127.0.0.1:50820] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (8 live) GZIP 25ms 330 bytes
May 09, 2019 12:01:07.772 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.772 [15252] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.772 [13024] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.772 [11000] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.819 [11976] DEBUG - Request: [127.0.0.1:50825 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:07.819 [11976] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
May 09, 2019 12:01:07.819 [11976] DEBUG - HTTP requesting GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/resourceHashes
May 09, 2019 12:01:07.897 [11976] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/resourceHashes
May 09, 2019 12:01:07.897 [11976] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
May 09, 2019 12:01:07.897 [16132] DEBUG - Completed: [127.0.0.1:50825] 200 GET /:/plugins/com.plexapp.system/resourceHashes (8 live) GZIP 71ms 3498 bytes
May 09, 2019 12:01:07.913 [12028] DEBUG - Request: [127.0.0.1:50831 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:07.913 [12028] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:07.913 [12028] DEBUG - HTTP requesting GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:07.913 [12976] DEBUG - Request: [127.0.0.1:50832 (Loopback)] GET / (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:07.913 [16132] DEBUG - Completed: [127.0.0.1:50832] 200 GET / (9 live) GZIP 2ms 3025 bytes
May 09, 2019 12:01:07.928 [12028] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 09, 2019 12:01:07.928 [12028] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
May 09, 2019 12:01:07.928 [16132] DEBUG - Completed: [127.0.0.1:50831] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (9 live) GZIP 19ms 19473 bytes
May 09, 2019 12:01:07.928 [11000] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.944 [3880] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.944 [11000] DEBUG - MyPlex: Updating device connections (from timer: 0)
May 09, 2019 12:01:07.944 [11000] DEBUG - HTTP requesting PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:07.944 [13424] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=0)
May 09, 2019 12:01:07.944 [13424] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=2ddff621-ed5f-49da-aa4c-f931107cb7ff
May 09, 2019 12:01:08.022 [15252] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:08.022 [13024] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:08.147 [3880] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:08.147 [13548] DEBUG - Connection Tester: Testing the server Lenovo-Laptop with 2 URLs.
May 09, 2019 12:01:08.163 [13544] DEBUG - HTTP requesting GET https://192-168-0-17.21be0d3c79704de7983d0d1a763d4ca7.plex.direct:32400
May 09, 2019 12:01:08.163 [13452] DEBUG - HTTP requesting GET https://81-107-172-154.21be0d3c79704de7983d0d1a763d4ca7.plex.direct:29192
May 09, 2019 12:01:08.163 [13424] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=2ddff621-ed5f-49da-aa4c-f931107cb7ff
May 09, 2019 12:01:08.163 [13424] DEBUG - MyPlex: Published Mapping State response was 201
May 09, 2019 12:01:08.163 [13424] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 09, 2019 12:01:08.506 [11976] DEBUG - Request: [127.0.0.1:50839 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:08.506 [11976] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 09, 2019 12:01:08.506 [11976] DEBUG - HTTP requesting GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 09, 2019 12:01:08.522 [11976] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 09, 2019 12:01:08.522 [11976] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
May 09, 2019 12:01:08.522 [16132] DEBUG - Completed: [127.0.0.1:50839] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP 5ms 465 bytes
May 09, 2019 12:01:08.553 [14736] DEBUG - Reading 148 bytes in the body, code is 200
May 09, 2019 12:01:08.553 [14736] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 50841.
May 09, 2019 12:01:08.553 [14736] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
May 09, 2019 12:01:08.553 [14736] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
May 09, 2019 12:01:08.553 [14736] DEBUG - HTTP requesting GET http://127.0.0.1:50841/:/plugins/com.plexapp.agents.imdb/prefs
May 09, 2019 12:01:08.584 [14736] DEBUG - HTTP 200 response from GET http://127.0.0.1:50841/:/plugins/com.plexapp.agents.imdb/prefs
May 09, 2019 12:01:08.584 [14736] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
May 09, 2019 12:01:08.584 [14736] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 866e12c6fb36c82b20f05dd1a5fe84bc41541e17
May 09, 2019 12:01:08.584 [14736] DEBUG - IVA: Downloading trailer lists.
May 09, 2019 12:01:08.725 [14736] DEBUG - IVA: Downloaded 43 trailers for source 1.
May 09, 2019 12:01:08.819 [14736] DEBUG - IVA: Downloaded 48 trailers for source 2.
May 09, 2019 12:01:09.959 [14580] DEBUG - NAT: UPnP, found device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>
May 09, 2019 12:01:10.037 [14580] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>.
May 09, 2019 12:01:10.100 [14580] DEBUG - NAT: UPnP, public address is 81.107.172.154
May 09, 2019 12:01:10.115 [14580] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=32400)
May 09, 2019 12:01:10.115 [14580] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ca5232ce-d8c2-4ef1-bc79-67091e4790ba
May 09, 2019 12:01:10.412 [13852] DEBUG - EventSource: Read HTTP reply header.
May 09, 2019 12:01:10.412 [13852] DEBUG - MyPlex: We appear to have regained Internet connectivity.
May 09, 2019 12:01:10.443 [13852] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 09, 2019 12:01:10.443 [13852] DEBUG - EventSource: Successfully connected to 176.58.127.172.
May 09, 2019 12:01:10.443 [15252] DEBUG - PublicAddressManager: Obtaining public IP.
May 09, 2019 12:01:10.443 [15252] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:10.506 [15252] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:10.506 [15252] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 81.107.172.154
May 09, 2019 12:01:10.568 [14580] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ca5232ce-d8c2-4ef1-bc79-67091e4790ba
May 09, 2019 12:01:10.568 [14580] DEBUG - MyPlex: Published Mapping State response was 201
May 09, 2019 12:01:10.568 [14580] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 09, 2019 12:01:10.724 [11000] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:10.724 [14580] DEBUG - MyPlex: Last published value didn't change, we're done.
May 09, 2019 12:01:10.724 [11000] DEBUG - CERT: Certificate will not expire soon.
May 09, 2019 12:01:11.818 [11564] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.0.32 (Lenovo-Desktop)
May 09, 2019 12:01:11.818 [11564] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.0.32
May 09, 2019 12:01:11.818 [11564] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.0.32
May 09, 2019 12:01:11.818 [11564] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/notify/serverUpdated?host=192.168.0.32
May 09, 2019 12:01:11.834 [12028] DEBUG - Request: [127.0.0.1:50868 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:11.834 [16132] DEBUG - Completed: [127.0.0.1:50868] 200 GET /servers (6 live) GZIP 0ms 496 bytes
May 09, 2019 12:01:11.834 [11976] DEBUG - Request: [127.0.0.1:50869 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:11.834 [16132] DEBUG - Completed: [127.0.0.1:50869] 200 GET /library/sections (6 live) GZIP 1ms 928 bytes
May 09, 2019 12:01:11.849 [12976] DEBUG - Request: [127.0.0.1:50870 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:11.849 [16132] DEBUG - Completed: [127.0.0.1:50870] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
May 09, 2019 12:01:11.849 [11564] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/notify/serverUpdated?host=192.168.0.32
May 09, 2019 12:01:11.849 [11564] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
May 09, 2019 12:01:12.943 [13752] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
May 09, 2019 12:01:13.115 [13752] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
May 09, 2019 12:01:13.177 [13544] ERROR - Error issuing curl_easy_perform(handle): 28
May 09, 2019 12:01:13.177 [13452] ERROR - Error issuing curl_easy_perform(handle): 28
May 09, 2019 12:01:13.177 [13544] DEBUG - HTTP simulating 408 after curl timeout
May 09, 2019 12:01:13.177 [13452] DEBUG - HTTP simulating 408 after curl timeout
May 09, 2019 12:01:13.614 [15252] DEBUG - NAT: UPnP, found device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>
May 09, 2019 12:01:13.646 [15252] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>.
May 09, 2019 12:01:13.661 [15252] DEBUG - NAT: UPnP, public address is 81.107.172.154
May 09, 2019 12:01:13.677 [15252] DEBUG - MyPlex: Last published value didn't change, we're done.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 82.94.168.18 was 51 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 178.79.145.139 was 51 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 176.58.127.172 was 51 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 172.105.64.14 was 66 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 50.116.51.249 was 231 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 45.56.115.195 was 253 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 184.105.148.118 was 298 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 72.14.179.64 was 314 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 139.162.57.189 was 537 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Time to connect to 172.105.203.197 was 555 ms.
May 09, 2019 12:01:13.958 [13752] DEBUG - PubSubManager: Updating best ping time for 176.58.127.172 to 51 ms.
May 09, 2019 12:01:15.302 [13300] DEBUG - DVR:Device: Discovering and refreshing all devices.
May 09, 2019 12:01:15.302 [13300] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
May 09, 2019 12:01:15.302 [13300] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
May 09, 2019 12:01:15.598 [13852] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="ca5232ce-d8c2-4ef1-bc79-67091e4790ba" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:01:15.598 [13852] DEBUG - PubSub: Got notified of reachability for async identifier ca5232ce-d8c2-4ef1-bc79-67091e4790ba: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:01:16.817 [11564] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.32:32469/DeviceDescription.xml
May 09, 2019 12:01:16.817 [11564] DEBUG - HTTP requesting GET http://192.168.0.32:32469/DeviceDescription.xml
May 09, 2019 12:01:16.832 [11564] DEBUG - HTTP 200 response from GET http://192.168.0.32:32469/DeviceDescription.xml
May 09, 2019 12:01:16.832 [11564] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.32:32469/DeviceDescription.xml
May 09, 2019 12:01:16.832 [11564] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.32 (Plex Media Server: Lenovo-Desktop)
May 09, 2019 12:01:16.864 [11564] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.1:5000/rootDesc.xml
May 09, 2019 12:01:16.864 [11564] DEBUG - HTTP requesting GET http://192.168.0.1:5000/rootDesc.xml
May 09, 2019 12:01:16.879 [11564] DEBUG - HTTP 200 response from GET http://192.168.0.1:5000/rootDesc.xml
May 09, 2019 12:01:16.879 [11564] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.1:5000/rootDesc.xml
May 09, 2019 12:01:16.879 [11564] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (ARRIS TG2492LG-85 Router)
May 09, 2019 12:01:16.879 [11564] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANDevice)
May 09, 2019 12:01:16.879 [11564] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANConnectionDevice)
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
May 09, 2019 12:01:17.285 [14580] DEBUG - Activity: registered new activity 7ba2ef0b-29a9-405a-b38c-63dc9e60a7f7 - Processing subscriptions
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: Scheduling subscriptions.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: Initialized 0 schedulers.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: Scheduled all subscriptions in 0 ms.
May 09, 2019 12:01:17.285 [14580] DEBUG - Activity: Ended activity 7ba2ef0b-29a9-405a-b38c-63dc9e60a7f7.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
May 09, 2019 12:01:17.285 [14580] DEBUG - Activity: Ended activity cd0090f1-7442-42d7-a399-76ab6253a9bb.
May 09, 2019 12:01:17.285 [14580] DEBUG - DVR:Subscription: Refreshed all content in 2 ms.
May 09, 2019 12:01:17.801 [13300] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
May 09, 2019 12:01:18.160 [13548] WARN - Connection Tester: Error testing media server Lenovo-Laptop in 9998 ms.
May 09, 2019 12:01:18.160 [3880] DEBUG - Media Server: Tested all servers in 10.0 seconds.
May 09, 2019 12:01:21.363 [11976] DEBUG - Request: [127.0.0.1:50904 (Loopback)] GET /web/index.html (9 live) GZIP Signed-in
May 09, 2019 12:01:21.363 [11976] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\index.html"
May 09, 2019 12:01:21.363 [11976] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\index.html is 9986.
May 09, 2019 12:01:21.363 [13852] DEBUG - Completed: [127.0.0.1:50904] 200 GET /web/index.html (9 live) GZIP 3ms 9986 bytes (pipelined: 1)
May 09, 2019 12:01:21.409 [12028] DEBUG - Request: [127.0.0.1:50906 (Loopback)] GET /web/chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.css (8 live) GZIP Signed-in
May 09, 2019 12:01:21.409 [12028] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.css"
May 09, 2019 12:01:21.409 [12028] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.css is 983756.
May 09, 2019 12:01:21.409 [11976] DEBUG - Request: [127.0.0.1:50905 (Loopback)] GET /web/js/chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.js (8 live) GZIP Signed-in
May 09, 2019 12:01:21.409 [11976] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\js\chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.js"
May 09, 2019 12:01:21.409 [11976] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\js\chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.js is 4420000.
May 09, 2019 12:01:21.409 [13852] DEBUG - Completed: [127.0.0.1:50906] 200 GET /web/chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.css (8 live) GZIP 7ms 983756 bytes (pipelined: 1)
May 09, 2019 12:01:21.425 [16132] DEBUG - Completed: [127.0.0.1:50905] 200 GET /web/js/chunk-2-4a32fe3f94e5216b5ceb-plex-3.95.2-25e2ffd.js (8 live) GZIP 18ms 4420000 bytes (pipelined: 1)
May 09, 2019 12:01:22.159 [12028] DEBUG - Request: [127.0.0.1:50905 (Loopback)] GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (7 live) GZIP Signed-in
May 09, 2019 12:01:22.159 [12028] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png"
May 09, 2019 12:01:22.159 [12028] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png is 31365.
May 09, 2019 12:01:22.159 [13852] DEBUG - Completed: [127.0.0.1:50905] 200 GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (7 live) GZIP 1ms 31365 bytes (pipelined: 2)
May 09, 2019 12:01:22.237 [11976] DEBUG - Request: [127.0.0.1:50905 (Loopback)] GET /web/translations/en-GB.json (7 live) GZIP Signed-in
May 09, 2019 12:01:22.237 [11976] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\translations\en-GB.json"
May 09, 2019 12:01:22.237 [15872] DEBUG - Request: [127.0.0.1:50906 (Loopback)] GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (7 live) GZIP Signed-in
May 09, 2019 12:01:22.237 [11976] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\translations\en-GB.json is 11121.
May 09, 2019 12:01:22.237 [15872] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\noise.8b05ce45d0df59343e206bc9ae78d85d.png"
May 09, 2019 12:01:22.237 [15872] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\noise.8b05ce45d0df59343e206bc9ae78d85d.png is 54413.
May 09, 2019 12:01:22.237 [13852] DEBUG - Completed: [127.0.0.1:50905] 200 GET /web/translations/en-GB.json (7 live) GZIP 1ms 11121 bytes (pipelined: 3)
May 09, 2019 12:01:22.237 [13852] DEBUG - Completed: [127.0.0.1:50906] 200 GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (7 live) GZIP 2ms 54413 bytes (pipelined: 2)
May 09, 2019 12:01:22.237 [12028] DEBUG - Request: [127.0.0.1:50906 (Loopback)] GET /web/favicon.ico (7 live) GZIP Signed-in
May 09, 2019 12:01:22.237 [11976] DEBUG - Request: [127.0.0.1:50905 (Loopback)] GET /web/img/desktop/ios/icon-ipad@2x.png (7 live) GZIP Signed-in
May 09, 2019 12:01:22.253 [11976] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\img\desktop\ios\icon-ipad@2x.png"
May 09, 2019 12:01:22.253 [12028] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\favicon.ico"
May 09, 2019 12:01:22.253 [11976] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\img\desktop\ios\icon-ipad@2x.png is 14211.
May 09, 2019 12:01:22.253 [12028] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\favicon.ico is 5430.
May 09, 2019 12:01:22.253 [13852] DEBUG - Completed: [127.0.0.1:50905] 200 GET /web/img/desktop/ios/icon-ipad@2x.png (7 live) GZIP 1ms 14211 bytes (pipelined: 4)
May 09, 2019 12:01:22.253 [16132] DEBUG - Completed: [127.0.0.1:50906] 200 GET /web/favicon.ico (7 live) GZIP 2ms 5430 bytes (pipelined: 3)
May 09, 2019 12:01:22.378 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.378 [15872] DEBUG - Request: [127.0.0.1:50907 (Loopback)] GET /media/providers (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.394 [16132] DEBUG - Completed: [127.0.0.1:50907] 200 GET /media/providers (7 live) GZIP 2ms 3322 bytes (pipelined: 1)
May 09, 2019 12:01:22.409 [13852] DEBUG - Request: [127.0.0.1:50907 (Loopback)] GET /media/providers (8 live) GZIP Signed-in
May 09, 2019 12:01:22.409 [16132] DEBUG - Request: [127.0.0.1:50908 (Loopback)] GET / (8 live) GZIP Signed-in
May 09, 2019 12:01:22.409 [13852] DEBUG - Completed: [127.0.0.1:50907] 401 GET /media/providers (8 live) GZIP 0ms 357 bytes
May 09, 2019 12:01:22.409 [16132] DEBUG - Completed: [127.0.0.1:50908] 401 GET / (8 live) GZIP 0ms 435 bytes
May 09, 2019 12:01:22.409 [16132] DEBUG - Request: [127.0.0.1:50904 (Loopback)] GET /media/providers (7 live) GZIP Signed-in
May 09, 2019 12:01:22.409 [13852] DEBUG - Completed: [127.0.0.1:50904] 401 GET /media/providers (7 live) GZIP 0ms 357 bytes
May 09, 2019 12:01:22.425 [13852] DEBUG - Request: [127.0.0.1:50918 (Loopback)] GET / (7 live) GZIP Signed-in
May 09, 2019 12:01:22.425 [16132] DEBUG - Completed: [127.0.0.1:50918] 401 GET / (7 live) GZIP 0ms 435 bytes
May 09, 2019 12:01:22.425 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.425 [15872] DEBUG - Request: [192.168.0.32:50916 (Subnet)] GET /media/providers (6 live) TLS GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.425 [16132] DEBUG - Completed: [192.168.0.32:50916] 200 GET /media/providers (6 live) TLS GZIP 4ms 3401 bytes (pipelined: 1)
May 09, 2019 12:01:22.503 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.519 [11976] DEBUG - Request: [127.0.0.1:50919 (Loopback)] GET /:/websockets/notifications (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.519 [11976] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
May 09, 2019 12:01:22.519 [11976] DEBUG - Beginning read from WebSocket
May 09, 2019 12:01:22.550 [12028] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /identity (7 live) GZIP Signed-in
May 09, 2019 12:01:22.550 [15872] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /identity (7 live) GZIP Signed-in
May 09, 2019 12:01:22.550 [16132] DEBUG - Completed: [127.0.0.1:50921] 200 GET /identity (7 live) GZIP 1ms 471 bytes (pipelined: 1)
May 09, 2019 12:01:22.550 [13852] DEBUG - Completed: [127.0.0.1:50920] 200 GET /identity (7 live) GZIP 1ms 471 bytes (pipelined: 1)
May 09, 2019 12:01:22.597 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.597 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.597 [11976] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /status/sessions (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.597 [12028] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /:/prefs (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.597 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.597 [11976] DEBUG - [Now] Adding 0 sessions.
May 09, 2019 12:01:22.597 [13852] DEBUG - Completed: [127.0.0.1:50920] 200 GET /status/sessions (8 live) GZIP 1ms 350 bytes (pipelined: 2)
May 09, 2019 12:01:22.597 [7444] DEBUG - Request: [127.0.0.1:50922 (Loopback)] GET /updater/status (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.597 [13852] DEBUG - Completed: [127.0.0.1:50922] 200 GET /updater/status (8 live) GZIP 1ms 447 bytes (pipelined: 1)
May 09, 2019 12:01:22.597 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.597 [15872] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /activities (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.597 [16132] DEBUG - Completed: [127.0.0.1:50920] 200 GET /activities (8 live) GZIP 0ms 350 bytes (pipelined: 3)
May 09, 2019 12:01:22.612 [16132] DEBUG - Completed: [127.0.0.1:50921] 200 GET /:/prefs (8 live) GZIP 20ms 7614 bytes (pipelined: 2)
May 09, 2019 12:01:22.628 [16132] DEBUG - WebSocket: client initiated close
May 09, 2019 12:01:22.628 [16132] DEBUG - NotificationStream: Removing because of close
May 09, 2019 12:01:22.628 [16132] DEBUG - handleStreamRead code 2: End of file
May 09, 2019 12:01:22.628 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.628 [7444] DEBUG - Request: [127.0.0.1:50923 (Loopback)] GET /:/websockets/notifications (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.628 [7444] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
May 09, 2019 12:01:22.628 [7444] DEBUG - Beginning read from WebSocket
May 09, 2019 12:01:22.643 [16132] DEBUG - WebSocket: client initiated close
May 09, 2019 12:01:22.643 [16132] DEBUG - NotificationStream: Removing because of close
May 09, 2019 12:01:22.643 [16132] DEBUG - handleStreamRead code 2: End of file
May 09, 2019 12:01:22.643 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.643 [11976] DEBUG - Request: [127.0.0.1:50925 (Loopback)] GET /:/websockets/notifications (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.643 [11976] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
May 09, 2019 12:01:22.643 [11976] DEBUG - Beginning read from WebSocket
May 09, 2019 12:01:22.659 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.659 [15872] DEBUG - Request: [127.0.0.1:50921 (Loopback)] PUT /updater/check?download=0 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.659 [15872] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e, download: NO
May 09, 2019 12:01:22.659 [15872] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:01:22.659 [15872] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:01:22.706 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:22.706 [12028] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /activities (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:22.706 [16132] DEBUG - Completed: [127.0.0.1:50920] 200 GET /activities (9 live) GZIP 0ms 350 bytes (pipelined: 4)
May 09, 2019 12:01:22.815 [15872] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:01:22.815 [15872] INFO - AutoUpdate: no updates available
May 09, 2019 12:01:22.815 [16132] DEBUG - Completed: [127.0.0.1:50921] 200 PUT /updater/check?download=0 (9 live) GZIP 156ms 195 bytes (pipelined: 3)
May 09, 2019 12:01:23.003 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.003 [7444] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /library/sections (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.003 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.003 [11976] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /media/providers (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.003 [13852] DEBUG - Completed: [127.0.0.1:50921] 200 GET /library/sections (8 live) GZIP 2ms 937 bytes (pipelined: 4)
May 09, 2019 12:01:23.003 [13852] DEBUG - Completed: [127.0.0.1:50920] 200 GET /media/providers (8 live) GZIP 2ms 3322 bytes (pipelined: 5)
May 09, 2019 12:01:23.156 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.156 [12028] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /music/iTunes (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.156 [16132] DEBUG - Completed: [127.0.0.1:50921] 404 GET /music/iTunes (8 live) GZIP 0ms 379 bytes (pipelined: 5)
May 09, 2019 12:01:23.217 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.217 [15872] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMedia=1&includeExternalMetadata=1&excludePlaylists=1 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.217 [15872] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.continue/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
May 09, 2019 12:01:23.217 [15872] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.ondeck/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
May 09, 2019 12:01:23.236 [15872] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 18 ms.
May 09, 2019 12:01:23.236 [7444] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (8 live) GZIP Signed-in
May 09, 2019 12:01:23.237 [15872] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 19 ms.
May 09, 2019 12:01:23.237 [15872] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
May 09, 2019 12:01:23.237 [7444] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png"
May 09, 2019 12:01:23.237 [15872] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.movies.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
May 09, 2019 12:01:23.237 [7444] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-107756f7e\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png is 42165.
May 09, 2019 12:01:23.238 [16132] DEBUG - Completed: [127.0.0.1:50920] 200 GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (8 live) GZIP 1ms 42165 bytes (pipelined: 6)
May 09, 2019 12:01:23.244 [15872] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 7 ms.
May 09, 2019 12:01:23.244 [15872] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.television.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
May 09, 2019 12:01:23.251 [15872] DEBUG - It took 0.000000 ms to retrieve 200 items.
May 09, 2019 12:01:23.305 [15872] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 60 ms.
May 09, 2019 12:01:23.305 [15872] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
May 09, 2019 12:01:23.312 [15872] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 6 ms.
May 09, 2019 12:01:23.312 [15872] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
May 09, 2019 12:01:23.313 [15872] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
May 09, 2019 12:01:23.313 [15872] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
May 09, 2019 12:01:23.314 [15872] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en-GB/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMedia=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
May 09, 2019 12:01:23.315 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.315 [15872] DEBUG - Selecting best audio stream for part ID 25810 (autoselect: 0 language: en)
May 09, 2019 12:01:23.315 [15872] DEBUG - Audio Stream: 42775, Subtitle Stream: -1
May 09, 2019 12:01:23.316 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.316 [15872] DEBUG - Selecting best audio stream for part ID 26429 (autoselect: 0 language: en)
May 09, 2019 12:01:23.316 [15872] DEBUG - Audio Stream: 45852, Subtitle Stream: -1
May 09, 2019 12:01:23.316 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.316 [15872] DEBUG - Selecting best audio stream for part ID 26069 (autoselect: 0 language: en)
May 09, 2019 12:01:23.316 [15872] DEBUG - Audio Stream: 43929, Subtitle Stream: -1
May 09, 2019 12:01:23.317 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.317 [15872] DEBUG - Selecting best audio stream for part ID 26770 (autoselect: 0 language: en)
May 09, 2019 12:01:23.317 [15872] DEBUG - Audio Stream: 46888, Subtitle Stream: -1
May 09, 2019 12:01:23.317 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.317 [15872] DEBUG - Selecting best audio stream for part ID 26767 (autoselect: 0 language: en)
May 09, 2019 12:01:23.317 [15872] DEBUG - Audio Stream: 46874, Subtitle Stream: -1
May 09, 2019 12:01:23.318 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.318 [15872] DEBUG - Selecting best audio stream for part ID 26766 (autoselect: 0 language: en)
May 09, 2019 12:01:23.318 [15872] DEBUG - Audio Stream: 46872, Subtitle Stream: -1
May 09, 2019 12:01:23.319 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.319 [15872] DEBUG - Selecting best audio stream for part ID 26760 (autoselect: 0 language: en)
May 09, 2019 12:01:23.319 [15872] DEBUG - Audio Stream: 46836, Subtitle Stream: -1
May 09, 2019 12:01:23.319 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.319 [15872] DEBUG - Selecting best audio stream for part ID 26757 (autoselect: 0 language: en)
May 09, 2019 12:01:23.319 [15872] DEBUG - Audio Stream: 46827, Subtitle Stream: -1
May 09, 2019 12:01:23.320 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.320 [15872] DEBUG - Selecting best audio stream for part ID 26754 (autoselect: 0 language: en)
May 09, 2019 12:01:23.320 [15872] DEBUG - Audio Stream: 46781, Subtitle Stream: -1
May 09, 2019 12:01:23.325 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.325 [15872] DEBUG - Selecting best audio stream for part ID 26600 (autoselect: 0 language: en)
May 09, 2019 12:01:23.326 [15872] DEBUG - Audio Stream: 46354, Subtitle Stream: -1
May 09, 2019 12:01:23.326 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.326 [15872] DEBUG - Selecting best audio stream for part ID 26587 (autoselect: 0 language: en)
May 09, 2019 12:01:23.326 [15872] DEBUG - Audio Stream: 46304, Subtitle Stream: -1
May 09, 2019 12:01:23.327 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.327 [15872] DEBUG - Selecting best audio stream for part ID 26581 (autoselect: 0 language: en)
May 09, 2019 12:01:23.327 [15872] DEBUG - Audio Stream: 46282, Subtitle Stream: -1
May 09, 2019 12:01:23.327 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.327 [15872] DEBUG - Selecting best audio stream for part ID 26575 (autoselect: 0 language: en)
May 09, 2019 12:01:23.328 [15872] DEBUG - Audio Stream: 46266, Subtitle Stream: -1
May 09, 2019 12:01:23.328 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.328 [15872] DEBUG - Selecting best audio stream for part ID 26573 (autoselect: 0 language: en)
May 09, 2019 12:01:23.328 [15872] DEBUG - Audio Stream: 46261, Subtitle Stream: -1
May 09, 2019 12:01:23.332 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.332 [15872] DEBUG - Selecting best audio stream for part ID 26553 (autoselect: 0 language: en)
May 09, 2019 12:01:23.332 [15872] DEBUG - Audio Stream: 46191, Subtitle Stream: -1
May 09, 2019 12:01:23.343 [15872] DEBUG - There were 1 top-level paths for Dark Matter.
May 09, 2019 12:01:23.344 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.344 [15872] DEBUG - Selecting best audio stream for part ID 26462 (autoselect: 0 language: en)
May 09, 2019 12:01:23.344 [15872] DEBUG - Audio Stream: 45950, Subtitle Stream: -1
May 09, 2019 12:01:23.345 [15872] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.345 [15872] DEBUG - Selecting best audio stream for part ID 26463 (autoselect: 0 language: en)
May 09, 2019 12:01:23.345 [15872] DEBUG - Audio Stream: 45952, Subtitle Stream: -1
May 09, 2019 12:01:23.352 [16132] DEBUG - Completed: [127.0.0.1:50921] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMedia=1&includeExternalMetadata=1&excludePlaylists=1 (8 live) GZIP 135ms 12565 bytes (pipelined: 6)
May 09, 2019 12:01:23.625 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.625 [7444] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /hubs/home/recentlyAdded?type=2&includeExternalMedia=1 (8 live) Page 16-50 GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.626 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.627 [11976] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /hubs/home/recentlyAdded?type=1&includeExternalMedia=1 (8 live) Page 16-50 GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.628 [11976] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 09, 2019 12:01:23.629 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.630 [12028] DEBUG - Request: [127.0.0.1:50922 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1&includeExternalMedia=1 (8 live) Page 16-50 GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.631 [12028] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 09, 2019 12:01:23.633 [7444] DEBUG - It took 0.000000 ms to retrieve 200 items.
May 09, 2019 12:01:23.644 [12028] DEBUG - Setting container serialization range to [16, 50] (total=62)
May 09, 2019 12:01:23.649 [13852] DEBUG - Completed: [127.0.0.1:50922] 200 GET /hubs/home/recentlyAdded?type=1&personal=1&includeExternalMedia=1 (8 live) GZIP Page 16-50 19ms 3803 bytes (pipelined: 2)
May 09, 2019 12:01:23.655 [11976] DEBUG - Setting container serialization range to [16, 50] (total=325)
May 09, 2019 12:01:23.661 [13852] DEBUG - Completed: [127.0.0.1:50921] 200 GET /hubs/home/recentlyAdded?type=1&includeExternalMedia=1 (8 live) GZIP Page 16-50 34ms 13704 bytes (pipelined: 7)
May 09, 2019 12:01:23.669 [7444] DEBUG - It took 62.500000 ms to retrieve 50 items.
May 09, 2019 12:01:23.684 [7444] DEBUG - Setting container serialization range to [16, 50] (total=-1)
May 09, 2019 12:01:23.684 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.684 [7444] DEBUG - Selecting best audio stream for part ID 26463 (autoselect: 0 language: en)
May 09, 2019 12:01:23.684 [7444] DEBUG - Audio Stream: 45952, Subtitle Stream: -1
May 09, 2019 12:01:23.685 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.685 [7444] DEBUG - Selecting best audio stream for part ID 26457 (autoselect: 0 language: en)
May 09, 2019 12:01:23.685 [7444] DEBUG - Audio Stream: 45930, Subtitle Stream: -1
May 09, 2019 12:01:23.685 [7444] DEBUG - There were 1 top-level paths for Killing Eve.
May 09, 2019 12:01:23.686 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.686 [7444] DEBUG - Selecting best audio stream for part ID 26424 (autoselect: 0 language: en)
May 09, 2019 12:01:23.686 [7444] DEBUG - Audio Stream: 45833, Subtitle Stream: -1
May 09, 2019 12:01:23.686 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.686 [7444] DEBUG - Selecting best audio stream for part ID 26406 (autoselect: 0 language: en)
May 09, 2019 12:01:23.686 [7444] DEBUG - Audio Stream: 45794, Subtitle Stream: -1
May 09, 2019 12:01:23.686 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.686 [7444] DEBUG - Selecting best audio stream for part ID 26403 (autoselect: 0 language: en)
May 09, 2019 12:01:23.686 [7444] DEBUG - Audio Stream: 45783, Subtitle Stream: -1
May 09, 2019 12:01:23.687 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.687 [7444] DEBUG - Selecting best audio stream for part ID 26397 (autoselect: 0 language: en)
May 09, 2019 12:01:23.687 [7444] DEBUG - Audio Stream: 45700, Subtitle Stream: -1
May 09, 2019 12:01:23.687 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.687 [7444] DEBUG - Selecting best audio stream for part ID 26395 (autoselect: 0 language: en)
May 09, 2019 12:01:23.687 [7444] DEBUG - Audio Stream: 45696, Subtitle Stream: -1
May 09, 2019 12:01:23.687 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.687 [7444] DEBUG - Selecting best audio stream for part ID 26396 (autoselect: 0 language: en)
May 09, 2019 12:01:23.687 [7444] DEBUG - Audio Stream: 45698, Subtitle Stream: -1
May 09, 2019 12:01:23.688 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.688 [7444] DEBUG - Selecting best audio stream for part ID 26382 (autoselect: 0 language: en)
May 09, 2019 12:01:23.688 [7444] DEBUG - Audio Stream: 45648, Subtitle Stream: -1
May 09, 2019 12:01:23.688 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.688 [7444] DEBUG - Selecting best audio stream for part ID 26379 (autoselect: 0 language: en)
May 09, 2019 12:01:23.688 [7444] DEBUG - Audio Stream: 45637, Subtitle Stream: -1
May 09, 2019 12:01:23.693 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.693 [7444] DEBUG - Selecting best audio stream for part ID 26268 (autoselect: 0 language: en)
May 09, 2019 12:01:23.693 [7444] DEBUG - Audio Stream: 45380, Subtitle Stream: -1
May 09, 2019 12:01:23.693 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.693 [7444] DEBUG - Selecting best audio stream for part ID 26147 (autoselect: 0 language: en)
May 09, 2019 12:01:23.693 [7444] DEBUG - Audio Stream: 45118, Subtitle Stream: 0
May 09, 2019 12:01:23.695 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.695 [7444] DEBUG - Selecting best audio stream for part ID 26067 (autoselect: 0 language: en)
May 09, 2019 12:01:23.695 [7444] DEBUG - Audio Stream: 43922, Subtitle Stream: 0
May 09, 2019 12:01:23.695 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.695 [7444] DEBUG - Selecting best audio stream for part ID 26069 (autoselect: 0 language: en)
May 09, 2019 12:01:23.695 [7444] DEBUG - Audio Stream: 43929, Subtitle Stream: -1
May 09, 2019 12:01:23.695 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.696 [7444] DEBUG - Selecting best audio stream for part ID 25883 (autoselect: 0 language: en)
May 09, 2019 12:01:23.696 [7444] DEBUG - Audio Stream: 43533, Subtitle Stream: -1
May 09, 2019 12:01:23.696 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.696 [7444] DEBUG - Selecting best audio stream for part ID 25884 (autoselect: 0 language: en)
May 09, 2019 12:01:23.696 [7444] DEBUG - Audio Stream: 43535, Subtitle Stream: -1
May 09, 2019 12:01:23.696 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.696 [7444] DEBUG - Selecting best audio stream for part ID 25836 (autoselect: 0 language: en)
May 09, 2019 12:01:23.696 [7444] DEBUG - Audio Stream: 43194, Subtitle Stream: -1
May 09, 2019 12:01:23.697 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.697 [7444] DEBUG - Selecting best audio stream for part ID 25835 (autoselect: 0 language: en)
May 09, 2019 12:01:23.697 [7444] DEBUG - Audio Stream: 43500, Subtitle Stream: -1
May 09, 2019 12:01:23.699 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.699 [7444] DEBUG - Selecting best audio stream for part ID 25811 (autoselect: 0 language: en)
May 09, 2019 12:01:23.699 [7444] DEBUG - Audio Stream: 42777, Subtitle Stream: 42965
May 09, 2019 12:01:23.699 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.699 [7444] DEBUG - Selecting best audio stream for part ID 25810 (autoselect: 0 language: en)
May 09, 2019 12:01:23.699 [7444] DEBUG - Audio Stream: 42775, Subtitle Stream: -1
May 09, 2019 12:01:23.700 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.700 [7444] DEBUG - Selecting best audio stream for part ID 25742 (autoselect: 0 language: en)
May 09, 2019 12:01:23.700 [7444] DEBUG - Audio Stream: 42554, Subtitle Stream: -1
May 09, 2019 12:01:23.700 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.700 [7444] DEBUG - Selecting best audio stream for part ID 25739 (autoselect: 0 language: en)
May 09, 2019 12:01:23.701 [7444] DEBUG - Audio Stream: 42544, Subtitle Stream: -1
May 09, 2019 12:01:23.701 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.701 [7444] DEBUG - Selecting best audio stream for part ID 25673 (autoselect: 0 language: en)
May 09, 2019 12:01:23.701 [7444] DEBUG - Audio Stream: 42405, Subtitle Stream: -1
May 09, 2019 12:01:23.702 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.702 [7444] DEBUG - Selecting best audio stream for part ID 25639 (autoselect: 0 language: en)
May 09, 2019 12:01:23.702 [7444] DEBUG - Audio Stream: 42322, Subtitle Stream: -1
May 09, 2019 12:01:23.702 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.702 [7444] DEBUG - Selecting best audio stream for part ID 25637 (autoselect: 0 language: en)
May 09, 2019 12:01:23.703 [7444] DEBUG - Audio Stream: 42318, Subtitle Stream: -1
May 09, 2019 12:01:23.703 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.703 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.704 [12028] DEBUG - Request: [127.0.0.1:50921 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F2087%2Fthumb%2F1555057607%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.703 [7444] DEBUG - Selecting best audio stream for part ID 25572 (autoselect: 0 language: en)
May 09, 2019 12:01:23.704 [7444] DEBUG - Audio Stream: 42118, Subtitle Stream: -1
May 09, 2019 12:01:23.704 [12028] DEBUG - Photo transcoder: Request for url [/library/metadata/2087/thumb/1555057607?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.704 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.705 [7444] DEBUG - Selecting best audio stream for part ID 25570 (autoselect: 0 language: en)
May 09, 2019 12:01:23.705 [7444] DEBUG - Audio Stream: 42112, Subtitle Stream: -1
May 09, 2019 12:01:23.705 [12028] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6d\6d317305012635a9e641834daa4d6c11c6b109ff.jpg is 7539.
May 09, 2019 12:01:23.705 [16132] DEBUG - Completed: [127.0.0.1:50921] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F2087%2Fthumb%2F1555057607%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP 1ms 7539 bytes (pipelined: 8)
May 09, 2019 12:01:23.705 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.706 [7444] DEBUG - Selecting best audio stream for part ID 25569 (autoselect: 0 language: en)
May 09, 2019 12:01:23.706 [7444] DEBUG - Audio Stream: 42110, Subtitle Stream: -1
May 09, 2019 12:01:23.707 [7444] DEBUG - We're going to try to auto-select an audio stream for account 1.
May 09, 2019 12:01:23.707 [7444] DEBUG - Selecting best audio stream for part ID 25467 (autoselect: 0 language: en)
May 09, 2019 12:01:23.707 [7444] DEBUG - Audio Stream: 41164, Subtitle Stream: 0
May 09, 2019 12:01:23.710 [16132] DEBUG - Completed: [127.0.0.1:50920] 200 GET /hubs/home/recentlyAdded?type=2&includeExternalMedia=1 (8 live) GZIP Page 16-50 85ms 11666 bytes (pipelined: 7)
May 09, 2019 12:01:23.790 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.791 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.791 [15872] DEBUG - Request: [127.0.0.1:50920 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17895%2Fthumb%2F1557218310%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.791 [7444] DEBUG - Request: [127.0.0.1:50922 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F1869%2Fthumb%2F1557130503%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.792 [15872] DEBUG - Photo transcoder: Request for url [/library/metadata/17895/thumb/1557218310?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.792 [7444] DEBUG - Photo transcoder: Request for url [/library/metadata/1869/thumb/1557130503?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.792 [15872] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\58\5836be16459fa1996ec0fa777c9dc76ee2cc7148.jpg is 3225.
May 09, 2019 12:01:23.793 [7444] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\50\5086c33f2f996d79546f0047408833b98a785a1a.jpg is 4432.
May 09, 2019 12:01:23.793 [16132] DEBUG - Completed: [127.0.0.1:50920] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17895%2Fthumb%2F1557218310%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP 2ms 3225 bytes (pipelined: 8)
May 09, 2019 12:01:23.793 [13852] DEBUG - Completed: [127.0.0.1:50922] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F1869%2Fthumb%2F1557130503%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP 2ms 4432 bytes (pipelined: 3)
May 09, 2019 12:01:23.861 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.862 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.863 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.863 [11976] DEBUG - Request: [127.0.0.1:50943 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17786%2Fthumb%2F1556104416%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.863 [12028] DEBUG - Request: [127.0.0.1:50945 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17753%2Fthumb%2F1554723134%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.863 [11976] DEBUG - Photo transcoder: Request for url [/library/metadata/17786/thumb/1556104416?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.864 [15872] DEBUG - Request: [127.0.0.1:50947 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17729%2Fthumb%2F1554199142%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.864 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.864 [15872] DEBUG - Photo transcoder: Request for url [/library/metadata/17729/thumb/1554199142?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.864 [11976] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\b4\b44f47b16bfa6a96b708035c7e4b5f8b021a2570.jpg is 4592.
May 09, 2019 12:01:23.865 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.865 [12028] DEBUG - Photo transcoder: Request for url [/library/metadata/17753/thumb/1554723134?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.865 [13852] DEBUG - Completed: [127.0.0.1:50943] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17786%2Fthumb%2F1556104416%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 4ms 4592 bytes (pipelined: 1)
May 09, 2019 12:01:23.866 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.866 [15872] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e8\e8a327006ec0c02031c2e19b3343b0fd4311109c.jpg is 7613.
May 09, 2019 12:01:23.866 [16132] DEBUG - Completed: [127.0.0.1:50947] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17729%2Fthumb%2F1554199142%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 2ms 7613 bytes (pipelined: 1)
May 09, 2019 12:01:23.867 [7444] DEBUG - Request: [127.0.0.1:50948 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17707%2Fthumb%2F1553862546%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.867 [7444] DEBUG - Photo transcoder: Request for url [/library/metadata/17707/thumb/1553862546?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.869 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.869 [7444] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\58\5890998b6b621612c9621330cb1c2fe080b20aea.jpg is 4530.
May 09, 2019 12:01:23.869 [12028] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\c8\c82b82bfa34c514b3258f3a6475a805a19ee4b68.jpg is 4229.
May 09, 2019 12:01:23.870 [16132] DEBUG - Completed: [127.0.0.1:50945] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17753%2Fthumb%2F1554723134%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 7ms 4229 bytes (pipelined: 1)
May 09, 2019 12:01:23.870 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17765%2Fthumb%2F1555582719%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.871 [15588] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17749%2Fthumb%2F1554391707%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.871 [11976] DEBUG - Photo transcoder: Request for url [/library/metadata/17765/thumb/1555582719?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.871 [16132] DEBUG - Completed: [127.0.0.1:50948] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17707%2Fthumb%2F1553862546%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 6ms 4530 bytes (pipelined: 1)
May 09, 2019 12:01:23.872 [11976] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\42\42aa7037ecfd53b74cef48d8551ac8cef24f97fb.jpg is 5642.
May 09, 2019 12:01:23.872 [15588] DEBUG - Photo transcoder: Request for url [/library/metadata/17749/thumb/1554391707?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.873 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17765%2Fthumb%2F1555582719%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 7ms 5642 bytes (pipelined: 1)
May 09, 2019 12:01:23.873 [15588] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\2f\2f55be04c7df443e59c3125271024de0511b3b59.jpg is 5964.
May 09, 2019 12:01:23.874 [16132] DEBUG - Completed: [127.0.0.1:50946] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17749%2Fthumb%2F1554391707%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 8ms 5964 bytes (pipelined: 1)
May 09, 2019 12:01:23.875 [16072] DEBUG - Request: [127.0.0.1:50943 (Loopback)] GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17705%2Fthumb%2F1553860995%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.875 [16072] DEBUG - Photo transcoder: Request for url [/library/metadata/17705/thumb/1553860995?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.877 [16072] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e8\e8451bbd5d1f1dbfedefecc810525fc3c3e2776d.jpg is 5616.
May 09, 2019 12:01:23.878 [16132] DEBUG - Completed: [127.0.0.1:50943] 200 GET /photo/:/transcode?width=120&height=181&minSize=1&url=%2Flibrary%2Fmetadata%2F17705%2Fthumb%2F1553860995%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 8ms 5616 bytes (pipelined: 2)
May 09, 2019 12:01:23.902 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.902 [15872] DEBUG - Request: [127.0.0.1:50947 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17570%2Fthumb%2F1555072201%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.902 [15872] DEBUG - Photo transcoder: Request for url [/library/metadata/17570/thumb/1555072201?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.903 [15872] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\a1\a125332ed79219753e79d0e765d192233847868a.jpg is 10317.
May 09, 2019 12:01:23.903 [13852] DEBUG - Completed: [127.0.0.1:50947] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17570%2Fthumb%2F1555072201%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 1ms 10317 bytes (pipelined: 2)
May 09, 2019 12:01:23.907 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.907 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.907 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.908 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.908 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17569%2Fthumb%2F1555072201%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.908 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:23.908 [7444] DEBUG - Photo transcoder: Request for url [/library/metadata/17569/thumb/1555072201?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.909 [15872] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17568%2Fthumb%2F1555072200%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.909 [7444] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\b8\b87c0eb8e46d1e2a6d4c4f3d872948aba89c2e63.jpg is 14152.
May 09, 2019 12:01:23.909 [11976] DEBUG - Request: [127.0.0.1:50943 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16971%2Fthumb%2F1555072200%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.909 [15872] DEBUG - Photo transcoder: Request for url [/library/metadata/17568/thumb/1555072200?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.909 [15588] DEBUG - Request: [127.0.0.1:50948 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16970%2Fthumb%2F1555072207%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.910 [11976] DEBUG - Photo transcoder: Request for url [/library/metadata/16971/thumb/1555072200?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.910 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17569%2Fthumb%2F1555072201%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 2ms 14152 bytes (pipelined: 2)
May 09, 2019 12:01:23.910 [15872] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\9d\9d5baf9f4016f8683bbc1f28a62b860d68b7e4cf.jpg is 19449.
May 09, 2019 12:01:23.910 [16072] DEBUG - Request: [127.0.0.1:50945 (Loopback)] GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16939%2Fthumb%2F1555072207%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:23.910 [15588] DEBUG - Photo transcoder: Request for url [/library/metadata/16970/thumb/1555072207?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.910 [11976] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\55\551792894780b3e5a5d3cb936d246fc96f62652a.jpg is 5456.
May 09, 2019 12:01:23.911 [16072] DEBUG - Photo transcoder: Request for url [/library/metadata/16939/thumb/1555072207?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
May 09, 2019 12:01:23.911 [15588] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e3\e331c16edbed811ea096053ff1d410b15a2bc32e.jpg is 8298.
May 09, 2019 12:01:23.911 [16072] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e1\e1d251087da59f998b91dfb8d0b1110bc44553e0.jpg is 8006.
May 09, 2019 12:01:23.911 [13852] DEBUG - Completed: [127.0.0.1:50943] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16971%2Fthumb%2F1555072200%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 3ms 5456 bytes (pipelined: 3)
May 09, 2019 12:01:23.911 [16132] DEBUG - Completed: [127.0.0.1:50948] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16970%2Fthumb%2F1555072207%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 3ms 8298 bytes (pipelined: 2)
May 09, 2019 12:01:23.912 [13852] DEBUG - Completed: [127.0.0.1:50945] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F16939%2Fthumb%2F1555072207%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 3ms 8006 bytes (pipelined: 2)
May 09, 2019 12:01:23.913 [13852] DEBUG - Completed: [127.0.0.1:50946] 200 GET /photo/:/transcode?width=240&height=135&minSize=1&url=%2Flibrary%2Fmetadata%2F17568%2Fthumb%2F1555072200%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 5ms 19449 bytes (pipelined: 2)
May 09, 2019 12:01:24.520 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:24.521 [12028] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (11 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:24.521 [12028] DEBUG - [CompanionProxy] registered new player: sn593t8ejm9u099ktnvwkakr (127.0.0.1)
May 09, 2019 12:01:26.847 [11564] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.0.32
May 09, 2019 12:01:28.955 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:28.955 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /:/prefs (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:28.986 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /:/prefs (10 live) GZIP 32ms 7614 bytes (pipelined: 3)
May 09, 2019 12:01:33.804 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:33.804 [12028] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /:/prefs (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:33.814 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:33.814 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:33.814 [11976] DEBUG - Request: [127.0.0.1:50948 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:33.814 [15588] DEBUG - Request: [127.0.0.1:50947 (Loopback)] GET /accounts/1 (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:33.814 [16132] DEBUG - Completed: [127.0.0.1:50947] 200 GET /accounts/1 (10 live) GZIP 1ms 520 bytes (pipelined: 3)
May 09, 2019 12:01:33.814 [16132] DEBUG - Completed: [127.0.0.1:50948] 200 GET /myplex/account (10 live) GZIP 2ms 2291 bytes (pipelined: 3)
May 09, 2019 12:01:33.814 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:33.824 [7444] DEBUG - Request: [127.0.0.1:50947 (Loopback)] GET /system/:/prefs (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:33.824 [7444] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:01:33.824 [7444] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:01:33.824 [7444] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:01:33.834 [7444] DEBUG - [com.plexapp.system] HTTP reply status 200, with 418 bytes of content.
May 09, 2019 12:01:33.834 [13852] DEBUG - Completed: [127.0.0.1:50947] 200 GET /system/:/prefs (10 live) GZIP 10ms 784 bytes (pipelined: 4)
May 09, 2019 12:01:33.834 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /:/prefs (10 live) GZIP 30ms 7614 bytes (pipelined: 4)
May 09, 2019 12:01:33.904 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:33.904 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] PUT /myplex/refreshReachability (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:33.904 [15872] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:01:33.909 [15872] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=7bf3f419-a76f-4103-ae82-b1048af857a2
May 09, 2019 12:01:34.054 [15872] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=7bf3f419-a76f-4103-ae82-b1048af857a2
May 09, 2019 12:01:34.059 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 PUT /myplex/refreshReachability (10 live) GZIP 151ms 195 bytes (pipelined: 5)
May 09, 2019 12:01:36.544 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:36.544 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] PUT /:/prefs?PublishServerOnPlexOnlineKey=false (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:36.544 [15872] DEBUG - MyPlex: Toggling server publish state.
May 09, 2019 12:01:36.544 [15872] DEBUG - PublicAddressManager: Stopping.
May 09, 2019 12:01:36.544 [15872] DEBUG - Destroying public address manager
May 09, 2019 12:01:36.551 [15872] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=0)
May 09, 2019 12:01:36.551 [15872] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=aba94632-c994-4454-a5d0-d9069471ab64
May 09, 2019 12:01:36.752 [15872] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=aba94632-c994-4454-a5d0-d9069471ab64
May 09, 2019 12:01:36.752 [15872] DEBUG - MyPlex: Published Mapping State response was 201
May 09, 2019 12:01:36.752 [15872] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 09, 2019 12:01:36.752 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 PUT /:/prefs?PublishServerOnPlexOnlineKey=false (10 live) GZIP 219ms 195 bytes (pipelined: 6)
May 09, 2019 12:01:36.767 [16132] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="aba94632-c994-4454-a5d0-d9069471ab64" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:01:36.767 [16132] DEBUG - PubSub: Got notified of reachability for async identifier aba94632-c994-4454-a5d0-d9069471ab64: 0 for :0 (responded in 0 seconds)
May 09, 2019 12:01:36.767 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:36.767 [11976] DEBUG - Request: [127.0.0.1:50947 (Loopback)] PUT /myplex/refreshReachability (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:36.767 [11976] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:01:36.767 [11976] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=46d3767f-7de1-4a6c-ab8c-1826025ff466
May 09, 2019 12:01:36.767 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:36.767 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:36.783 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 0ms 2291 bytes (pipelined: 7)
May 09, 2019 12:01:36.952 [11976] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=46d3767f-7de1-4a6c-ab8c-1826025ff466
May 09, 2019 12:01:36.952 [16132] DEBUG - Completed: [127.0.0.1:50947] 200 PUT /myplex/refreshReachability (10 live) GZIP 175ms 195 bytes (pipelined: 5)
May 09, 2019 12:01:36.952 [16132] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="46d3767f-7de1-4a6c-ab8c-1826025ff466" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:01:36.952 [16132] DEBUG - PubSub: Got notified of reachability for async identifier 46d3767f-7de1-4a6c-ab8c-1826025ff466: 0 for :0 (responded in 0 seconds)
May 09, 2019 12:01:36.968 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:36.968 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:36.968 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2291 bytes (pipelined: 8)
May 09, 2019 12:01:37.300 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:37.300 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:37.300 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 0ms 2291 bytes (pipelined: 9)
May 09, 2019 12:01:37.817 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:37.817 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:37.817 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 3ms 2291 bytes (pipelined: 10)
May 09, 2019 12:01:38.303 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:38.303 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:38.303 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 0ms 2291 bytes (pipelined: 11)
May 09, 2019 12:01:38.803 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:38.803 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:38.803 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2291 bytes (pipelined: 12)
May 09, 2019 12:01:39.035 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:39.035 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] PUT /:/prefs?PublishServerOnPlexOnlineKey=true (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:39.035 [7444] DEBUG - MyPlex: Toggling server publish state.
May 09, 2019 12:01:39.035 [7444] DEBUG - PublicAddressManager: Starting.
May 09, 2019 12:01:39.035 [7444] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 09, 2019 12:01:39.035 [13024] DEBUG - PublicAddressManager: Obtaining public IP.
May 09, 2019 12:01:39.035 [13024] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:39.035 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 PUT /:/prefs?PublishServerOnPlexOnlineKey=true (10 live) GZIP 1ms 195 bytes (pipelined: 13)
May 09, 2019 12:01:39.035 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:39.051 [12028] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:39.051 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 14)
May 09, 2019 12:01:39.057 [13852] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="7bf3f419-a76f-4103-ae82-b1048af857a2" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:01:39.057 [13852] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (7bf3f419-a76f-4103-ae82-b1048af857a2, expected 46d3767f-7de1-4a6c-ab8c-1826025ff466)
May 09, 2019 12:01:39.104 [13024] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 09, 2019 12:01:39.104 [13024] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 81.107.172.154
May 09, 2019 12:01:39.305 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:39.305 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:39.305 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 15)
May 09, 2019 12:01:39.575 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:39.575 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:39.575 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 2ms 2229 bytes (pipelined: 16)
May 09, 2019 12:01:39.806 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:39.806 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:39.806 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 17)
May 09, 2019 12:01:40.076 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:40.076 [12028] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:40.076 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 2ms 2229 bytes (pipelined: 18)
May 09, 2019 12:01:40.307 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:40.307 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:40.307 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 2ms 2229 bytes (pipelined: 19)
May 09, 2019 12:01:40.577 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:40.593 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:40.593 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 3ms 2229 bytes (pipelined: 20)
May 09, 2019 12:01:40.809 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:40.809 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:40.809 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 21)
May 09, 2019 12:01:41.078 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:41.078 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:41.078 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 0ms 2229 bytes (pipelined: 22)
May 09, 2019 12:01:41.325 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:41.325 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:41.325 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 3ms 2229 bytes (pipelined: 23)
May 09, 2019 12:01:41.579 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:41.579 [12028] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:41.579 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 24)
May 09, 2019 12:01:41.811 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:41.811 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:41.811 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2229 bytes (pipelined: 25)
May 09, 2019 12:01:42.081 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.081 [15872] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.096 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 3ms 2229 bytes (pipelined: 26)
May 09, 2019 12:01:42.181 [13024] DEBUG - NAT: UPnP, found device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>
May 09, 2019 12:01:42.266 [13024] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>.
May 09, 2019 12:01:42.281 [13024] DEBUG - NAT: UPnP, public address is 81.107.172.154
May 09, 2019 12:01:42.297 [13024] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=32400)
May 09, 2019 12:01:42.297 [13024] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=48f71fbe-1718-4d0b-84e6-48a2fcd1eaae
May 09, 2019 12:01:42.313 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.313 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.313 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2230 bytes (pipelined: 27)
May 09, 2019 12:01:42.328 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.328 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] PUT /:/prefs?PublishServerOnPlexOnlineKey=true (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.328 [11976] DEBUG - MyPlex: Toggling server publish state.
May 09, 2019 12:01:42.328 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 PUT /:/prefs?PublishServerOnPlexOnlineKey=true (10 live) GZIP 1ms 195 bytes (pipelined: 28)
May 09, 2019 12:01:42.344 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.344 [12028] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.344 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (10 live) GZIP 1ms 2230 bytes (pipelined: 29)
May 09, 2019 12:01:42.529 [13024] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=48f71fbe-1718-4d0b-84e6-48a2fcd1eaae
May 09, 2019 12:01:42.529 [13024] DEBUG - MyPlex: Published Mapping State response was 201
May 09, 2019 12:01:42.529 [13024] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 09, 2019 12:01:42.544 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.544 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.544 [7444] DEBUG - MyPlex: we already have requested a connectivity refresh for async identifier 48f71fbe-1718-4d0b-84e6-48a2fcd1eaae which has not yet expired.
May 09, 2019 12:01:42.544 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 PUT /myplex/refreshReachability (9 live) GZIP 0ms 195 bytes (pipelined: 30)
May 09, 2019 12:01:42.544 [13024] DEBUG - MyPlex: Updating device connections (from timer: 0)
May 09, 2019 12:01:42.544 [13024] DEBUG - HTTP requesting PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:42.582 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.582 [15588] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.582 [16132] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (9 live) GZIP 0ms 2284 bytes (pipelined: 31)
May 09, 2019 12:01:42.714 [13024] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 09, 2019 12:01:42.867 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:42.867 [11976] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:42.867 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (9 live) GZIP 3ms 2284 bytes (pipelined: 32)
May 09, 2019 12:01:44.533 [13852] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) GZIP 20014ms 5 bytes (pipelined: 3)
May 09, 2019 12:01:44.533 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:44.533 [7444] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:44.549 [7444] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:01:47.564 [16132] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="48f71fbe-1718-4d0b-84e6-48a2fcd1eaae" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:01:47.564 [16132] DEBUG - PubSub: Got notified of reachability for async identifier 48f71fbe-1718-4d0b-84e6-48a2fcd1eaae: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:01:47.579 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:01:47.579 [7444] DEBUG - Request: [127.0.0.1:50944 (Loopback)] GET /myplex/account (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:01:47.579 [13852] DEBUG - Completed: [127.0.0.1:50944] 200 GET /myplex/account (7 live) GZIP 3ms 2291 bytes (pipelined: 33)
May 09, 2019 12:02:04.561 [13852] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP 20013ms 5 bytes (pipelined: 4)
May 09, 2019 12:02:04.576 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:04.576 [16040] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:04.576 [16040] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:02:20.214 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.214 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.230 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.214 [16040] DEBUG - Request: [127.0.0.1:51013 (Loopback)] GET /:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.230 [13556] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.230 [5824] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.230 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.230 [16132] DEBUG - Completed: [127.0.0.1:51014] 200 GET /accounts/1 (9 live) GZIP 2ms 520 bytes (pipelined: 1)
May 09, 2019 12:02:20.230 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 GET /myplex/account (9 live) GZIP 2ms 2291 bytes (pipelined: 1)
May 09, 2019 12:02:20.230 [8480] DEBUG - Request: [127.0.0.1:51016 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.230 [8480] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:02:20.230 [8480] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:20.230 [8480] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:20.230 [8480] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:02:20.230 [13852] DEBUG - Completed: [127.0.0.1:51016] 304 GET /system/:/prefs (9 live) GZIP 9ms 324 bytes (pipelined: 1)
May 09, 2019 12:02:20.245 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 GET /:/prefs (9 live) GZIP 23ms 7614 bytes (pipelined: 1)
May 09, 2019 12:02:20.292 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.292 [13556] DEBUG - Request: [127.0.0.1:51013 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.292 [13556] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:02:20.292 [13556] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=056b253b-f4ab-4096-bce0-4227e4b73a19
May 09, 2019 12:02:20.308 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.308 [8480] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /updater/status (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.308 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 GET /updater/status (9 live) GZIP 0ms 454 bytes (pipelined: 2)
May 09, 2019 12:02:20.308 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.308 [5824] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /photo/:/transcode?width=60&height=60&url=https%3A%2F%2Fplex.tv%2Fusers%2Fbf2d5937a303c23a%2Favatar%3Fc%3D1557399496 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.323 [5824] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/bf2d5937a303c23a/avatar?c=1557399496] (is local: 0 upscaled: 0)
May 09, 2019 12:02:20.323 [5824] DEBUG - HTTP requesting GET https://plex.tv/users/bf2d5937a303c23a/avatar?c=1557399496
May 09, 2019 12:02:20.355 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:20.355 [16040] DEBUG - Request: [127.0.0.1:51015 (Loopback)] PUT /updater/check?download=0 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:20.355 [16040] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e, download: NO
May 09, 2019 12:02:20.355 [16040] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:02:20.355 [16040] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:02:20.480 [13556] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=056b253b-f4ab-4096-bce0-4227e4b73a19
May 09, 2019 12:02:20.480 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 PUT /myplex/refreshReachability (9 live) GZIP 184ms 195 bytes (pipelined: 2)
May 09, 2019 12:02:20.511 [16040] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:02:20.511 [16040] INFO - AutoUpdate: no updates available
May 09, 2019 12:02:20.511 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 PUT /updater/check?download=0 (9 live) GZIP 154ms 195 bytes (pipelined: 3)
May 09, 2019 12:02:20.729 [5824] DEBUG - HTTP 200 response from GET https://plex.tv/users/bf2d5937a303c23a/avatar?c=1557399496
May 09, 2019 12:02:20.729 [5824] DEBUG - Photo cache obtained 771 bytes from https://plex.tv/users/bf2d5937a303c23a/avatar?c=1557399496
May 09, 2019 12:02:20.729 [5824] DEBUG - Saving original media file to C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6e\6e6ac737fed6cbe88c0be2b2dcb9dbc7ef5cac39.jpg
May 09, 2019 12:02:20.745 [5824] DEBUG - Created thumbnail of size 60x60, has pixels: 1
May 09, 2019 12:02:20.745 [13852] DEBUG - Completed: [127.0.0.1:51014] 200 GET /photo/:/transcode?width=60&height=60&url=https%3A%2F%2Fplex.tv%2Fusers%2Fbf2d5937a303c23a%2Favatar%3Fc%3D1557399496 (9 live) GZIP 443ms 1342 bytes (pipelined: 2)
May 09, 2019 12:02:24.588 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (9 live) GZIP 20009ms 5 bytes (pipelined: 5)
May 09, 2019 12:02:24.604 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:24.604 [8480] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:24.604 [8480] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:02:25.500 [13852] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="056b253b-f4ab-4096-bce0-4227e4b73a19" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:02:25.500 [13852] DEBUG - PubSub: Got notified of reachability for async identifier 056b253b-f4ab-4096-bce0-4227e4b73a19: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:02:25.500 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:25.500 [13556] DEBUG - Request: [127.0.0.1:51013 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:25.500 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 GET /myplex/account (9 live) GZIP 1ms 2291 bytes (pipelined: 3)
May 09, 2019 12:02:28.597 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.597 [16040] DEBUG - Request: [127.0.0.1:51013 (Loopback)] GET /:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.597 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.597 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.597 [5824] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.597 [13556] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.597 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.597 [13852] DEBUG - Completed: [127.0.0.1:51014] 200 GET /myplex/account (9 live) GZIP 1ms 2291 bytes (pipelined: 3)
May 09, 2019 12:02:28.612 [8480] DEBUG - Request: [127.0.0.1:51016 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.612 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 GET /accounts/1 (9 live) GZIP 2ms 520 bytes (pipelined: 4)
May 09, 2019 12:02:28.612 [8480] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:02:28.612 [8480] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:28.612 [8480] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:28.612 [8480] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:02:28.612 [13852] DEBUG - Completed: [127.0.0.1:51016] 304 GET /system/:/prefs (9 live) GZIP 9ms 324 bytes (pipelined: 2)
May 09, 2019 12:02:28.612 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 GET /:/prefs (9 live) GZIP 20ms 7614 bytes (pipelined: 4)
May 09, 2019 12:02:28.659 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.659 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.659 [13556] DEBUG - Request: [127.0.0.1:51013 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.659 [4240] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /system/agents?mediaType=1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.659 [13556] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:02:28.659 [4240] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents?mediaType=1
May 09, 2019 12:02:28.659 [4240] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents?mediaType=1
May 09, 2019 12:02:28.659 [13556] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=ab8180f4-5be9-4d79-8d4f-0a62e2065e26
May 09, 2019 12:02:28.659 [4240] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/agents?mediaType=1
May 09, 2019 12:02:28.659 [4240] DEBUG - [com.plexapp.system] HTTP reply status 200, with 1992 bytes of content.
May 09, 2019 12:02:28.659 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 GET /system/agents?mediaType=1 (9 live) GZIP 7ms 861 bytes (pipelined: 5)
May 09, 2019 12:02:28.706 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.706 [5824] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /system/agents/com.plexapp.agents.none/config/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.706 [5824] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/com.plexapp.agents.none/config/1
May 09, 2019 12:02:28.706 [5824] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents/com.plexapp.agents.none/config/1
May 09, 2019 12:02:28.706 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.706 [4240] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.706 [4240] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none
May 09, 2019 12:02:28.706 [4240] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none
May 09, 2019 12:02:28.722 [4240] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none
May 09, 2019 12:02:28.722 [4240] DEBUG - [com.plexapp.system] HTTP reply status 200, with 249 bytes of content.
May 09, 2019 12:02:28.722 [13852] DEBUG - Completed: [127.0.0.1:51015] 200 GET /system/agents/contributors?mediaType=1&primaryAgent=com.plexapp.agents.none (9 live) GZIP 15ms 658 bytes (pipelined: 6)
May 09, 2019 12:02:28.722 [5824] DEBUG - HTTP 200 response from GET http://127.0.0.1:50775/system/agents/com.plexapp.agents.none/config/1
May 09, 2019 12:02:28.722 [5824] DEBUG - [com.plexapp.system] HTTP reply status 200, with 795 bytes of content.
May 09, 2019 12:02:28.722 [13852] DEBUG - Completed: [127.0.0.1:51014] 200 GET /system/agents/com.plexapp.agents.none/config/1 (9 live) GZIP 23ms 778 bytes (pipelined: 4)
May 09, 2019 12:02:28.737 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.737 [16040] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.737 [16040] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.737 [16040] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.737 [16040] DEBUG - HTTP 404 response from GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.737 [16040] DEBUG - [com.plexapp.system] HTTP reply status 404, with 0 bytes of content.
May 09, 2019 12:02:28.737 [16132] DEBUG - Completed: [127.0.0.1:51015] 404 GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia (9 live) GZIP 7ms 290 bytes (pipelined: 7)
May 09, 2019 12:02:28.753 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.753 [4240] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /system/agents/attribution?identifier=com.plexapp.agents.none (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.753 [4240] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/attribution?identifier=com.plexapp.agents.none
May 09, 2019 12:02:28.753 [4240] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.none
May 09, 2019 12:02:28.753 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:28.753 [16040] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:28.753 [16040] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.753 [16040] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.768 [4240] DEBUG - HTTP 404 response from GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.none
May 09, 2019 12:02:28.768 [4240] DEBUG - [com.plexapp.system] HTTP reply status 404, with 0 bytes of content.
May 09, 2019 12:02:28.768 [16132] DEBUG - Completed: [127.0.0.1:51014] 404 GET /system/agents/attribution?identifier=com.plexapp.agents.none (9 live) GZIP 12ms 290 bytes (pipelined: 5)
May 09, 2019 12:02:28.768 [16040] DEBUG - HTTP 404 response from GET http://127.0.0.1:50775/system/agents/attribution?identifier=com.plexapp.agents.localmedia
May 09, 2019 12:02:28.768 [16040] DEBUG - [com.plexapp.system] HTTP reply status 404, with 0 bytes of content.
May 09, 2019 12:02:28.768 [16132] DEBUG - Completed: [127.0.0.1:51015] 404 GET /system/agents/attribution?identifier=com.plexapp.agents.localmedia (9 live) GZIP 12ms 290 bytes (pipelined: 8)
May 09, 2019 12:02:28.800 [13556] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=ab8180f4-5be9-4d79-8d4f-0a62e2065e26
May 09, 2019 12:02:28.800 [16132] DEBUG - Completed: [127.0.0.1:51013] 200 PUT /myplex/refreshReachability (9 live) GZIP 142ms 195 bytes (pipelined: 5)
May 09, 2019 12:02:32.174 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:32.174 [8480] DEBUG - Request: [127.0.0.1:51013 (Loopback)] GET /:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:32.174 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:32.174 [5824] DEBUG - Request: [127.0.0.1:51014 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:32.174 [13852] DEBUG - Completed: [127.0.0.1:51014] 200 GET /accounts/1 (9 live) GZIP 1ms 520 bytes (pipelined: 6)
May 09, 2019 12:02:32.174 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:32.174 [16040] DEBUG - Request: [127.0.0.1:51015 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:32.190 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:32.190 [16132] DEBUG - Completed: [127.0.0.1:51015] 200 GET /myplex/account (9 live) GZIP 2ms 2291 bytes (pipelined: 9)
May 09, 2019 12:02:32.190 [13556] DEBUG - Request: [127.0.0.1:51016 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:32.190 [13556] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:02:32.190 [13556] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:32.190 [13556] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:02:32.190 [13556] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:02:32.190 [16132] DEBUG - Completed: [127.0.0.1:51016] 304 GET /system/:/prefs (9 live) GZIP 11ms 324 bytes (pipelined: 3)
May 09, 2019 12:02:32.205 [16132] DEBUG - Completed: [127.0.0.1:51013] 200 GET /:/prefs (9 live) GZIP 24ms 7614 bytes (pipelined: 6)
May 09, 2019 12:02:32.236 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:32.236 [4240] DEBUG - Request: [127.0.0.1:51013 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:32.236 [4240] DEBUG - MyPlex: we already have requested a connectivity refresh for async identifier ab8180f4-5be9-4d79-8d4f-0a62e2065e26 which has not yet expired.
May 09, 2019 12:02:32.236 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 PUT /myplex/refreshReachability (9 live) GZIP 1ms 195 bytes (pipelined: 7)
May 09, 2019 12:02:33.830 [13852] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="ab8180f4-5be9-4d79-8d4f-0a62e2065e26" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:02:33.830 [13852] DEBUG - PubSub: Got notified of reachability for async identifier ab8180f4-5be9-4d79-8d4f-0a62e2065e26: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:02:33.830 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:33.830 [5824] DEBUG - Request: [127.0.0.1:51013 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:33.830 [13852] DEBUG - Completed: [127.0.0.1:51013] 200 GET /myplex/account (9 live) GZIP 1ms 2291 bytes (pipelined: 8)
May 09, 2019 12:02:44.609 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (8 live) GZIP 20000ms 5 bytes (pipelined: 6)
May 09, 2019 12:02:44.609 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:02:44.609 [4240] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:02:44.609 [4240] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:03:04.603 [6732] DEBUG - BPQ: [Starting] -> [Processing]
May 09, 2019 12:03:04.603 [6732] DEBUG - BPQ: generating queue items from 0 generator(s)
May 09, 2019 12:03:04.603 [6732] DEBUG - BPQ: generated 0 item(s) for queue
May 09, 2019 12:03:04.603 [6732] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
May 09, 2019 12:03:04.603 [6732] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
May 09, 2019 12:03:04.603 [6732] DEBUG - BPQ: [Processing] -> [Idle]
May 09, 2019 12:03:04.618 [13852] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP 19996ms 5 bytes (pipelined: 7)
May 09, 2019 12:03:04.618 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:04.618 [12572] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:04.618 [12572] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:03:06.758 [15292] DEBUG - Request: [127.0.0.1:51092 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:06.758 [13852] DEBUG - Completed: [127.0.0.1:51092] 200 GET /servers (5 live) GZIP 2ms 496 bytes
May 09, 2019 12:03:06.758 [12572] DEBUG - Request: [127.0.0.1:51093 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:06.758 [13852] DEBUG - Completed: [127.0.0.1:51093] 200 GET /library/sections (6 live) GZIP 2ms 928 bytes
May 09, 2019 12:03:06.774 [15292] DEBUG - Request: [127.0.0.1:51094 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:06.774 [13852] DEBUG - Completed: [127.0.0.1:51094] 200 GET /channels/all (6 live) GZIP 1ms 387 bytes
May 09, 2019 12:03:06.774 [12572] DEBUG - Request: [127.0.0.1:51095 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:06.774 [13852] DEBUG - Completed: [127.0.0.1:51095] 200 GET /library/sections (6 live) GZIP 1ms 928 bytes
May 09, 2019 12:03:06.774 [15292] DEBUG - Request: [127.0.0.1:51096 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:06.774 [13852] DEBUG - Completed: [127.0.0.1:51096] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
May 09, 2019 12:03:20.677 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.677 [12572] DEBUG - Request: [127.0.0.1:51111 (Loopback)] GET /:/prefs (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.692 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.692 [16300] DEBUG - Request: [127.0.0.1:51113 (Loopback)] GET /accounts/1 (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.692 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.692 [2940] DEBUG - Request: [127.0.0.1:51114 (Loopback)] GET /myplex/account (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.692 [16132] DEBUG - Completed: [127.0.0.1:51113] 200 GET /accounts/1 (7 live) GZIP 3ms 520 bytes (pipelined: 1)
May 09, 2019 12:03:20.692 [16132] DEBUG - Completed: [127.0.0.1:51114] 200 GET /myplex/account (7 live) GZIP 1ms 2291 bytes (pipelined: 1)
May 09, 2019 12:03:20.692 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.692 [10792] DEBUG - Request: [127.0.0.1:51115 (Loopback)] GET /system/:/prefs (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.692 [10792] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:03:20.692 [10792] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:03:20.708 [10792] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:03:20.708 [10792] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:03:20.708 [13852] DEBUG - Completed: [127.0.0.1:51115] 304 GET /system/:/prefs (8 live) GZIP 11ms 324 bytes (pipelined: 1)
May 09, 2019 12:03:20.708 [13852] DEBUG - Completed: [127.0.0.1:51111] 200 GET /:/prefs (8 live) GZIP 25ms 7614 bytes (pipelined: 1)
May 09, 2019 12:03:20.786 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.786 [16300] DEBUG - Request: [127.0.0.1:51111 (Loopback)] PUT /myplex/refreshReachability (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.786 [16300] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:03:20.786 [16300] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=f42286bc-1286-40bc-bd65-30669fb0e168
May 09, 2019 12:03:20.802 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.802 [2940] DEBUG - Request: [127.0.0.1:51114 (Loopback)] GET /updater/status (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.802 [13852] DEBUG - Completed: [127.0.0.1:51114] 200 GET /updater/status (8 live) GZIP 0ms 454 bytes (pipelined: 2)
May 09, 2019 12:03:20.817 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.817 [12572] DEBUG - Request: [127.0.0.1:51114 (Loopback)] GET /photo/:/transcode?width=60&height=60&url=https%3A%2F%2Fplex.tv%2Fusers%2Fbf2d5937a303c23a%2Favatar%3Fc%3D1557399496 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.833 [12572] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/bf2d5937a303c23a/avatar?c=1557399496] (is local: 0 upscaled: 0)
May 09, 2019 12:03:20.833 [12572] DEBUG - Content-Length of C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\35\357993bfb37368de836e6151a02acbc6c71770b5.jpg is 1012.
May 09, 2019 12:03:20.833 [16132] DEBUG - Completed: [127.0.0.1:51114] 200 GET /photo/:/transcode?width=60&height=60&url=https%3A%2F%2Fplex.tv%2Fusers%2Fbf2d5937a303c23a%2Favatar%3Fc%3D1557399496 (8 live) GZIP 14ms 1012 bytes (pipelined: 3)
May 09, 2019 12:03:20.880 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:20.880 [10792] DEBUG - Request: [127.0.0.1:51113 (Loopback)] PUT /updater/check?download=0 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:20.880 [10792] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e, download: NO
May 09, 2019 12:03:20.880 [10792] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:03:20.880 [10792] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:03:20.958 [16300] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=f42286bc-1286-40bc-bd65-30669fb0e168
May 09, 2019 12:03:20.958 [13852] DEBUG - Completed: [127.0.0.1:51111] 200 PUT /myplex/refreshReachability (8 live) GZIP 178ms 195 bytes (pipelined: 2)
May 09, 2019 12:03:21.036 [10792] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.4.994-107756f7e
May 09, 2019 12:03:21.036 [10792] INFO - AutoUpdate: no updates available
May 09, 2019 12:03:21.036 [13852] DEBUG - Completed: [127.0.0.1:51113] 200 PUT /updater/check?download=0 (8 live) GZIP 154ms 195 bytes (pipelined: 2)
May 09, 2019 12:03:24.629 [13852] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (8 live) GZIP 20001ms 5 bytes (pipelined: 8)
May 09, 2019 12:03:24.629 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:24.629 [2940] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:24.629 [2940] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:03:25.972 [16132] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="f42286bc-1286-40bc-bd65-30669fb0e168" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:03:25.972 [16132] DEBUG - PubSub: Got notified of reachability for async identifier f42286bc-1286-40bc-bd65-30669fb0e168: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:03:25.972 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:25.972 [12572] DEBUG - Request: [127.0.0.1:51111 (Loopback)] GET /myplex/account (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:25.972 [16132] DEBUG - Completed: [127.0.0.1:51111] 200 GET /myplex/account (7 live) GZIP 0ms 2291 bytes (pipelined: 3)
May 09, 2019 12:03:44.640 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP 20005ms 5 bytes (pipelined: 9)
May 09, 2019 12:03:44.656 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:03:44.656 [2940] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:03:44.656 [2940] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:04:04.667 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP 20009ms 5 bytes (pipelined: 10)
May 09, 2019 12:04:04.682 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:04.682 [11688] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:04.698 [11688] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:04:24.709 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP 20013ms 5 bytes (pipelined: 11)
May 09, 2019 12:04:24.724 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:24.724 [13564] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:24.724 [13564] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:04:44.735 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (6 live) GZIP 20001ms 5 bytes (pipelined: 12)
May 09, 2019 12:04:44.735 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:44.735 [5592] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:44.735 [5592] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:04:51.970 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:51.970 [5824] DEBUG - Request: [127.0.0.1:51185 (Loopback)] GET /:/prefs (6 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:51.970 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:51.970 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:51.970 [5592] DEBUG - Request: [127.0.0.1:51186 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:51.970 [3132] DEBUG - Request: [127.0.0.1:51187 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:51.970 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:51.985 [0224] DEBUG - Request: [127.0.0.1:51188 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:51.985 [13852] DEBUG - Completed: [127.0.0.1:51186] 200 GET /accounts/1 (9 live) GZIP 3ms 520 bytes (pipelined: 1)
May 09, 2019 12:04:51.985 [16132] DEBUG - Completed: [127.0.0.1:51187] 200 GET /myplex/account (9 live) GZIP 3ms 2291 bytes (pipelined: 1)
May 09, 2019 12:04:51.985 [0224] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:04:51.985 [0224] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:04:51.985 [16132] DEBUG - Completed: [127.0.0.1:51185] 200 GET /:/prefs (9 live) GZIP 21ms 7614 bytes (pipelined: 1)
May 09, 2019 12:04:51.985 [0224] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:04:51.985 [0224] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:04:51.985 [16132] DEBUG - Completed: [127.0.0.1:51188] 304 GET /system/:/prefs (9 live) GZIP 11ms 324 bytes (pipelined: 1)
May 09, 2019 12:04:52.064 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:52.064 [13316] DEBUG - Request: [127.0.0.1:51185 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:52.064 [13316] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:04:52.064 [13316] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=a3f8d4ce-153b-40bc-a045-fc47016e6910
May 09, 2019 12:04:52.235 [13316] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=a3f8d4ce-153b-40bc-a045-fc47016e6910
May 09, 2019 12:04:52.235 [13852] DEBUG - Completed: [127.0.0.1:51185] 200 PUT /myplex/refreshReachability (9 live) GZIP 174ms 195 bytes (pipelined: 2)
May 09, 2019 12:04:57.266 [16132] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="a3f8d4ce-153b-40bc-a045-fc47016e6910" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:04:57.266 [16132] DEBUG - PubSub: Got notified of reachability for async identifier a3f8d4ce-153b-40bc-a045-fc47016e6910: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:04:57.281 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:57.281 [5592] DEBUG - Request: [127.0.0.1:51185 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:57.281 [13852] DEBUG - Completed: [127.0.0.1:51185] 200 GET /myplex/account (9 live) GZIP 4ms 2291 bytes (pipelined: 3)
May 09, 2019 12:04:58.796 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:58.812 [13316] DEBUG - Request: [127.0.0.1:51185 (Loopback)] GET /:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:58.812 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:58.812 [5824] DEBUG - Request: [127.0.0.1:51187 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:58.812 [13852] DEBUG - Completed: [127.0.0.1:51187] 200 GET /accounts/1 (9 live) GZIP 1ms 520 bytes (pipelined: 2)
May 09, 2019 12:04:58.812 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:58.812 [0224] DEBUG - Request: [127.0.0.1:51186 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:58.812 [13852] DEBUG - Completed: [127.0.0.1:51186] 200 GET /myplex/account (9 live) GZIP 1ms 2291 bytes (pipelined: 2)
May 09, 2019 12:04:58.812 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:58.828 [3132] DEBUG - Request: [127.0.0.1:51187 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:58.828 [3132] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:04:58.828 [3132] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:04:58.828 [3132] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:04:58.828 [3132] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:04:58.828 [16132] DEBUG - Completed: [127.0.0.1:51187] 304 GET /system/:/prefs (9 live) GZIP 7ms 324 bytes (pipelined: 3)
May 09, 2019 12:04:58.828 [16132] DEBUG - Completed: [127.0.0.1:51185] 200 GET /:/prefs (9 live) GZIP 23ms 7614 bytes (pipelined: 4)
May 09, 2019 12:04:58.874 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:04:58.890 [5824] DEBUG - Request: [127.0.0.1:51185 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:04:58.890 [5824] DEBUG - MyPlex: Requesting reachability check.
May 09, 2019 12:04:58.890 [5824] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=5720faa3-cb23-4bc1-9c8c-8baeafe6bc21
May 09, 2019 12:04:59.093 [5824] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=5720faa3-cb23-4bc1-9c8c-8baeafe6bc21
May 09, 2019 12:04:59.109 [13852] DEBUG - Completed: [127.0.0.1:51185] 200 PUT /myplex/refreshReachability (9 live) GZIP 219ms 195 bytes (pipelined: 5)
May 09, 2019 12:05:04.108 [13852] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="5720faa3-cb23-4bc1-9c8c-8baeafe6bc21" connectivity="0" command="notifyConnectivity"/>'
May 09, 2019 12:05:04.108 [13852] DEBUG - PubSub: Got notified of reachability for async identifier 5720faa3-cb23-4bc1-9c8c-8baeafe6bc21: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 09, 2019 12:05:04.123 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:04.123 [0224] DEBUG - Request: [127.0.0.1:51185 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:04.123 [16132] DEBUG - Completed: [127.0.0.1:51185] 200 GET /myplex/account (9 live) GZIP 1ms 2291 bytes (pipelined: 6)
May 09, 2019 12:05:04.748 [13852] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (9 live) GZIP 20001ms 5 bytes (pipelined: 13)
May 09, 2019 12:05:04.764 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:04.764 [13316] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (9 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:04.764 [13316] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:05:08.778 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:08.778 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:08.778 [13316] DEBUG - Request: [127.0.0.1:51186 (Loopback)] GET /accounts/1 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:08.778 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:08.778 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:08.778 [5824] DEBUG - Request: [127.0.0.1:51185 (Loopback)] GET /:/prefs (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:08.778 [3132] DEBUG - Request: [127.0.0.1:51187 (Loopback)] GET /myplex/account (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:08.778 [0224] DEBUG - Request: [127.0.0.1:51188 (Loopback)] GET /system/:/prefs (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:08.778 [13852] DEBUG - Completed: [127.0.0.1:51186] 200 GET /accounts/1 (8 live) GZIP 4ms 520 bytes (pipelined: 3)
May 09, 2019 12:05:08.794 [0224] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
May 09, 2019 12:05:08.794 [0224] DEBUG - HTTP requesting GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:05:08.794 [13852] DEBUG - Completed: [127.0.0.1:51187] 200 GET /myplex/account (8 live) GZIP 3ms 2291 bytes (pipelined: 4)
May 09, 2019 12:05:08.794 [0224] DEBUG - HTTP 304 response from GET http://127.0.0.1:50775/system/:/prefs
May 09, 2019 12:05:08.794 [0224] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
May 09, 2019 12:05:08.794 [13852] DEBUG - Completed: [127.0.0.1:51188] 304 GET /system/:/prefs (8 live) GZIP 13ms 324 bytes (pipelined: 2)
May 09, 2019 12:05:08.810 [13852] DEBUG - Completed: [127.0.0.1:51185] 200 GET /:/prefs (8 live) GZIP 28ms 7614 bytes (pipelined: 7)
May 09, 2019 12:05:24.769 [16132] DEBUG - Completed: [127.0.0.1:50946] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (8 live) GZIP 19999ms 5 bytes (pipelined: 14)
May 09, 2019 12:05:24.785 [16132] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:24.785 [16168] DEBUG - Request: [127.0.0.1:50946 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (8 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:24.785 [16168] WARN - [CompanionPlayer] We already have a handler, overwriting.
May 09, 2019 12:05:31.299 [16132] DEBUG - WebSocket: client initiated close
May 09, 2019 12:05:31.299 [16132] DEBUG - NotificationStream: Removing because of close
May 09, 2019 12:05:31.299 [16132] DEBUG - handleStreamRead code 2: End of file
May 09, 2019 12:05:31.314 [13852] DEBUG - Auth: authenticated user 1 as richard49
May 09, 2019 12:05:31.314 [6728] DEBUG - Request: [127.0.0.1:51226 (Loopback)] GET /diagnostics/logs (7 live) GZIP Signed-in Token (richard49)
May 09, 2019 12:05:31.314 [6728] DEBUG - Diagnostics: Building logfile zip
