Apr 16, 2019 15:43:16.428 [8820] INFO - Plex Media Server v1.15.3.876-ad6e39743 - Microsoft PC x64 - build: windows-x86 windows - GMT -04:00
Apr 16, 2019 15:43:16.428 [8820] INFO - Windows version: 10.0 (Build 17134), language en-US
Apr 16, 2019 15:43:16.428 [8820] INFO - 4 3292 MHz processor(s): Architecture=0, Level=6, Revision=15363 Processor Identifier=Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
Apr 16, 2019 15:43:16.428 [8820] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
Apr 16, 2019 15:43:16.428 [10404] DEBUG - Plex for Windows Store application is not installed
Apr 16, 2019 15:43:16.428 [10404] DEBUG - BPQ: [Idle] -> [Starting]
Apr 16, 2019 15:43:16.428 [10404] DEBUG - FeatureManager: Using cached data for features list
Apr 16, 2019 15:43:16.428 [10404] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:43:16.443 [10404] INFO - SQLITE3:0xa64f8068, 283, recovered 641 frames from WAL file C:\Users\mike1\AppData\Local\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.db-wal
Apr 16, 2019 15:43:16.475 [10404] DEBUG - Running migrations. (EPG 0)
Apr 16, 2019 15:43:16.490 [10404] DEBUG - ChangestampAllocator: initialized to 10506677
Apr 16, 2019 15:43:16.490 [10404] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:43:16.490 [10404] DEBUG - Running migrations. (EPG 0)
Apr 16, 2019 15:43:17.162 [10404] DEBUG - CERT: Installed certificate with fingerprint 94:77:32:3a:7f:f9:5e:27:42:90:e3:f0:aa:3d:5b:15:27:33:68:9c.
Apr 16, 2019 15:43:17.162 [10404] DEBUG - CERT: Installed new private key.
Apr 16, 2019 15:43:17.162 [10404] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.04bea987fdf9486d9cae167ff85e1ef8.plex.direct
Apr 16, 2019 15:43:17.162 [10404] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Apr 16, 2019 15:43:17.162 [10404] INFO - OCSP: Successfully retrieved response from cache.
Apr 16, 2019 15:43:17.162 [10404] DEBUG - CERT: Installed intermediate certificate.
Apr 16, 2019 15:43:17.162 [10404] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Apr 16, 2019 15:43:17.162 [10404] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Apr 16, 2019 15:43:17.162 [10404] INFO - Killing PlexScriptHost.exe process with pid 2532
Apr 16, 2019 15:43:17.162 [10404] INFO - Killing PlexScriptHost.exe process with pid 10672
Apr 16, 2019 15:43:17.193 [2348] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:43:17.193 [10404] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Apr 16, 2019 15:43:17.193 [10404] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 16, 2019 15:43:17.193 [8272] DEBUG - Relay: read 22 cached entries from hosts file
Apr 16, 2019 15:43:17.193 [8272] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:17.193 [2348] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:43:17.193 [10404] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:43:17.193 [10404] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:43:17.193 [10404] DEBUG - Opening 1 database sessions to library (), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:43:17.193 [10436] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 16, 2019 15:43:17.193 [10436] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:43:17.193 [10448] DEBUG - File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743" changed: -1 => 2019-04-08 14:19:30 (1554747570).
Apr 16, 2019 15:43:17.193 [10448] DEBUG - File "C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2018-12-15 09:15:10 (1544883310).
Apr 16, 2019 15:43:17.193 [10448] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743"
Apr 16, 2019 15:43:17.209 [10448] DEBUG - Scanning for plug-ins in "C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins"
Apr 16, 2019 15:43:17.209 [10448] DEBUG - PluginRepository::setStartState: 1, startingSystem
Apr 16, 2019 15:43:17.209 [10448] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\System.bundle.
Apr 16, 2019 15:43:17.209 [10448] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Apr 16, 2019 15:43:17.209 [10448] DEBUG - Starting file watcher for com.plexapp.system
Apr 16, 2019 15:43:17.209 [10448] 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-ad6e39743\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\System.bundle"
Apr 16, 2019 15:43:17.209 [10448] DEBUG - Spawned plug-in com.plexapp.system with PID 10664
Apr 16, 2019 15:43:17.209 [10448] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Apr 16, 2019 15:43:17.709 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:17.787 [10436] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:43:17.787 [10436] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:43:17.787 [2348] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.256 [2348] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.256 [2348] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.725 [2348] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.725 [2348] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.725 [8272] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\mike1\AppData\Local\Plex Media Server\Crash Reports\1.15.3.876-ad6e39743" --version=1.15.3.876-ad6e39743 --platform=Windows "--platformVersion=10.0 (Build 17134)" --serverUuid=9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f --userId=mike1825@gmail.com --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx --vendor=Microsoft --model=x64 --device=PC 
Apr 16, 2019 15:43:18.725 [8272] DEBUG - Jobs: Starting child process with pid 3024
Apr 16, 2019 15:43:18.756 [10448] DEBUG - Reading 486 bytes in the body, code is 200
Apr 16, 2019 15:43:18.756 [10448] DEBUG - [com.plexapp.system] Plug-in running on port 50453.
Apr 16, 2019 15:43:18.756 [10448] DEBUG -  * Plug-in handles prefix: /system
Apr 16, 2019 15:43:18.756 [10448] DEBUG -  * Plug-in handles prefix: /player
Apr 16, 2019 15:43:18.756 [10448] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Apr 16, 2019 15:43:18.756 [10448] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Apr 16, 2019 15:43:18.756 [10448] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Apr 16, 2019 15:43:18.756 [10448] DEBUG - Updating the list of agents known by the system.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - Image transcode cache directory: "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Apr 16, 2019 15:43:18.756 [10448] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Apr 16, 2019 15:43:18.756 [10404] DEBUG - Transcoder: Cleaning old transcode directories.
Apr 16, 2019 15:43:18.756 [10448] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents
Apr 16, 2019 15:43:18.756 [10404] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:43:18.756 [10404] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:43:18.756 [10428] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.756 [10404] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:43:18.756 [10404] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:43:18.756 [10404] DEBUG - PublicAddressManager: Starting.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 16, 2019 15:43:18.756 [10904] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:43:18.756 [10904] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:43:18.756 [4104] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkInterface: ignoring adapter 'VirtualBox Host-Only Network' (fe80::f87e:1ec7:c34a:e7ac%8,192.168.56.1) because it has no gateway
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 2' (fe80::4c1a:3bb9:a51:9d8e%11,169.254.157.142) because it has no gateway
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 3' (fe80::d02f:97da:4cdd:c245%9,169.254.194.69) because it has no gateway
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Apr 16, 2019 15:43:18.756 [10404] DEBUG - Detected primary interface: 192.168.86.123
Apr 16, 2019 15:43:18.756 [10404] DEBUG - Network interfaces:
Apr 16, 2019 15:43:18.756 [10404] DEBUG -  * 16 Ethernet {E0A6A9D7-FB75-4B7B-963E-67CD17BA53CB} (192.168.86.123) (loopback: 0)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - Creating NetworkServices singleton.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkServices: Initializing...
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.756 [6348] DEBUG - Network change for advertiser.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [6348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10436] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.756 [6348] DEBUG - Network change for advertiser.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [6348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:43:18.756 [10404] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:43:18.771 [6348] DEBUG - Network change for advertiser.
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Apr 16, 2019 15:43:18.771 [6348] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 239.255.255.250 (index: 0)
Apr 16, 2019 15:43:18.834 [10448] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents
Apr 16, 2019 15:43:18.834 [10448] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Apr 16, 2019 15:43:18.834 [10448] DEBUG - The system knows about 13 agents.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Fanart-TV.bundle.
Apr 16, 2019 15:43:18.834 [10448] DEBUG - Waiting for 18 plug-ins to finish starting.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Framework.bundle.
Apr 16, 2019 15:43:18.834 [3748] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\HTbackdrops.bundle.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LastFM.bundle.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LocalMedia.bundle.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LyricFind.bundle.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Media-Flags.bundle.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\MoviePosterDB.bundle.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\OpenSubtitles.bundle.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PersonalMedia.bundle.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMovie.bundle.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Apr 16, 2019 15:43:18.834 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMusic.bundle.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Apr 16, 2019 15:43:18.834 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexThemeMusic.bundle.
Apr 16, 2019 15:43:18.850 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Apr 16, 2019 15:43:18.850 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Scanners.bundle.
Apr 16, 2019 15:43:18.850 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Apr 16, 2019 15:43:18.850 [0468] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheMovieDB.bundle.
Apr 16, 2019 15:43:18.850 [9536] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheTVDB.bundle.
Apr 16, 2019 15:43:18.850 [3748] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Apr 16, 2019 15:43:18.850 [3748] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle.
Apr 16, 2019 15:43:18.850 [0468] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Apr 16, 2019 15:43:18.850 [0468] DEBUG - Starting plug-in C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Apr 16, 2019 15:43:18.850 [9536] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Apr 16, 2019 15:43:18.850 [10448] DEBUG - Starting scheduled updates, every 3600 seconds
Apr 16, 2019 15:43:18.850 [10448] INFO - Refreshing paths watched by LibraryUpdateManager
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:43:18.850 [10448] DEBUG - LibraryUpdateManager will start watching D:\Movies
Apr 16, 2019 15:43:18.850 [10448] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Music
Apr 16, 2019 15:43:18.850 [10448] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Pictures
Apr 16, 2019 15:43:18.850 [10448] DEBUG - LibraryUpdateManager will start watching D:\Shows
Apr 16, 2019 15:43:18.850 [10448] DEBUG - Done waiting for all plug-ins to start.
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale ar
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale cs_CZ
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale da
Apr 16, 2019 15:43:18.850 [10448] DEBUG - PluginRepository::setStartState: 3, complete
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale de
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale en
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale es
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale et_EE
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale fr
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale he
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale hr_HR
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale hu_HU
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale it
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale ja_JP
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale ko
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale lt
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale nl
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale nl_BE
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale no
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale pt_BR
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale ro
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale ru
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale sr
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale sv
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Translator: Found locale zh_CN
Apr 16, 2019 15:43:18.850 [10344] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Apr 16, 2019 15:43:18.850 [10344] 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-ad6e39743\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMovie.bundle"
Apr 16, 2019 15:43:18.865 [10344] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 10648
Apr 16, 2019 15:43:18.865 [10344] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Apr 16, 2019 15:43:18.881 [10404] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
Apr 16, 2019 15:43:18.881 [10404] DEBUG - Gracenote: Using SQLite 3.26.0
Apr 16, 2019 15:43:18.881 [10404] DEBUG - Starting HTTP server.
Apr 16, 2019 15:43:18.881 [10404] DEBUG - Running server...
Apr 16, 2019 15:43:18.881 [10404] DEBUG - Waiting for server condition to be signaled.
Apr 16, 2019 15:43:18.881 [3400] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Apr 16, 2019 15:43:18.881 [3400] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.881 [3732] DEBUG - Plex Tuner Service.exe starting.
Apr 16, 2019 15:43:18.881 [3732] 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.3.876-ad6e39743 32600 /waitmutex 
Apr 16, 2019 15:43:18.881 [6384] DEBUG - Request: [127.0.0.1:50445 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.881 [3468] DEBUG - Request: [127.0.0.1:50446 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.881 [6384] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:18.881 [6384] DEBUG - HTTP requesting GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:18.881 [3732] DEBUG - Jobs: Starting child process with pid 8612
Apr 16, 2019 15:43:18.881 [8280] DEBUG - Completed: [127.0.0.1:50446] 200 GET / (5 live) GZIP 4ms 2950 bytes
Apr 16, 2019 15:43:18.881 [1520] DEBUG - Request: [127.0.0.1:50451 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.881 [7640] DEBUG - Completed: [127.0.0.1:50451] 200 GET / (5 live) GZIP 8ms 2950 bytes
Apr 16, 2019 15:43:18.881 [11204] DEBUG - Request: [127.0.0.1:50452 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.881 [3468] DEBUG - Request: [127.0.0.1:50463 (Loopback)] GET / (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.896 [7640] DEBUG - Completed: [127.0.0.1:50452] 200 GET /servers (6 live) GZIP 12ms 492 bytes
Apr 16, 2019 15:43:18.896 [7640] DEBUG - Completed: [127.0.0.1:50463] 200 GET / (6 live) GZIP 1ms 2950 bytes
Apr 16, 2019 15:43:18.896 [1520] DEBUG - Request: [127.0.0.1:50465 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.896 [7640] DEBUG - Completed: [127.0.0.1:50465] 200 GET /library/sections (7 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:43:18.896 [3468] DEBUG - Request: [127.0.0.1:50466 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.896 [7640] DEBUG - Completed: [127.0.0.1:50466] 200 GET /channels/all (7 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:43:18.912 [11204] DEBUG - Request: [127.0.0.1:50467 (Loopback)] GET /library/sections (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.912 [7640] DEBUG - Completed: [127.0.0.1:50467] 200 GET /library/sections (8 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:43:18.912 [1520] DEBUG - Request: [127.0.0.1:50468 (Loopback)] GET /channels/all (9 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:18.912 [7640] DEBUG - Completed: [127.0.0.1:50468] 200 GET /channels/all (9 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:43:18.928 [2348] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.943 [10428] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:18.959 [6384] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:18.959 [6384] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:43:18.959 [10904] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:43:18.959 [10904] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:43:18.959 [7640] DEBUG - Completed: [127.0.0.1:50445] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (9 live) GZIP 90ms 19473 bytes
Apr 16, 2019 15:43:19.053 [2348] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Apr 16, 2019 15:43:19.350 [3400] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.350 [3400] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.412 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:43:19.428 [4104] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:43:19.428 [4104] DEBUG - HTTP requesting GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:43:19.662 [3468] DEBUG - Request: [127.0.0.1:50474 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (10 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:19.662 [3468] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:43:19.662 [3468] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:43:19.678 [3468] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:43:19.678 [3468] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:43:19.678 [7640] DEBUG - Completed: [127.0.0.1:50474] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (10 live) GZIP 28ms 330 bytes
Apr 16, 2019 15:43:19.756 [11204] DEBUG - Request: [127.0.0.1:50477 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:19.756 [11204] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:43:19.756 [11204] DEBUG - HTTP requesting GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:43:19.818 [11204] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:43:19.818 [11204] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Apr 16, 2019 15:43:19.818 [7640] DEBUG - Completed: [127.0.0.1:50477] 200 GET /:/plugins/com.plexapp.system/resourceHashes (11 live) GZIP 53ms 3498 bytes
Apr 16, 2019 15:43:19.818 [3400] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Running database fix-ups.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Database Fixups: Destroyed 0 transient items.
Apr 16, 2019 15:43:19.834 [3468] DEBUG - Request: [127.0.0.1:50480 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (12 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:19.834 [3468] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:19.834 [3468] DEBUG - HTTP requesting GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Found 0 collections tags in section 1.
Apr 16, 2019 15:43:19.834 [11204] DEBUG - Request: [127.0.0.1:50481 (Loopback)] GET / (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Synced tags in section 1 in 0ms.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Apr 16, 2019 15:43:19.834 [7640] DEBUG - Completed: [127.0.0.1:50481] 200 GET / (11 live) GZIP 1ms 2950 bytes
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Found 0 collections tags in section 2.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Synced tags in section 2 in 0ms.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Apr 16, 2019 15:43:19.834 [3400] DEBUG - Running database fix-ups completed in 0.0 seconds.
Apr 16, 2019 15:43:19.834 [9100] DEBUG - Versions: updating status for generator 468
Apr 16, 2019 15:43:19.834 [3400] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.834 [9100] DEBUG - BPQ: generator 468 is in complete state
Apr 16, 2019 15:43:19.834 [9100] DEBUG - Versions: updating status for generator 470
Apr 16, 2019 15:43:19.834 [9100] DEBUG - BPQ: generator 470 is in complete state
Apr 16, 2019 15:43:19.834 [9100] DEBUG - Versions: updating status for generator 471
Apr 16, 2019 15:43:19.834 [9100] DEBUG - BPQ: generator 471 is in complete state
Apr 16, 2019 15:43:19.849 [3468] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:43:19.849 [3468] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:43:19.865 [7640] DEBUG - Completed: [127.0.0.1:50480] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (11 live) GZIP 30ms 19473 bytes
Apr 16, 2019 15:43:19.865 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:43:19.865 [8272] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.865 [4104] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:19.928 [5372] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 3024 is 0 (success)
Apr 16, 2019 15:43:20.293 [3400] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.321 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.324 [4104] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.348 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.348 [8272] DEBUG - DVR: Starting up.
Apr 16, 2019 15:43:20.352 [8272] DEBUG - EPG[onconnect]: Starting.
Apr 16, 2019 15:43:20.353 [8272] DEBUG - Opening 10 database sessions to library (tv.plex.providers.epg.onconnect:2), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:43:20.369 [3400] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:43:20.371 [8272] INFO - SQLITE3:0xa64f8068, 283, recovered 921 frames from WAL file C:\Users\mike1\AppData\Local\Plex Media Server\Plug-in Support\Databases\tv.plex.providers.epg.onconnect-c866a282-3a3b-443b-aeeb-c95a100b1390.db-wal
Apr 16, 2019 15:43:20.405 [8272] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:43:20.408 [8272] DEBUG - Media Provider: Registering provider tv.plex.providers.epg.onconnect:2
Apr 16, 2019 15:43:20.409 [8272] DEBUG - Activity: registered new activity e89a10a5-5344-4094-a158-8d7d9b51a953 - Refreshing guide data
Apr 16, 2019 15:43:20.409 [8272] DEBUG - Activity: updated activity e89a10a5-5344-4094-a158-8d7d9b51a953 - completed 0% - Refreshing guide data
Apr 16, 2019 15:43:20.409 [2348] DEBUG - EPG[onconnect]: starting incremental refresh for lineup lineup://tv.plex.providers.epg.onconnect/USA-OTA46074#Local%20Over%20the%20Air%20Broadcast
Apr 16, 2019 15:43:20.409 [8272] DEBUG - EPG[onconnect]: Scheduling next refresh for 2019-04-16 19:43:20.
Apr 16, 2019 15:43:20.410 [2348] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:43:20.411 [8272] DEBUG - Activity: registered new activity 9b4682e5-52df-4f3b-8614-90506786bea4 - Processing subscriptions
Apr 16, 2019 15:43:20.411 [10416] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.412 [8272] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Apr 16, 2019 15:43:20.445 [10080] DEBUG - EPG[onconnect]: Next thing to start/end is at 2019-04-16 19:58:00 GMT (in 881 seconds)
Apr 16, 2019 15:43:20.508 [2348] DEBUG - EPG[onconnect]: Matched channel 6.1 (ABC) with grabber channel, adding.
Apr 16, 2019 15:43:20.520 [2348] DEBUG - EPG[onconnect]: Matched channel 8.1 (CW) with grabber channel, adding.
Apr 16, 2019 15:43:20.540 [2348] DEBUG - EPG[onconnect]: Matched channel 13.1 (NBC) with grabber channel, adding.
Apr 16, 2019 15:43:20.544 [2348] DEBUG - EPG[onconnect]: Matched channel 13.3 (METVN) with grabber channel, adding.
Apr 16, 2019 15:43:20.569 [2348] DEBUG - EPG[onconnect]: Matched channel 18.4 (GETTV) with grabber channel, adding.
Apr 16, 2019 15:43:20.572 [2348] DEBUG - EPG[onconnect]: Matched channel 19.1 (TELE) with grabber channel, adding.
Apr 16, 2019 15:43:20.617 [2348] DEBUG - EPG[onconnect]: Matched channel 29.1 (CBS) with grabber channel, adding.
Apr 16, 2019 15:43:20.618 [2348] DEBUG - EPG[onconnect]: Matched channel 29.2 (Independent) with grabber channel, adding.
Apr 16, 2019 15:43:20.623 [2348] DEBUG - EPG[onconnect]: Matched channel 30.1 (QUESTSD) with grabber channel, adding.
Apr 16, 2019 15:43:20.627 [2348] DEBUG - EPG[onconnect]: Matched channel 30.4 (CBNNEWS) with grabber channel, adding.
Apr 16, 2019 15:43:20.629 [2348] DEBUG - EPG[onconnect]: Matched channel 30.5 (Independent) with grabber channel, adding.
Apr 16, 2019 15:43:20.645 [2348] DEBUG - EPG[onconnect]: Matched channel 40.1 (Independent) with grabber channel, adding.
Apr 16, 2019 15:43:20.679 [2348] DEBUG - EPG[onconnect]: Matched channel 49.1 (PBS) with grabber channel, adding.
Apr 16, 2019 15:43:20.680 [2348] DEBUG - EPG[onconnect]: Matched channel 49.2 (CREATE) with grabber channel, adding.
Apr 16, 2019 15:43:20.682 [2348] DEBUG - EPG[onconnect]: Matched channel 49.3 (Independent) with grabber channel, adding.
Apr 16, 2019 15:43:20.686 [2348] DEBUG - EPG[onconnect]: Matched channel 59.1 (FOX) with grabber channel, adding.
Apr 16, 2019 15:43:20.705 [2348] DEBUG - EPG[onconnect]: Matched channel 63.4 (Independent) with grabber channel, adding.
Apr 16, 2019 15:43:20.707 [2348] DEBUG - EPG[onconnect]: Matched channel 63.5 (QVC) with grabber channel, adding.
Apr 16, 2019 15:43:20.708 [2348] DEBUG - EPG[onconnect]: Matched channel 63.6 (HSN) with grabber channel, adding.
Apr 16, 2019 15:43:20.720 [1520] DEBUG - Request: [127.0.0.1:50495 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:20.721 [1520] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:43:20.721 [1520] DEBUG - HTTP requesting GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:43:20.725 [1520] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:43:20.725 [1520] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Apr 16, 2019 15:43:20.725 [7640] DEBUG - Completed: [127.0.0.1:50495] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (11 live) GZIP 5ms 465 bytes
Apr 16, 2019 15:43:20.731 [10344] DEBUG - Reading 148 bytes in the body, code is 200
Apr 16, 2019 15:43:20.731 [10344] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 50497.
Apr 16, 2019 15:43:20.731 [10344] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Apr 16, 2019 15:43:20.731 [10344] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:43:20.731 [10344] DEBUG - HTTP requesting GET http://127.0.0.1:50497/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:43:20.754 [10344] DEBUG - HTTP 200 response from GET http://127.0.0.1:50497/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:43:20.754 [10344] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Apr 16, 2019 15:43:20.754 [10344] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 07b9db54ed88186ae435dd3081e43ec04912506e
Apr 16, 2019 15:43:20.755 [10344] DEBUG - IVA: Downloading trailer lists.
Apr 16, 2019 15:43:20.785 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.787 [4104] DEBUG - Sync: onMyPlexSignIn
Apr 16, 2019 15:43:20.788 [4104] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.912 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:20.918 [10344] DEBUG - IVA: Downloaded 41 trailers for source 1.
Apr 16, 2019 15:43:21.004 [10344] DEBUG - IVA: Downloaded 55 trailers for source 2.
Apr 16, 2019 15:43:21.197 [6384] DEBUG - Request: [127.0.0.1:50512 (Loopback)] GET /web/index.html (12 live) GZIP Signed-in
Apr 16, 2019 15:43:21.200 [6384] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\index.html"
Apr 16, 2019 15:43:21.201 [6384] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\index.html is 9330.
Apr 16, 2019 15:43:21.227 [8280] DEBUG - Completed: [127.0.0.1:50512] 200 GET /web/index.html (13 live) GZIP 29ms 9330 bytes (pipelined: 1)
Apr 16, 2019 15:43:21.239 [11204] DEBUG - Request: [127.0.0.1:50512 (Loopback)] GET /web/js/vendors-2-3b63739766ecb29d0a09-plex-3.83.1-0043312.js (12 live) GZIP Signed-in
Apr 16, 2019 15:43:21.240 [11204] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\js\vendors-2-3b63739766ecb29d0a09-plex-3.83.1-0043312.js"
Apr 16, 2019 15:43:21.241 [3468] DEBUG - Request: [127.0.0.1:50513 (Loopback)] GET /web/js/main-1-278dcf29f71f97b8b7f0-plex-3.83.1-0043312.js (11 live) GZIP Signed-in
Apr 16, 2019 15:43:21.241 [3468] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\js\main-1-278dcf29f71f97b8b7f0-plex-3.83.1-0043312.js"
Apr 16, 2019 15:43:21.242 [11204] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\js\vendors-2-3b63739766ecb29d0a09-plex-3.83.1-0043312.js is 1606363.
Apr 16, 2019 15:43:21.242 [3468] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\js\main-1-278dcf29f71f97b8b7f0-plex-3.83.1-0043312.js is 3522656.
Apr 16, 2019 15:43:21.246 [1520] DEBUG - Request: [127.0.0.1:50515 (Loopback)] GET /web/main.v3.3b7f49e6554d92eddc7b1c31a2764195.css (13 live) GZIP Signed-in
Apr 16, 2019 15:43:21.246 [1520] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\main.v3.3b7f49e6554d92eddc7b1c31a2764195.css"
Apr 16, 2019 15:43:21.247 [1520] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\main.v3.3b7f49e6554d92eddc7b1c31a2764195.css is 307554.
Apr 16, 2019 15:43:21.256 [8280] DEBUG - Completed: [127.0.0.1:50515] 200 GET /web/main.v3.3b7f49e6554d92eddc7b1c31a2764195.css (13 live) GZIP 10ms 307554 bytes (pipelined: 1)
Apr 16, 2019 15:43:21.268 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.268 [4104] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Apr 16, 2019 15:43:21.268 [4104] DEBUG - Sync: synchronizing 0 sync lists to generators
Apr 16, 2019 15:43:21.268 [4104] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Apr 16, 2019 15:43:21.268 [4104] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Apr 16, 2019 15:43:21.269 [4104] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.270 [9100] DEBUG - BPQ: garbageCollect - sync - forced - 10
Apr 16, 2019 15:43:21.270 [9100] DEBUG - Sync: starting Sync garbage collection: 10, forced
Apr 16, 2019 15:43:21.270 [9100] DEBUG - Sync: garbage collecting filesystem
Apr 16, 2019 15:43:21.270 [9100] WARN - Sync: local sync directory "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
Apr 16, 2019 15:43:21.270 [9100] DEBUG - Sync: garbage collected filesystem
Apr 16, 2019 15:43:21.270 [9100] DEBUG - Sync: finished Sync garbage collection: 10
Apr 16, 2019 15:43:21.270 [6476] DEBUG - Request: [127.0.0.1:50516 (Loopback)] GET /web/main.67fe8c8af64d75942e0d3017ccb55654.css (13 live) GZIP Signed-in
Apr 16, 2019 15:43:21.271 [6476] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\main.67fe8c8af64d75942e0d3017ccb55654.css"
Apr 16, 2019 15:43:21.271 [6476] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\main.67fe8c8af64d75942e0d3017ccb55654.css is 763472.
Apr 16, 2019 15:43:21.294 [8280] DEBUG - Completed: [127.0.0.1:50516] 200 GET /web/main.67fe8c8af64d75942e0d3017ccb55654.css (13 live) GZIP 47ms 763472 bytes (pipelined: 1)
Apr 16, 2019 15:43:21.628 [7640] DEBUG - Completed: [127.0.0.1:50512] 200 GET /web/js/vendors-2-3b63739766ecb29d0a09-plex-3.83.1-0043312.js (13 live) GZIP 389ms 1606363 bytes (pipelined: 2)
Apr 16, 2019 15:43:21.709 [4104] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.712 [10432] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.712 [4104] DEBUG - EventSource: Resolving 173.230.131.250 port 443
Apr 16, 2019 15:43:21.712 [8272] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.712 [10408] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.722 [4104] DEBUG - MyPlex: Updating device connections (from timer: 0)
Apr 16, 2019 15:43:21.722 [4104] DEBUG - HTTP requesting PUT https://plex.tv/devices/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f?Connection[][uri]=http://192.168.86.123:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:21.723 [8280] DEBUG - EventSource: Resolved 173.230.131.250 to 173.230.131.250
Apr 16, 2019 15:43:21.727 [9108] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=0)
Apr 16, 2019 15:43:21.728 [9108] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=d7fdd262-e5b4-4d2a-af3a-1e49c5eab201
Apr 16, 2019 15:43:21.770 [8280] DEBUG - EventSource: Connected in 32 ms.
Apr 16, 2019 15:43:21.771 [7640] DEBUG - EventSource: Wrote data, reading reply.
Apr 16, 2019 15:43:21.820 [10436] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:43:21.824 [10436] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:43:21.827 [10436] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:43:21.827 [10436] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:43:21.978 [2348] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:43:21.987 [2348] DEBUG - Activity: updated activity e89a10a5-5344-4094-a158-8d7d9b51a953 - completed 1% - Refreshing guide data
Apr 16, 2019 15:43:22.001 [7640] DEBUG - Completed: [127.0.0.1:50513] 200 GET /web/js/main-1-278dcf29f71f97b8b7f0-plex-3.83.1-0043312.js (7 live) GZIP 760ms 3522656 bytes (pipelined: 1)
Apr 16, 2019 15:43:22.012 [2348] DEBUG - EPG[onconnect]: Step 1/84 (2019-04-16T16:00Z) (network: 1.5 sec, database: 1.5 sec total: 1.6 sec)
Apr 16, 2019 15:43:22.013 [2348] DEBUG - Activity: updated activity e89a10a5-5344-4094-a158-8d7d9b51a953 - completed 1% - Refreshing guide data
Apr 16, 2019 15:43:22.024 [10904] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:43:22.028 [10904] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:43:22.030 [10904] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:43:22.165 [10408] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:22.198 [7640] DEBUG - EventSource: Read HTTP reply header.
Apr 16, 2019 15:43:22.198 [7640] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Apr 16, 2019 15:43:22.203 [7640] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:43:22.203 [7640] DEBUG - EventSource: Successfully connected to 173.230.131.250.
Apr 16, 2019 15:43:22.203 [10080] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:43:22.203 [10416] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:43:22.203 [10080] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:43:22.206 [10432] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:22.206 [10432] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Apr 16, 2019 15:43:22.220 [2348] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:43:22.231 [4104] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f?Connection[][uri]=http://192.168.86.123:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:22.231 [10436] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:43:22.234 [4104] DEBUG - CERT: Certificate will not expire soon.
Apr 16, 2019 15:43:22.235 [9108] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=d7fdd262-e5b4-4d2a-af3a-1e49c5eab201
Apr 16, 2019 15:43:22.235 [9108] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:43:22.235 [9108] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:43:22.249 [10904] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=20877)
Apr 16, 2019 15:43:22.250 [10904] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=45887de9-af5c-4b44-8e5a-6cd6076c9180
Apr 16, 2019 15:43:22.253 [2348] DEBUG - Activity: updated activity e89a10a5-5344-4094-a158-8d7d9b51a953 - completed 2% - Refreshing guide data
Apr 16, 2019 15:43:22.283 [2348] DEBUG - EPG: Found a dupe show 'Have Thinning Hair? Keranique Can Help Regrow Beautiful, Thicker Hair!' with a different GUID.
Apr 16, 2019 15:43:22.290 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 2/84 (2019-04-16T20:00Z) (network: 1.7 sec, database: 1.7 sec total: 1.9 sec)
Apr 16, 2019 15:43:22.301 [2348] DEBUG - Activity: updated activity e89a10a5-5344-4094-a158-8d7d9b51a953 - completed 2% - Refreshing guide data
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 3/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 4/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 5/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 6/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 7/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 8/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 9/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 10/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 11/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 12/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.301 [2348] DEBUG - EPG[onconnect]: Step 13/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 14/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 15/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 16/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 17/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 18/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 19/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 20/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 21/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 22/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 23/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 24/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 25/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 26/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 27/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 28/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 29/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.302 [2348] DEBUG - EPG[onconnect]: Step 30/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 31/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 32/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 33/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 34/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 35/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 36/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 37/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 38/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 39/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 40/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 41/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 42/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 43/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 44/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 45/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 46/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 47/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 48/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 49/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 50/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.303 [2348] DEBUG - EPG[onconnect]: Step 51/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 52/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 53/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 54/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 55/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 56/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 57/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 58/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 59/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 60/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 61/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 62/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 63/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 64/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 65/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 66/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 67/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 68/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 69/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.304 [2348] DEBUG - EPG[onconnect]: Step 70/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 71/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 72/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 73/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 74/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 75/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 76/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 77/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 78/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 79/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 80/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 81/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 82/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 83/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.305 [2348] DEBUG - EPG[onconnect]: Step 84/84: skipping chunk for incremental loading
Apr 16, 2019 15:43:22.408 [10080] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:43:22.408 [10080] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:43:22.510 [6384] DEBUG - Request: [127.0.0.1:50513 (Loopback)] GET /web/translations/en.json (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.510 [6384] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\translations\en.json"
Apr 16, 2019 15:43:22.511 [6384] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\translations\en.json is 2.
Apr 16, 2019 15:43:22.512 [8280] DEBUG - Completed: [127.0.0.1:50513] 200 GET /web/translations/en.json (7 live) GZIP 2ms 2 bytes (pipelined: 2)
Apr 16, 2019 15:43:22.514 [6476] DEBUG - Request: [127.0.0.1:50512 (Loopback)] GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.514 [6384] DEBUG - Request: [127.0.0.1:50513 (Loopback)] GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.514 [6384] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\noise.8b05ce45d0df59343e206bc9ae78d85d.png"
Apr 16, 2019 15:43:22.515 [6476] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png"
Apr 16, 2019 15:43:22.516 [6384] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\noise.8b05ce45d0df59343e206bc9ae78d85d.png is 54413.
Apr 16, 2019 15:43:22.517 [6476] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png is 31365.
Apr 16, 2019 15:43:22.518 [8280] DEBUG - Completed: [127.0.0.1:50513] 200 GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (7 live) GZIP 4ms 54413 bytes (pipelined: 3)
Apr 16, 2019 15:43:22.519 [8280] DEBUG - Completed: [127.0.0.1:50512] 200 GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (7 live) GZIP 4ms 31365 bytes (pipelined: 3)
Apr 16, 2019 15:43:22.605 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.605 [3468] DEBUG - Request: [127.0.0.1:50512 (Loopback)] GET /media/providers (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.607 [7640] DEBUG - Completed: [127.0.0.1:50512] 200 GET /media/providers (7 live) GZIP 2ms 3598 bytes (pipelined: 4)
Apr 16, 2019 15:43:22.610 [7640] DEBUG - Request: [127.0.0.1:50512 (Loopback)] GET /media/providers (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.610 [7640] DEBUG - Completed: [127.0.0.1:50512] 401 GET /media/providers (7 live) GZIP 0ms 357 bytes
Apr 16, 2019 15:43:22.610 [8280] DEBUG - Request: [127.0.0.1:50513 (Loopback)] GET / (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.610 [7640] DEBUG - Completed: [127.0.0.1:50513] 401 GET / (7 live) GZIP 0ms 435 bytes
Apr 16, 2019 15:43:22.614 [7640] DEBUG - Request: [127.0.0.1:50516 (Loopback)] GET /media/providers (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.615 [7640] DEBUG - Completed: [127.0.0.1:50516] 401 GET /media/providers (7 live) GZIP 0ms 357 bytes
Apr 16, 2019 15:43:22.615 [8280] DEBUG - Request: [127.0.0.1:50515 (Loopback)] GET / (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.615 [7640] DEBUG - Completed: [127.0.0.1:50515] 401 GET / (6 live) GZIP 0ms 435 bytes
Apr 16, 2019 15:43:22.663 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.664 [1520] DEBUG - Request: [127.0.0.1:50546 (Loopback)] GET /:/websockets/notifications (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.664 [1520] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
Apr 16, 2019 15:43:22.664 [1520] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:43:22.676 [11204] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /identity (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.677 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.677 [7640] DEBUG - Completed: [127.0.0.1:50547] 200 GET /identity (8 live) GZIP 0ms 469 bytes (pipelined: 1)
Apr 16, 2019 15:43:22.677 [6476] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /media/providers (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.679 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 GET /media/providers (8 live) GZIP 2ms 3598 bytes (pipelined: 1)
Apr 16, 2019 15:43:22.688 [6384] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /identity (7 live) GZIP Signed-in
Apr 16, 2019 15:43:22.689 [8280] DEBUG - Completed: [127.0.0.1:50548] 200 GET /identity (7 live) GZIP 1ms 469 bytes (pipelined: 2)
Apr 16, 2019 15:43:22.735 [7640] DEBUG - CERT: incomplete TLS handshake: sslv3 alert certificate unknown
Apr 16, 2019 15:43:22.751 [10904] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=45887de9-af5c-4b44-8e5a-6cd6076c9180
Apr 16, 2019 15:43:22.751 [10904] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:43:22.751 [10904] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:43:22.762 [10904] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:43:22.782 [8272] DEBUG - Database analyze: starting analyze (all tables)
Apr 16, 2019 15:43:22.782 [2348] DEBUG - EPG[onconnect]: Total time to load EPG was 2.4 (HTTP details cached 100.0%, CloudFlare grid cached: 100.0%, 0 HTTP errors)
Apr 16, 2019 15:43:22.782 [2348] DEBUG - Activity: Ended activity e89a10a5-5344-4094-a158-8d7d9b51a953.
Apr 16, 2019 15:43:22.785 [2348] DEBUG - Activity: registered new activity 3a6ecd7b-0a4b-4b89-9c4b-808f8fcd6c0f - Processing subscriptions
Apr 16, 2019 15:43:22.787 [10904] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:43:22.788 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.788 [10904] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:43:22.788 [3468] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /activities (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.788 [10904] DEBUG - Activity: registered new activity 303e5ecc-7aa2-4c56-8d04-db4a5602c001 - Processing subscriptions
Apr 16, 2019 15:43:22.789 [10904] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:43:22.790 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.790 [10904] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:43:22.790 [10904] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:43:22.791 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 GET /activities (7 live) GZIP 2ms 550 bytes (pipelined: 3)
Apr 16, 2019 15:43:22.791 [1520] DEBUG - Request: [127.0.0.1:50547 (Loopback)] PUT /myplex/refreshReachability (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:Subscription: Scheduled all subscriptions in 2 ms.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - Activity: Ended activity 303e5ecc-7aa2-4c56-8d04-db4a5602c001.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - Activity: Ended activity 3a6ecd7b-0a4b-4b89-9c4b-808f8fcd6c0f.
Apr 16, 2019 15:43:22.791 [10904] DEBUG - DVR:Subscription: Refreshed all content in 4 ms.
Apr 16, 2019 15:43:22.792 [1520] DEBUG - MyPlex: we already have requested a connectivity refresh for async identifier 45887de9-af5c-4b44-8e5a-6cd6076c9180 which has not yet expired.
Apr 16, 2019 15:43:22.792 [7640] DEBUG - Completed: [127.0.0.1:50547] 200 PUT /myplex/refreshReachability (7 live) GZIP 2ms 274 bytes (pipelined: 2)
Apr 16, 2019 15:43:22.796 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.796 [11204] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /:/prefs (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.797 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.798 [6476] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /status/sessions (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.799 [6476] DEBUG - [Now] Adding 0 sessions.
Apr 16, 2019 15:43:22.799 [7640] DEBUG - Completed: [127.0.0.1:50547] 200 GET /status/sessions (7 live) GZIP 1ms 397 bytes (pipelined: 3)
Apr 16, 2019 15:43:22.803 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.803 [6384] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /updater/status (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.804 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /updater/status (7 live) GZIP 0ms 447 bytes (pipelined: 4)
Apr 16, 2019 15:43:22.813 [8280] DEBUG - WebSocket: client initiated close
Apr 16, 2019 15:43:22.814 [8280] DEBUG - NotificationStream: Removing because of close
Apr 16, 2019 15:43:22.814 [8280] DEBUG - handleStreamRead code 2: End of file
Apr 16, 2019 15:43:22.816 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.816 [1520] DEBUG - Request: [127.0.0.1:50551 (Loopback)] GET /:/websockets/notifications (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.817 [1520] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
Apr 16, 2019 15:43:22.817 [1520] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:43:22.818 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 GET /:/prefs (7 live) GZIP 21ms 7405 bytes (pipelined: 4)
Apr 16, 2019 15:43:22.840 [7640] DEBUG - WebSocket: client initiated close
Apr 16, 2019 15:43:22.840 [8280] DEBUG - handleStreamRead code 2: End of file
Apr 16, 2019 15:43:22.840 [8280] DEBUG - NotificationStream: Removing because of error
Apr 16, 2019 15:43:22.842 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.843 [3468] DEBUG - Request: [127.0.0.1:50552 (Loopback)] GET /:/websockets/notifications (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.843 [3468] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
Apr 16, 2019 15:43:22.843 [3468] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:43:22.856 [8272] DEBUG - Database analyze: analyze complete
Apr 16, 2019 15:43:22.868 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.869 [6476] DEBUG - Request: [127.0.0.1:50548 (Loopback)] PUT /updater/check?download=0 (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.870 [6476] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743, download: NO
Apr 16, 2019 15:43:22.870 [6476] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:22.870 [6476] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:22.908 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:22.908 [6384] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /activities (9 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:22.909 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /activities (9 live) GZIP 0ms 491 bytes (pipelined: 5)
Apr 16, 2019 15:43:22.912 [11204] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /web/favicon.ico (9 live) GZIP Signed-in
Apr 16, 2019 15:43:22.912 [11204] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\favicon.ico"
Apr 16, 2019 15:43:22.913 [11204] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\favicon.ico is 5430.
Apr 16, 2019 15:43:22.916 [7640] DEBUG - Completed: [127.0.0.1:50547] 200 GET /web/favicon.ico (9 live) GZIP 4ms 5430 bytes (pipelined: 6)
Apr 16, 2019 15:43:23.094 [1520] DEBUG - Request: [52.49.250.227:40572 (WAN)] GET /identity (8 live) TLS Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.095 [7640] DEBUG - Completed: [52.49.250.227:40572] 200 GET /identity (8 live) TLS 0ms 385 bytes (pipelined: 1)
Apr 16, 2019 15:43:23.296 [6476] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:23.296 [6476] INFO - AutoUpdate: no updates available
Apr 16, 2019 15:43:23.299 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 PUT /updater/check?download=0 (8 live) GZIP 430ms 274 bytes (pipelined: 5)
Apr 16, 2019 15:43:23.350 [7640] DEBUG - EventSource: Got event [data] '<Message address="199.66.67.126" port="20877" asyncIdentifier="45887de9-af5c-4b44-8e5a-6cd6076c9180" connectivity="1" command="notifyConnectivity"/>'
Apr 16, 2019 15:43:23.350 [7640] DEBUG - PubSub: Got notified of reachability for async identifier 45887de9-af5c-4b44-8e5a-6cd6076c9180: 1 for 199.66.67.126:20877 (responded in 1 seconds)
Apr 16, 2019 15:43:23.353 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.353 [3468] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /myplex/account (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.354 [8280] DEBUG - Completed: [127.0.0.1:50548] 200 GET /myplex/account (8 live) GZIP 1ms 2240 bytes (pipelined: 6)
Apr 16, 2019 15:43:23.415 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.415 [11204] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /library/sections (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.417 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.417 [8280] DEBUG - Completed: [127.0.0.1:50548] 200 GET /library/sections (8 live) GZIP 2ms 1021 bytes (pipelined: 7)
Apr 16, 2019 15:43:23.418 [6384] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /media/providers (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.420 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /media/providers (8 live) GZIP 2ms 3596 bytes (pipelined: 7)
Apr 16, 2019 15:43:23.515 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.515 [6476] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /music/iTunes (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.516 [7640] DEBUG - Completed: [127.0.0.1:50547] 404 GET /music/iTunes (8 live) GZIP 0ms 379 bytes (pipelined: 8)
Apr 16, 2019 15:43:23.521 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.522 [3468] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.522 [3468] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:23.522 [3468] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:23.530 [3468] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 7 ms.
Apr 16, 2019 15:43:23.530 [3468] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 7 ms.
Apr 16, 2019 15:43:23.530 [3468] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:43:23.530 [3468] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:23.535 [3468] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 5 ms.
Apr 16, 2019 15:43:23.536 [3468] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:23.539 [3468] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 3 ms.
Apr 16, 2019 15:43:23.539 [3468] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=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/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:43:23.539 [3468] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Apr 16, 2019 15:43:23.540 [3468] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=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/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:43:23.541 [3468] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 1 ms.
Apr 16, 2019 15:43:23.541 [3468] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=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/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:43:23.542 [3468] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 1 ms.
Apr 16, 2019 15:43:23.549 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (8 live) GZIP 26ms 5827 bytes (pipelined: 9)
Apr 16, 2019 15:43:23.635 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.635 [1520] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (8 live) Page 16-50 GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.635 [11204] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (8 live) GZIP Signed-in
Apr 16, 2019 15:43:23.636 [11204] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png"
Apr 16, 2019 15:43:23.636 [1520] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Apr 16, 2019 15:43:23.636 [11204] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png is 42165.
Apr 16, 2019 15:43:23.638 [8280] DEBUG - Completed: [127.0.0.1:50548] 200 GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (8 live) GZIP 2ms 42165 bytes (pipelined: 8)
Apr 16, 2019 15:43:23.645 [1520] DEBUG - Setting container serialization range to [16, 50] (total=134)
Apr 16, 2019 15:43:23.650 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /hubs/home/recentlyAdded?type=1 (8 live) GZIP Page 16-50 15ms 14664 bytes (pipelined: 10)
Apr 16, 2019 15:43:23.706 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.707 [6384] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fart%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.707 [6384] DEBUG - Photo transcoder: Request for url [/library/metadata/1631/art/1554083914?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.707 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.708 [6476] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F3%2Fart%2F1554962703%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (9 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.708 [6476] DEBUG - Photo transcoder: Request for url [/library/metadata/3/art/1554962703?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.708 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.708 [3468] DEBUG - Request: [127.0.0.1:50563 (Loopback)] GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F814%2Fart%2F1554962732%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (9 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.709 [3468] DEBUG - Photo transcoder: Request for url [/library/metadata/814/art/1554962732?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.711 [6384] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\bb\bbdc4b7f6f9a140f2cbf5d56fdefeb803ff46c14.jpg is 19902.
Apr 16, 2019 15:43:23.711 [6476] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.711 [3468] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.712 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fart%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (9 live) GZIP 5ms 19902 bytes (pipelined: 11)
Apr 16, 2019 15:43:23.717 [6476] DEBUG - Computed media url for item 3: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:43:23.717 [6476] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:43:23.719 [3468] DEBUG - Computed media url for item 814: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:43:23.719 [3468] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:43:23.742 [6476] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:43:23.742 [6476] DEBUG - Calculated media file path for path [metadata://art/com.plexapp.agents.imdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\7\fb3cf9f5089b9a24986c9da60ec9fbccef91939.bundle\Contents\_combined/art/com.plexapp.agents.imdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41"]
Apr 16, 2019 15:43:23.742 [3468] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:43:23.743 [3468] DEBUG - Calculated media file path for path [metadata://art/com.plexapp.agents.imdb_f999c2b40847843c2212477057e693d08c147be0]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\a\8d4e53cc92a042f37eb7ffcd452e76daa87bc07.bundle\Contents\_combined/art/com.plexapp.agents.imdb_f999c2b40847843c2212477057e693d08c147be0"]
Apr 16, 2019 15:43:23.743 [6476] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.744 [3468] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.744 [6476] DEBUG - Photo cache obtained 139917 bytes from /library/metadata/3/art/1554962703?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.744 [6476] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\73\73f9be5b98844db5c1cddb894d745dae98b0ff8c.jpg
Apr 16, 2019 15:43:23.744 [3468] DEBUG - Photo cache obtained 216223 bytes from /library/metadata/814/art/1554962732?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.744 [3468] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\9b\9baf58638c64c9f1e1463f0ca16a496f71b3a7d6.jpg
Apr 16, 2019 15:43:23.749 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.749 [1520] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1671%2Fthumb%2F1554837735%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.750 [1520] DEBUG - Photo transcoder: Request for url [/library/metadata/1671/thumb/1554837735?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.750 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.752 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.752 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.753 [11204] DEBUG - Request: [127.0.0.1:50567 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1657%2Fthumb%2F1554962753%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.753 [11204] DEBUG - Photo transcoder: Request for url [/library/metadata/1657/thumb/1554962753?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.754 [10188] DEBUG - Request: [127.0.0.1:50568 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fthumb%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.754 [10188] DEBUG - Photo transcoder: Request for url [/library/metadata/1631/thumb/1554083914?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.755 [1520] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.756 [11204] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.756 [10188] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\d4\d4d4b4dd09f1267ad0a8925ea157978aedeb64c8.jpg is 12207.
Apr 16, 2019 15:43:23.757 [10704] DEBUG - Request: [127.0.0.1:50569 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.757 [10704] DEBUG - Photo transcoder: Request for url [/library/metadata/1626/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.758 [11204] DEBUG - Computed media url for item 1657: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:43:23.758 [11204] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:43:23.759 [1520] DEBUG - Computed media url for item 1671: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt7959026%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_d4dcff0d2d8e9eb40bb1e8d2906f29b0bc717223
Apr 16, 2019 15:43:23.759 [1520] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt7959026%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_d4dcff0d2d8e9eb40bb1e8d2906f29b0bc717223
Apr 16, 2019 15:43:23.760 [10704] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.763 [10704] DEBUG - Computed media url for item 1626: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:43:23.763 [10704] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:43:23.766 [7640] DEBUG - Completed: [127.0.0.1:50568] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fthumb%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP 13ms 12207 bytes (pipelined: 1)
Apr 16, 2019 15:43:23.767 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.767 [6384] DEBUG - Request: [127.0.0.1:50568 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1610%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (11 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.768 [6384] DEBUG - Photo transcoder: Request for url [/library/metadata/1610/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.770 [6384] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.772 [6348] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.86.123 (Media-PC)
Apr 16, 2019 15:43:23.772 [6348] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:43:23.772 [6348] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:43:23.772 [6348] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:43:23.774 [6384] DEBUG - Computed media url for item 1610: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:43:23.774 [6384] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:43:23.788 [11204] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:43:23.789 [11204] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_16d3730524dae47c7f22128fef528b94159a3ab9]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\c\a5f0f555fb087a5fb2befba2d1b3e62225eae34.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_16d3730524dae47c7f22128fef528b94159a3ab9"]
Apr 16, 2019 15:43:23.790 [11204] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.790 [11204] DEBUG - Photo cache obtained 99127 bytes from /library/metadata/1657/thumb/1554962753?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.790 [11204] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\78\7809355d9ab79182c6e76dc98caf356dda215a55.jpg
Apr 16, 2019 15:43:23.793 [1520] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt7959026%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_d4dcff0d2d8e9eb40bb1e8d2906f29b0bc717223
Apr 16, 2019 15:43:23.794 [1520] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_d4dcff0d2d8e9eb40bb1e8d2906f29b0bc717223]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\e\92a76874050e8f8f033169d419be77f45115299.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_d4dcff0d2d8e9eb40bb1e8d2906f29b0bc717223"]
Apr 16, 2019 15:43:23.795 [1520] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.795 [1520] DEBUG - Photo cache obtained 334026 bytes from /library/metadata/1671/thumb/1554837735?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.796 [1520] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\da\da9fa2145c5670a880eb6391ba82d42be4ff0a5c.jpg
Apr 16, 2019 15:43:23.802 [3468] DEBUG - Created thumbnail of size 350x197, has pixels: 1
Apr 16, 2019 15:43:23.804 [6204] DEBUG - Request: [127.0.0.1:50575 (Loopback)] GET /servers (12 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.807 [8280] DEBUG - Completed: [127.0.0.1:50575] 200 GET /servers (12 live) GZIP 11ms 492 bytes
Apr 16, 2019 15:43:23.809 [6384] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:43:23.809 [6384] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\e\9ecd41e5268418d58875c603783bde66df5af73.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2"]
Apr 16, 2019 15:43:23.810 [6384] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.810 [10704] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:43:23.811 [6384] DEBUG - Photo cache obtained 147983 bytes from /library/metadata/1610/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.811 [6384] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\05\059112ac9cfa478a325170da6d16bda196ceeefa.jpg
Apr 16, 2019 15:43:23.816 [10704] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_9a3bd802222bf6ce92926b73991ece46007f2a20]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\6\ad3d46b2dcf9610b668cd066a790daa478b6212.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_9a3bd802222bf6ce92926b73991ece46007f2a20"]
Apr 16, 2019 15:43:23.817 [10704] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.818 [10704] DEBUG - Photo cache obtained 590590 bytes from /library/metadata/1626/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.818 [10704] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\66\662b86007bd380a6294c5ba5c285b04f833c9503.jpg
Apr 16, 2019 15:43:23.819 [11204] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:23.834 [10188] DEBUG - Request: [127.0.0.1:50576 (Loopback)] GET /library/sections (13 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.838 [7640] DEBUG - Completed: [127.0.0.1:50576] 200 GET /library/sections (13 live) GZIP 4ms 1013 bytes
Apr 16, 2019 15:43:23.847 [6476] DEBUG - Created thumbnail of size 350x197, has pixels: 1
Apr 16, 2019 15:43:23.847 [1520] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:23.864 [8280] DEBUG - Completed: [127.0.0.1:50567] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1657%2Fthumb%2F1554962753%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP 114ms 6925 bytes (pipelined: 1)
Apr 16, 2019 15:43:23.878 [6204] DEBUG - Request: [127.0.0.1:50577 (Loopback)] GET /channels/all (14 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.879 [8280] DEBUG - Completed: [127.0.0.1:50577] 200 GET /channels/all (14 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:43:23.879 [7640] DEBUG - Completed: [127.0.0.1:50547] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1671%2Fthumb%2F1554837735%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (14 live) GZIP 130ms 3712 bytes (pipelined: 12)
Apr 16, 2019 15:43:23.881 [6348] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:43:23.881 [6348] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:43:23.881 [6348] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.27
Apr 16, 2019 15:43:23.882 [6348] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.250
Apr 16, 2019 15:43:23.889 [6384] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:23.891 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.891 [1520] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1609%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (14 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.891 [1520] DEBUG - Photo transcoder: Request for url [/library/metadata/1609/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.892 [1520] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.893 [8280] DEBUG - Completed: [127.0.0.1:50563] 200 GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F814%2Fart%2F1554962732%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (14 live) GZIP 185ms 12235 bytes (pipelined: 1)
Apr 16, 2019 15:43:23.893 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 GET /photo/:/transcode?width=350&height=197&minSize=1&url=%2Flibrary%2Fmetadata%2F3%2Fart%2F1554962703%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (14 live) GZIP 186ms 14574 bytes (pipelined: 9)
Apr 16, 2019 15:43:23.894 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.895 [11204] DEBUG - Request: [127.0.0.1:50567 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1603%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (14 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.895 [11204] DEBUG - Photo transcoder: Request for url [/library/metadata/1603/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.896 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.896 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.896 [1520] DEBUG - Computed media url for item 1609: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:43:23.896 [10188] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1601%2Fthumb%2F1553202090%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.896 [3468] DEBUG - Request: [127.0.0.1:50563 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F666%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.897 [1520] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:43:23.897 [10188] DEBUG - Photo transcoder: Request for url [/library/metadata/1601/thumb/1553202090?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.897 [3468] DEBUG - Photo transcoder: Request for url [/library/metadata/666/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.898 [11204] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.899 [10188] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\55\551aaeb6fc0798f604ef6abedbe6959dc8c6e25c.jpg is 8447.
Apr 16, 2019 15:43:23.899 [3468] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\4c\4c3cda6e87cef6aadeb47cb6e61d214f169870aa.jpg is 5942.
Apr 16, 2019 15:43:23.900 [11204] DEBUG - Computed media url for item 1603: http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:43:23.900 [11204] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:43:23.900 [8280] DEBUG - Completed: [127.0.0.1:50563] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F666%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (13 live) GZIP 4ms 5942 bytes (pipelined: 2)
Apr 16, 2019 15:43:23.901 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.902 [6204] DEBUG - Request: [127.0.0.1:50563 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F636%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.902 [10704] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:23.902 [6204] DEBUG - Photo transcoder: Request for url [/library/metadata/636/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.903 [7640] DEBUG - Completed: [127.0.0.1:50548] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1601%2Fthumb%2F1553202090%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 7ms 8447 bytes (pipelined: 10)
Apr 16, 2019 15:43:23.904 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:23.904 [6204] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\fc\fc3a629be930971fedd5b6e35f0d8b3ba55566b9.jpg is 7442.
Apr 16, 2019 15:43:23.904 [6476] DEBUG - Request: [127.0.0.1:50548 (Loopback)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F665%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:23.905 [6476] DEBUG - Photo transcoder: Request for url [/library/metadata/665/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:23.906 [8280] DEBUG - Completed: [127.0.0.1:50563] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F636%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 4ms 7442 bytes (pipelined: 3)
Apr 16, 2019 15:43:23.906 [6476] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\43\43eda1a1ebeaa43c3cca0ae48994a74a04f0a379.jpg is 5911.
Apr 16, 2019 15:43:23.908 [8280] DEBUG - Completed: [127.0.0.1:50568] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1610%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 142ms 2671 bytes (pipelined: 2)
Apr 16, 2019 15:43:23.911 [8280] DEBUG - Completed: [127.0.0.1:50548] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F665%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 6ms 5911 bytes (pipelined: 11)
Apr 16, 2019 15:43:23.912 [8280] DEBUG - Completed: [127.0.0.1:50569] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 160ms 8441 bytes (pipelined: 1)
Apr 16, 2019 15:43:23.916 [1520] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:43:23.917 [1520] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\d\f1960f8ef8b6ce96cbde090457e2da7f192865b.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf"]
Apr 16, 2019 15:43:23.918 [11204] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:43:23.918 [1520] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.918 [11204] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_71bd30588db65552507992a01270c39a7704a3e4]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\2\d7628129ab5ce440b25ae374315d125f6808ec6.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_71bd30588db65552507992a01270c39a7704a3e4"]
Apr 16, 2019 15:43:23.919 [1520] DEBUG - Photo cache obtained 924010 bytes from /library/metadata/1609/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.919 [1520] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\7e\7ef0dcc50c5c3f0449056d5bb84e64b9e94a1b10.jpg
Apr 16, 2019 15:43:23.919 [11204] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:43:23.920 [11204] DEBUG - Photo cache obtained 256707 bytes from /library/metadata/1603/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:43:23.920 [11204] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\3b\3b3364c8a0a08ff3a062db4bed30b4e474cb5aec.jpg
Apr 16, 2019 15:43:23.953 [11204] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:23.964 [8280] DEBUG - Completed: [127.0.0.1:50567] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1603%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 68ms 9458 bytes (pipelined: 2)
Apr 16, 2019 15:43:24.012 [1520] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:43:24.026 [8280] DEBUG - Completed: [127.0.0.1:50547] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1609%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (12 live) GZIP 134ms 12107 bytes (pipelined: 13)
Apr 16, 2019 15:43:24.088 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:24.089 [10188] DEBUG - Request: [192.168.86.123:50580 (Subnet)] GET /media/providers (13 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:24.092 [8280] DEBUG - Completed: [192.168.86.123:50580] 200 GET /media/providers (13 live) TLS GZIP 3ms 3676 bytes (pipelined: 1)
Apr 16, 2019 15:43:24.662 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:24.663 [3468] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (13 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:24.663 [3468] DEBUG - [CompanionProxy] registered new player: c516uyndt7b9it753auxfn3v (127.0.0.1)
Apr 16, 2019 15:43:25.262 [10416] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:43:25.266 [10416] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:43:25.269 [10416] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:43:25.269 [10416] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:43:25.283 [10416] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:43:25.464 [10080] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:43:25.469 [10080] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:43:25.470 [10080] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:43:25.486 [10080] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:43:26.714 [10432] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:43:27.152 [10432] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:43:27.198 [8272] DEBUG - DVR:Device: Discovering and refreshing all devices.
Apr 16, 2019 15:43:27.198 [8272] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Apr 16, 2019 15:43:27.198 [8272] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:43:27.198 [8272] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:43:27.198 [8272] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:27.214 [8272] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:27.214 [8272] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:27.214 [8272] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:27.245 [8272] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:43:27.245 [8272] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 45.79.197.109 was 33 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 173.230.131.250 was 33 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 172.104.24.90 was 52 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 66.228.52.149 was 66 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 184.105.148.113 was 104 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 139.162.245.220 was 180 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 82.94.168.19 was 208 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 172.104.252.87 was 256 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 335 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Time to connect to 172.104.173.210 was 426 ms.
Apr 16, 2019 15:43:27.792 [10432] DEBUG - PubSubManager: Updating best ping time for 173.230.131.250 to 33 ms.
Apr 16, 2019 15:43:28.776 [6348] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.123
Apr 16, 2019 15:43:28.776 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.776 [6348] DEBUG - HTTP requesting GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.49 (Family Room Display)
Apr 16, 2019 15:43:28.792 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - HTTP requesting GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.792 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.50 (Guest Room)
Apr 16, 2019 15:43:28.823 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:43:28.823 [6348] DEBUG - HTTP requesting GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:43:28.839 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:43:28.839 [6348] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:43:28.839 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (OnHub)
Apr 16, 2019 15:43:28.839 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANDevice)
Apr 16, 2019 15:43:28.839 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANConnectionDevice)
Apr 16, 2019 15:43:28.870 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.870 [6348] DEBUG - HTTP requesting GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.886 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.886 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:28.886 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.38 (Master Bedroom)
Apr 16, 2019 15:43:28.933 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:43:28.933 [6348] DEBUG - HTTP requesting GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:43:28.948 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:43:28.948 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:43:28.948 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.250 (Mike's 3rd Fire TV)
Apr 16, 2019 15:43:28.948 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:43:28.948 [6348] DEBUG - HTTP requesting GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:43:28.964 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:43:28.964 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:43:28.964 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.39 (Home Theater Room)
Apr 16, 2019 15:43:29.026 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:43:29.026 [6348] DEBUG - HTTP requesting GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:43:29.058 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:43:29.058 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:43:29.058 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.35 (Loft Fire TV)
Apr 16, 2019 15:43:29.073 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:43:29.073 [6348] DEBUG - HTTP requesting GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:43:29.089 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:43:29.089 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:43:29.089 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.125 (HDHomeRun DMS 104C05F1)
Apr 16, 2019 15:43:29.089 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:29.089 [6348] DEBUG - HTTP requesting GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:29.105 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:29.105 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:29.105 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.233 (Master Bedroom Soundbar)
Apr 16, 2019 15:43:29.744 [8272] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:43:29.744 [10408] DEBUG - DVR:Device: Discovering and refreshing devices with identifier tv.plex.grabbers.hdhomerun
Apr 16, 2019 15:43:29.744 [10408] DEBUG - DVR:Grabber: HDHomerun discovered a model HDHR4-2US.
Apr 16, 2019 15:43:29.744 [10408] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:29.744 [10408] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:29.744 [10408] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:29.760 [10408] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:29.760 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:43:29.760 [6348] DEBUG - HTTP requesting GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:43:29.776 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:43:29.776 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:43:29.776 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.40 (Family Room)
Apr 16, 2019 15:43:29.791 [10408] DEBUG - DVR:Grabber: HDHomerun discovered 1 compatible devices.
Apr 16, 2019 15:43:29.791 [10408] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:43:29.791 [10408] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:43:29.791 [10408] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:29.791 [10408] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:43:29.791 [10408] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:29.807 [10408] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:43:29.838 [10408] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:43:30.289 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.289 [3468] DEBUG - Request: [192.168.86.150:33782 (Subnet)] GET /media/providers (13 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.305 [7640] DEBUG - Completed: [192.168.86.150:33782] 200 GET /media/providers (13 live) TLS GZIP 3ms 3675 bytes (pipelined: 1)
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - Activity: registered new activity 4d6f0ea0-8503-4ff4-bb38-4031af93d0d3 - Processing subscriptions
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:43:30.414 [10436] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - Activity: Ended activity 4d6f0ea0-8503-4ff4-bb38-4031af93d0d3.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - Activity: Ended activity 9b4682e5-52df-4f3b-8614-90506786bea4.
Apr 16, 2019 15:43:30.417 [10436] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Apr 16, 2019 15:43:30.444 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.444 [10704] DEBUG - Request: [192.168.86.150:33784 (Subnet)] GET /:/websockets/notifications (14 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.444 [10704] DEBUG - WebSocket: Performing handshake from origin https://app.plex.tv
Apr 16, 2019 15:43:30.444 [10704] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:43:30.471 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.471 [3468] DEBUG - Request: [192.168.86.150:33782 (Subnet)] GET /:/prefs (14 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.471 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.471 [10188] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /status/sessions (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.471 [10188] DEBUG - [Now] Adding 0 sessions.
Apr 16, 2019 15:43:30.471 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.471 [10704] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /updater/status (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.471 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /status/sessions (16 live) TLS GZIP 1ms 429 bytes (pipelined: 1)
Apr 16, 2019 15:43:30.471 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /updater/status (16 live) TLS GZIP 2ms 533 bytes (pipelined: 1)
Apr 16, 2019 15:43:30.487 [7640] DEBUG - Completed: [192.168.86.150:33782] 200 GET /:/prefs (16 live) TLS GZIP 14ms 7484 bytes (pipelined: 2)
Apr 16, 2019 15:43:30.487 [10704] DEBUG - Request: [192.168.86.150:33782 (Subnet)] OPTIONS /updater/check?download=0 (16 live) TLS GZIP Signed-in Token ()
Apr 16, 2019 15:43:30.487 [7640] DEBUG - Completed: [192.168.86.150:33782] 200 OPTIONS /updater/check?download=0 (16 live) TLS GZIP 1ms 349 bytes (pipelined: 3)
Apr 16, 2019 15:43:30.518 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.518 [3468] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /activities (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.518 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /activities (16 live) TLS GZIP 2ms 429 bytes (pipelined: 2)
Apr 16, 2019 15:43:30.596 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:30.612 [10704] DEBUG - Request: [192.168.86.150:33786 (Subnet)] PUT /updater/check?download=0 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:30.612 [10704] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743, download: NO
Apr 16, 2019 15:43:30.612 [10704] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:30.612 [10704] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:30.815 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:43:30.815 [6348] DEBUG - HTTP requesting GET http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:43:30.815 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:43:30.831 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:43:30.831 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.150 (MIKE-PERSONAL: mike1825@gmail.com:)
Apr 16, 2019 15:43:31.034 [10704] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:43:31.034 [10704] INFO - AutoUpdate: no updates available
Apr 16, 2019 15:43:31.034 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 PUT /updater/check?download=0 (15 live) TLS GZIP 433ms 274 bytes (pipelined: 3)
Apr 16, 2019 15:43:31.362 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.362 [6348] DEBUG - HTTP requesting GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.378 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.378 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.378 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.27 (SHIELD)
Apr 16, 2019 15:43:31.409 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.409 [3468] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.424 [3468] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Apr 16, 2019 15:43:31.424 [3468] DEBUG - PlayQueue: No need to refresh, active window is operational.
Apr 16, 2019 15:43:31.424 [3468] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.424 [3468] DEBUG - PlayQueue: Client 01svskz8w8tvlqnxb32uwh55 requested ownership of play queue 602, but already had it.
Apr 16, 2019 15:43:31.424 [3468] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.424 [3468] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.424 [3468] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:31.424 [8280] DEBUG - Completed: [192.168.86.150:33786] 200 GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (15 live) TLS GZIP 9ms 5137 bytes (pipelined: 4)
Apr 16, 2019 15:43:31.440 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.440 [10704] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /library/sections (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.456 [8280] DEBUG - Completed: [192.168.86.150:33786] 200 GET /library/sections (15 live) TLS GZIP 2ms 1100 bytes (pipelined: 5)
Apr 16, 2019 15:43:31.456 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.456 [6384] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /media/providers (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.456 [8280] DEBUG - Completed: [192.168.86.150:33785] 200 GET /media/providers (15 live) TLS GZIP 3ms 3675 bytes (pipelined: 2)
Apr 16, 2019 15:43:31.518 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:43:31.518 [6348] DEBUG - HTTP requesting GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:43:31.549 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:43:31.549 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:43:31.549 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.53 (Guest Room)
Apr 16, 2019 15:43:31.596 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.596 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.596 [6476] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /photo/:/transcode?width=150&height=85&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fart%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.596 [10188] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.596 [6476] DEBUG - Photo transcoder: Request for url [/library/metadata/1626/art/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:43:31.596 [6476] DEBUG - The cache file did not exist, but the original did [C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\9f\9f878f6a0c50b562c64f5110b11ba7bab7b54824.jpg].
Apr 16, 2019 15:43:31.596 [10188] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3305 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8168, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3306 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8170, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3307 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8172, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3308 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8174, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3309 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8176, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Selecting best audio stream for part ID 3310 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Audio Stream: 8178, Subtitle Stream: -1
Apr 16, 2019 15:43:31.612 [10188] DEBUG - Activity: registered new activity 2d3f3e58-caee-4a7e-997c-73ea2e4033aa - Refreshing
Apr 16, 2019 15:43:31.612 [8272] DEBUG - Activity: updated activity 2d3f3e58-caee-4a7e-997c-73ea2e4033aa - completed 0% - Refreshing
Apr 16, 2019 15:43:31.612 [8272] DEBUG - Activity: Ended activity 2d3f3e58-caee-4a7e-997c-73ea2e4033aa.
Apr 16, 2019 15:43:31.612 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (15 live) TLS GZIP 14ms 5909 bytes (pipelined: 3)
Apr 16, 2019 15:43:31.634 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.635 [1520] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /music/iTunes (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.637 [8280] DEBUG - Completed: [192.168.86.150:33785] 404 GET /music/iTunes (15 live) TLS GZIP 2ms 458 bytes (pipelined: 4)
Apr 16, 2019 15:43:31.639 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.640 [3468] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.640 [3468] DEBUG - HubCache: Retrieving '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:43:31.640 [3468] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:43:31.640 [3468] DEBUG - HubCache: Retrieving '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:43:31.641 [3468] DEBUG - HubCache: Retrieving '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:43:31.641 [3468] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:31.641 [3468] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 0 ms.
Apr 16, 2019 15:43:31.641 [6476] DEBUG - Created thumbnail of size 151x85, has pixels: 1
Apr 16, 2019 15:43:31.641 [3468] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:31.643 [3468] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 1 ms.
Apr 16, 2019 15:43:31.643 [3468] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:43:31.644 [3468] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 1 ms.
Apr 16, 2019 15:43:31.649 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (15 live) TLS GZIP 9ms 5906 bytes (pipelined: 5)
Apr 16, 2019 15:43:31.649 [8280] DEBUG - Completed: [192.168.86.150:33786] 200 GET /photo/:/transcode?width=150&height=85&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fart%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (15 live) TLS GZIP 43ms 6450 bytes (pipelined: 6)
Apr 16, 2019 15:43:31.721 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.721 [6384] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=paused&hasMDE=1&time=0&duration=5435000 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.721 [6384] DEBUG - Client [7iv7l0lk357r6rsz79z7qsnq] reporting timeline state paused, progress of 0/5435000ms for guid=, ratingKey=1626 url=, key=/library/metadata/1626, containerKey=, metadataId=1626, source=
Apr 16, 2019 15:43:31.721 [6384] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.736 [6384] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:31.736 [6384] DEBUG - [Now] User is Mike1825 (ID: 1)
Apr 16, 2019 15:43:31.736 [6384] DEBUG - [Now] Device is Chrome (Chrome).
Apr 16, 2019 15:43:31.736 [6384] DEBUG - [Now] Profile is Web
Apr 16, 2019 15:43:31.736 [6384] DEBUG - [Now] Updated play state for /library/metadata/1626.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - HubCache: Expiring '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because of event plex.event.play.session.state.update.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - HubCache: Expiring '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because event plex.event.play.session.state.update referenced an item the hub contained
Apr 16, 2019 15:43:31.736 [6384] DEBUG - HubCache: Expiring '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because of event plex.event.play.session.state.update.
Apr 16, 2019 15:43:31.736 [6384] DEBUG - Statistics: (01svskz8w8tvlqnxb32uwh55) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Apr 16, 2019 15:43:31.736 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.736 [6204] DEBUG - Request: [127.0.0.1:50567 (Loopback)] GET /status/sessions (15 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.736 [6204] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:31.736 [7640] DEBUG - Completed: [127.0.0.1:50567] 200 GET /status/sessions (15 live) GZIP 1ms 397 bytes (pipelined: 3)
Apr 16, 2019 15:43:31.752 [8280] DEBUG - Completed: [192.168.86.150:33786] 200 GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=paused&hasMDE=1&time=0&duration=5435000 (15 live) TLS GZIP 21ms 500 bytes (pipelined: 7)
Apr 16, 2019 15:43:31.768 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.768 [6348] DEBUG - HTTP requesting GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.768 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.768 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:43:31.768 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.33 (Bar)
Apr 16, 2019 15:43:31.830 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1626&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=37r10woa7d837zpd9krlvsma&subtitles=burn&Accept-Language=en (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Found session GUID of 37r10woa7d837zpd9krlvsma in session start.
Apr 16, 2019 15:43:31.830 [10188] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1626?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=7iv7l0lk357r6rsz79z7qsnq
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.830 [10188] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.830 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.830 [10704] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /status/sessions (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.830 [10188] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.830 [10704] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:31.830 [10188] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:31.830 [8280] DEBUG - Completed: [192.168.86.150:33785] 200 GET /status/sessions (15 live) TLS GZIP 2ms 429 bytes (pipelined: 6)
Apr 16, 2019 15:43:31.846 [10188] DEBUG - Codecs: testing h264_qsv (encoder)
Apr 16, 2019 15:43:31.846 [10188] DEBUG - Codecs: hardware transcoding: testing API qsv
Apr 16, 2019 15:43:31.846 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.846 [1520] DEBUG - Request: [192.168.86.150:33789 (Subnet)] GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.846 [1520] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Apr 16, 2019 15:43:31.846 [1520] DEBUG - PlayQueue: No need to refresh, active window is operational.
Apr 16, 2019 15:43:31.846 [1520] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:31.846 [1520] DEBUG - PlayQueue: Client 01svskz8w8tvlqnxb32uwh55 requested ownership of play queue 602, but already had it.
Apr 16, 2019 15:43:31.846 [1520] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:31.846 [1520] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:31.846 [1520] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:31.846 [8280] DEBUG - Completed: [192.168.86.150:33789] 200 GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (16 live) TLS GZIP 8ms 5137 bytes (pipelined: 1)
Apr 16, 2019 15:43:31.986 [10188] ERROR - [FFMPEG] - Error initializing an MFX session: -3.
Apr 16, 2019 15:43:31.986 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:31.986 [11204] DEBUG - Request: [192.168.86.150:33789 (Subnet)] GET /hubs/home/recentlyAdded?type=1 (16 live) TLS Page 16-50 GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:31.986 [10188] DEBUG - Codecs: hardware transcoding: opening hw device failed - probably not supported by this system, error: Unknown error occurred
Apr 16, 2019 15:43:31.986 [10188] DEBUG - Codecs: testing h264_nvenc (encoder)
Apr 16, 2019 15:43:31.986 [11204] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Apr 16, 2019 15:43:31.986 [10188] ERROR - [FFMPEG] - Cannot load nvEncodeAPI.dll
Apr 16, 2019 15:43:31.986 [10188] ERROR - [FFMPEG] - The minimum required Nvidia driver for nvenc is 378.66 or newer
Apr 16, 2019 15:43:32.002 [10188] WARN - avcodec_open2 returned -1313558101 for encoder 'h264_nvenc'
Apr 16, 2019 15:43:32.002 [10188] DEBUG - Codecs: testing h264_mf (encoder)
Apr 16, 2019 15:43:32.002 [11204] DEBUG - Setting container serialization range to [16, 50] (total=134)
Apr 16, 2019 15:43:32.002 [7640] DEBUG - Completed: [192.168.86.150:33789] 200 GET /hubs/home/recentlyAdded?type=1 (16 live) TLS GZIP Page 16-50 15ms 14743 bytes (pipelined: 2)
Apr 16, 2019 15:43:32.143 [10188] INFO - [FFMPEG] - MFT name: 'Intel® Quick Sync Video H.264 Encoder MFT'
Apr 16, 2019 15:43:32.221 [10188] DEBUG - Codecs: hardware transcoding: testing API dxva2
Apr 16, 2019 15:43:32.252 [10188] DEBUG - Scaled up video bitrate to 61056Kbps based on 4.500000x fudge factor.
Apr 16, 2019 15:43:32.252 [10188] DEBUG - Scaled maximum bitrate for resolution reduction to 21444Kbps.
Apr 16, 2019 15:43:32.252 [10188] DEBUG - Codecs: testing aac_mf (encoder)
Apr 16, 2019 15:43:32.283 [10188] INFO - [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Apr 16, 2019 15:43:32.330 [10188] DEBUG - Codecs: testing eac3_mf (decoder)
Apr 16, 2019 15:43:32.393 [10188] INFO - [FFMPEG] - MFT name: 'Microsoft Dolby Digital Plus Decoder MFT'
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Selected protocol dash; container: mp4
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: analyzing media item 3303
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Holmes & Watson (2018): Direct Play is disabled
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Holmes & Watson (2018): media must be transcoded in order to use the dash protocol
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Holmes & Watson (2018): no direct play video profile exists for http/mkv/hevc
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Holmes & Watson (2018): no direct play video profile exists for http/mkv/hevc/eac3
Apr 16, 2019 15:43:32.424 [10188] DEBUG - Holmes & Watson - video.bitDepth limitation applies: 10 > 8
Apr 16, 2019 15:43:32.424 [10188] DEBUG - MDE: Holmes & Watson (2018): no remuxable profile found, so video stream will be transcoded
Apr 16, 2019 15:43:32.424 [10188] DEBUG - Codecs: testing h264_nvenc (encoder)
Apr 16, 2019 15:43:32.424 [10188] ERROR - [FFMPEG] - Cannot load nvEncodeAPI.dll
Apr 16, 2019 15:43:32.424 [10188] ERROR - [FFMPEG] - The minimum required Nvidia driver for nvenc is 378.66 or newer
Apr 16, 2019 15:43:32.424 [10188] WARN - avcodec_open2 returned -1313558101 for encoder 'h264_nvenc'
Apr 16, 2019 15:43:32.424 [10188] DEBUG - Codecs: testing h264_mf (encoder)
Apr 16, 2019 15:43:32.424 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:32.424 [3468] DEBUG - Request: [192.168.86.150:33789 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:32.424 [3468] DEBUG - [CompanionProxy] registered new player: 01svskz8w8tvlqnxb32uwh55 (192.168.86.150)
Apr 16, 2019 15:43:32.533 [10188] INFO - [FFMPEG] - MFT name: 'Intel® Quick Sync Video H.264 Encoder MFT'
Apr 16, 2019 15:43:32.582 [10188] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Apr 16, 2019 15:43:32.582 [10188] DEBUG - Codecs: hardware transcoding: testing API dxva2
Apr 16, 2019 15:43:32.613 [10188] DEBUG - Scaled up video bitrate to 61056Kbps based on 4.500000x fudge factor.
Apr 16, 2019 15:43:32.613 [10188] DEBUG - Scaled maximum bitrate for resolution reduction to 21444Kbps.
Apr 16, 2019 15:43:32.613 [10188] DEBUG - Codecs: testing aac_mf (encoder)
Apr 16, 2019 15:43:32.613 [10188] INFO - [FFMPEG] - MFT name: 'Microsoft AAC Audio Encoder MFT'
Apr 16, 2019 15:43:32.660 [10188] DEBUG - MDE: Cannot direct stream audio stream due to codec eac3 when profile only allows aac
Apr 16, 2019 15:43:32.660 [10188] DEBUG - MDE: Holmes & Watson (2018): selected media 0 / 3303
Apr 16, 2019 15:43:32.660 [10188] DEBUG - Streaming Resource: Adding session 0x5798ff0:7iv7l0lk357r6rsz79z7qsnq which is using transcoder slot.  Used slots is now 1
Apr 16, 2019 15:43:32.660 [10188] DEBUG - Streaming Resource: Added session 0x5798ff0:7iv7l0lk357r6rsz79z7qsnq
Apr 16, 2019 15:43:32.660 [10188] DEBUG - Streaming Resource: Reached Decision id=1626 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=3303 part=(id=3303 decision=transcode container=mp4 protocol=dash streams=(Video=(id=8179 decision=transcode bitrate=21444 encoder=h264_mf width=2276 height=1280) Audio=(id=8180 decision=transcode bitrate=256 encoder=aac_mf channels=2 rate=48000))))
Apr 16, 2019 15:43:32.675 [8280] DEBUG - Completed: [192.168.86.150:33786] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1626&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=37r10woa7d837zpd9krlvsma&subtitles=burn&Accept-Language=en (16 live) TLS GZIP 840ms 5095 bytes (pipelined: 8)
Apr 16, 2019 15:43:32.753 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1626&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=37r10woa7d837zpd9krlvsma&subtitles=burn&Accept-Language=en (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Found session GUID of 37r10woa7d837zpd9krlvsma in session start.
Apr 16, 2019 15:43:32.753 [10704] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1626?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=7iv7l0lk357r6rsz79z7qsnq
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:32.753 [10704] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:43:32.753 [10704] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Found session GUID of 37r10woa7d837zpd9krlvsma in session start.
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Cleaning directory for session 37r10woa7d837zpd9krlvsma ()
Apr 16, 2019 15:43:32.753 [10704] DEBUG - Starting a transcode session 37r10woa7d837zpd9krlvsma at offset -1.0 (state=3)
Apr 16, 2019 15:43:32.769 [10704] DEBUG - TPU: hardware transcoding: using hardware decode accelerator dxva2
Apr 16, 2019 15:43:32.769 [10704] DEBUG - [Universal] Using local file path instead of URL: D:\Movies\Holmes and Watson.mkv
Apr 16, 2019 15:43:32.769 [10704] DEBUG - TPU: hardware transcoding: zero-copy support not present
Apr 16, 2019 15:43:32.769 [10704] DEBUG - TPU: hardware transcoding: final decoder: dxva2, final encoder: mf
Apr 16, 2019 15:43:32.769 [10704] DEBUG - Job running: EAE_ROOT='\\?\C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='C\:\\Users\\mike1\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\a22632d-2172-windows-x86\\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe -codec:0 hevc -hwaccel:0 dxva2 -hwaccel_fallback_threshold:0 10 -codec:1 eac3_mf -analyzeduration 20000000 -probesize 20000000 -i "D:\Movies\Holmes and Watson.mkv" -filter_complex [0:0]scale=w=2276:h=1280[0];[0]format=pix_fmts=nv12[1] -filter_complex "[0:1] aresample=async=1:ocl='stereo':osr=48000[2]" -map [1] -codec:0 h264_mf -b:0 21444k -hw_encoding:0 1 -r:0 23.975999999999999 -force_key_frames:0 expr:gte(t,0+n_forced*3) -map [2] -metadata:s:1 language=eng -codec:1 aac_mf -b:1 256k -f dash -min_seg_duration 3000000 -skip_to_segment 1 -time_delta 0.0625 -manifest_name http://127.0.0.1:32400/video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/manifest -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 dash -start_at_zero -copyts -vsync cfr -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress 
Apr 16, 2019 15:43:32.769 [10704] DEBUG - Jobs: Starting child process with pid 2500
Apr 16, 2019 15:43:32.932 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:32.932 [6476] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /:/prefs (16 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:32.945 [8280] DEBUG - Completed: [192.168.86.150:33785] 200 GET /:/prefs (16 live) TLS GZIP 12ms 7484 bytes (pipelined: 7)
Apr 16, 2019 15:43:33.100 [1520] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=startup (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [8280] DEBUG - Completed: [127.0.0.1:50638] 204 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=startup (17 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [11204] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=opening (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [7640] DEBUG - Completed: [127.0.0.1:50638] 204 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=opening (17 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [6476] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=opened (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [7640] DEBUG - Completed: [127.0.0.1:50638] 204 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?status=opened (17 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [1520] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/stream?index=0&id=0&codec=hevc&type=video (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [8280] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/stream?index=0&id=0&codec=hevc&type=video (17 live) 0ms 256 bytes (pipelined: 4) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [11204] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/stream?index=1&id=0&codec=eac3&type=audio (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [7640] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/stream?index=1&id=0&codec=eac3&type=audio (17 live) 0ms 256 bytes (pipelined: 5) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [10188] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=0&id=0&codec=hevc&type=video&profile=Main%2010&width=3840&height=2160&interlaced=0&sar=1:1&level=153&frameRate=23.976&disp_default=1 (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [7640] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=0&id=0&codec=hevc&type=video&profile=Main%2010&width=3840&height=2160&interlaced=0&sar=1:1&level=153&frameRate=23.976&disp_default=1 (17 live) 0ms 256 bytes (pipelined: 6) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [3468] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=1&id=0&codec=eac3&type=audio&bitrate=576000&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [8280] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=1&id=0&codec=eac3&type=audio&bitrate=576000&language=eng&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (17 live) 0ms 256 bytes (pipelined: 7) (range: bytes=0-) 
Apr 16, 2019 15:43:33.100 [1520] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.100 [7640] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=2&id=0&codec=subrip&type=subtitle&language=eng (17 live) 0ms 256 bytes (pipelined: 8) (range: bytes=0-) 
Apr 16, 2019 15:43:33.116 [11204] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=3&id=0&codec=subrip&type=subtitle&language=eng (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.116 [8280] DEBUG - Completed: [127.0.0.1:50638] 206 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress/streamDetail?index=3&id=0&codec=subrip&type=subtitle&language=eng (17 live) 0ms 256 bytes (pipelined: 9) (range: bytes=0-) 
Apr 16, 2019 15:43:33.116 [6476] DEBUG - Request: [127.0.0.1:50638 (Loopback)] PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?duration=5435.104000 (17 live) Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.116 [10704] DEBUG - Started session successfully: 37r10woa7d837zpd9krlvsma
Apr 16, 2019 15:43:33.116 [8280] DEBUG - Completed: [127.0.0.1:50638] 204 PUT /video/:/transcode/session/37r10woa7d837zpd9krlvsma/3222e1b9-2c2d-4f5c-bf5c-5c34392b6866/progress?duration=5435.104000 (17 live) 0ms 203 bytes (pipelined: 10) (range: bytes=0-) 
Apr 16, 2019 15:43:33.116 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1626&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=37r10woa7d837zpd9krlvsma&subtitles=burn&Accept-Language=en (17 live) TLS GZIP 364ms 1031 bytes (pipelined: 9)
Apr 16, 2019 15:43:33.116 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:33.116 [6204] DEBUG - Request: [127.0.0.1:50567 (Loopback)] GET /status/sessions (17 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.116 [6204] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:33.116 [8280] DEBUG - Completed: [127.0.0.1:50567] 200 GET /status/sessions (17 live) GZIP 0ms 397 bytes (pipelined: 4)
Apr 16, 2019 15:43:33.116 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:33.116 [6384] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /status/sessions (17 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:33.116 [6384] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:33.116 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /status/sessions (17 live) TLS GZIP 2ms 429 bytes (pipelined: 10)
Apr 16, 2019 15:43:33.131 [3468] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/initial.mp4 (17 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:33.131 [10188] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/0.m4s (17 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:33.131 [10188] DEBUG - Asked for segment 0 from session.
Apr 16, 2019 15:43:33.131 [6204] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/0.m4s (19 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:33.131 [1520] DEBUG - Request: [192.168.86.150:33793 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/initial.mp4 (19 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:33.131 [6204] DEBUG - Asked for segment 0 from session.
Apr 16, 2019 15:43:33.210 [6348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:43:33.210 [6348] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:43:33.225 [6476] ERROR - [Transcoder] [AVHWFramesContext @ 07f47580] Could not create the surfaces
Apr 16, 2019 15:43:33.225 [6384] ERROR - [Transcoder] [AVHWFramesContext @ 07f47580] Error creating an internal frame pool
Apr 16, 2019 15:43:33.225 [6476] ERROR - [Transcoder] [hevc @ 026ae200] Failed setup for format dxva2_vld: hwaccel initialisation returned error.
Apr 16, 2019 15:43:33.256 [6348] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:43:33.256 [6348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:43:33.256 [6348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.49.1 (Mike's 2nd Fire TV)
Apr 16, 2019 15:43:33.742 [10704] DEBUG - Transcoder: session 37r10woa7d837zpd9krlvsma indicated fallback to software decoding
Apr 16, 2019 15:43:35.128 [11204] DEBUG - Transcoder segment range: 0 - 0 (0)
Apr 16, 2019 15:43:35.237 [3468] DEBUG - Exists("C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s") => 1
Apr 16, 2019 15:43:35.237 [3468] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s is 737.
Apr 16, 2019 15:43:35.237 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/initial.mp4 (20 live) TLS GZIP 2099ms 737 bytes (pipelined: 11)
Apr 16, 2019 15:43:35.237 [1520] DEBUG - Exists("C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s") => 1
Apr 16, 2019 15:43:35.237 [1520] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s is 818.
Apr 16, 2019 15:43:35.237 [6204] DEBUG - Returning segment 0 from session
Apr 16, 2019 15:43:35.237 [6204] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00001.m4s is 5372203.
Apr 16, 2019 15:43:35.237 [10436] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 0kbps over 0.00s
Apr 16, 2019 15:43:35.237 [7640] DEBUG - Completed: [192.168.86.150:33793] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/initial.mp4 (20 live) TLS GZIP 2098ms 818 bytes (pipelined: 1)
Apr 16, 2019 15:43:35.237 [10188] DEBUG - Returning segment 0 from session
Apr 16, 2019 15:43:35.237 [10416] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 0kbps over 0.00s
Apr 16, 2019 15:43:35.237 [10188] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00001.m4s is 45555.
Apr 16, 2019 15:43:35.237 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/0.m4s (20 live) TLS GZIP 2103ms 45555 bytes (pipelined: 8)
Apr 16, 2019 15:43:35.268 [6384] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/1.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:35.268 [6384] DEBUG - Asked for segment 1 from session.
Apr 16, 2019 15:43:35.284 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/0.m4s (20 live) TLS GZIP 2148ms 5372203 bytes (pipelined: 1)
Apr 16, 2019 15:43:35.331 [6476] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/1.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:35.331 [6476] DEBUG - Asked for segment 1 from session.
Apr 16, 2019 15:43:36.612 [11204] DEBUG - Transcoder segment range: 0 - 1 (1)
Apr 16, 2019 15:43:36.674 [6476] DEBUG - Returning segment 1 from session
Apr 16, 2019 15:43:36.674 [11080] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 908984kbps over 1.43s
Apr 16, 2019 15:43:36.674 [6476] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00002.m4s is 8052918.
Apr 16, 2019 15:43:36.721 [6384] DEBUG - Returning segment 1 from session
Apr 16, 2019 15:43:36.721 [9408] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 43200kbps over 1.48s
Apr 16, 2019 15:43:36.721 [5508] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 43535kbps over 1.48s
Apr 16, 2019 15:43:36.721 [6384] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00002.m4s is 45054.
Apr 16, 2019 15:43:36.721 [8280] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/1.m4s (20 live) TLS GZIP 1448ms 45054 bytes (pipelined: 9)
Apr 16, 2019 15:43:36.737 [3468] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/2.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:36.737 [3468] DEBUG - Asked for segment 2 from session.
Apr 16, 2019 15:43:36.753 [8280] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/1.m4s (20 live) TLS GZIP 1413ms 8052918 bytes (pipelined: 2)
Apr 16, 2019 15:43:36.815 [10704] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/2.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:36.815 [10704] DEBUG - Asked for segment 2 from session.
Apr 16, 2019 15:43:38.174 [1520] DEBUG - Transcoder segment range: 0 - 2 (2)
Apr 16, 2019 15:43:38.268 [10704] DEBUG - Returning segment 2 from session
Apr 16, 2019 15:43:38.268 [2348] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 73109kbps over 3.02s
Apr 16, 2019 15:43:38.268 [10704] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00003.m4s is 8052920.
Apr 16, 2019 15:43:38.284 [3468] DEBUG - Returning segment 2 from session
Apr 16, 2019 15:43:38.284 [3468] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00003.m4s is 67189.
Apr 16, 2019 15:43:38.284 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/2.m4s (21 live) TLS GZIP 1474ms 8052920 bytes (pipelined: 3)
Apr 16, 2019 15:43:38.315 [1416] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 57555kbps over 3.08s
Apr 16, 2019 15:43:38.331 [8280] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/2.m4s (21 live) TLS GZIP 1593ms 67189 bytes (pipelined: 10)
Apr 16, 2019 15:43:38.456 [6476] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/3.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:38.456 [6476] DEBUG - Asked for segment 3 from session.
Apr 16, 2019 15:43:38.471 [6384] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/3.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:38.471 [6384] DEBUG - Asked for segment 3 from session.
Apr 16, 2019 15:43:38.737 [1416] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 57034kbps over 3.50s
Apr 16, 2019 15:43:39.721 [11204] DEBUG - Transcoder segment range: 0 - 3 (3)
Apr 16, 2019 15:43:39.815 [6384] DEBUG - Returning segment 3 from session
Apr 16, 2019 15:43:39.815 [10432] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 57034kbps over 4.57s
Apr 16, 2019 15:43:39.815 [6384] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00004.m4s is 8052918.
Apr 16, 2019 15:43:39.815 [6476] DEBUG - Returning segment 3 from session
Apr 16, 2019 15:43:39.815 [1556] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 39120kbps over 4.57s
Apr 16, 2019 15:43:39.815 [6476] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00004.m4s is 70125.
Apr 16, 2019 15:43:39.815 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/3.m4s (21 live) TLS GZIP 1355ms 70125 bytes (pipelined: 11)
Apr 16, 2019 15:43:39.830 [1520] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/4.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:39.830 [1520] DEBUG - Asked for segment 4 from session.
Apr 16, 2019 15:43:39.830 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/3.m4s (20 live) TLS GZIP 1363ms 8052918 bytes (pipelined: 4)
Apr 16, 2019 15:43:39.924 [3468] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/4.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:39.924 [3468] DEBUG - Asked for segment 4 from session.
Apr 16, 2019 15:43:40.768 [6944] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 52766kbps over 5.53s
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:40.815 [10188] DEBUG - Request: [192.168.86.150:33793 (Subnet)] GET /:/prefs (20 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:40.815 [6476] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /accounts/1 (21 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:40.815 [11204] DEBUG - Request: [192.168.86.150:33802 (Subnet)] GET /myplex/account (20 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /accounts/1 (20 live) TLS GZIP 2ms 600 bytes (pipelined: 12)
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Completed: [192.168.86.150:33802] 200 GET /myplex/account (20 live) TLS GZIP 1ms 2327 bytes (pipelined: 1)
Apr 16, 2019 15:43:40.815 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:40.815 [6384] DEBUG - Request: [192.168.86.150:33786 (Subnet)] GET /system/:/prefs (20 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:40.815 [6384] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Apr 16, 2019 15:43:40.815 [6384] DEBUG - HTTP requesting GET http://127.0.0.1:50453/system/:/prefs
Apr 16, 2019 15:43:40.893 [6384] DEBUG - HTTP 200 response from GET http://127.0.0.1:50453/system/:/prefs
Apr 16, 2019 15:43:40.893 [6384] DEBUG - [com.plexapp.system] HTTP reply status 200, with 418 bytes of content.
Apr 16, 2019 15:43:40.893 [7640] DEBUG - Completed: [192.168.86.150:33786] 200 GET /system/:/prefs (20 live) TLS GZIP 73ms 863 bytes (pipelined: 13)
Apr 16, 2019 15:43:40.893 [7640] DEBUG - Completed: [192.168.86.150:33793] 200 GET /:/prefs (20 live) TLS GZIP 82ms 7484 bytes (pipelined: 2)
Apr 16, 2019 15:43:41.705 [6476] DEBUG - Transcoder segment range: 0 - 4 (4)
Apr 16, 2019 15:43:41.783 [3468] DEBUG - Returning segment 4 from session
Apr 16, 2019 15:43:41.783 [1528] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 52766kbps over 6.55s
Apr 16, 2019 15:43:41.783 [3468] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00005.m4s is 8932444.
Apr 16, 2019 15:43:41.783 [1520] DEBUG - Returning segment 4 from session
Apr 16, 2019 15:43:41.783 [10408] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 37400kbps over 6.55s
Apr 16, 2019 15:43:41.783 [1520] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00005.m4s is 74565.
Apr 16, 2019 15:43:41.783 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/4.m4s (21 live) TLS GZIP 1961ms 74565 bytes (pipelined: 12)
Apr 16, 2019 15:43:41.815 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/4.m4s (21 live) TLS GZIP 1882ms 8932444 bytes (pipelined: 5)
Apr 16, 2019 15:43:41.815 [11204] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/5.m4s (21 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:41.815 [11204] DEBUG - Asked for segment 5 from session.
Apr 16, 2019 15:43:41.971 [10704] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/5.m4s (21 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:41.971 [10704] DEBUG - Asked for segment 5 from session.
Apr 16, 2019 15:43:42.768 [9512] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 47993kbps over 7.53s
Apr 16, 2019 15:43:43.753 [6476] DEBUG - Transcoder segment range: 0 - 5 (5)
Apr 16, 2019 15:43:43.862 [11204] DEBUG - Returning segment 5 from session
Apr 16, 2019 15:43:43.862 [10408] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 47993kbps over 8.62s
Apr 16, 2019 15:43:43.862 [11204] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00006.m4s is 74846.
Apr 16, 2019 15:43:43.862 [10704] DEBUG - Returning segment 5 from session
Apr 16, 2019 15:43:43.862 [5508] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 36941kbps over 8.63s
Apr 16, 2019 15:43:43.862 [10704] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00006.m4s is 7748710.
Apr 16, 2019 15:43:43.878 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/5.m4s (20 live) TLS GZIP 1915ms 7748710 bytes (pipelined: 6)
Apr 16, 2019 15:43:43.940 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/5.m4s (20 live) TLS GZIP 2116ms 74846 bytes (pipelined: 13)
Apr 16, 2019 15:43:44.003 [10188] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/6.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:44.003 [10188] DEBUG - Asked for segment 6 from session.
Apr 16, 2019 15:43:44.018 [6384] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/6.m4s (20 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:44.018 [6384] DEBUG - Asked for segment 6 from session.
Apr 16, 2019 15:43:44.706 [8280] DEBUG - Completed: [127.0.0.1:50547] -2 GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (20 live) GZIP 20049ms 5 bytes (pipelined: 14)
Apr 16, 2019 15:43:44.706 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:44.706 [3468] DEBUG - Request: [127.0.0.1:50547 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (16 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:44.706 [3468] WARN - [CompanionPlayer] We already have a handler, overwriting.
Apr 16, 2019 15:43:44.815 [10416] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 43942kbps over 9.58s
Apr 16, 2019 15:43:45.252 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:45.252 [6476] DEBUG - Request: [192.168.86.150:33793 (Subnet)] GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=paused&hasMDE=1&time=0&duration=5435000 (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:45.252 [6476] DEBUG - Client [7iv7l0lk357r6rsz79z7qsnq] reporting timeline state paused, progress of 0/5435000ms for guid=, ratingKey=1626 url=, key=/library/metadata/1626, containerKey=, metadataId=1626, source=
Apr 16, 2019 15:43:45.252 [6476] DEBUG - [Now] User is Mike1825 (ID: 1)
Apr 16, 2019 15:43:45.252 [6476] DEBUG - [Now] Device is Chrome (Chrome).
Apr 16, 2019 15:43:45.252 [6476] DEBUG - [Now] Profile is Web
Apr 16, 2019 15:43:45.252 [6476] DEBUG - [Now] Updated play state for /library/metadata/1626.
Apr 16, 2019 15:43:45.252 [6476] DEBUG - Statistics: (01svskz8w8tvlqnxb32uwh55) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Apr 16, 2019 15:43:45.252 [1564] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 43942kbps over 10.02s
Apr 16, 2019 15:43:45.252 [7640] DEBUG - Completed: [192.168.86.150:33793] 200 GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=paused&hasMDE=1&time=0&duration=5435000 (15 live) TLS GZIP 4ms 851 bytes (pipelined: 3)
Apr 16, 2019 15:43:45.268 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:45.268 [11204] DEBUG - Request: [192.168.86.150:33793 (Subnet)] GET /status/sessions (15 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:45.268 [11204] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:45.268 [8280] DEBUG - Completed: [192.168.86.150:33793] 200 GET /status/sessions (15 live) TLS GZIP 2ms 5470 bytes (pipelined: 4)
Apr 16, 2019 15:43:45.315 [8280] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:45.315 [3468] DEBUG - Request: [127.0.0.1:50567 (Loopback)] GET /status/sessions (15 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:45.315 [3468] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:43:45.315 [7640] DEBUG - Completed: [127.0.0.1:50567] 200 GET /status/sessions (15 live) GZIP 2ms 5341 bytes (pipelined: 5)
Apr 16, 2019 15:43:46.390 [6204] DEBUG - Transcoder segment range: 0 - 6 (6)
Apr 16, 2019 15:43:46.437 [10188] DEBUG - Returning segment 6 from session
Apr 16, 2019 15:43:46.437 [1528] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 43942kbps over 11.20s
Apr 16, 2019 15:43:46.437 [10188] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream1.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream1-00007.m4s is 75135.
Apr 16, 2019 15:43:46.437 [6384] DEBUG - Returning segment 6 from session
Apr 16, 2019 15:43:46.437 [10428] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 34157kbps over 11.21s
Apr 16, 2019 15:43:46.437 [6384] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\init-stream0.m4s,C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-37r10woa7d837zpd9krlvsma-3222e1b9-2c2d-4f5c-bf5c-5c34392b6866\chunk-stream0-00007.m4s is 8565285.
Apr 16, 2019 15:43:46.468 [7640] DEBUG - Completed: [192.168.86.150:33794] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/6.m4s (15 live) TLS GZIP 2442ms 8565285 bytes (pipelined: 7)
Apr 16, 2019 15:43:46.468 [7640] DEBUG - Completed: [192.168.86.150:33785] 200 GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/6.m4s (15 live) TLS GZIP 2457ms 75135 bytes (pipelined: 14)
Apr 16, 2019 15:43:46.609 [11204] DEBUG - Request: [192.168.86.150:33785 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/1/7.m4s (15 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:46.609 [11204] DEBUG - Asked for segment 7 from session.
Apr 16, 2019 15:43:46.624 [6476] DEBUG - Request: [192.168.86.150:33794 (Subnet)] GET /video/:/transcode/universal/dash/37r10woa7d837zpd9krlvsma/0/7.m4s (15 live) TLS GZIP Signed-in
Apr 16, 2019 15:43:46.624 [6476] DEBUG - Asked for segment 7 from session.
Apr 16, 2019 15:43:46.828 [10436] DEBUG - Streaming Resource: Estimated bandwidth for 7iv7l0lk357r6rsz79z7qsnq to be 41335kbps over 10.36s
Apr 16, 2019 15:43:46.828 [7640] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:43:46.828 [10704] DEBUG - Request: [192.168.86.150:33809 (Subnet)] GET /diagnostics/logs (17 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:43:46.828 [10704] DEBUG - Diagnostics: Building logfile zip
