May 08, 2019 09:24:32.738 [11512] INFO - Plex Media Server v1.15.4.993-bb4a2cb6c - Microsoft PC x64 - build: windows-x86 windows - GMT 01:00
May 08, 2019 09:24:32.738 [11512] INFO - Windows version: 10.0 (Build 17763), language en-US
May 08, 2019 09:24:32.738 [11512] INFO - 8 3600 MHz processor(s): Architecture=0, Level=6, Revision=40457 Processor Identifier=Intel64 Family 6 Model 158 Stepping 9, GenuineIntel
May 08, 2019 09:24:32.738 [11512] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
May 08, 2019 09:24:32.769 [11492] DEBUG - Plex for Windows Store application is not installed
May 08, 2019 09:24:32.832 [11492] DEBUG - BPQ: [Idle] -> [Starting]
May 08, 2019 09:24:32.926 [11492] DEBUG - FeatureManager: Using cached data for features list
May 08, 2019 09:24:33.051 [11492] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
May 08, 2019 09:24:33.176 [11492] DEBUG - Running migrations. (EPG 0)
May 08, 2019 09:24:33.379 [11492] DEBUG - ChangestampAllocator: initialized to 280147
May 08, 2019 09:24:33.425 [11492] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.26.0, threadsafe=1
May 08, 2019 09:24:33.457 [11492] DEBUG - Running migrations. (EPG 0)
May 08, 2019 09:24:34.207 [11492] DEBUG - CERT: Installed certificate with fingerprint 5c:96:80:23:62:db:f6:12:d6:99:62:34:31:19:2d:42:5f:0d:5e:40.
May 08, 2019 09:24:34.207 [11492] DEBUG - CERT: Installed new private key.
May 08, 2019 09:24:34.207 [11492] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.5520a132865040c59d29b9d38c11dc0d.plex.direct
May 08, 2019 09:24:34.207 [11492] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
May 08, 2019 09:24:34.222 [11492] INFO - OCSP: Successfully retrieved response from cache.
May 08, 2019 09:24:34.222 [11492] DEBUG - CERT: Installed intermediate certificate.
May 08, 2019 09:24:34.253 [11492] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
May 08, 2019 09:24:34.253 [11492] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
May 08, 2019 09:24:34.378 [6100] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
May 08, 2019 09:24:34.394 [11492] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
May 08, 2019 09:24:34.410 [11492] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
May 08, 2019 09:24:34.410 [2736] DEBUG - Relay: read 1 cached entries from hosts file
May 08, 2019 09:24:34.410 [11492] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 08, 2019 09:24:34.410 [11492] DEBUG - MyPlex: updating with 7 access tokens
May 08, 2019 09:24:34.456 [2736] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:34.456 [6984] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
May 08, 2019 09:24:34.456 [6100] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
May 08, 2019 09:24:34.488 [11492] DEBUG - Opening 1 database sessions to library (), SQLite 3.26.0, threadsafe=1
May 08, 2019 09:24:34.503 [6984] DEBUG - HTTP requesting GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 08, 2019 09:24:34.503 [12140] DEBUG - File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c" changed: -1 => 2019-04-24 11:27:49 (1556101669).
May 08, 2019 09:24:34.503 [12140] DEBUG - File "C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2018-12-15 11:18:36 (1544872716).
May 08, 2019 09:24:34.503 [12140] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c"
May 08, 2019 09:24:34.566 [12140] DEBUG - Scanning for plug-ins in "C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins"
May 08, 2019 09:24:34.597 [12140] DEBUG - PluginRepository::setStartState: 1, startingSystem
May 08, 2019 09:24:34.597 [12140] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\System.bundle.
May 08, 2019 09:24:34.597 [12140] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
May 08, 2019 09:24:34.644 [7336] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\RICHA\AppData\Local\Plex Media Server\Crash Reports\1.15.4.993-bb4a2cb6c" --version=1.15.4.993-bb4a2cb6c --platform=Windows "--platformVersion=10.0 (Build 17763)" --serverUuid=9bb5e326fe8035f501ef3fd2c09078924475dfc2 --userId=richard49@gmail.com --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx --vendor=Microsoft --model=x64 --device=PC 
May 08, 2019 09:24:34.644 [7336] DEBUG - Jobs: Starting child process with pid 12156
May 08, 2019 09:24:34.660 [12140] DEBUG - Starting file watcher for com.plexapp.system
May 08, 2019 09:24:34.691 [2736] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:34.722 [12140] 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-bb4a2cb6c\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\System.bundle"
May 08, 2019 09:24:34.753 [6984] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 08, 2019 09:24:34.753 [6984] DEBUG - MyPlex: updating with 7 access tokens
May 08, 2019 09:24:34.753 [6968] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:34.753 [12140] DEBUG - Spawned plug-in com.plexapp.system with PID 12076
May 08, 2019 09:24:34.753 [12140] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

