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

Apr 16, 2019 15:41:47.846 [8316] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\mike1\AppData\Local\Plex Media Server\Crash Reports\1.15.3.876-ad6e39743" --version=1.15.3.876-ad6e39743 --platform=Windows "--platformVersion=10.0 (Build 17134)" --serverUuid=9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f --userId=mike1825@gmail.com --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx --vendor=Microsoft --model=x64 --device=PC 
Apr 16, 2019 15:41:47.877 [8316] DEBUG - Jobs: Starting child process with pid 2348
Apr 16, 2019 15:41:48.933 [8280] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 2348 is 0 (success)
Apr 16, 2019 15:41:49.080 [8284] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:49.118 [8792] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:41:49.134 [8792] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:41:49.134 [8272] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:49.597 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:49.597 [8272] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:49.967 [8292] DEBUG - Reading 486 bytes in the body, code is 200
Apr 16, 2019 15:41:49.967 [8292] DEBUG - [com.plexapp.system] Plug-in running on port 50265.
Apr 16, 2019 15:41:49.967 [8292] DEBUG -  * Plug-in handles prefix: /system
Apr 16, 2019 15:41:49.967 [8292] DEBUG -  * Plug-in handles prefix: /player
Apr 16, 2019 15:41:49.967 [8292] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Apr 16, 2019 15:41:49.967 [8292] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Apr 16, 2019 15:41:49.967 [8292] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Apr 16, 2019 15:41:49.967 [8292] DEBUG - Updating the list of agents known by the system.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - Image transcode cache directory: "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Apr 16, 2019 15:41:49.967 [8292] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Apr 16, 2019 15:41:49.967 [8292] DEBUG - HTTP requesting GET http://127.0.0.1:50265/system/agents
Apr 16, 2019 15:41:49.967 [9324] DEBUG - Transcoder: Cleaning old transcode directories.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:41:49.967 [9324] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:41:49.967 [9324] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:41:49.967 [9324] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:41:49.967 [9324] DEBUG - PublicAddressManager: Starting.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:41:49.967 [7796] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 16, 2019 15:41:49.967 [7796] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:41:49.967 [10416] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkInterface: ignoring adapter 'VirtualBox Host-Only Network' (fe80::f87e:1ec7:c34a:e7ac%8,192.168.56.1) because it has no gateway
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 2' (fe80::4c1a:3bb9:a51:9d8e%11,169.254.157.142) because it has no gateway
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 3' (fe80::d02f:97da:4cdd:c245%9,169.254.194.69) because it has no gateway
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Apr 16, 2019 15:41:49.967 [9324] DEBUG - Detected primary interface: 192.168.86.123
Apr 16, 2019 15:41:49.967 [9324] DEBUG - Network interfaces:
Apr 16, 2019 15:41:49.967 [9324] DEBUG -  * 16 Ethernet {E0A6A9D7-FB75-4B7B-963E-67CD17BA53CB} (192.168.86.123) (loopback: 0)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - Creating NetworkServices singleton.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkServices: Initializing...
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.967 [8300] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:41:49.967 [10432] DEBUG - Network change for advertiser.
Apr 16, 2019 15:41:49.967 [10432] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Apr 16, 2019 15:41:49.967 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.982 [9324] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:41:49.982 [9324] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:41:49.982 [9324] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - Network change for advertiser.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - Network change for advertiser.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Apr 16, 2019 15:41:49.982 [10432] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 239.255.255.250 (index: 0)
Apr 16, 2019 15:41:50.070 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.090 [9324] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
Apr 16, 2019 15:41:50.090 [9324] DEBUG - Gracenote: Using SQLite 3.26.0
Apr 16, 2019 15:41:50.090 [8792] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.092 [9324] DEBUG - Starting HTTP server.
Apr 16, 2019 15:41:50.092 [9324] DEBUG - Running server...
Apr 16, 2019 15:41:50.092 [9324] DEBUG - Waiting for server condition to be signaled.
Apr 16, 2019 15:41:50.092 [10448] DEBUG - Plex Tuner Service.exe starting.
Apr 16, 2019 15:41:50.092 [10448] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\Plex Tuner Service.exe "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Private" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Tuner\Shared" 1.15.3.876-ad6e39743 32600 /waitmutex 
Apr 16, 2019 15:41:50.092 [10452] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Apr 16, 2019 15:41:50.092 [10452] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Request: [127.0.0.1:50259 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.092 [10460] DEBUG - Request: [127.0.0.1:50258 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale ar
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale cs_CZ
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale da
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale de
Apr 16, 2019 15:41:50.092 [10468] DEBUG - Request: [127.0.0.1:50264 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.092 [10472] DEBUG - Request: [127.0.0.1:50268 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale en
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale es
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale et_EE
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale fr
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale he
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale hr_HR
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale hu_HU
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale it
Apr 16, 2019 15:41:50.092 [10448] DEBUG - Jobs: Starting child process with pid 10476
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale ja_JP
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale ko
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale lt
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale nl
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale nl_BE
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale no
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale pt_BR
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale ro
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale ru
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale sr
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale sv
Apr 16, 2019 15:41:50.092 [10456] DEBUG - Translator: Found locale zh_CN
Apr 16, 2019 15:41:50.123 [10460] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:50.123 [10460] DEBUG - HTTP requesting GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:50.123 [7876] DEBUG - Completed: [127.0.0.1:50268] 200 GET /servers (5 live) GZIP 32ms 492 bytes
Apr 16, 2019 15:41:50.123 [7876] DEBUG - Completed: [127.0.0.1:50264] 200 GET / (5 live) GZIP 33ms 2961 bytes
Apr 16, 2019 15:41:50.131 [7608] DEBUG - Completed: [127.0.0.1:50259] 200 GET / (5 live) GZIP 35ms 2961 bytes
Apr 16, 2019 15:41:50.131 [10472] DEBUG - Request: [127.0.0.1:50275 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.131 [7876] DEBUG - Completed: [127.0.0.1:50275] 200 GET / (5 live) GZIP 2ms 2961 bytes
Apr 16, 2019 15:41:50.131 [10456] DEBUG - Request: [127.0.0.1:50276 (Loopback)] GET / (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.131 [7876] DEBUG - Completed: [127.0.0.1:50276] 200 GET / (7 live) GZIP 2ms 2961 bytes
Apr 16, 2019 15:41:50.141 [10468] DEBUG - Request: [127.0.0.1:50277 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.143 [7876] DEBUG - Completed: [127.0.0.1:50277] 200 GET /library/sections (6 live) GZIP 2ms 1013 bytes
Apr 16, 2019 15:41:50.143 [10472] DEBUG - Request: [127.0.0.1:50278 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.143 [7608] DEBUG - Completed: [127.0.0.1:50278] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:41:50.163 [10456] DEBUG - Request: [127.0.0.1:50279 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.171 [7876] DEBUG - Completed: [127.0.0.1:50279] 200 GET /library/sections (6 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:41:50.181 [10468] DEBUG - Request: [127.0.0.1:50280 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:50.181 [7608] DEBUG - Completed: [127.0.0.1:50280] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:41:50.183 [7796] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:41:50.183 [7796] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:41:50.550 [10452] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.566 [10452] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.566 [8792] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.566 [8792] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.588 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:41:50.588 [10416] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:41:50.588 [10416] DEBUG - HTTP requesting GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:41:50.619 [8292] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/system/agents
Apr 16, 2019 15:41:50.619 [8292] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Apr 16, 2019 15:41:50.635 [8292] DEBUG - The system knows about 13 agents.
Apr 16, 2019 15:41:50.635 [8292] DEBUG - Waiting for 18 plug-ins to finish starting.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Framework.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Fanart-TV.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\HTbackdrops.bundle.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LastFM.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LocalMedia.bundle.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LyricFind.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Media-Flags.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\MoviePosterDB.bundle.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\OpenSubtitles.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PersonalMedia.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMovie.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMusic.bundle.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexThemeMusic.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Scanners.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheMovieDB.bundle.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheTVDB.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle.
Apr 16, 2019 15:41:50.635 [10652] DEBUG - Starting plug-in C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Apr 16, 2019 15:41:50.635 [10644] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Apr 16, 2019 15:41:50.635 [10648] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Apr 16, 2019 15:41:50.635 [8292] DEBUG - Starting scheduled updates, every 3600 seconds
Apr 16, 2019 15:41:50.635 [8292] INFO - Refreshing paths watched by LibraryUpdateManager
Apr 16, 2019 15:41:50.635 [8292] DEBUG - LibraryUpdateManager will start watching D:\Movies
Apr 16, 2019 15:41:50.635 [8292] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Music
Apr 16, 2019 15:41:50.635 [8292] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Pictures
Apr 16, 2019 15:41:50.635 [8292] DEBUG - LibraryUpdateManager will start watching D:\Shows
Apr 16, 2019 15:41:50.635 [8292] DEBUG - Done waiting for all plug-ins to start.
Apr 16, 2019 15:41:50.635 [8292] DEBUG - PluginRepository::setStartState: 3, complete
Apr 16, 2019 15:41:50.635 [10656] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:41:50.635 [10656] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Apr 16, 2019 15:41:50.635 [10460] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:50.635 [10460] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:41:50.635 [10656] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files (x86)\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMovie.bundle"
Apr 16, 2019 15:41:50.651 [7876] DEBUG - Completed: [127.0.0.1:50258] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 557ms 19473 bytes
Apr 16, 2019 15:41:50.651 [10656] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 10672
Apr 16, 2019 15:41:50.651 [10656] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Apr 16, 2019 15:41:50.673 [8316] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Apr 16, 2019 15:41:50.789 [8792] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:50.789 [8792] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.036 [10452] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.036 [10452] DEBUG - Running database fix-ups.
Apr 16, 2019 15:41:51.052 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:41:51.052 [10416] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Database Fixups: Destroyed 0 transient items.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Found 0 collections tags in section 1.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Synced tags in section 1 in 2ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Found 0 collections tags in section 2.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Synced tags in section 2 in 0ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Apr 16, 2019 15:41:51.052 [10452] DEBUG - Running database fix-ups completed in 0.0 seconds.
Apr 16, 2019 15:41:51.052 [10760] DEBUG - Versions: updating status for generator 468
Apr 16, 2019 15:41:51.052 [10452] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.052 [10760] DEBUG - BPQ: generator 468 is in complete state
Apr 16, 2019 15:41:51.052 [10760] DEBUG - Versions: updating status for generator 470
Apr 16, 2019 15:41:51.052 [10760] DEBUG - BPQ: generator 470 is in complete state
Apr 16, 2019 15:41:51.067 [10760] DEBUG - Versions: updating status for generator 471
Apr 16, 2019 15:41:51.067 [10760] DEBUG - BPQ: generator 471 is in complete state
Apr 16, 2019 15:41:51.368 [8792] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.374 [10472] DEBUG - Request: [127.0.0.1:50289 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:51.374 [10472] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:41:51.374 [10472] DEBUG - HTTP requesting GET http://127.0.0.1:50265/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:41:51.390 [10472] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:41:51.390 [10472] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:41:51.390 [7876] DEBUG - Completed: [127.0.0.1:50289] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (6 live) GZIP 26ms 330 bytes
Apr 16, 2019 15:41:51.453 [10456] DEBUG - Request: [127.0.0.1:50291 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:51.453 [10456] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:41:51.453 [10456] DEBUG - HTTP requesting GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:41:51.506 [10452] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.506 [8320] DEBUG - DVR: Starting up.
Apr 16, 2019 15:41:51.522 [10456] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:41:51.522 [10456] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Apr 16, 2019 15:41:51.522 [7608] DEBUG - Completed: [127.0.0.1:50291] 200 GET /:/plugins/com.plexapp.system/resourceHashes (6 live) GZIP 64ms 3498 bytes
Apr 16, 2019 15:41:51.522 [8320] DEBUG - EPG[onconnect]: Starting.
Apr 16, 2019 15:41:51.522 [8320] DEBUG - Opening 10 database sessions to library (tv.plex.providers.epg.onconnect:2), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:41:51.522 [10452] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:41:51.537 [8320] INFO - SQLITE3:0x85126f5a, 283, recovered 206 frames from WAL file C:\Users\mike1\AppData\Local\Plex Media Server\Plug-in Support\Databases\tv.plex.providers.epg.onconnect-c866a282-3a3b-443b-aeeb-c95a100b1390.db-wal
Apr 16, 2019 15:41:51.537 [10456] DEBUG - Request: [127.0.0.1:50294 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:51.537 [10460] DEBUG - Request: [127.0.0.1:50295 (Loopback)] GET / (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:51.537 [10456] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:51.537 [10456] DEBUG - HTTP requesting GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:51.537 [7876] DEBUG - Completed: [127.0.0.1:50295] 200 GET / (8 live) GZIP 1ms 2961 bytes
Apr 16, 2019 15:41:51.553 [10456] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:41:51.553 [10456] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:41:51.553 [8320] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:41:51.553 [8320] DEBUG - Media Provider: Registering provider tv.plex.providers.epg.onconnect:2
Apr 16, 2019 15:41:51.553 [8272] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Apr 16, 2019 15:41:51.553 [8320] DEBUG - Activity: registered new activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - Refreshing guide data
Apr 16, 2019 15:41:51.553 [8320] DEBUG - Activity: updated activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - completed 0% - Refreshing guide data
Apr 16, 2019 15:41:51.553 [7876] DEBUG - Completed: [127.0.0.1:50294] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (8 live) GZIP 19ms 19473 bytes
Apr 16, 2019 15:41:51.553 [8792] DEBUG - EPG[onconnect]: starting incremental refresh for lineup lineup://tv.plex.providers.epg.onconnect/USA-OTA46074#Local%20Over%20the%20Air%20Broadcast
Apr 16, 2019 15:41:51.553 [8320] DEBUG - EPG[onconnect]: Scheduling next refresh for 2019-04-16 19:41:51.
Apr 16, 2019 15:41:51.553 [8792] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:41:51.553 [8320] DEBUG - Activity: registered new activity 87879e5c-bcec-4db8-a1f0-fc2ed4f8db80 - Processing subscriptions
Apr 16, 2019 15:41:51.553 [8284] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.569 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.575 [10416] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:51.606 [8316] DEBUG - EPG[onconnect]: Next thing to start/end is at 2019-04-16 19:58:00 GMT (in 970 seconds)
Apr 16, 2019 15:41:51.675 [8792] DEBUG - EPG[onconnect]: Matched channel 6.1 (ABC) with grabber channel, adding.
Apr 16, 2019 15:41:51.675 [8792] DEBUG - EPG[onconnect]: Matched channel 8.1 (CW) with grabber channel, adding.
Apr 16, 2019 15:41:51.691 [8792] DEBUG - EPG[onconnect]: Matched channel 13.1 (NBC) with grabber channel, adding.
Apr 16, 2019 15:41:51.738 [8792] DEBUG - EPG[onconnect]: Matched channel 13.3 (METVN) with grabber channel, adding.
Apr 16, 2019 15:41:51.753 [8792] DEBUG - EPG[onconnect]: Matched channel 18.4 (GETTV) with grabber channel, adding.
Apr 16, 2019 15:41:51.753 [8792] DEBUG - EPG[onconnect]: Matched channel 19.1 (TELE) with grabber channel, adding.
Apr 16, 2019 15:41:51.791 [8792] DEBUG - EPG[onconnect]: Matched channel 29.1 (CBS) with grabber channel, adding.
Apr 16, 2019 15:41:51.822 [8792] DEBUG - EPG[onconnect]: Matched channel 29.2 (Independent) with grabber channel, adding.
Apr 16, 2019 15:41:51.822 [8792] DEBUG - EPG[onconnect]: Matched channel 30.1 (QUESTSD) with grabber channel, adding.
Apr 16, 2019 15:41:51.822 [8792] DEBUG - EPG[onconnect]: Matched channel 30.4 (CBNNEWS) with grabber channel, adding.
Apr 16, 2019 15:41:51.838 [8792] DEBUG - EPG[onconnect]: Matched channel 30.5 (Independent) with grabber channel, adding.
Apr 16, 2019 15:41:51.854 [8792] DEBUG - EPG[onconnect]: Matched channel 40.1 (Independent) with grabber channel, adding.
Apr 16, 2019 15:41:51.876 [8792] DEBUG - EPG[onconnect]: Matched channel 49.1 (PBS) with grabber channel, adding.
Apr 16, 2019 15:41:51.876 [8792] DEBUG - EPG[onconnect]: Matched channel 49.2 (CREATE) with grabber channel, adding.
Apr 16, 2019 15:41:51.876 [8792] DEBUG - EPG[onconnect]: Matched channel 49.3 (Independent) with grabber channel, adding.
Apr 16, 2019 15:41:51.876 [8792] DEBUG - EPG[onconnect]: Matched channel 59.1 (FOX) with grabber channel, adding.
Apr 16, 2019 15:41:51.907 [8792] DEBUG - EPG[onconnect]: Matched channel 63.4 (Independent) with grabber channel, adding.
Apr 16, 2019 15:41:51.907 [8792] DEBUG - EPG[onconnect]: Matched channel 63.5 (QVC) with grabber channel, adding.
Apr 16, 2019 15:41:51.907 [8792] DEBUG - EPG[onconnect]: Matched channel 63.6 (HSN) with grabber channel, adding.
Apr 16, 2019 15:41:52.023 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.023 [10416] DEBUG - Sync: onMyPlexSignIn
Apr 16, 2019 15:41:52.023 [10416] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.023 [8284] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.092 [10468] DEBUG - Request: [127.0.0.1:50302 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:52.092 [10468] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:41:52.092 [10468] DEBUG - HTTP requesting GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:41:52.108 [10468] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:41:52.108 [10468] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Apr 16, 2019 15:41:52.108 [7608] DEBUG - Completed: [127.0.0.1:50302] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (8 live) GZIP 14ms 465 bytes
Apr 16, 2019 15:41:52.123 [10656] DEBUG - Reading 148 bytes in the body, code is 200
Apr 16, 2019 15:41:52.123 [10656] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 50304.
Apr 16, 2019 15:41:52.123 [10656] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Apr 16, 2019 15:41:52.123 [10656] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:41:52.123 [10656] DEBUG - HTTP requesting GET http://127.0.0.1:50304/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:41:52.155 [10656] DEBUG - HTTP 200 response from GET http://127.0.0.1:50304/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:41:52.155 [10656] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Apr 16, 2019 15:41:52.155 [10656] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 07b9db54ed88186ae435dd3081e43ec04912506e
Apr 16, 2019 15:41:52.155 [10656] DEBUG - IVA: Downloading trailer lists.
Apr 16, 2019 15:41:52.270 [10656] DEBUG - IVA: Downloaded 41 trailers for source 1.
Apr 16, 2019 15:41:52.377 [10656] DEBUG - IVA: Downloaded 55 trailers for source 2.
Apr 16, 2019 15:41:52.478 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.493 [10416] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Apr 16, 2019 15:41:52.493 [10416] DEBUG - Sync: synchronizing 0 sync lists to generators
Apr 16, 2019 15:41:52.493 [10416] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Apr 16, 2019 15:41:52.493 [10760] DEBUG - BPQ: garbageCollect - sync - forced - 10
Apr 16, 2019 15:41:52.493 [10416] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Apr 16, 2019 15:41:52.493 [10760] DEBUG - Sync: starting Sync garbage collection: 10, forced
Apr 16, 2019 15:41:52.493 [10760] DEBUG - Sync: garbage collecting filesystem
Apr 16, 2019 15:41:52.493 [10760] WARN - Sync: local sync directory "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
Apr 16, 2019 15:41:52.493 [10760] DEBUG - Sync: garbage collected filesystem
Apr 16, 2019 15:41:52.493 [10760] DEBUG - Sync: finished Sync garbage collection: 10
Apr 16, 2019 15:41:52.493 [10416] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.926 [10416] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.926 [8272] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.926 [10840] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.926 [10416] DEBUG - EventSource: Resolving 173.230.131.250 port 443
Apr 16, 2019 15:41:52.926 [8316] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.941 [7876] DEBUG - EventSource: Resolved 173.230.131.250 to 173.230.131.250
Apr 16, 2019 15:41:52.941 [10416] DEBUG - MyPlex: Updating device connections (from timer: 0)
Apr 16, 2019 15:41:52.941 [10416] DEBUG - HTTP requesting PUT https://plex.tv/devices/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f?Connection[][uri]=http://192.168.86.123:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:52.941 [10908] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=0)
Apr 16, 2019 15:41:52.941 [10908] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=f46733f8-55c0-4619-b1ab-a481bd5b6346
Apr 16, 2019 15:41:52.979 [7876] DEBUG - EventSource: Connected in 32 ms.
Apr 16, 2019 15:41:52.979 [7608] DEBUG - EventSource: Wrote data, reading reply.
Apr 16, 2019 15:41:53.041 [8300] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:41:53.041 [8300] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:41:53.041 [8792] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:41:53.041 [8300] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:41:53.041 [8300] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:41:53.057 [8792] DEBUG - Activity: updated activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - completed 1% - Refreshing guide data
Apr 16, 2019 15:41:53.088 [8792] DEBUG - EPG[onconnect]: Step 1/84 (2019-04-16T16:00Z) (network: 1.4 sec, database: 1.4 sec total: 1.5 sec)
Apr 16, 2019 15:41:53.088 [8792] DEBUG - Activity: updated activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - completed 1% - Refreshing guide data
Apr 16, 2019 15:41:53.260 [7796] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:41:53.260 [7796] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:41:53.260 [7796] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:41:53.307 [8792] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:41:53.338 [8792] DEBUG - Activity: updated activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - completed 2% - Refreshing guide data
Apr 16, 2019 15:41:53.354 [8792] DEBUG - EPG: Found a dupe show 'Have Thinning Hair? Keranique Can Help Regrow Beautiful, Thicker Hair!' with a different GUID.
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 2/84 (2019-04-16T20:00Z) (network: 1.6 sec, database: 1.6 sec total: 1.8 sec)
Apr 16, 2019 15:41:53.370 [8792] DEBUG - Activity: updated activity 89e7cd33-877e-4269-a5ac-1d632c253b6f - completed 2% - Refreshing guide data
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 3/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 4/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 5/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 6/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 7/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 8/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 9/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 10/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 11/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 12/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 13/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 14/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 15/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 16/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 17/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 18/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 19/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 20/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 21/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 22/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 23/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 24/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 25/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 26/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 27/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 28/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 29/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 30/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 31/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 32/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 33/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 34/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 35/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 36/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 37/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 38/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 39/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 40/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 41/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 42/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 43/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 44/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 45/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 46/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 47/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.370 [8792] DEBUG - EPG[onconnect]: Step 48/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 49/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 50/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 51/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 52/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 53/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 54/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 55/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 56/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 57/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 58/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 59/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 60/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 61/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 62/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 63/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 64/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 65/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 66/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 67/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 68/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 69/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 70/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 71/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 72/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 73/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 74/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 75/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 76/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 77/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 78/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 79/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 80/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 81/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 82/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 83/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.385 [8792] DEBUG - EPG[onconnect]: Step 84/84: skipping chunk for incremental loading
Apr 16, 2019 15:41:53.401 [10416] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f?Connection[][uri]=http://192.168.86.123:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:53.401 [8300] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:41:53.416 [10416] DEBUG - CERT: Certificate will not expire soon.
Apr 16, 2019 15:41:53.416 [8316] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:53.416 [8316] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Apr 16, 2019 15:41:53.416 [7876] DEBUG - EventSource: Read HTTP reply header.
Apr 16, 2019 15:41:53.416 [7876] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Apr 16, 2019 15:41:53.416 [7876] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:41:53.416 [8300] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:41:53.416 [8316] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:41:53.416 [7876] DEBUG - EventSource: Successfully connected to 173.230.131.250.
Apr 16, 2019 15:41:53.416 [8300] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:41:53.432 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:53.448 [10908] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=f46733f8-55c0-4619-b1ab-a481bd5b6346
Apr 16, 2019 15:41:53.448 [10908] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:41:53.448 [10908] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:41:53.448 [7796] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=20877)
Apr 16, 2019 15:41:53.463 [7796] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=55a5fe24-a089-4a68-86ef-558279ee8866
Apr 16, 2019 15:41:53.604 [10840] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:41:53.635 [8300] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:41:53.635 [8300] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:41:53.807 [8284] DEBUG - Database analyze: starting analyze (all tables)
Apr 16, 2019 15:41:53.807 [8792] DEBUG - EPG[onconnect]: Total time to load EPG was 2.2 (HTTP details cached 100.0%, CloudFlare grid cached: 100.0%, 0 HTTP errors)
Apr 16, 2019 15:41:53.807 [8792] DEBUG - Activity: Ended activity 89e7cd33-877e-4269-a5ac-1d632c253b6f.
Apr 16, 2019 15:41:53.807 [8792] DEBUG - Activity: registered new activity ac835d67-3dc4-48c6-9989-aeef8b6ae767 - Processing subscriptions
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - Activity: registered new activity 6f96f279-7cbf-4aaa-823f-9f3595991a6c - Processing subscriptions
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - Activity: Ended activity 6f96f279-7cbf-4aaa-823f-9f3595991a6c.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - Activity: Ended activity ac835d67-3dc4-48c6-9989-aeef8b6ae767.
Apr 16, 2019 15:41:53.807 [8272] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Apr 16, 2019 15:41:53.916 [8284] DEBUG - Database analyze: analyze complete
Apr 16, 2019 15:41:53.948 [7796] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=55a5fe24-a089-4a68-86ef-558279ee8866
Apr 16, 2019 15:41:53.948 [7796] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:41:53.948 [7796] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:41:53.963 [7796] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:41:54.291 [10456] DEBUG - Request: [63.34.171.72:40860 (WAN)] GET /identity (7 live) TLS Signed-in Token (Mike1825)
Apr 16, 2019 15:41:54.291 [7876] DEBUG - Completed: [63.34.171.72:40860] 200 GET /identity (7 live) TLS 1ms 385 bytes (pipelined: 1)
Apr 16, 2019 15:41:54.541 [7608] DEBUG - EventSource: Got event [data] '<Message address="199.66.67.126" port="20877" asyncIdentifier="55a5fe24-a089-4a68-86ef-558279ee8866" connectivity="1" command="notifyConnectivity"/>'
Apr 16, 2019 15:41:54.541 [7608] DEBUG - PubSub: Got notified of reachability for async identifier 55a5fe24-a089-4a68-86ef-558279ee8866: 1 for 199.66.67.126:20877 (responded in 1 seconds)
Apr 16, 2019 15:41:54.979 [10432] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.27
Apr 16, 2019 15:41:54.994 [10432] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.86.123 (Media-PC)
Apr 16, 2019 15:41:54.994 [10432] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:41:54.994 [10432] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:41:54.994 [10432] DEBUG - HTTP requesting GET http://127.0.0.1:50265/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:41:55.010 [10460] DEBUG - Request: [127.0.0.1:50342 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:55.010 [7608] DEBUG - Completed: [127.0.0.1:50342] 200 GET /servers (6 live) GZIP 0ms 492 bytes
Apr 16, 2019 15:41:55.010 [10468] DEBUG - Request: [127.0.0.1:50343 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:55.010 [7608] DEBUG - Completed: [127.0.0.1:50343] 200 GET /library/sections (7 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:41:55.010 [10456] DEBUG - Request: [127.0.0.1:50344 (Loopback)] GET /channels/all (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:41:55.010 [7608] DEBUG - Completed: [127.0.0.1:50344] 200 GET /channels/all (8 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:41:55.010 [10432] DEBUG - HTTP 200 response from GET http://127.0.0.1:50265/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:41:55.026 [10432] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:41:55.151 [10432] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.250
Apr 16, 2019 15:41:56.494 [8316] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:41:56.494 [8316] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:41:56.494 [8316] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:41:56.494 [8316] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:41:56.510 [8316] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:41:56.713 [8300] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:41:56.713 [8300] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:41:56.713 [8300] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:41:56.729 [8300] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:41:56.995 [8320] DEBUG - DVR:Device: Discovering and refreshing all devices.
Apr 16, 2019 15:41:56.995 [8320] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Apr 16, 2019 15:41:56.995 [8320] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:41:56.995 [8320] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:41:56.995 [8320] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:41:56.995 [8320] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:41:56.995 [8320] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:41:57.010 [8320] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:41:57.026 [8320] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:41:57.026 [8320] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:41:57.932 [8272] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:41:58.386 [8272] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 66.228.61.220 was 33 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 173.230.131.250 was 33 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 172.104.216.125 was 51 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 45.33.119.35 was 67 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 184.105.148.112 was 102 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 178.79.132.174 was 185 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 82.94.168.22 was 204 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 172.104.132.7 was 239 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 348 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Time to connect to 172.104.173.210 was 443 ms.
Apr 16, 2019 15:41:59.042 [8272] DEBUG - PubSubManager: Updating best ping time for 173.230.131.250 to 33 ms.
Apr 16, 2019 15:41:59.542 [8320] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:42:00.043 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:42:00.043 [10432] DEBUG - HTTP requesting GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:42:00.058 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:42:00.058 [10432] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:42:00.058 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (OnHub)
Apr 16, 2019 15:42:00.058 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANDevice)
Apr 16, 2019 15:42:00.058 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANConnectionDevice)
Apr 16, 2019 15:42:00.074 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:42:00.074 [10432] DEBUG - HTTP requesting GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:42:00.090 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:42:00.090 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:42:00.090 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.40 (Family Room)
Apr 16, 2019 15:42:00.281 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:00.281 [10432] DEBUG - HTTP requesting GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:00.281 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:00.281 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:00.281 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.50 (Guest Room)
Apr 16, 2019 15:42:00.419 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:42:00.419 [10432] DEBUG - HTTP requesting GET http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:42:00.419 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:42:00.419 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:42:00.419 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.150 (MIKE-PERSONAL: mike1825@gmail.com:)
Apr 16, 2019 15:42:00.466 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:42:00.466 [10432] DEBUG - HTTP requesting GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:42:00.513 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:42:00.513 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:42:00.513 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.39 (Home Theater Room)
Apr 16, 2019 15:42:00.654 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:42:00.654 [10432] DEBUG - HTTP requesting GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:42:00.669 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:42:00.669 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:42:00.669 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.125 (HDHomeRun DMS 104C05F1)
Apr 16, 2019 15:42:00.669 [8316] DEBUG - DVR:Device: Discovering and refreshing devices with identifier tv.plex.grabbers.hdhomerun
Apr 16, 2019 15:42:00.669 [8316] DEBUG - DVR:Grabber: HDHomerun discovered a model HDHR4-2US.
Apr 16, 2019 15:42:00.669 [8316] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:42:00.669 [8316] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:42:00.669 [8316] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:42:00.685 [8316] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:42:00.716 [8316] DEBUG - DVR:Grabber: HDHomerun discovered 1 compatible devices.
Apr 16, 2019 15:42:00.716 [8316] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:42:00.716 [8316] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:42:00.716 [8316] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:42:00.716 [8316] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:42:00.716 [8316] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:42:00.732 [8316] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:42:00.747 [8316] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:42:01.373 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.373 [10432] DEBUG - HTTP requesting GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.389 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.389 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.389 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.27 (SHIELD)
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - Activity: registered new activity 8232952d-45ff-4acb-84ff-79de2f070fe2 - Processing subscriptions
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - Activity: Ended activity 8232952d-45ff-4acb-84ff-79de2f070fe2.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - Activity: Ended activity 87879e5c-bcec-4db8-a1f0-fc2ed4f8db80.
Apr 16, 2019 15:42:01.561 [8320] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Apr 16, 2019 15:42:01.639 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:42:01.639 [10432] DEBUG - HTTP requesting GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:42:01.671 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:42:01.671 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:42:01.671 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.35 (Loft Fire TV)
Apr 16, 2019 15:42:01.702 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:42:01.702 [10432] DEBUG - HTTP requesting GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:42:01.749 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:42:01.749 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:42:01.749 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.53 (Guest Room)
Apr 16, 2019 15:42:01.764 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.764 [10432] DEBUG - HTTP requesting GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.764 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.764 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.764 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.33 (Bar)
Apr 16, 2019 15:42:01.890 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:42:01.890 [10432] DEBUG - HTTP requesting GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:42:01.936 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:42:01.936 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:42:01.936 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.250 (Mike's 3rd Fire TV)
Apr 16, 2019 15:42:01.936 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.936 [10432] DEBUG - HTTP requesting GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.968 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.968 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:01.968 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.38 (Master Bedroom)
Apr 16, 2019 15:42:02.093 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.093 [10432] DEBUG - HTTP requesting GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.108 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.108 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.108 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.233 (Master Bedroom Soundbar)
Apr 16, 2019 15:42:02.343 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.343 [10432] DEBUG - HTTP requesting GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.343 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.343 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:42:02.343 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.49 (Family Room Display)
Apr 16, 2019 15:42:10.846 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:10.846 [10432] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:10.892 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:10.892 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:10.892 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.49.1 (Mike's 2nd Fire TV)
Apr 16, 2019 15:42:32.133 [10432] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:32.133 [10432] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:32.180 [10432] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:32.180 [10432] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:42:32.180 [10432] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:42:44.117 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.117 [2088] DEBUG - Request: [192.168.86.150:33690 (Subnet)] GET /media/providers (3 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.117 [7876] DEBUG - Completed: [192.168.86.150:33690] 200 GET /media/providers (3 live) TLS GZIP 6ms 3675 bytes (pipelined: 1)
Apr 16, 2019 15:42:44.258 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.258 [2088] DEBUG - Request: [192.168.86.150:33692 (Subnet)] GET /:/websockets/notifications (4 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.258 [2088] DEBUG - WebSocket: Performing handshake from origin https://app.plex.tv
Apr 16, 2019 15:42:44.258 [2088] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:42:44.289 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.289 [8628] DEBUG - Request: [192.168.86.150:33690 (Subnet)] GET /:/prefs (4 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.289 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.289 [2088] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /status/sessions (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.289 [2088] DEBUG - [Now] Adding 0 sessions.
Apr 16, 2019 15:42:44.289 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.289 [2500] DEBUG - Request: [192.168.86.150:33694 (Subnet)] GET /updater/status (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.289 [7608] DEBUG - Completed: [192.168.86.150:33693] 200 GET /status/sessions (6 live) TLS GZIP 2ms 429 bytes (pipelined: 1)
Apr 16, 2019 15:42:44.289 [7608] DEBUG - Completed: [192.168.86.150:33694] 200 GET /updater/status (6 live) TLS GZIP 2ms 526 bytes (pipelined: 1)
Apr 16, 2019 15:42:44.305 [7608] DEBUG - Completed: [192.168.86.150:33690] 200 GET /:/prefs (6 live) TLS GZIP 14ms 7484 bytes (pipelined: 2)
Apr 16, 2019 15:42:44.320 [9232] DEBUG - Request: [192.168.86.150:33690 (Subnet)] OPTIONS /updater/check?download=0 (6 live) TLS GZIP Signed-in Token ()
Apr 16, 2019 15:42:44.320 [7608] DEBUG - Completed: [192.168.86.150:33690] 200 OPTIONS /updater/check?download=0 (6 live) TLS GZIP 1ms 349 bytes (pipelined: 3)
Apr 16, 2019 15:42:44.399 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.399 [2088] DEBUG - Request: [192.168.86.150:33694 (Subnet)] GET /activities (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.399 [7608] DEBUG - Completed: [192.168.86.150:33694] 200 GET /activities (6 live) TLS GZIP 2ms 429 bytes (pipelined: 2)
Apr 16, 2019 15:42:44.414 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:44.414 [9232] DEBUG - Request: [192.168.86.150:33694 (Subnet)] PUT /updater/check?download=0 (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:44.414 [9232] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743, download: NO
Apr 16, 2019 15:42:44.414 [9232] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:42:44.414 [9232] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:42:44.867 [9232] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/5/check.xml?build=windows-x86&channel=16&distribution=windows&version=1.15.3.876-ad6e39743
Apr 16, 2019 15:42:44.867 [9232] INFO - AutoUpdate: no updates available
Apr 16, 2019 15:42:44.867 [7876] DEBUG - Completed: [192.168.86.150:33694] 200 PUT /updater/check?download=0 (5 live) TLS GZIP 450ms 274 bytes (pipelined: 3)
Apr 16, 2019 15:42:45.008 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:45.008 [8628] DEBUG - Request: [192.168.86.150:33694 (Subnet)] GET /library/sections (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:45.008 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:45.008 [7876] DEBUG - Completed: [192.168.86.150:33694] 200 GET /library/sections (5 live) TLS GZIP 2ms 1100 bytes (pipelined: 4)
Apr 16, 2019 15:42:45.008 [2500] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /media/providers (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:45.008 [7876] DEBUG - Completed: [192.168.86.150:33693] 200 GET /media/providers (5 live) TLS GZIP 3ms 3675 bytes (pipelined: 2)
Apr 16, 2019 15:42:45.149 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:45.149 [9232] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /music/iTunes (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:45.149 [7608] DEBUG - Completed: [192.168.86.150:33693] 404 GET /music/iTunes (5 live) TLS GZIP 2ms 458 bytes (pipelined: 3)
Apr 16, 2019 15:42:45.164 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:45.164 [2500] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:45.164 [2500] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:42:45.164 [2500] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 173 ms.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 173 ms.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' from the cache.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 6 ms.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' to the cache (1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1).
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 4 ms.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Apr 16, 2019 15:42:45.336 [2500] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:42:45.352 [2500] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 1 ms.
Apr 16, 2019 15:42:45.352 [2500] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10).
Apr 16, 2019 15:42:45.352 [2500] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Apr 16, 2019 15:42:45.352 [7876] DEBUG - Completed: [192.168.86.150:33693] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (5 live) TLS GZIP 195ms 5906 bytes (pipelined: 4)
Apr 16, 2019 15:42:45.586 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:45.586 [9232] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /hubs/home/recentlyAdded?type=1 (5 live) TLS Page 16-50 GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:45.586 [9232] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Apr 16, 2019 15:42:45.602 [9232] DEBUG - Setting container serialization range to [16, 50] (total=134)
Apr 16, 2019 15:42:45.602 [7608] DEBUG - Completed: [192.168.86.150:33693] 200 GET /hubs/home/recentlyAdded?type=1 (5 live) TLS GZIP Page 16-50 22ms 14743 bytes (pipelined: 5)
Apr 16, 2019 15:42:46.258 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:46.258 [2500] DEBUG - Request: [192.168.86.150:33693 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:46.258 [2500] DEBUG - [CompanionProxy] registered new player: 01svskz8w8tvlqnxb32uwh55 (192.168.86.150)
Apr 16, 2019 15:42:47.648 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:47.648 [2088] DEBUG - Request: [192.168.86.150:33694 (Subnet)] POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2F9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F1626&repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:47.648 [2088] DEBUG - PlayQueue: Converted 'server://9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/com.plexapp.plugins.library/library/metadata/1626' to 'library://x/item/%2Flibrary%2Fmetadata%2F1626'
Apr 16, 2019 15:42:47.648 [2088] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Apr 16, 2019 15:42:47.648 [2088] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Apr 16, 2019 15:42:47.648 [2088] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Apr 16, 2019 15:42:47.648 [2088] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Apr 16, 2019 15:42:47.680 [2088] DEBUG - It took 15.625000 ms to retrieve 125 items.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Extras: Looking for extras for item com.plexapp.agents.imdb://tt1255919?lang=en with unwatched: 1 fromLibrary: 1 fromTheater: 0 fromBluray: 0 and preroll count: 0.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Extras: Using allowable content ratings: G, PG, PG-13.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Extras: Using genres: Adventure, Comedy.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Extras: Found 0 extras from library.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.742 [2088] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:42:47.742 [7608] DEBUG - Completed: [192.168.86.150:33694] 200 POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2F9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F1626&repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (5 live) TLS GZIP 95ms 5137 bytes (pipelined: 5)
Apr 16, 2019 15:42:47.820 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:47.820 [2500] DEBUG - Request: [192.168.86.150:33694 (Subnet)] GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:47.820 [2500] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3305 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8168, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3306 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8170, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3307 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8172, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3308 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8174, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3309 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8176, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Selecting best audio stream for part ID 3310 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Audio Stream: 8178, Subtitle Stream: -1
Apr 16, 2019 15:42:47.898 [2500] DEBUG - Activity: registered new activity e2ef01e5-0a38-4eae-bc87-8bb868f1ae6e - Refreshing
Apr 16, 2019 15:42:47.898 [7796] DEBUG - Activity: updated activity e2ef01e5-0a38-4eae-bc87-8bb868f1ae6e - completed 0% - Refreshing
Apr 16, 2019 15:42:47.898 [7796] DEBUG - Activity: Ended activity e2ef01e5-0a38-4eae-bc87-8bb868f1ae6e.
Apr 16, 2019 15:42:47.914 [7876] DEBUG - Completed: [192.168.86.150:33694] 200 GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (5 live) TLS GZIP 86ms 5909 bytes (pipelined: 6)
Apr 16, 2019 15:42:47.945 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:47.945 [2088] DEBUG - Request: [192.168.86.150:33694 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1626&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=fwz94ivnvyggwya8u1aeuccw&subtitles=burn&Accept-Language=en (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:47.945 [2088] DEBUG - Found session GUID of fwz94ivnvyggwya8u1aeuccw in session start.
Apr 16, 2019 15:42:47.961 [2088] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1626?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=5n7g4d66ccd511qwdehtrs7p
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:47.961 [2088] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:47.961 [2088] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Codecs: testing h264_qsv (encoder)
Apr 16, 2019 15:42:47.961 [2088] DEBUG - Codecs: hardware transcoding: testing API qsv
Apr 16, 2019 15:42:48.008 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:48.008 [2500] DEBUG - Request: [192.168.86.150:33706 (Subnet)] GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=playing&hasMDE=1&time=0&duration=5435000 (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:48.008 [2500] DEBUG - Client [5n7g4d66ccd511qwdehtrs7p] reporting timeline state playing, progress of 0/5435000ms for guid=, ratingKey=1626 url=, key=/library/metadata/1626, containerKey=, metadataId=1626, source=
Apr 16, 2019 15:42:48.008 [2500] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:48.008 [2500] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:48.008 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:48.023 [2500] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:42:48.023 [2500] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:42:48.023 [2500] DEBUG - [Now] User is Mike1825 (ID: 1)
Apr 16, 2019 15:42:48.023 [2500] DEBUG - [Now] Device is Chrome (Chrome).
Apr 16, 2019 15:42:48.023 [2500] DEBUG - [Now] Profile is Web
Apr 16, 2019 15:42:48.023 [2500] DEBUG - [Now] Updated play state for /library/metadata/1626.
Apr 16, 2019 15:42:48.023 [2500] DEBUG - HubCache: Expiring '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because of event plex.event.play.session.state.update.
Apr 16, 2019 15:42:48.023 [2500] DEBUG - HubCache: Expiring '1/home.movies.recent/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because event plex.event.play.session.state.update referenced an item the hub contained
Apr 16, 2019 15:42:48.023 [2500] DEBUG - HubCache: Expiring '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' because of event plex.event.play.session.state.update.
Apr 16, 2019 15:42:48.023 [2500] DEBUG - Statistics: (01svskz8w8tvlqnxb32uwh55) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Apr 16, 2019 15:42:48.023 [7876] DEBUG - Completed: [192.168.86.150:33706] 200 GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1102&state=playing&hasMDE=1&time=0&duration=5435000 (6 live) TLS GZIP 19ms 500 bytes (pipelined: 1)
Apr 16, 2019 15:42:48.023 [7876] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:48.023 [9232] DEBUG - Request: [192.168.86.150:33706 (Subnet)] GET /status/sessions (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:48.023 [9232] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:42:48.023 [7608] DEBUG - Completed: [192.168.86.150:33706] 200 GET /status/sessions (6 live) TLS GZIP 3ms 5299 bytes (pipelined: 2)
Apr 16, 2019 15:42:48.039 [7608] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:42:48.039 [2500] DEBUG - Request: [192.168.86.150:33706 (Subnet)] GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:42:48.039 [2500] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Apr 16, 2019 15:42:48.039 [2500] DEBUG - PlayQueue: No need to refresh, active window is operational.
Apr 16, 2019 15:42:48.039 [2500] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:42:48.039 [2500] DEBUG - PlayQueue: Client 01svskz8w8tvlqnxb32uwh55 requested ownership of play queue 602, but already had it.
Apr 16, 2019 15:42:48.039 [2500] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:42:48.039 [2500] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:42:48.039 [2500] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:42:48.039 [7876] DEBUG - Completed: [192.168.86.150:33706] 200 GET /playQueues/602?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (6 live) TLS GZIP 8ms 5137 bytes (pipelined: 3)
Apr 16, 2019 15:42:48.383 [2088] ERROR - [FFMPEG] - Error initializing an MFX session: -3.
Apr 16, 2019 15:42:48.398 [2088] DEBUG - Codecs: hardware transcoding: opening hw device failed - probably not supported by this system, error: Unknown error occurred
Apr 16, 2019 15:42:48.398 [2088] DEBUG - Codecs: testing h264_nvenc (encoder)
Apr 16, 2019 15:42:48.430 [2088] ERROR - [FFMPEG] - Cannot load nvEncodeAPI.dll
Apr 16, 2019 15:42:48.430 [2088] ERROR - [FFMPEG] - The minimum required Nvidia driver for nvenc is 378.66 or newer
Apr 16, 2019 15:42:48.445 [2088] WARN - avcodec_open2 returned -1313558101 for encoder 'h264_nvenc'
Apr 16, 2019 15:42:48.445 [2088] DEBUG - Codecs: testing h264_mf (encoder)
Apr 16, 2019 15:42:48.883 [2088] INFO - [FFMPEG] - MFT name: 'Intel® Quick Sync Video H.264 Encoder MFT'
Apr 16, 2019 15:42:49.101 [5688] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\mike1\AppData\Local\Plex Media Server\Crash Reports\1.15.3.876-ad6e39743" --version=1.15.3.876-ad6e39743 --platform=Windows "--platformVersion=10.0 (Build 17134)" --serverUuid=9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f --userId=mike1825@gmail.com --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx --vendor=Microsoft --model=x64 --device=PC 
Apr 16, 2019 15:42:49.117 [5688] DEBUG - Jobs: Starting child process with pid 10916
