Sep 01, 2018 13:37:54.372 [14456] INFO - Plex Media Server v1.13.6.5339-115f087d6 - Microsoft PC x64 - build: windows-i386 english - GMT -04:00
Sep 01, 2018 13:37:54.373 [14456] INFO - Windows version: 10.0 (Build 17134), language en-US
Sep 01, 2018 13:37:54.373 [14456] INFO - 16 2994 MHz processor(s): Architecture=0, Level=23, Revision=257 Processor Identifier=AMD64 Family 23 Model 1 Stepping 1, AuthenticAMD
Sep 01, 2018 13:37:54.373 [14456] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
Sep 01, 2018 13:37:54.372 [21448] DEBUG - Plex for Windows Store application is not installed
Sep 01, 2018 13:37:54.373 [21448] DEBUG - BPQ: [Idle] -> [Starting]
Sep 01, 2018 13:37:54.379 [21448] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Sep 01, 2018 13:37:54.436 [21448] DEBUG - Running migrations. (EPG 0)
Sep 01, 2018 13:37:54.447 [21448] DEBUG - ChangestampAllocator: initialized to 6983
Sep 01, 2018 13:37:54.448 [21448] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.13.0, threadsafe=1
Sep 01, 2018 13:37:54.454 [21448] DEBUG - Running migrations. (EPG 0)
Sep 01, 2018 13:37:55.514 [21448] DEBUG - CERT: Installed certificate with fingerprint 71:85:91:c9:00:ad:f3:c6:bd:4a:72:14:07:80:59:d8:4c:f1:93:63.
Sep 01, 2018 13:37:55.514 [21448] DEBUG - CERT: Installed new private key.
Sep 01, 2018 13:37:55.514 [21448] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.80d652440c044bd88a6d74c00626aeb4.plex.direct
Sep 01, 2018 13:37:55.514 [21448] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Sep 01, 2018 13:37:55.515 [21448] INFO - OCSP: Successfully retrieved response from cache.
Sep 01, 2018 13:37:55.515 [21448] DEBUG - CERT: Installed intermediate certificate.
Sep 01, 2018 13:37:55.515 [21448] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Sep 01, 2018 13:37:55.520 [21448] DEBUG - NetworkInterface: ignoring adapter 'Local Area Connection* 13' (fe80::4c22:bd0c:259:cb8a%23,169.254.203.138) because it has no gateway
Sep 01, 2018 13:37:55.520 [21448] DEBUG - NetworkInterface: ignoring adapter 'Local Area Connection* 14' (fe80::b443:459a:cb91:5a1b%6,169.254.90.27) because it has no gateway
Sep 01, 2018 13:37:55.522 [21448] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Sep 01, 2018 13:37:55.523 [21448] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Sep 01, 2018 13:37:55.528 [21448] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Sep 01, 2018 13:37:55.535 [21448] INFO - Killing PlexScriptHost.exe process with pid 13748
Sep 01, 2018 13:37:55.549 [21448] INFO - Killing PlexTranscoder.exe process with pid 19256
Sep 01, 2018 13:37:55.591 [10144] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Sep 01, 2018 13:37:55.591 [21448] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Sep 01, 2018 13:37:55.591 [21448] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 01, 2018 13:37:55.591 [0484] DEBUG - Relay: read 0 cached entries from hosts file
Sep 01, 2018 13:37:55.592 [21448] DEBUG - Relay: read 0 cached entries from hosts file
Sep 01, 2018 13:37:55.592 [10144] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Sep 01, 2018 13:37:55.592 [0484] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:55.592 [21448] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Sep 01, 2018 13:37:55.593 [21448] DEBUG - MyPlex: updating with 8 access tokens
Sep 01, 2018 13:37:55.593 [8400] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 01, 2018 13:37:55.594 [8400] DEBUG - HTTP requesting GET https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Sep 01, 2018 13:37:55.594 [17412] DEBUG - File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6" changed: -1 => 1535819811.
Sep 01, 2018 13:37:55.594 [17412] DEBUG - File "C:\Users\Ross Klos\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 1535819851.
Sep 01, 2018 13:37:55.594 [17412] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6"
Sep 01, 2018 13:37:55.602 [17412] DEBUG - Scanning for plug-ins in "C:\Users\Ross Klos\AppData\Local\Plex Media Server\Plug-ins"
Sep 01, 2018 13:37:55.602 [17412] DEBUG - PluginRepository::setStartState: 1, startingSystem
Sep 01, 2018 13:37:55.602 [17412] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\System.bundle.
Sep 01, 2018 13:37:55.603 [17412] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Sep 01, 2018 13:37:55.605 [17412] DEBUG - Starting file watcher for com.plexapp.system
Sep 01, 2018 13:37:55.606 [17412] 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-115f087d6\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\System.bundle"
Sep 01, 2018 13:37:55.609 [17412] DEBUG - Spawned plug-in com.plexapp.system with PID 10512
Sep 01, 2018 13:37:55.609 [17412] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Sep 01, 2018 13:37:56.177 [0484] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:56.198 [8400] DEBUG - HTTP 200 response from GET https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Sep 01, 2018 13:37:56.201 [8400] DEBUG - MyPlex: updating with 8 access tokens
Sep 01, 2018 13:37:56.201 [10144] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:56.371 [8400] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\Ross Klos\AppData\Local\Plex Media Server\Crash Reports\1.13.6.5339-115f087d6" --version=1.13.6.5339-115f087d6 "--platform=windows-i386 (10.0 (Build 17134))" --serverUuid=0a8da23742e20e2b3169643a2e9cdd2448c34681 --userId=klosterman.53@gmail.com --url=https://crashreport.plexapp.com 
Sep 01, 2018 13:37:56.374 [8400] DEBUG - Jobs: Starting child process with pid 12336
Sep 01, 2018 13:37:56.728 [10144] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:56.728 [10144] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 12336 is 0 (success)
Sep 01, 2018 13:37:57.741 [17412] DEBUG - Reading 486 bytes in the body, code is 200
Sep 01, 2018 13:37:57.741 [17412] DEBUG - [com.plexapp.system] Plug-in running on port 52781.
Sep 01, 2018 13:37:57.741 [17412] DEBUG -  * Plug-in handles prefix: /system
Sep 01, 2018 13:37:57.741 [17412] DEBUG -  * Plug-in handles prefix: /player
Sep 01, 2018 13:37:57.741 [17412] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Sep 01, 2018 13:37:57.743 [17412] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Sep 01, 2018 13:37:57.744 [17412] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Sep 01, 2018 13:37:57.744 [17412] DEBUG - Updating the list of agents known by the system.
Sep 01, 2018 13:37:57.744 [17412] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Sep 01, 2018 13:37:57.744 [17412] DEBUG - HTTP requesting GET http://127.0.0.1:52781/system/agents
Sep 01, 2018 13:37:57.744 [21448] DEBUG - Image transcode cache directory: "C:\Users\Ross Klos\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Sep 01, 2018 13:37:57.744 [21448] DEBUG - Transcoder: Cleaning old transcode directories.
Sep 01, 2018 13:37:57.746 [21448] DEBUG - Transcoder: Cleaning old transcode directory: "C:\Users\Ross Klos\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-hh6r4l6f00gzvof57d44er96-7b139204-94bb-4477-8125-c6e9874b8af3"
Sep 01, 2018 13:37:57.747 [21448] ERROR - Transcoder: Failed to delete session directory (boost::filesystem::remove: The process cannot access the file because it is being used by another process: "C:\Users\Ross Klos\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-hh6r4l6f00gzvof57d44er96-7b139204-94bb-4477-8125-c6e9874b8af3")
Sep 01, 2018 13:37:57.747 [21448] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Sep 01, 2018 13:37:57.748 [21448] DEBUG - MyPlex: updating with 8 access tokens
Sep 01, 2018 13:37:57.748 [0484] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:57.748 [21448] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Sep 01, 2018 13:37:57.749 [21448] DEBUG - MyPlex: username is Klospin, login is klosterman.53@gmail.com, home is 0, no pin
Sep 01, 2018 13:37:57.750 [21448] DEBUG - PublicAddressManager: Starting.
Sep 01, 2018 13:37:57.750 [21448] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 01, 2018 13:37:57.750 [21448] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 01, 2018 13:37:57.750 [17788] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 01, 2018 13:37:57.750 [21520] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Sep 01, 2018 13:37:57.750 [17788] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Sep 01, 2018 13:37:57.756 [21448] DEBUG - Network interfaces:
Sep 01, 2018 13:37:57.756 [21448] DEBUG -  * 24 Wi-Fi 2 {F9B97710-CAF7-400D-9032-F954A1709A9B} (192.168.86.248) (loopback: 0)
Sep 01, 2018 13:37:57.757 [21448] DEBUG - Creating NetworkServices singleton.
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkServices: Initializing...
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.757 [19348] DEBUG - Network change for advertiser.
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.757 [19348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Sep 01, 2018 13:37:57.757 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.758 [19348] DEBUG - Network change for advertiser.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.758 [19348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Creating new service.
Sep 01, 2018 13:37:57.758 [21448] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 01, 2018 13:37:57.759 [21448] DEBUG - NetworkService: Quick dispatch of network change.
Sep 01, 2018 13:37:57.759 [19348] DEBUG - Network change for advertiser.
Sep 01, 2018 13:37:57.759 [19348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Sep 01, 2018 13:37:57.759 [10144] DEBUG - NAT: UPnP, attempting port mapping.
Sep 01, 2018 13:37:57.759 [19348] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 01, 2018 13:37:57.759 [19348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Browsing on interface 192.168.86.248 on broadcast address 192.168.86.255 (index: 0)
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Browsing on interface 192.168.86.248 on broadcast address 192.168.86.255 (index: 0)
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Sep 01, 2018 13:37:57.760 [19348] DEBUG - NetworkService: Browsing on interface 192.168.86.248 on broadcast address 239.255.255.250 (index: 0)
Sep 01, 2018 13:37:57.769 [19348] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.86.248 (Home)
Sep 01, 2018 13:37:57.769 [19348] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.86.248
Sep 01, 2018 13:37:57.769 [19348] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.86.248
Sep 01, 2018 13:37:57.769 [19348] DEBUG - HTTP requesting GET http://127.0.0.1:52781/system/notify/serverUpdated?host=192.168.86.248
Sep 01, 2018 13:37:57.852 [21448] DEBUG - Gracenote Version 3.07.7.3686 (build: 2015-07-05 11:53-0700)
Sep 01, 2018 13:37:57.853 [21448] DEBUG - Gracenote: Using SQLite 3.13.0
Sep 01, 2018 13:37:57.854 [17816] DEBUG - Plex Dlna Server.exe starting.
Sep 01, 2018 13:37:57.854 [17816] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Dlna Server.exe 
Sep 01, 2018 13:37:57.854 [21448] DEBUG - Starting HTTP server.
Sep 01, 2018 13:37:57.854 [21448] DEBUG - Running server...
Sep 01, 2018 13:37:57.854 [21448] DEBUG - Waiting for server condition to be signaled.
Sep 01, 2018 13:37:57.854 [18488] DEBUG - Plex Tuner Service.exe starting.
Sep 01, 2018 13:37:57.854 [13640] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Sep 01, 2018 13:37:57.855 [13640] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:57.857 [17816] DEBUG - Jobs: Starting child process with pid 15044
Sep 01, 2018 13:37:57.858 [18488] 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.13.6.5339-115f087d6 32600 /waitmutex 
Sep 01, 2018 13:37:57.859 [17412] DEBUG - HTTP 200 response from GET http://127.0.0.1:52781/system/agents
Sep 01, 2018 13:37:57.859 [17412] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Sep 01, 2018 13:37:57.861 [18488] DEBUG - Jobs: Starting child process with pid 14044
Sep 01, 2018 13:37:57.863 [17412] DEBUG - The system knows about 13 agents.
Sep 01, 2018 13:37:57.863 [23496] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\Fanart-TV.bundle.
Sep 01, 2018 13:37:57.863 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\Framework.bundle.
Sep 01, 2018 13:37:57.863 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\HTbackdrops.bundle.
Sep 01, 2018 13:37:57.863 [17412] DEBUG - Waiting for 18 plug-ins to finish starting.
Sep 01, 2018 13:37:57.865 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\LastFM.bundle.
Sep 01, 2018 13:37:57.865 [23496] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Sep 01, 2018 13:37:57.865 [23496] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\LocalMedia.bundle.
Sep 01, 2018 13:37:57.865 [4628] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Sep 01, 2018 13:37:57.865 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\LyricFind.bundle.
Sep 01, 2018 13:37:57.866 [10040] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Sep 01, 2018 13:37:57.866 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\Media-Flags.bundle.
Sep 01, 2018 13:37:57.866 [4628] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Sep 01, 2018 13:37:57.866 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\MoviePosterDB.bundle.
Sep 01, 2018 13:37:57.867 [23496] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Sep 01, 2018 13:37:57.867 [23496] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\OpenSubtitles.bundle.
Sep 01, 2018 13:37:57.867 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\PersonalMedia.bundle.
Sep 01, 2018 13:37:57.868 [4628] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Sep 01, 2018 13:37:57.868 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\PlexMovie.bundle.
Sep 01, 2018 13:37:57.869 [23496] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Sep 01, 2018 13:37:57.868 [10040] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Sep 01, 2018 13:37:57.869 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\PlexThemeMusic.bundle.
Sep 01, 2018 13:37:57.869 [23496] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\PlexMusic.bundle.
Sep 01, 2018 13:37:57.870 [4628] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Sep 01, 2018 13:37:57.870 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\Scanners.bundle.
Sep 01, 2018 13:37:57.870 [10040] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Sep 01, 2018 13:37:57.870 [10040] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\TheMovieDB.bundle.
Sep 01, 2018 13:37:57.871 [23496] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Sep 01, 2018 13:37:57.871 [23496] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\TheTVDB.bundle.
Sep 01, 2018 13:37:57.871 [4628] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\WebClient.bundle.
Sep 01, 2018 13:37:57.872 [10040] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Sep 01, 2018 13:37:57.872 [10040] DEBUG - Starting plug-in C:\Users\Ross Klos\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Sep 01, 2018 13:37:57.873 [23496] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Sep 01, 2018 13:37:57.874 [17412] DEBUG - Starting scheduled updates, every 3600 seconds
Sep 01, 2018 13:37:57.874 [17412] INFO - Refreshing paths watched by LibraryUpdateManager
Sep 01, 2018 13:37:57.874 [17412] DEBUG - LibraryUpdateManager will start watching G:\Movies Home
Sep 01, 2018 13:37:57.874 [16548] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Sep 01, 2018 13:37:57.874 [17412] DEBUG - LibraryUpdateManager will start watching C:\Users\Ross Klos\Desktop\Test
Sep 01, 2018 13:37:57.875 [17412] DEBUG - LibraryUpdateManager will start watching G:\Movies Home
Sep 01, 2018 13:37:57.875 [17412] DEBUG - Done waiting for all plug-ins to start.
Sep 01, 2018 13:37:57.875 [17412] DEBUG - PluginRepository::setStartState: 3, complete
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale ar
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale cs_CZ
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale da
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale de
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale en
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale es
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale et_EE
Sep 01, 2018 13:37:57.876 [16548] DEBUG - Translator: Found locale fr
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale he
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale hr_HR
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale hu_HU
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale it
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale ja_JP
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale ko
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale lt
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale nl
Sep 01, 2018 13:37:57.877 [16548] DEBUG - Translator: Found locale nl_BE
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale no
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale pt_BR
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale ro
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale ru
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale sr
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale sv
Sep 01, 2018 13:37:57.878 [16548] DEBUG - Translator: Found locale zh_CN
Sep 01, 2018 13:37:57.892 [16548] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Sep 01, 2018 13:37:57.893 [16548] 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-115f087d6\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-115f087d6\PlexMovie.bundle"
Sep 01, 2018 13:37:57.896 [16548] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 14928
Sep 01, 2018 13:37:57.896 [16548] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Sep 01, 2018 13:37:57.997 [17788] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Sep 01, 2018 13:37:57.997 [17788] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 65.96.122.105
Sep 01, 2018 13:37:58.289 [0484] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:58.396 [21520] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Sep 01, 2018 13:37:58.400 [21520] DEBUG - MyPlex: username is Klospin, login is klosterman.53@gmail.com, home is 0, no pin
Sep 01, 2018 13:37:58.401 [0484] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Sep 01, 2018 13:37:58.401 [21520] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:58.403 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:58.407 [13640] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:58.969 [21520] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:58.973 [21520] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.014 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.017 [13640] DEBUG - Running database fix-ups.
Sep 01, 2018 13:37:59.018 [13640] DEBUG - Database Fixups: Destroyed 0 transient items.
Sep 01, 2018 13:37:59.019 [13640] DEBUG - Collections: Found 0 collections tags in section 1.
Sep 01, 2018 13:37:59.019 [13640] DEBUG - Collections: We had 0 collections in the library already.
Sep 01, 2018 13:37:59.019 [13640] DEBUG - Collections: Synced tags in section 1 in 0ms.
Sep 01, 2018 13:37:59.019 [13640] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
Sep 01, 2018 13:37:59.054 [13640] DEBUG - Collection: Refreshed visibility in section 1 in 34ms.
Sep 01, 2018 13:37:59.055 [13640] DEBUG - Collections: Found 0 collections tags in section 2.
Sep 01, 2018 13:37:59.055 [13640] DEBUG - Collections: We had 0 collections in the library already.
Sep 01, 2018 13:37:59.055 [13640] DEBUG - Collections: Synced tags in section 2 in 0ms.
Sep 01, 2018 13:37:59.055 [13640] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Sep 01, 2018 13:37:59.056 [13640] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Sep 01, 2018 13:37:59.056 [13640] DEBUG - Running database fix-ups completed in 0.0 seconds.
Sep 01, 2018 13:37:59.056 [8400] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.056 [13640] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.496 [21520] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.499 [21520] DEBUG - Sync: onMyPlexSignIn
Sep 01, 2018 13:37:59.499 [21520] DEBUG - HTTP requesting GET https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.576 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.597 [8400] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.598 [8400] DEBUG - DVR: Starting up.
Sep 01, 2018 13:37:59.598 [0484] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:37:59.599 [8400] DEBUG - Activity: registered new activity 46a8de59-ee1c-4f93-977e-d53f93020fc9 - Processing subscriptions
Sep 01, 2018 13:38:00.048 [21520] DEBUG - HTTP 200 response from GET https://plex.tv/servers/0a8da23742e20e2b3169643a2e9cdd2448c34681/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.048 [21520] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Sep 01, 2018 13:38:00.048 [21520] DEBUG - Sync: synchronizing 0 sync lists to generators
Sep 01, 2018 13:38:00.048 [21520] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Sep 01, 2018 13:38:00.048 [21520] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Sep 01, 2018 13:38:00.049 [10300] DEBUG - BPQ: garbageCollect - sync - forced - 8
Sep 01, 2018 13:38:00.049 [21520] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.049 [10300] DEBUG - Sync: starting Sync garbage collection: 8, forced
Sep 01, 2018 13:38:00.049 [20792] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.049 [10300] DEBUG - Sync: finished Sync garbage collection: 8
Sep 01, 2018 13:38:00.144 [0484] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.547 [21520] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.550 [8400] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.550 [21520] DEBUG - EventSource: Resolving 45.33.75.206 port 443s
Sep 01, 2018 13:38:00.551 [5296] DEBUG - EventSource: Resolved 45.33.75.206 to 45.33.75.206
Sep 01, 2018 13:38:00.551 [21520] DEBUG - EventSource: Resolving 45.33.75.206 port 443s
Sep 01, 2018 13:38:00.551 [10572] DEBUG - EventSource: Resolved 45.33.75.206 to 45.33.75.206
Sep 01, 2018 13:38:00.658 [20792] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:00.661 [10572] DEBUG - EventSource: Connected in 79 ms.
Sep 01, 2018 13:38:00.661 [5296] DEBUG - EventSource: Connected in 79 ms.
Sep 01, 2018 13:38:00.662 [5296] DEBUG - EventSource: Wrote data, reading reply.
Sep 01, 2018 13:38:00.662 [10572] DEBUG - EventSource: Wrote data, reading reply.
Sep 01, 2018 13:38:00.835 [10144] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>
Sep 01, 2018 13:38:00.852 [10144] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>.
Sep 01, 2018 13:38:00.862 [10144] DEBUG - NAT: UPnP, mapped port 23030 to 192.168.86.248:32400.
Sep 01, 2018 13:38:01.050 [10572] DEBUG - EventSource: Read HTTP reply header.
Sep 01, 2018 13:38:01.050 [5296] DEBUG - EventSource: Read HTTP reply header.
Sep 01, 2018 13:38:01.050 [5296] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Sep 01, 2018 13:38:01.050 [10572] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Sep 01, 2018 13:38:01.050 [5296] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Sep 01, 2018 13:38:01.050 [10572] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Sep 01, 2018 13:38:01.074 [17788] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>
Sep 01, 2018 13:38:01.097 [17788] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>.
Sep 01, 2018 13:38:01.110 [17788] DEBUG - NAT: UPnP, public address is 65.96.122.105
Sep 01, 2018 13:38:01.120 [8400] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:01.129 [21520] DEBUG - MyPlex: Updating device connections (from timer: 0)
Sep 01, 2018 13:38:01.129 [10144] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.248:23030.
Sep 01, 2018 13:38:01.130 [21520] DEBUG - HTTP requesting PUT https://plex.tv/devices/0a8da23742e20e2b3169643a2e9cdd2448c34681?Connection[][uri]=http://192.168.86.248:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:01.137 [10572] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 01, 2018 13:38:01.138 [10572] DEBUG - EventSource: Successfully connected to 45.33.75.206.
Sep 01, 2018 13:38:01.137 [13640] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 01, 2018 13:38:01.137 [8400] DEBUG - NAT: UPnP, attempting port mapping.
Sep 01, 2018 13:38:01.137 [0484] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 01, 2018 13:38:01.138 [0484] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Sep 01, 2018 13:38:01.138 [5296] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 01, 2018 13:38:01.138 [13640] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 01, 2018 13:38:01.138 [5296] DEBUG - EventSource: Successfully connected to 45.33.75.206.
Sep 01, 2018 13:38:01.138 [14660] DEBUG - MyPlex: Sending Server Info to myPlex (user=klosterman.53@gmail.com, ip=, port=0)
Sep 01, 2018 13:38:01.139 [14660] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=6a33444d-43a4-4113-9ec6-328fa70a31da
Sep 01, 2018 13:38:01.384 [0484] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Sep 01, 2018 13:38:01.384 [0484] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 65.96.122.105
Sep 01, 2018 13:38:01.671 [21520] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/0a8da23742e20e2b3169643a2e9cdd2448c34681?Connection[][uri]=http://192.168.86.248:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Sep 01, 2018 13:38:01.675 [21520] DEBUG - CERT: Certificate will not expire soon.
Sep 01, 2018 13:38:01.705 [14660] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=6a33444d-43a4-4113-9ec6-328fa70a31da
Sep 01, 2018 13:38:01.705 [14660] DEBUG - MyPlex: Published Mapping State response was 201
Sep 01, 2018 13:38:01.705 [14660] DEBUG - MyPlex: Got response for 0a8da23742e20e2b3169643a2e9cdd2448c34681 ~ registered :0
Sep 01, 2018 13:38:01.790 [5296] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="6a33444d-43a4-4113-9ec6-328fa70a31da" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:01.790 [10572] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="6a33444d-43a4-4113-9ec6-328fa70a31da" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:01.791 [10572] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6a33444d-43a4-4113-9ec6-328fa70a31da, expected )
Sep 01, 2018 13:38:01.790 [5296] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6a33444d-43a4-4113-9ec6-328fa70a31da, expected )
Sep 01, 2018 13:38:04.207 [8400] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>
Sep 01, 2018 13:38:04.236 [8400] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>.
Sep 01, 2018 13:38:04.255 [8400] DEBUG - NAT: UPnP, mapped port 23030 to 192.168.86.248:32400.
Sep 01, 2018 13:38:04.459 [0484] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>
Sep 01, 2018 13:38:04.509 [0484] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.248>.
Sep 01, 2018 13:38:04.520 [0484] DEBUG - NAT: UPnP, public address is 65.96.122.105
Sep 01, 2018 13:38:04.526 [8400] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.248:23030.
Sep 01, 2018 13:38:04.544 [0484] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 01, 2018 13:38:04.551 [17788] DEBUG - MyPlex: Sending Server Info to myPlex (user=klosterman.53@gmail.com, ip=, port=23030)
Sep 01, 2018 13:38:04.552 [17788] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=a3835cfc-262c-458d-81ca-f4820ce20a20
Sep 01, 2018 13:38:04.555 [8400] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 01, 2018 13:38:05.144 [17788] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=a3835cfc-262c-458d-81ca-f4820ce20a20
Sep 01, 2018 13:38:05.145 [17788] DEBUG - MyPlex: Published Mapping State response was 201
Sep 01, 2018 13:38:05.145 [17788] DEBUG - MyPlex: Got response for 0a8da23742e20e2b3169643a2e9cdd2448c34681 ~ registered :0
Sep 01, 2018 13:38:05.158 [10144] DEBUG - MyPlex: Sending Server Info to myPlex (user=klosterman.53@gmail.com, ip=, port=23030)
Sep 01, 2018 13:38:05.159 [10144] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=81167804-4cd4-4873-a5a7-80d26cccdae8
Sep 01, 2018 13:38:05.163 [17788] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 01, 2018 13:38:05.551 [20792] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Sep 01, 2018 13:38:05.590 [12856] DEBUG - DVR:Device: Discovering and refreshing devices.
Sep 01, 2018 13:38:05.590 [12856] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Sep 01, 2018 13:38:05.590 [12856] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Sep 01, 2018 13:38:05.739 [10144] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=81167804-4cd4-4873-a5a7-80d26cccdae8
Sep 01, 2018 13:38:05.739 [10144] DEBUG - MyPlex: Published Mapping State response was 201
Sep 01, 2018 13:38:05.740 [10144] DEBUG - MyPlex: Got response for 0a8da23742e20e2b3169643a2e9cdd2448c34681 ~ registered :0
Sep 01, 2018 13:38:05.740 [10572] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6a33444d-43a4-4113-9ec6-328fa70a31da, expected 81167804-4cd4-4873-a5a7-80d26cccdae8)
Sep 01, 2018 13:38:05.740 [5296] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6a33444d-43a4-4113-9ec6-328fa70a31da, expected 81167804-4cd4-4873-a5a7-80d26cccdae8)
Sep 01, 2018 13:38:05.751 [10144] DEBUG - MyPlex: Last published value didn't change, we're done.
Sep 01, 2018 13:38:06.096 [20792] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 45.33.75.206 was 75 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 45.79.137.197 was 76 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 50.116.44.223 was 123 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 45.79.10.123 was 174 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 139.162.227.137 was 207 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 184.105.148.101 was 207 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 82.94.168.55 was 263 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 172.104.225.166 was 267 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 139.162.117.249 was 385 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Time to connect to 139.162.54.192 was 556 ms.
Sep 01, 2018 13:38:06.935 [20792] DEBUG - PubSubManager: Updating best ping time for 45.33.75.206 to 75 ms.
Sep 01, 2018 13:38:08.013 [12856] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Sep 01, 2018 13:38:09.600 [0484] DEBUG - Activity: registered new activity 7bda32b0-4b7a-4907-b757-f39262fac9b1 - Processing subscriptions
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: Scheduling subscriptions.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: Initialized 0 schedulers.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: Scheduled all subscriptions in 0 ms.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - Activity: Ended activity 7bda32b0-4b7a-4907-b757-f39262fac9b1.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: There are 0 grabs at the end.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - Activity: Ended activity 46a8de59-ee1c-4f93-977e-d53f93020fc9.
Sep 01, 2018 13:38:09.601 [0484] DEBUG - DVR:Subscription: Refreshed all content in 1 ms.
Sep 01, 2018 13:38:15.401 [10572] DEBUG - EventSource: Got event [data] '<Message address="65.96.122.105" port="23030" asyncIdentifier="a3835cfc-262c-458d-81ca-f4820ce20a20" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:15.401 [5296] DEBUG - EventSource: Got event [data] '<Message address="65.96.122.105" port="23030" asyncIdentifier="a3835cfc-262c-458d-81ca-f4820ce20a20" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:15.401 [10572] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (a3835cfc-262c-458d-81ca-f4820ce20a20, expected )
Sep 01, 2018 13:38:15.402 [5296] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (a3835cfc-262c-458d-81ca-f4820ce20a20, expected )
Sep 01, 2018 13:38:15.402 [10572] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (a3835cfc-262c-458d-81ca-f4820ce20a20, expected 81167804-4cd4-4873-a5a7-80d26cccdae8)
Sep 01, 2018 13:38:15.402 [5296] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (a3835cfc-262c-458d-81ca-f4820ce20a20, expected 81167804-4cd4-4873-a5a7-80d26cccdae8)
Sep 01, 2018 13:38:16.012 [10572] DEBUG - EventSource: Got event [data] '<Message address="65.96.122.105" port="23030" asyncIdentifier="81167804-4cd4-4873-a5a7-80d26cccdae8" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:16.012 [5296] DEBUG - EventSource: Got event [data] '<Message address="65.96.122.105" port="23030" asyncIdentifier="81167804-4cd4-4873-a5a7-80d26cccdae8" connectivity="0" command="notifyConnectivity"/>'
Sep 01, 2018 13:38:16.012 [5296] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (81167804-4cd4-4873-a5a7-80d26cccdae8, expected )
Sep 01, 2018 13:38:16.012 [10572] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (81167804-4cd4-4873-a5a7-80d26cccdae8, expected )
Sep 01, 2018 13:38:16.012 [5296] DEBUG - PubSub: Got notified of reachability: 0 for 65.96.122.105:23030
Sep 01, 2018 13:38:16.012 [10572] DEBUG - PubSub: Got notified of reachability: 0 for 65.96.122.105:23030
Sep 01, 2018 13:38:17.820 [19348] DEBUG - HTTP 500 response from GET http://127.0.0.1:52781/system/notify/serverUpdated?host=192.168.86.248
Sep 01, 2018 13:38:17.820 [19348] DEBUG - [com.plexapp.system] HTTP reply status 500, with 3550 bytes of content.
Sep 01, 2018 13:38:17.820 [19348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.1:5000/rootDesc.xml
Sep 01, 2018 13:38:17.820 [19348] DEBUG - HTTP requesting GET http://192.168.86.1:5000/rootDesc.xml
Sep 01, 2018 13:38:17.830 [19348] DEBUG - HTTP 200 response from GET http://192.168.86.1:5000/rootDesc.xml
Sep 01, 2018 13:38:17.831 [19348] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.86.1:5000/rootDesc.xml
Sep 01, 2018 13:38:17.831 [19348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (OnHub)
Sep 01, 2018 13:38:17.831 [19348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANDevice)
Sep 01, 2018 13:38:17.831 [19348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANConnectionDevice)
Sep 01, 2018 13:38:17.834 [19348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.249:8008/ssdp/device-desc.xml
Sep 01, 2018 13:38:17.834 [19348] DEBUG - HTTP requesting GET http://192.168.86.249:8008/ssdp/device-desc.xml
Sep 01, 2018 13:38:17.862 [19348] DEBUG - HTTP 200 response from GET http://192.168.86.249:8008/ssdp/device-desc.xml
Sep 01, 2018 13:38:17.863 [19348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.249:8008/ssdp/device-desc.xml
Sep 01, 2018 13:38:17.863 [19348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.249 (Living Room)
Sep 01, 2018 13:38:17.863 [19348] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.248:32469/DeviceDescription.xml
Sep 01, 2018 13:38:17.863 [19348] DEBUG - HTTP requesting GET http://192.168.86.248:32469/DeviceDescription.xml
Sep 01, 2018 13:38:17.865 [19348] DEBUG - HTTP 200 response from GET http://192.168.86.248:32469/DeviceDescription.xml
Sep 01, 2018 13:38:17.865 [19348] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.248:32469/DeviceDescription.xml
Sep 01, 2018 13:38:17.865 [19348] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.248 (Plex Media Server: Home)
Sep 01, 2018 13:39:22.966 [6716] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Tuner Service.exe' exit code for process 14044 is 1073807364 (failure)
Sep 01, 2018 13:39:22.966 [18488] DEBUG - Plex Tuner Service.exe exited.
Sep 01, 2018 13:39:22.967 [10484] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Dlna Server.exe' exit code for process 15044 is 1073807364 (failure)
Sep 01, 2018 13:39:22.967 [17816] DEBUG - Plex Dlna Server.exe exited.
Sep 01, 2018 13:39:23.067 [18488] 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.13.6.5339-115f087d6 32600 /waitmutex 
Sep 01, 2018 13:39:23.105 [18488] DEBUG - Jobs: Starting child process with pid 14064
Sep 01, 2018 13:39:23.105 [17816] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Dlna Server.exe 
Sep 01, 2018 13:39:23.156 [17816] DEBUG - Jobs: Starting child process with pid 18116