May 08, 2019 09:24:34.941 [6968] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:34.941 [7336] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:34.941 [6968] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:35.238 [10744] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 12156 is 0 (success)
May 08, 2019 09:24:35.363 [6968] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:35.394 [6968] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:35.503 [7336] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:35.691 [6968] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.206 [12140] DEBUG - Reading 486 bytes in the body, code is 200
May 08, 2019 09:24:38.206 [12140] DEBUG - [com.plexapp.system] Plug-in running on port 49742.
May 08, 2019 09:24:38.206 [12140] DEBUG -  * Plug-in handles prefix: /system
May 08, 2019 09:24:38.206 [12140] DEBUG -  * Plug-in handles prefix: /player
May 08, 2019 09:24:38.206 [12140] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
May 08, 2019 09:24:38.206 [12140] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
May 08, 2019 09:24:38.206 [12140] DEBUG - PluginRepository::setStartState: 2, startingPlugins
May 08, 2019 09:24:38.206 [12140] DEBUG - Updating the list of agents known by the system.
May 08, 2019 09:24:38.206 [12140] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
May 08, 2019 09:24:38.206 [12140] DEBUG - HTTP requesting GET http://127.0.0.1:49742/system/agents
May 08, 2019 09:24:38.252 [11492] DEBUG - Image transcode cache directory: "C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
May 08, 2019 09:24:38.284 [11492] DEBUG - Transcoder: Cleaning old transcode directories.
May 08, 2019 09:24:38.284 [11492] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
May 08, 2019 09:24:38.284 [11492] DEBUG - MyPlex: updating with 7 access tokens
May 08, 2019 09:24:38.299 [6984] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.299 [11492] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 08, 2019 09:24:38.299 [11492] DEBUG - MyPlex: username is richard49, login is richard49@gmail.com, home is 0, no pin
May 08, 2019 09:24:38.299 [11492] DEBUG - PublicAddressManager: Starting.
May 08, 2019 09:24:38.299 [11492] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 08, 2019 09:24:38.299 [12160] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 08, 2019 09:24:38.299 [6972] DEBUG - PublicAddressManager: Obtaining public IP.
May 08, 2019 09:24:38.299 [6972] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkInterface: ignoring adapter 'Wi-Fi' (fe80::a1f1:bfe7:9ba4:ab95%5,169.254.171.149) because it has no gateway
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkInterface: ignoring adapter 'Local Area Connection* 3' (fe80::a97a:50a6:86c1:702a%4,169.254.112.42) because it has no gateway
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 2' (fe80::49d2:d60a:7d08:1878%10,10.102.37.150,169.254.24.120) because it has no gateway
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
May 08, 2019 09:24:38.299 [11492] DEBUG - Detected primary interface: 192.168.0.32
May 08, 2019 09:24:38.299 [11492] DEBUG - Network interfaces:
May 08, 2019 09:24:38.299 [11492] DEBUG -  * 7 Ethernet {4DDAA004-5AFB-4F9E-B56D-03836F6C5191} (192.168.0.32) (loopback: 0)
May 08, 2019 09:24:38.299 [11492] DEBUG - Creating NetworkServices singleton.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkServices: Initializing...
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.299 [2636] DEBUG - Network change for advertiser.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.299 [2636] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.299 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Creating new service.
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Got notification of changed network (first change: 1)
May 08, 2019 09:24:38.315 [11492] DEBUG - NetworkService: Quick dispatch of network change.
May 08, 2019 09:24:38.315 [2636] DEBUG - Network change for advertiser.
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - Network change for advertiser.
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 192.168.0.255 (index: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 192.168.0.255 (index: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
May 08, 2019 09:24:38.315 [2636] DEBUG - NetworkService: Browsing on interface 192.168.0.32 on broadcast address 239.255.255.250 (index: 0)
May 08, 2019 09:24:38.440 [11492] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
May 08, 2019 09:24:38.502 [6984] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.549 [11492] DEBUG - Gracenote: Using SQLite 3.26.0
May 08, 2019 09:24:38.549 [12164] DEBUG - Plex Dlna Server.exe starting.
May 08, 2019 09:24:38.549 [12164] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Dlna Server.exe 
May 08, 2019 09:24:38.549 [11492] DEBUG - Starting HTTP server.
May 08, 2019 09:24:38.549 [11492] DEBUG - Running server...
May 08, 2019 09:24:38.549 [12068] DEBUG - Plex Tuner Service.exe starting.
May 08, 2019 09:24:38.549 [11492] DEBUG - Waiting for server condition to be signaled.
May 08, 2019 09:24:38.549 [9372] DEBUG - HttpServer: Set up a thread pool with 2 threads.
May 08, 2019 09:24:38.549 [9372] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.659 [12164] DEBUG - Jobs: Starting child process with pid 9368
May 08, 2019 09:24:38.659 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
May 08, 2019 09:24:38.659 [12068] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Tuner Service.exe "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Private" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Shared" 1.15.4.993-bb4a2cb6c 32600 /waitmutex 
May 08, 2019 09:24:38.659 [12160] DEBUG - MyPlex: username is richard49, login is richard49@gmail.com, home is 0, no pin
May 08, 2019 09:24:38.659 [12160] DEBUG - HTTP requesting GET https://plex.tv/api/v2/geoip
May 08, 2019 09:24:38.690 [9372] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.690 [1464] DEBUG - Request: [127.0.0.1:49739 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.690 [9424] DEBUG - Request: [127.0.0.1:49738 (Loopback)] GET / (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.690 [9572] DEBUG - Request: [127.0.0.1:49740 (Loopback)] GET / (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.690 [11856] DEBUG - Request: [127.0.0.1:49741 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.705 [9372] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.752 [12140] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/system/agents
May 08, 2019 09:24:38.752 [12140] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
May 08, 2019 09:24:38.752 [12140] DEBUG - The system knows about 13 agents.
May 08, 2019 09:24:38.752 [11484] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\Fanart-TV.bundle.
May 08, 2019 09:24:38.752 [12140] DEBUG - Waiting for 18 plug-ins to finish starting.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\Framework.bundle.
May 08, 2019 09:24:38.752 [5328] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\HTbackdrops.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\LastFM.bundle.
May 08, 2019 09:24:38.752 [11484] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
May 08, 2019 09:24:38.752 [11484] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\LocalMedia.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\LyricFind.bundle.
May 08, 2019 09:24:38.752 [12068] DEBUG - Jobs: Starting child process with pid 9476
May 08, 2019 09:24:38.752 [5328] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
May 08, 2019 09:24:38.752 [5328] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\Media-Flags.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\MoviePosterDB.bundle.
May 08, 2019 09:24:38.752 [11484] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
May 08, 2019 09:24:38.752 [11484] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\OpenSubtitles.bundle.
May 08, 2019 09:24:38.752 [5328] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\PersonalMedia.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\PlexMovie.bundle.
May 08, 2019 09:24:38.752 [11484] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
May 08, 2019 09:24:38.752 [11484] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\PlexMusic.bundle.
May 08, 2019 09:24:38.752 [5328] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
May 08, 2019 09:24:38.752 [5328] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\PlexThemeMusic.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\Scanners.bundle.
May 08, 2019 09:24:38.752 [11484] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
May 08, 2019 09:24:38.752 [5328] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
May 08, 2019 09:24:38.752 [11484] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\TheMovieDB.bundle.
May 08, 2019 09:24:38.752 [5328] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\TheTVDB.bundle.
May 08, 2019 09:24:38.752 [10760] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\WebClient.bundle.
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale ar
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale cs_CZ
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale da
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale de
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale en
May 08, 2019 09:24:38.752 [1464] DEBUG - Translator: Found locale es
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale et_EE
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale fr
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale he
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale hr_HR
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale hu_HU
May 08, 2019 09:24:38.768 [11484] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
May 08, 2019 09:24:38.768 [10760] DEBUG - Starting plug-in C:\Users\RICHA\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
May 08, 2019 09:24:38.768 [5328] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale it
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale ja_JP
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale ko
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale lt
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale nl
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale nl_BE
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale no
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale pt_BR
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale ro
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale ru
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale sr
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale sv
May 08, 2019 09:24:38.768 [1464] DEBUG - Translator: Found locale zh_CN
May 08, 2019 09:24:38.768 [12140] DEBUG - Starting scheduled updates, every 3600 seconds
May 08, 2019 09:24:38.768 [12140] INFO - LibraryUpdateManager path watching is disabled
May 08, 2019 09:24:38.768 [12140] DEBUG - Done waiting for all plug-ins to start.
May 08, 2019 09:24:38.768 [12140] DEBUG - PluginRepository::setStartState: 3, complete
May 08, 2019 09:24:38.768 [10872] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
May 08, 2019 09:24:38.799 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/geoip
May 08, 2019 09:24:38.799 [12160] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.862 [6100] DEBUG - Gracenote: Loading 29 locales in 0.3 seconds.
May 08, 2019 09:24:38.862 [10872] DEBUG - Starting file watcher for com.plexapp.agents.imdb
May 08, 2019 09:24:38.862 [1464] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:38.862 [1464] DEBUG - HTTP requesting GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:38.862 [9372] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.862 [10872] 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-bb4a2cb6c\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-bb4a2cb6c\PlexMovie.bundle"
May 08, 2019 09:24:38.862 [9372] DEBUG - Running database fix-ups.
May 08, 2019 09:24:38.877 [10872] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 10272
May 08, 2019 09:24:38.877 [10872] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

May 08, 2019 09:24:38.877 [1520] DEBUG - Completed: [127.0.0.1:49741] 200 GET /servers (5 live) GZIP 182ms 497 bytes
May 08, 2019 09:24:38.877 [7180] DEBUG - Completed: [127.0.0.1:49738] 200 GET / (5 live) GZIP 183ms 3025 bytes
May 08, 2019 09:24:38.877 [1520] DEBUG - Completed: [127.0.0.1:49740] 200 GET / (5 live) GZIP 183ms 3025 bytes
May 08, 2019 09:24:38.893 [11856] DEBUG - Request: [127.0.0.1:49754 (Loopback)] GET / (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.893 [7180] DEBUG - Completed: [127.0.0.1:49754] 200 GET / (6 live) GZIP 1ms 3025 bytes
May 08, 2019 09:24:38.893 [9424] DEBUG - Request: [127.0.0.1:49755 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.893 [1520] DEBUG - Completed: [127.0.0.1:49755] 200 GET /library/sections (5 live) GZIP 2ms 926 bytes
May 08, 2019 09:24:38.909 [9572] DEBUG - Request: [127.0.0.1:49756 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.909 [7180] DEBUG - Completed: [127.0.0.1:49756] 200 GET /channels/all (5 live) GZIP 0ms 387 bytes
May 08, 2019 09:24:38.924 [9424] DEBUG - Request: [127.0.0.1:49757 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.924 [7180] DEBUG - Completed: [127.0.0.1:49757] 200 GET /library/sections (5 live) GZIP 2ms 926 bytes
May 08, 2019 09:24:38.924 [9372] DEBUG - Database Fixups: Destroyed 0 transient items.
May 08, 2019 09:24:38.940 [11856] DEBUG - Request: [127.0.0.1:49759 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:38.940 [7180] DEBUG - Completed: [127.0.0.1:49759] 200 GET /channels/all (5 live) GZIP 0ms 387 bytes
May 08, 2019 09:24:38.955 [1464] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:38.955 [1464] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
May 08, 2019 09:24:38.955 [7180] DEBUG - Completed: [127.0.0.1:49739] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP 264ms 19474 bytes
May 08, 2019 09:24:38.971 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:38.971 [12160] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Found 0 collections tags in section 1.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: We had 0 collections in the library already.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Synced tags in section 1 in 0ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collection: Refreshed visibility in section 1 in 1ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Found 0 collections tags in section 2.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: We had 0 collections in the library already.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Synced tags in section 2 in 0ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Found 0 collections tags in section 5.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: We had 0 collections in the library already.
May 08, 2019 09:24:39.002 [9372] DEBUG - Collections: Synced tags in section 5 in 0ms.
May 08, 2019 09:24:39.018 [9372] DEBUG - Collections: Refreshed attributes for 0 collections in section 5 in 0ms.
May 08, 2019 09:24:39.018 [9372] DEBUG - Collection: Refreshed visibility in section 5 in 0ms.
May 08, 2019 09:24:39.018 [9372] DEBUG - Running database fix-ups completed in 0.1 seconds.
May 08, 2019 09:24:39.018 [9372] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.143 [6972] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 08, 2019 09:24:39.143 [6972] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 81.107.172.154
May 08, 2019 09:24:39.174 [9372] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.174 [6968] DEBUG - DVR: Starting up.
May 08, 2019 09:24:39.174 [2736] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.190 [6968] DEBUG - Activity: registered new activity 215c94d3-de88-464a-8100-bae17e5b924b - Processing subscriptions
May 08, 2019 09:24:39.205 [9372] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 08, 2019 09:24:39.237 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.237 [12160] DEBUG - Sync: onMyPlexSignIn
May 08, 2019 09:24:39.268 [12160] DEBUG - HTTP requesting GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.346 [2736] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.471 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9bb5e326fe8035f501ef3fd2c09078924475dfc2/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.471 [12160] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
May 08, 2019 09:24:39.471 [12160] DEBUG - Sync: synchronizing 0 sync lists to generators
May 08, 2019 09:24:39.471 [12160] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
May 08, 2019 09:24:39.471 [12160] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
May 08, 2019 09:24:39.471 [12300] DEBUG - BPQ: garbageCollect - sync - forced - 10
May 08, 2019 09:24:39.471 [12300] DEBUG - Sync: starting Sync garbage collection: 10, forced
May 08, 2019 09:24:39.471 [6100] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.471 [12300] DEBUG - Sync: garbage collecting filesystem
May 08, 2019 09:24:39.471 [12160] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.471 [12300] WARN - Sync: local sync directory "C:\Users\RICHA\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
May 08, 2019 09:24:39.471 [12300] DEBUG - Sync: garbage collected filesystem
May 08, 2019 09:24:39.471 [12300] DEBUG - Sync: finished Sync garbage collection: 10
May 08, 2019 09:24:39.627 [12160] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.627 [6984] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.627 [12160] DEBUG - EventSource: Resolving 176.58.107.84 port 443
May 08, 2019 09:24:39.627 [7336] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.627 [7180] DEBUG - EventSource: Resolved 176.58.107.84 to 176.58.107.84
May 08, 2019 09:24:39.643 [12160] DEBUG - MyPlex: Updating device connections (from timer: 0)
May 08, 2019 09:24:39.643 [12160] DEBUG - HTTP requesting PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.643 [12380] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=0)
May 08, 2019 09:24:39.643 [12380] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=e00e750b-ef5f-4452-ba11-9b3a1fda5590
May 08, 2019 09:24:39.690 [7180] DEBUG - EventSource: Connected in 39 ms.
May 08, 2019 09:24:39.690 [1520] DEBUG - EventSource: Wrote data, reading reply.
May 08, 2019 09:24:39.690 [6100] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.736 [9572] DEBUG - Request: [127.0.0.1:49794 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:39.736 [9572] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
May 08, 2019 09:24:39.736 [9572] DEBUG - HTTP requesting GET http://127.0.0.1:49742/system/messaging/clear_events/com.plexapp.agents.imdb
May 08, 2019 09:24:39.752 [9572] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/system/messaging/clear_events/com.plexapp.agents.imdb
May 08, 2019 09:24:39.752 [9572] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
May 08, 2019 09:24:39.752 [7180] DEBUG - Completed: [127.0.0.1:49794] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (5 live) GZIP 29ms 330 bytes
May 08, 2019 09:24:39.768 [7180] DEBUG - EventSource: Read HTTP reply header.
May 08, 2019 09:24:39.768 [7180] DEBUG - MyPlex: We appear to have regained Internet connectivity.
May 08, 2019 09:24:39.783 [7180] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 08, 2019 09:24:39.783 [7180] DEBUG - EventSource: Successfully connected to 176.58.107.84.
May 08, 2019 09:24:39.799 [9424] DEBUG - Request: [127.0.0.1:49796 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:39.815 [9424] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
May 08, 2019 09:24:39.815 [9424] DEBUG - HTTP requesting GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/resourceHashes
May 08, 2019 09:24:39.815 [6984] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.815 [12160] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9bb5e326fe8035f501ef3fd2c09078924475dfc2?Connection[][uri]=http://192.168.0.32:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.815 [12160] DEBUG - CERT: Certificate will not expire soon.
May 08, 2019 09:24:39.877 [9424] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/resourceHashes
May 08, 2019 09:24:39.877 [9424] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
May 08, 2019 09:24:39.877 [7180] DEBUG - Completed: [127.0.0.1:49796] 200 GET /:/plugins/com.plexapp.system/resourceHashes (5 live) GZIP 67ms 3498 bytes
May 08, 2019 09:24:39.877 [7336] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
May 08, 2019 09:24:39.877 [12472] DEBUG - Connection Tester: Testing the server Lenovo-Laptop with 2 URLs.
May 08, 2019 09:24:39.877 [12380] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=e00e750b-ef5f-4452-ba11-9b3a1fda5590
May 08, 2019 09:24:39.877 [12476] DEBUG - HTTP requesting GET https://192-168-0-17.21be0d3c79704de7983d0d1a763d4ca7.plex.direct:32400
May 08, 2019 09:24:39.877 [12480] DEBUG - HTTP requesting GET https://81-107-172-154.21be0d3c79704de7983d0d1a763d4ca7.plex.direct:29192
May 08, 2019 09:24:39.877 [12380] DEBUG - MyPlex: Published Mapping State response was 201
May 08, 2019 09:24:39.877 [12380] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 08, 2019 09:24:39.893 [9424] DEBUG - Request: [127.0.0.1:49800 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (7 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:39.893 [9424] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:39.893 [9424] DEBUG - HTTP requesting GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:39.893 [9572] DEBUG - Request: [127.0.0.1:49801 (Loopback)] GET / (6 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:39.893 [7180] DEBUG - Completed: [127.0.0.1:49801] 200 GET / (6 live) GZIP 1ms 3025 bytes
May 08, 2019 09:24:39.908 [9424] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
May 08, 2019 09:24:39.908 [9424] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
May 08, 2019 09:24:39.908 [1520] DEBUG - Completed: [127.0.0.1:49800] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 26ms 19474 bytes
May 08, 2019 09:24:40.689 [11856] DEBUG - Request: [127.0.0.1:49813 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (6 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:40.689 [11856] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 08, 2019 09:24:40.689 [11856] DEBUG - HTTP requesting GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 08, 2019 09:24:40.689 [11856] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
May 08, 2019 09:24:40.689 [11856] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
May 08, 2019 09:24:40.689 [1520] DEBUG - Completed: [127.0.0.1:49813] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (6 live) GZIP 5ms 465 bytes
May 08, 2019 09:24:40.721 [10872] DEBUG - Reading 148 bytes in the body, code is 200
May 08, 2019 09:24:40.721 [10872] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 49815.
May 08, 2019 09:24:40.721 [10872] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
May 08, 2019 09:24:40.721 [10872] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
May 08, 2019 09:24:40.721 [10872] DEBUG - HTTP requesting GET http://127.0.0.1:49815/:/plugins/com.plexapp.agents.imdb/prefs
May 08, 2019 09:24:40.736 [10872] DEBUG - HTTP 200 response from GET http://127.0.0.1:49815/:/plugins/com.plexapp.agents.imdb/prefs
May 08, 2019 09:24:40.736 [10872] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
May 08, 2019 09:24:40.736 [10872] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 866e12c6fb36c82b20f05dd1a5fe84bc41541e17
May 08, 2019 09:24:40.736 [10872] DEBUG - IVA: Downloading trailer lists.
May 08, 2019 09:24:40.846 [10872] DEBUG - IVA: Downloaded 43 trailers for source 1.
May 08, 2019 09:24:40.971 [10872] DEBUG - IVA: Downloaded 48 trailers for source 2.
May 08, 2019 09:24:42.258 [6972] DEBUG - NAT: UPnP, found device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>
May 08, 2019 09:24:42.352 [6972] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>.
May 08, 2019 09:24:42.399 [6972] DEBUG - NAT: UPnP, public address is 81.107.172.154
May 08, 2019 09:24:42.414 [6972] DEBUG - MyPlex: Sending Server Info to myPlex (user=richard49@gmail.com, ip=, port=32400)
May 08, 2019 09:24:42.414 [6972] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ec0497fe-50b9-4b52-a124-150a84893d96
May 08, 2019 09:24:42.602 [6972] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ec0497fe-50b9-4b52-a124-150a84893d96
May 08, 2019 09:24:42.602 [6972] DEBUG - MyPlex: Published Mapping State response was 201
May 08, 2019 09:24:42.602 [6972] DEBUG - MyPlex: Got response for 9bb5e326fe8035f501ef3fd2c09078924475dfc2 ~ registered :0
May 08, 2019 09:24:42.618 [6972] DEBUG - MyPlex: Last published value didn't change, we're done.
May 08, 2019 09:24:43.321 [2636] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.0.32 (Lenovo-Desktop)
May 08, 2019 09:24:43.321 [2636] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.0.32
May 08, 2019 09:24:43.321 [2636] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.0.32
May 08, 2019 09:24:43.321 [2636] DEBUG - HTTP requesting GET http://127.0.0.1:49742/system/notify/serverUpdated?host=192.168.0.32
May 08, 2019 09:24:43.336 [9424] DEBUG - Request: [127.0.0.1:49832 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:43.336 [7180] DEBUG - Completed: [127.0.0.1:49832] 200 GET /servers (5 live) GZIP 0ms 497 bytes
May 08, 2019 09:24:43.336 [9572] DEBUG - Request: [127.0.0.1:49833 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:43.336 [7180] DEBUG - Completed: [127.0.0.1:49833] 200 GET /library/sections (5 live) GZIP 1ms 926 bytes
May 08, 2019 09:24:43.336 [11856] DEBUG - Request: [127.0.0.1:49834 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:24:43.336 [7180] DEBUG - Completed: [127.0.0.1:49834] 200 GET /channels/all (5 live) GZIP 0ms 387 bytes
May 08, 2019 09:24:43.336 [2636] DEBUG - HTTP 200 response from GET http://127.0.0.1:49742/system/notify/serverUpdated?host=192.168.0.32
May 08, 2019 09:24:43.336 [2636] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
May 08, 2019 09:24:44.383 [6968] DEBUG - DVR:Device: Discovering and refreshing all devices.
May 08, 2019 09:24:44.383 [6968] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
May 08, 2019 09:24:44.383 [6968] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
May 08, 2019 09:24:44.633 [6972] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
May 08, 2019 09:24:44.789 [6972] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
May 08, 2019 09:24:44.883 [12476] ERROR - Error issuing curl_easy_perform(handle): 28
May 08, 2019 09:24:44.883 [12480] ERROR - Error issuing curl_easy_perform(handle): 28
May 08, 2019 09:24:44.883 [12476] DEBUG - HTTP simulating 408 after curl timeout
May 08, 2019 09:24:44.883 [12480] DEBUG - HTTP simulating 408 after curl timeout
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 176.58.127.172 was 37 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 176.58.107.84 was 37 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 82.94.168.18 was 50 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 139.162.170.32 was 78 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 172.104.24.90 was 222 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 45.56.116.228 was 246 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 45.79.23.169 was 284 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 184.105.148.117 was 297 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 172.104.44.222 was 526 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Time to connect to 139.162.120.52 was 571 ms.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Another server (176.58.127.172) beat the current one (176.58.107.84).
May 08, 2019 09:24:45.648 [6972] DEBUG - EventSource: Stopping.
May 08, 2019 09:24:45.648 [6972] DEBUG - EventSource: Resolving 176.58.127.172 port 443
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Updating best host to 176.58.127.172.
May 08, 2019 09:24:45.648 [6972] DEBUG - PubSubManager: Updating best ping time for 176.58.127.172 to 37 ms.
May 08, 2019 09:24:45.648 [1520] DEBUG - EventSource: Resolved 176.58.127.172 to 176.58.127.172
May 08, 2019 09:24:45.726 [1520] DEBUG - EventSource: Connected in 46 ms.
May 08, 2019 09:24:45.726 [7180] DEBUG - EventSource: Wrote data, reading reply.
May 08, 2019 09:24:45.820 [7180] DEBUG - EventSource: Read HTTP reply header.
May 08, 2019 09:24:45.820 [7180] DEBUG - MyPlex: We appear to have regained Internet connectivity.
May 08, 2019 09:24:45.820 [7180] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 08, 2019 09:24:45.820 [2736] DEBUG - PublicAddressManager: Obtaining public IP.
May 08, 2019 09:24:45.820 [7180] DEBUG - EventSource: Successfully connected to 176.58.127.172.
May 08, 2019 09:24:45.820 [2736] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 08, 2019 09:24:45.898 [2736] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
May 08, 2019 09:24:45.898 [2736] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 81.107.172.154
May 08, 2019 09:24:46.882 [6968] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
May 08, 2019 09:24:47.632 [1520] DEBUG - EventSource: Got event [data] '<Message address="81.107.172.154" port="32400" asyncIdentifier="ec0497fe-50b9-4b52-a124-150a84893d96" connectivity="0" command="notifyConnectivity"/>'
May 08, 2019 09:24:47.632 [1520] DEBUG - PubSub: Got notified of reachability for async identifier ec0497fe-50b9-4b52-a124-150a84893d96: 0 for 81.107.172.154:32400 (responded in 5 seconds)
May 08, 2019 09:24:48.382 [2636] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.1:5000/rootDesc.xml
May 08, 2019 09:24:48.382 [2636] DEBUG - HTTP requesting GET http://192.168.0.1:5000/rootDesc.xml
May 08, 2019 09:24:48.382 [2636] DEBUG - HTTP 200 response from GET http://192.168.0.1:5000/rootDesc.xml
May 08, 2019 09:24:48.397 [2636] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.1:5000/rootDesc.xml
May 08, 2019 09:24:48.397 [2636] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (ARRIS TG2492LG-85 Router)
May 08, 2019 09:24:48.397 [2636] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANDevice)
May 08, 2019 09:24:48.397 [2636] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANConnectionDevice)
May 08, 2019 09:24:48.975 [2736] DEBUG - NAT: UPnP, found device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>
May 08, 2019 09:24:49.022 [2736] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:5000/rootDesc.xml> with private address <192.168.0.32>.
May 08, 2019 09:24:49.038 [2736] DEBUG - NAT: UPnP, public address is 81.107.172.154
May 08, 2019 09:24:49.038 [2736] DEBUG - MyPlex: Last published value didn't change, we're done.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
May 08, 2019 09:24:49.194 [6984] DEBUG - Activity: registered new activity 05ad5dda-a2cf-4d86-849d-3ed08ed3d7a7 - Processing subscriptions
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: Scheduling subscriptions.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: Initialized 0 schedulers.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: Scheduled all subscriptions in 0 ms.
May 08, 2019 09:24:49.194 [6984] DEBUG - Activity: Ended activity 05ad5dda-a2cf-4d86-849d-3ed08ed3d7a7.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
May 08, 2019 09:24:49.194 [6984] DEBUG - Activity: Ended activity 215c94d3-de88-464a-8100-bae17e5b924b.
May 08, 2019 09:24:49.194 [6984] DEBUG - DVR:Subscription: Refreshed all content in 1 ms.
May 08, 2019 09:24:49.882 [12472] WARN - Connection Tester: Error testing media server Lenovo-Laptop in 9994 ms.
May 08, 2019 09:24:49.882 [7336] DEBUG - Media Server: Tested all servers in 10.0 seconds.
May 08, 2019 09:24:51.381 [2636] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.32:32469/DeviceDescription.xml
May 08, 2019 09:24:51.381 [2636] DEBUG - HTTP requesting GET http://192.168.0.32:32469/DeviceDescription.xml
May 08, 2019 09:24:51.381 [2636] DEBUG - HTTP 200 response from GET http://192.168.0.32:32469/DeviceDescription.xml
May 08, 2019 09:24:51.381 [2636] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.32:32469/DeviceDescription.xml
May 08, 2019 09:24:51.381 [2636] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.32 (Plex Media Server: Lenovo-Desktop)
May 08, 2019 09:26:32.838 [12444] DEBUG - BPQ: [Starting] -> [Processing]
May 08, 2019 09:26:32.853 [12444] DEBUG - BPQ: generating queue items from 0 generator(s)
May 08, 2019 09:26:32.869 [12444] DEBUG - BPQ: generated 0 item(s) for queue
May 08, 2019 09:26:32.869 [12444] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
May 08, 2019 09:26:32.869 [12444] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
May 08, 2019 09:26:32.869 [12444] DEBUG - BPQ: [Processing] -> [Idle]
May 08, 2019 09:26:38.149 [10584] DEBUG - Request: [127.0.0.1:50121 (Loopback)] GET /servers (3 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:26:38.149 [7180] DEBUG - Completed: [127.0.0.1:50121] 200 GET /servers (3 live) GZIP 0ms 497 bytes
May 08, 2019 09:26:38.149 [10584] DEBUG - Request: [127.0.0.1:50122 (Loopback)] GET /library/sections (4 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:26:38.149 [7180] DEBUG - Completed: [127.0.0.1:50122] 200 GET /library/sections (4 live) GZIP 1ms 926 bytes
May 08, 2019 09:26:38.149 [8888] DEBUG - Request: [127.0.0.1:50123 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:26:38.149 [7180] DEBUG - Completed: [127.0.0.1:50123] 200 GET /channels/all (5 live) GZIP 0ms 387 bytes
May 08, 2019 09:26:38.149 [10584] DEBUG - Request: [127.0.0.1:50124 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:26:38.149 [7180] DEBUG - Completed: [127.0.0.1:50124] 200 GET /library/sections (6 live) GZIP 1ms 926 bytes
May 08, 2019 09:26:38.165 [8888] DEBUG - Request: [127.0.0.1:50125 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (richard49)
May 08, 2019 09:26:38.165 [7180] DEBUG - Completed: [127.0.0.1:50125] 200 GET /channels/all (7 live) GZIP 0ms 387 bytes
May 08, 2019 09:29:38.316 [6984] DEBUG - Butler: we're in the window, starting.
May 08, 2019 09:29:38.316 [6984] DEBUG - Butler: Waking up!
May 08, 2019 09:29:38.316 [6984] DEBUG - Butler: Scheduling randomized task 'RefreshEpgGuides' in 25 minutes.
May 08, 2019 09:29:38.316 [6984] DEBUG - Butler: Scheduling randomized task 'RefreshPeriodicMetadata' in 199 minutes.
May 08, 2019 09:29:38.316 [6984] DEBUG - Butler: Scheduling randomized task 'ReverseGeocode' in 18 minutes.
May 08, 2019 09:29:38.316 [2112] DEBUG - Activity: registered new activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - Database
May 08, 2019 09:29:39.221 [2112] DEBUG - Beginning database backup
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 0.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 1.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 2.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 3.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 4.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 5.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 6.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 7.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 8.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 9.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 10.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 11.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 12.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 13.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 14.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 15.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 16.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 17.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 18.
May 08, 2019 09:29:39.221 [2112] DEBUG - Captured session 19.
May 08, 2019 09:29:39.221 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 1% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 3% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 4% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 6% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 7% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 9% - Database
May 08, 2019 09:29:39.237 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 10% - Database
May 08, 2019 09:29:39.253 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 12% - Database
May 08, 2019 09:29:39.253 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 13% - Database
May 08, 2019 09:29:39.253 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 15% - Database
May 08, 2019 09:29:39.253 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 16% - Database
May 08, 2019 09:29:39.253 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 18% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 19% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 21% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 22% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 24% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 25% - Database
May 08, 2019 09:29:39.268 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 27% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 28% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 30% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 31% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 33% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 34% - Database
May 08, 2019 09:29:39.284 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 36% - Database
May 08, 2019 09:29:39.299 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 37% - Database
May 08, 2019 09:29:39.299 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 39% - Database
May 08, 2019 09:29:39.299 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 40% - Database
May 08, 2019 09:29:39.299 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 42% - Database
May 08, 2019 09:29:39.299 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 43% - Database
May 08, 2019 09:29:39.315 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 45% - Database
May 08, 2019 09:29:39.315 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 46% - Database
May 08, 2019 09:29:39.315 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 48% - Database
May 08, 2019 09:29:39.315 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 49% - Database
May 08, 2019 09:29:39.315 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 51% - Database
May 08, 2019 09:29:39.331 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 52% - Database
May 08, 2019 09:29:39.331 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 54% - Database
May 08, 2019 09:29:39.331 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 55% - Database
May 08, 2019 09:29:39.331 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 57% - Database
May 08, 2019 09:29:39.331 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 58% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 60% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 61% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 63% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 64% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 66% - Database
May 08, 2019 09:29:39.346 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 67% - Database
May 08, 2019 09:29:39.362 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 69% - Database
May 08, 2019 09:29:39.362 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 70% - Database
May 08, 2019 09:29:39.362 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 72% - Database
May 08, 2019 09:29:39.362 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 73% - Database
May 08, 2019 09:29:39.362 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 75% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 76% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 78% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 79% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 81% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 82% - Database
May 08, 2019 09:29:39.378 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 84% - Database
May 08, 2019 09:29:39.393 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 85% - Database
May 08, 2019 09:29:39.393 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 87% - Database
May 08, 2019 09:29:39.393 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 88% - Database
May 08, 2019 09:29:39.393 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 90% - Database
May 08, 2019 09:29:39.393 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 91% - Database
May 08, 2019 09:29:39.409 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 93% - Database
May 08, 2019 09:29:39.409 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 94% - Database
May 08, 2019 09:29:39.409 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 96% - Database
May 08, 2019 09:29:39.409 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 97% - Database
May 08, 2019 09:29:39.409 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 99% - Database
May 08, 2019 09:29:39.565 [2112] DEBUG - Activity: updated activity d3aae3c3-5632-4910-8dc1-326bf52ee2af - completed 100% - Database
May 08, 2019 09:29:39.565 [2112] DEBUG - Database backup completed: 0
May 08, 2019 09:29:39.565 [2112] DEBUG - Activity: Ended activity d3aae3c3-5632-4910-8dc1-326bf52ee2af.
May 08, 2019 09:29:39.565 [2112] DEBUG - Butler: backed up your database
May 08, 2019 09:29:39.565 [2112] DEBUG - Activity: registered new activity 7d969502-c138-4d31-bdef-2d79e5438662 - Database
May 08, 2019 09:29:39.659 [2112] DEBUG - Beginning database backup
May 08, 2019 09:29:39.659 [2112] DEBUG - Captured session 0.
May 08, 2019 09:29:39.659 [2112] DEBUG - Captured session 1.
May 08, 2019 09:29:39.659 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 11% - Database
May 08, 2019 09:29:39.659 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 23% - Database
May 08, 2019 09:29:39.659 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 34% - Database
May 08, 2019 09:29:39.674 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 46% - Database
May 08, 2019 09:29:39.674 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 57% - Database
May 08, 2019 09:29:39.674 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 69% - Database
May 08, 2019 09:29:39.674 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 80% - Database
May 08, 2019 09:29:39.690 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 92% - Database
May 08, 2019 09:29:39.721 [2112] DEBUG - Activity: updated activity 7d969502-c138-4d31-bdef-2d79e5438662 - completed 100% - Database
May 08, 2019 09:29:39.721 [2112] DEBUG - Database backup completed: 0
May 08, 2019 09:29:39.721 [2112] DEBUG - Activity: Ended activity 7d969502-c138-4d31-bdef-2d79e5438662.
May 08, 2019 09:29:39.721 [2112] DEBUG - Butler: backed up your database
May 08, 2019 09:29:39.784 [2112] DEBUG - It took 0.000000 ms to retrieve 103 items.
May 08, 2019 09:29:39.971 [2112] DEBUG - It took 156.250000 ms to retrieve 838 items.
May 08, 2019 09:29:40.174 [2112] DEBUG - Butler: Computed a set of 0 albums to get loudness analysis in 0ms.
May 08, 2019 09:29:41.690 [2112] DEBUG - Have a missing thumbnail, attempting to regenerate
May 08, 2019 09:29:46.205 [2112] DEBUG - The butler analyzed 0 items.
May 08, 2019 09:29:46.206 [2112] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe --generate --chapter-thumbs-only --item 14979 --log-file-suffix " Chapter Thumbnails" 
May 08, 2019 09:29:46.303 [2112] DEBUG - Jobs: Starting child process with pid 15336
May 08, 2019 09:29:46.865 [12992] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 15336 is 0 (success)
May 08, 2019 09:29:46.866 [2112] DEBUG - The butler generated chapter thumbnails for 2 files
May 08, 2019 09:29:46.867 [2112] DEBUG - The butler performed loudness analysis on 0 albums.
May 08, 2019 09:29:46.867 [2112] DEBUG - The butler processed 0 files for autotag generation
May 08, 2019 09:29:46.868 [2112] DEBUG - Butler: Scheduling deep analysis for: 1:23:45
May 08, 2019 09:29:46.869 [2112] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe --analyze-deeply --item 17897 --log-file-suffix " Deep Analysis" 
May 08, 2019 09:29:46.876 [2112] DEBUG - Jobs: Starting child process with pid 14324
May 08, 2019 09:29:47.044 [12992] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 14324 is 0 (success)
May 08, 2019 09:29:47.044 [2112] DEBUG - The butler performed deep analysis on 1 files
May 08, 2019 09:29:47.044 [2112] DEBUG - Butler: refreshed 0 items
May 08, 2019 09:47:38.321 [6984] DEBUG - Butler: Starting delayed task ReverseGeocode.
May 08, 2019 09:48:29.624 [2112] DEBUG - The butler processed 0 files for reverse geocoding
