Apr 16, 2019 15:16:33.503 [9088] INFO - Plex Media Server v1.15.3.876-ad6e39743 - Microsoft PC x64 - build: windows-x86 windows - GMT -04:00
Apr 16, 2019 15:16:33.503 [9088] INFO - Windows version: 10.0 (Build 17134), language en-US
Apr 16, 2019 15:16:33.503 [9088] 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:16:33.503 [9088] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
Apr 16, 2019 15:16:33.503 [4640] DEBUG - Plex for Windows Store application is not installed
Apr 16, 2019 15:16:33.503 [4640] DEBUG - BPQ: [Idle] -> [Starting]
Apr 16, 2019 15:16:33.518 [4640] DEBUG - FeatureManager: Using cached data for features list
Apr 16, 2019 15:16:33.518 [4640] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:16:33.518 [4640] INFO - SQLITE3:0x4af4cf1a, 283, recovered 509 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:16:33.565 [4640] DEBUG - Running migrations. (EPG 0)
Apr 16, 2019 15:16:33.565 [4640] DEBUG - ChangestampAllocator: initialized to 10506202
Apr 16, 2019 15:16:33.565 [4640] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:16:33.581 [4640] DEBUG - Running migrations. (EPG 0)
Apr 16, 2019 15:16:34.276 [4640] 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:16:34.276 [4640] DEBUG - CERT: Installed new private key.
Apr 16, 2019 15:16:34.276 [4640] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.04bea987fdf9486d9cae167ff85e1ef8.plex.direct
Apr 16, 2019 15:16:34.276 [4640] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Apr 16, 2019 15:16:34.276 [4640] INFO - OCSP: Successfully retrieved response from cache.
Apr 16, 2019 15:16:34.276 [4640] DEBUG - CERT: Installed intermediate certificate.
Apr 16, 2019 15:16:34.276 [4640] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Apr 16, 2019 15:16:34.276 [4640] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Apr 16, 2019 15:16:34.292 [4640] INFO - Killing PlexScriptHost.exe process with pid 9508
Apr 16, 2019 15:16:34.354 [6692] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:16:34.354 [4640] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Apr 16, 2019 15:16:34.354 [4640] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 16, 2019 15:16:34.354 [6692] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:16:34.354 [10112] DEBUG - Relay: read 22 cached entries from hosts file
Apr 16, 2019 15:16:34.354 [10112] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:34.354 [4640] 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:16:34.354 [4640] DEBUG - MyPlex: updating with 14 access tokens
Apr 16, 2019 15:16:34.354 [4640] DEBUG - Opening 1 database sessions to library (), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:16:34.354 [9360] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 16, 2019 15:16:34.354 [9360] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:16:34.354 [10100] 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:16:34.354 [10100] 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:16:34.354 [10100] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743"
Apr 16, 2019 15:16:34.417 [10100] DEBUG - Scanning for plug-ins in "C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins"
Apr 16, 2019 15:16:34.417 [10100] DEBUG - PluginRepository::setStartState: 1, startingSystem
Apr 16, 2019 15:16:34.417 [10100] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\System.bundle.
Apr 16, 2019 15:16:34.417 [10100] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Apr 16, 2019 15:16:34.432 [10100] DEBUG - Starting file watcher for com.plexapp.system
Apr 16, 2019 15:16:34.432 [10100] 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:16:34.432 [10100] DEBUG - Spawned plug-in com.plexapp.system with PID 11684
Apr 16, 2019 15:16:34.432 [10100] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Apr 16, 2019 15:16:34.823 [10112] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:34.917 [9360] 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:16:34.917 [9360] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:16:34.917 [5248] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:35.401 [5248] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:35.401 [5248] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:35.870 [5248] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:35.870 [5248] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:35.870 [9360] 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:16:35.885 [9360] DEBUG - Jobs: Starting child process with pid 1496
Apr 16, 2019 15:16:36.245 [0552] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 1496 is 0 (success)
Apr 16, 2019 15:16:36.339 [5248] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:36.339 [5248] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:36.776 [10100] DEBUG - Reading 486 bytes in the body, code is 200
Apr 16, 2019 15:16:36.776 [10100] DEBUG - [com.plexapp.system] Plug-in running on port 60627.
Apr 16, 2019 15:16:36.776 [10100] DEBUG -  * Plug-in handles prefix: /system
Apr 16, 2019 15:16:36.776 [10100] DEBUG -  * Plug-in handles prefix: /player
Apr 16, 2019 15:16:36.776 [10100] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Apr 16, 2019 15:16:36.776 [10100] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Apr 16, 2019 15:16:36.776 [10100] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Apr 16, 2019 15:16:36.776 [10100] DEBUG - Updating the list of agents known by the system.
Apr 16, 2019 15:16:36.776 [10100] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Apr 16, 2019 15:16:36.776 [10100] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents
Apr 16, 2019 15:16:36.776 [4640] DEBUG - Image transcode cache directory: "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Apr 16, 2019 15:16:36.776 [4640] DEBUG - Transcoder: Cleaning old transcode directories.
Apr 16, 2019 15:16:36.776 [4640] 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:16:36.776 [4640] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:16:36.776 [6692] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:36.776 [4640] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:16:36.776 [4640] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:16:36.776 [4640] DEBUG - PublicAddressManager: Starting.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 16, 2019 15:16:36.776 [10112] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:16:36.776 [9360] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:16:36.776 [9360] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:16:36.776 [9824] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:16:36.776 [4640] 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:16:36.776 [4640] 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:16:36.776 [4640] 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:16:36.776 [4640] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Apr 16, 2019 15:16:36.776 [4640] DEBUG - Detected primary interface: 192.168.86.123
Apr 16, 2019 15:16:36.776 [4640] DEBUG - Network interfaces:
Apr 16, 2019 15:16:36.776 [4640] DEBUG -  * 16 Ethernet {E0A6A9D7-FB75-4B7B-963E-67CD17BA53CB} (192.168.86.123) (loopback: 0)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - Creating NetworkServices singleton.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkServices: Initializing...
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - Network change for advertiser.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - Network change for advertiser.
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Creating new service.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Apr 16, 2019 15:16:36.776 [4640] DEBUG - NetworkService: Quick dispatch of network change.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - Network change for advertiser.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Apr 16, 2019 15:16:36.776 [6116] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 192.168.86.255 (index: 0)
Apr 16, 2019 15:16:36.792 [6116] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Apr 16, 2019 15:16:36.792 [6116] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Apr 16, 2019 15:16:36.792 [6116] DEBUG - NetworkService: Browsing on interface 192.168.86.123 on broadcast address 239.255.255.250 (index: 0)
Apr 16, 2019 15:16:36.885 [4640] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
Apr 16, 2019 15:16:36.885 [4640] DEBUG - Gracenote: Using SQLite 3.26.0
Apr 16, 2019 15:16:36.885 [4640] DEBUG - Starting HTTP server.
Apr 16, 2019 15:16:36.885 [4640] DEBUG - Running server...
Apr 16, 2019 15:16:36.885 [4640] DEBUG - Waiting for server condition to be signaled.
Apr 16, 2019 15:16:36.885 [7984] DEBUG - Plex Tuner Service.exe starting.
Apr 16, 2019 15:16:36.885 [7984] 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:16:36.885 [10512] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Apr 16, 2019 15:16:36.885 [10512] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:36.885 [11544] DEBUG - Request: [127.0.0.1:60620 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.901 [1780] DEBUG - Request: [127.0.0.1:60621 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale ar
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale cs_CZ
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale da
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale de
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale en
Apr 16, 2019 15:16:36.901 [7984] DEBUG - Jobs: Starting child process with pid 5916
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale es
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale et_EE
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale fr
Apr 16, 2019 15:16:36.901 [6956] DEBUG - Request: [127.0.0.1:60625 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale he
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale hr_HR
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale hu_HU
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale it
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale ja_JP
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale ko
Apr 16, 2019 15:16:36.901 [5696] DEBUG - Request: [127.0.0.1:60626 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale lt
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale nl
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale nl_BE
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale no
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale pt_BR
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale ro
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale ru
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale sr
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale sv
Apr 16, 2019 15:16:36.901 [11544] DEBUG - Translator: Found locale zh_CN
Apr 16, 2019 15:16:36.901 [5248] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:36.901 [11544] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:36.901 [11544] DEBUG - HTTP requesting GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:36.917 [4544] DEBUG - Completed: [127.0.0.1:60626] 200 GET /servers (5 live) GZIP 18ms 492 bytes
Apr 16, 2019 15:16:36.917 [3684] DEBUG - Completed: [127.0.0.1:60621] 200 GET / (5 live) GZIP 20ms 2961 bytes
Apr 16, 2019 15:16:36.917 [4544] DEBUG - Completed: [127.0.0.1:60625] 200 GET / (5 live) GZIP 19ms 2961 bytes
Apr 16, 2019 15:16:36.917 [5696] DEBUG - Request: [127.0.0.1:60637 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.917 [1780] DEBUG - Request: [127.0.0.1:60636 (Loopback)] GET / (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.917 [4544] DEBUG - Completed: [127.0.0.1:60637] 200 GET /library/sections (5 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:16:36.917 [3684] DEBUG - Completed: [127.0.0.1:60636] 200 GET / (5 live) GZIP 2ms 2961 bytes
Apr 16, 2019 15:16:36.932 [6956] DEBUG - Request: [127.0.0.1:60638 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.932 [3684] DEBUG - Completed: [127.0.0.1:60638] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:16:36.948 [5696] DEBUG - Request: [127.0.0.1:60639 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.948 [4544] DEBUG - Completed: [127.0.0.1:60639] 200 GET /library/sections (6 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:16:36.948 [1780] DEBUG - Request: [127.0.0.1:60640 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:36.948 [4544] DEBUG - Completed: [127.0.0.1:60640] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:16:36.979 [9360] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:16:36.979 [9360] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:16:36.995 [5540] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Apr 16, 2019 15:16:37.229 [6692] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.292 [10100] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents
Apr 16, 2019 15:16:37.292 [10100] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Apr 16, 2019 15:16:37.292 [10100] DEBUG - The system knows about 13 agents.
Apr 16, 2019 15:16:37.292 [10100] DEBUG - Waiting for 18 plug-ins to finish starting.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Framework.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\HTbackdrops.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Fanart-TV.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LastFM.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LocalMedia.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\LyricFind.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Media-Flags.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\OpenSubtitles.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\MoviePosterDB.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PersonalMedia.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMovie.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexMusic.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\PlexThemeMusic.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\Scanners.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheMovieDB.bundle.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\TheTVDB.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ad6e39743\WebClient.bundle.
Apr 16, 2019 15:16:37.292 [6036] DEBUG - Starting plug-in C:\Users\mike1\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Apr 16, 2019 15:16:37.292 [11320] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Apr 16, 2019 15:16:37.292 [0632] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Apr 16, 2019 15:16:37.292 [10100] DEBUG - Starting scheduled updates, every 3600 seconds
Apr 16, 2019 15:16:37.292 [10100] INFO - Refreshing paths watched by LibraryUpdateManager
Apr 16, 2019 15:16:37.292 [6404] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:16:37.292 [10100] DEBUG - LibraryUpdateManager will start watching D:\Movies
Apr 16, 2019 15:16:37.292 [10100] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Music
Apr 16, 2019 15:16:37.292 [10100] DEBUG - LibraryUpdateManager will start watching C:\Users\mike1\Pictures
Apr 16, 2019 15:16:37.292 [10100] DEBUG - LibraryUpdateManager will start watching D:\Shows
Apr 16, 2019 15:16:37.292 [6404] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Apr 16, 2019 15:16:37.292 [10100] DEBUG - Done waiting for all plug-ins to start.
Apr 16, 2019 15:16:37.292 [10100] DEBUG - PluginRepository::setStartState: 3, complete
Apr 16, 2019 15:16:37.292 [6404] 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:16:37.307 [6404] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 9796
Apr 16, 2019 15:16:37.307 [6404] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Apr 16, 2019 15:16:37.323 [11544] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:37.323 [11544] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:16:37.323 [4544] DEBUG - Completed: [127.0.0.1:60620] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 440ms 19473 bytes
Apr 16, 2019 15:16:37.339 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user?includeSubscriptions=1&includeProviders=1
Apr 16, 2019 15:16:37.354 [10512] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.354 [9824] DEBUG - MyPlex: username is Mike1825, login is mike1825@gmail.com, home is 0, no pin
Apr 16, 2019 15:16:37.354 [9824] DEBUG - HTTP requesting GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:16:37.354 [10512] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.792 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/geoip
Apr 16, 2019 15:16:37.792 [9824] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.870 [10512] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.870 [10512] DEBUG - Running database fix-ups.
Apr 16, 2019 15:16:37.870 [10512] DEBUG - Database Fixups: Destroyed 0 transient items.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Found 0 collections tags in section 1.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Synced tags in section 1 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Refreshed attributes for 0 collections in section 1 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Found 0 collections tags in section 2.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: We had 0 collections in the library already.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Synced tags in section 2 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Apr 16, 2019 15:16:37.885 [10512] DEBUG - Running database fix-ups completed in 0.0 seconds.
Apr 16, 2019 15:16:37.885 [10140] DEBUG - Versions: updating status for generator 468
Apr 16, 2019 15:16:37.885 [10512] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:37.885 [10140] DEBUG - BPQ: generator 468 is in complete state
Apr 16, 2019 15:16:37.885 [10140] DEBUG - Versions: updating status for generator 470
Apr 16, 2019 15:16:37.885 [10140] DEBUG - BPQ: generator 470 is in complete state
Apr 16, 2019 15:16:37.885 [10140] DEBUG - Versions: updating status for generator 471
Apr 16, 2019 15:16:37.885 [10140] DEBUG - BPQ: generator 471 is in complete state
Apr 16, 2019 15:16:37.995 [5696] DEBUG - Request: [127.0.0.1:60647 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:37.995 [5696] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:16:37.995 [5696] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:16:38.010 [5696] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/messaging/clear_events/com.plexapp.agents.imdb
Apr 16, 2019 15:16:38.026 [5696] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:16:38.026 [3684] DEBUG - Completed: [127.0.0.1:60647] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (6 live) GZIP 26ms 330 bytes
Apr 16, 2019 15:16:38.073 [11544] DEBUG - Request: [127.0.0.1:60649 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:38.073 [11544] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:16:38.073 [11544] DEBUG - HTTP requesting GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:16:38.135 [11544] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/resourceHashes
Apr 16, 2019 15:16:38.135 [11544] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Apr 16, 2019 15:16:38.135 [3684] DEBUG - Completed: [127.0.0.1:60649] 200 GET /:/plugins/com.plexapp.system/resourceHashes (7 live) GZIP 56ms 3498 bytes
Apr 16, 2019 15:16:38.151 [6956] DEBUG - Request: [127.0.0.1:60652 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:38.151 [5696] DEBUG - Request: [127.0.0.1:60653 (Loopback)] GET / (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:38.151 [6956] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:38.151 [6956] DEBUG - HTTP requesting GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:38.151 [3684] DEBUG - Completed: [127.0.0.1:60653] 200 GET / (7 live) GZIP 1ms 2961 bytes
Apr 16, 2019 15:16:38.167 [6956] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Apr 16, 2019 15:16:38.167 [6956] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Apr 16, 2019 15:16:38.167 [3684] DEBUG - Completed: [127.0.0.1:60652] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (7 live) GZIP 19ms 19473 bytes
Apr 16, 2019 15:16:38.229 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.229 [9824] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.323 [10512] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.323 [5540] DEBUG - DVR: Starting up.
Apr 16, 2019 15:16:38.338 [5540] DEBUG - EPG[onconnect]: Starting.
Apr 16, 2019 15:16:38.338 [5540] DEBUG - Opening 10 database sessions to library (tv.plex.providers.epg.onconnect:2), SQLite 3.26.0, threadsafe=1
Apr 16, 2019 15:16:38.338 [10512] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:16:38.338 [5540] INFO - SQLITE3:0x4af4cf1a, 283, recovered 615 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:16:38.354 [5540] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:16:38.354 [5540] DEBUG - Media Provider: Registering provider tv.plex.providers.epg.onconnect:2
Apr 16, 2019 15:16:38.354 [5540] DEBUG - Activity: registered new activity b6c605cf-9532-4dd4-b737-acfc35e9237c - Refreshing guide data
Apr 16, 2019 15:16:38.354 [5540] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 0% - Refreshing guide data
Apr 16, 2019 15:16:38.354 [5540] DEBUG - EPG[onconnect]: Scheduling next refresh for 2019-04-16 19:16:38.
Apr 16, 2019 15:16:38.354 [5540] DEBUG - Activity: registered new activity e38fc6ae-5c35-42a1-ab37-04a9bdd6ceb9 - Processing subscriptions
Apr 16, 2019 15:16:38.354 [6468] 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:16:38.354 [6468] DEBUG - Running migrations. (EPG 1)
Apr 16, 2019 15:16:38.354 [5248] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.370 [5344] DEBUG - EPG[onconnect]: Purging 91 airings which completed in the past.
Apr 16, 2019 15:16:38.370 [5344] DEBUG - Destroying metadata item 106610 (Episode 106)
Apr 16, 2019 15:16:38.370 [5344] DEBUG - Destroying metadata item 106622 (Episode 91)
Apr 16, 2019 15:16:38.370 [6692] DEBUG - EPG[onconnect]: Next thing to start/end is at 2019-04-16 19:30:00 GMT (in 803 seconds)
Apr 16, 2019 15:16:38.370 [5344] DEBUG - Destroying metadata item 106638 (Episode 76)
Apr 16, 2019 15:16:38.385 [5344] DEBUG - Destroying metadata item 106687 (The Broken Window)
Apr 16, 2019 15:16:38.385 [5344] DEBUG - Destroying metadata item 106704 (Emmett's Brother-in-Law)
Apr 16, 2019 15:16:38.401 [5344] DEBUG - Destroying metadata item 106720 (Honey of a Monkey; Curious George's Egg Hunt)
Apr 16, 2019 15:16:38.432 [5344] DEBUG - Destroying metadata item 106736 (Episode 04-16)
Apr 16, 2019 15:16:38.432 [5344] DEBUG - Destroying metadata item 106742 (Episode 04-16)
Apr 16, 2019 15:16:38.432 [5344] DEBUG - Destroying metadata item 106744 (Episode 04-16)
Apr 16, 2019 15:16:38.432 [5344] DEBUG - Destroying metadata item 114556 (Sudden Terror 3)
Apr 16, 2019 15:16:38.448 [5344] DEBUG - Destroying metadata item 114565 (Episode 04-16)
Apr 16, 2019 15:16:38.448 [5344] DEBUG - Destroying metadata item 113683 (Season 2019)
Apr 16, 2019 15:16:38.448 [5344] DEBUG - Destroying metadata item 113682 (U-Box by U-Haul Containers for Moving & Storage)
Apr 16, 2019 15:16:38.448 [5344] DEBUG - Destroying metadata item 106679 (Episode 04-16)
Apr 16, 2019 15:16:38.479 [5344] DEBUG - Destroying metadata item 106699 (Episode 142)
Apr 16, 2019 15:16:38.495 [5344] DEBUG - Destroying metadata item 106715 (Episode 177)
Apr 16, 2019 15:16:38.495 [5344] DEBUG - Destroying metadata item 106721 (Bob the Plant; Aren't We a Pair?)
Apr 16, 2019 15:16:38.495 [5344] DEBUG - Destroying metadata item 106676 (Episode 160)
Apr 16, 2019 15:16:38.510 [5344] DEBUG - Destroying metadata item 106683 (Episode 76)
Apr 16, 2019 15:16:38.510 [5344] DEBUG - Destroying metadata item 106688 (The Case of the Golfer's Gambit)
Apr 16, 2019 15:16:38.510 [5344] DEBUG - Destroying metadata item 106694 (Steve Don't Get Nun)
Apr 16, 2019 15:16:38.510 [5344] DEBUG - Destroying metadata item 106700 (Episode 37)
Apr 16, 2019 15:16:38.526 [5344] DEBUG - Destroying metadata item 106646 (Episode 04-16)
Apr 16, 2019 15:16:38.526 [5344] DEBUG - Destroying metadata item 106722 (Daniel Makes a Mistake; Baking Mistakes)
Apr 16, 2019 15:16:38.557 [5344] DEBUG - Destroying metadata item 106737 (Episode 04-16)
Apr 16, 2019 15:16:38.573 [5344] DEBUG - Destroying metadata item 106747 (Episode 2)
Apr 16, 2019 15:16:38.573 [5344] DEBUG - Destroying metadata item 106746 (Season 2019)
Apr 16, 2019 15:16:38.573 [5344] DEBUG - Destroying metadata item 106745 (Patricia Nash Jewelry)
Apr 16, 2019 15:16:38.573 [5344] DEBUG - Destroying metadata item 114557 (Ropes & Chains)
Apr 16, 2019 15:16:38.573 [5344] DEBUG - Destroying metadata item 114560 (Ephesians Bible Study: Part 2)
Apr 16, 2019 15:16:38.588 [5344] DEBUG - Destroying metadata item 114570 (Episode 04-16)
Apr 16, 2019 15:16:38.588 [5344] DEBUG - Destroying metadata item 106680 (Episode 04-16)
Apr 16, 2019 15:16:38.604 [5344] DEBUG - Destroying metadata item 106634 (Episode 04-16)
Apr 16, 2019 15:16:38.620 [6468] DEBUG - EPG[onconnect]: Matched channel 6.1 (ABC) with grabber channel, adding.
Apr 16, 2019 15:16:38.635 [5344] DEBUG - Destroying metadata item 106723 (You Are Special; Daniel Is Special)
Apr 16, 2019 15:16:38.667 [5344] DEBUG - Destroying metadata item 106684 (Episode 7)
Apr 16, 2019 15:16:38.667 [5344] DEBUG - Destroying metadata item 106689 (The Rat Pack)
Apr 16, 2019 15:16:38.667 [5344] DEBUG - Destroying metadata item 106695 (Bait)
Apr 16, 2019 15:16:38.682 [5344] DEBUG - Destroying metadata item 106701 (Episode 143)
Apr 16, 2019 15:16:38.698 [11544] DEBUG - Request: [127.0.0.1:60659 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (8 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:38.698 [11544] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:16:38.698 [11544] DEBUG - HTTP requesting GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:16:38.713 [11544] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Apr 16, 2019 15:16:38.713 [11544] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Apr 16, 2019 15:16:38.713 [4544] DEBUG - Completed: [127.0.0.1:60659] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (8 live) GZIP 7ms 465 bytes
Apr 16, 2019 15:16:38.713 [5344] DEBUG - Destroying metadata item 106724 (Sand Palace; Zoo Day)
Apr 16, 2019 15:16:38.713 [5344] DEBUG - Destroying metadata item 106738 (3 Men ... 3 Tests ... Who's the Father of Tiffany's Baby?)
Apr 16, 2019 15:16:38.729 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.729 [9824] DEBUG - Sync: onMyPlexSignIn
Apr 16, 2019 15:16:38.729 [9824] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.745 [6404] DEBUG - Reading 148 bytes in the body, code is 200
Apr 16, 2019 15:16:38.745 [6404] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 60661.
Apr 16, 2019 15:16:38.745 [6404] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Apr 16, 2019 15:16:38.745 [6404] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:16:38.745 [6404] DEBUG - HTTP requesting GET http://127.0.0.1:60661/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:16:38.745 [5344] DEBUG - Destroying metadata item 106748 (Episode 43)
Apr 16, 2019 15:16:38.760 [5344] DEBUG - Destroying metadata item 114553 (Jessica & Cody Nickson)
Apr 16, 2019 15:16:38.760 [5344] DEBUG - Destroying metadata item 114552 (Season 2)
Apr 16, 2019 15:16:38.760 [5344] DEBUG - Destroying metadata item 114558 (Grease)
Apr 16, 2019 15:16:38.760 [6404] DEBUG - HTTP 200 response from GET http://127.0.0.1:60661/:/plugins/com.plexapp.agents.imdb/prefs
Apr 16, 2019 15:16:38.760 [6404] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Apr 16, 2019 15:16:38.760 [6404] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 07b9db54ed88186ae435dd3081e43ec04912506e
Apr 16, 2019 15:16:38.760 [6404] DEBUG - IVA: Downloading trailer lists.
Apr 16, 2019 15:16:38.760 [5344] DEBUG - Destroying metadata item 106681 (Episode 04-16)
Apr 16, 2019 15:16:38.776 [5344] DEBUG - Destroying metadata item 106697 (Episode 04-16)
Apr 16, 2019 15:16:38.776 [5344] DEBUG - Destroying metadata item 106717 (Episode 04-16)
Apr 16, 2019 15:16:38.776 [5344] DEBUG - Destroying metadata item 106725 (Street Food)
Apr 16, 2019 15:16:38.792 [5344] DEBUG - Destroying metadata item 106690 (Discards)
Apr 16, 2019 15:16:38.807 [5344] DEBUG - Destroying metadata item 106696 (Silver Star)
Apr 16, 2019 15:16:38.807 [5344] DEBUG - Destroying metadata item 106698 (El charlatÃ¡n)
Apr 16, 2019 15:16:38.807 [5344] DEBUG - Destroying metadata item 106702 (Episode 141)
Apr 16, 2019 15:16:38.807 [5344] DEBUG - Destroying metadata item 106709 (Episode 136)
Apr 16, 2019 15:16:38.823 [5344] DEBUG - Destroying metadata item 106718 (Episode 04-16)
Apr 16, 2019 15:16:38.823 [5248] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:38.823 [5344] DEBUG - Destroying metadata item 106726 (Pole-to-Pole, Parts 1 & 2)
Apr 16, 2019 15:16:38.838 [5344] DEBUG - Destroying metadata item 106739 (You Abused Your Son: Now He's Afraid of You)
Apr 16, 2019 15:16:38.838 [5344] DEBUG - Destroying metadata item 106749 (Episode 04-16)
Apr 16, 2019 15:16:38.854 [5344] DEBUG - Destroying metadata item 114555 (Episode 125)
Apr 16, 2019 15:16:38.854 [5344] DEBUG - Destroying metadata item 114554 (Season 22)
Apr 16, 2019 15:16:38.854 [6404] DEBUG - IVA: Downloaded 41 trailers for source 1.
Apr 16, 2019 15:16:38.870 [6468] DEBUG - EPG[onconnect]: Matched channel 8.1 (CW) with grabber channel, adding.
Apr 16, 2019 15:16:38.901 [5344] DEBUG - Destroying metadata item 114559 (Packaging)
Apr 16, 2019 15:16:38.901 [5344] DEBUG - Destroying metadata item 106682 (Episode 04-16)
Apr 16, 2019 15:16:38.932 [6468] DEBUG - EPG[onconnect]: Matched channel 13.1 (NBC) with grabber channel, adding.
Apr 16, 2019 15:16:38.948 [6468] DEBUG - EPG[onconnect]: Matched channel 13.3 (METVN) with grabber channel, adding.
Apr 16, 2019 15:16:38.963 [6468] DEBUG - EPG[onconnect]: Matched channel 18.4 (GETTV) with grabber channel, adding.
Apr 16, 2019 15:16:38.963 [6468] DEBUG - EPG[onconnect]: Matched channel 19.1 (TELE) with grabber channel, adding.
Apr 16, 2019 15:16:38.963 [6404] DEBUG - IVA: Downloaded 55 trailers for source 2.
Apr 16, 2019 15:16:39.010 [6468] DEBUG - EPG[onconnect]: Matched channel 29.1 (CBS) with grabber channel, adding.
Apr 16, 2019 15:16:39.010 [6468] DEBUG - EPG[onconnect]: Matched channel 29.2 (Independent) with grabber channel, adding.
Apr 16, 2019 15:16:39.010 [6468] DEBUG - EPG[onconnect]: Matched channel 30.1 (QUESTSD) with grabber channel, adding.
Apr 16, 2019 15:16:39.026 [6468] DEBUG - EPG[onconnect]: Matched channel 30.4 (CBNNEWS) with grabber channel, adding.
Apr 16, 2019 15:16:39.026 [6468] DEBUG - EPG[onconnect]: Matched channel 30.5 (Independent) with grabber channel, adding.
Apr 16, 2019 15:16:39.042 [6468] DEBUG - EPG[onconnect]: Matched channel 40.1 (Independent) with grabber channel, adding.
Apr 16, 2019 15:16:39.088 [6468] DEBUG - EPG[onconnect]: Matched channel 49.1 (PBS) with grabber channel, adding.
Apr 16, 2019 15:16:39.088 [6468] DEBUG - EPG[onconnect]: Matched channel 49.2 (CREATE) with grabber channel, adding.
Apr 16, 2019 15:16:39.088 [6468] DEBUG - EPG[onconnect]: Matched channel 49.3 (Independent) with grabber channel, adding.
Apr 16, 2019 15:16:39.104 [6468] DEBUG - EPG[onconnect]: Matched channel 59.1 (FOX) with grabber channel, adding.
Apr 16, 2019 15:16:39.120 [6468] DEBUG - EPG[onconnect]: Matched channel 63.4 (Independent) with grabber channel, adding.
Apr 16, 2019 15:16:39.120 [6468] DEBUG - EPG[onconnect]: Matched channel 63.5 (QVC) with grabber channel, adding.
Apr 16, 2019 15:16:39.120 [6468] DEBUG - EPG[onconnect]: Matched channel 63.6 (HSN) with grabber channel, adding.
Apr 16, 2019 15:16:39.229 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.229 [9824] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Apr 16, 2019 15:16:39.229 [9824] DEBUG - Sync: synchronizing 0 sync lists to generators
Apr 16, 2019 15:16:39.229 [9824] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Apr 16, 2019 15:16:39.229 [9824] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Apr 16, 2019 15:16:39.229 [10140] DEBUG - BPQ: garbageCollect - sync - forced - 10
Apr 16, 2019 15:16:39.229 [10140] DEBUG - Sync: starting Sync garbage collection: 10, forced
Apr 16, 2019 15:16:39.229 [10140] DEBUG - Sync: garbage collecting filesystem
Apr 16, 2019 15:16:39.229 [10140] WARN - Sync: local sync directory "C:\Users\mike1\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
Apr 16, 2019 15:16:39.229 [10140] DEBUG - Sync: garbage collected filesystem
Apr 16, 2019 15:16:39.229 [10140] DEBUG - Sync: finished Sync garbage collection: 10
Apr 16, 2019 15:16:39.229 [9824] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.666 [9824] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.682 [5248] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.682 [9824] DEBUG - EventSource: Resolving 173.230.131.250 port 443
Apr 16, 2019 15:16:39.682 [5540] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.682 [5828] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:39.682 [3684] DEBUG - EventSource: Resolved 173.230.131.250 to 173.230.131.250
Apr 16, 2019 15:16:39.682 [9824] DEBUG - MyPlex: Updating device connections (from timer: 0)
Apr 16, 2019 15:16:39.682 [9824] 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:16:39.682 [11260] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=0)
Apr 16, 2019 15:16:39.682 [11260] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ea886822-d9c3-402c-bfd7-3bdb0b75fce4
Apr 16, 2019 15:16:39.729 [3684] DEBUG - EventSource: Connected in 33 ms.
Apr 16, 2019 15:16:39.729 [4544] DEBUG - EventSource: Wrote data, reading reply.
Apr 16, 2019 15:16:39.838 [10112] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:16:39.838 [10112] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:16:39.838 [10112] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:16:39.838 [10112] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:16:40.057 [9360] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:16:40.057 [9360] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:16:40.057 [9360] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:16:40.151 [5540] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:40.151 [5540] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Apr 16, 2019 15:16:40.166 [3684] DEBUG - EventSource: Read HTTP reply header.
Apr 16, 2019 15:16:40.166 [3684] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Apr 16, 2019 15:16:40.166 [3684] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Apr 16, 2019 15:16:40.166 [3684] DEBUG - EventSource: Successfully connected to 173.230.131.250.
Apr 16, 2019 15:16:40.166 [5540] DEBUG - PublicAddressManager: Obtaining public IP.
Apr 16, 2019 15:16:40.166 [6692] DEBUG - NAT: UPnP, attempting port mapping.
Apr 16, 2019 15:16:40.166 [5540] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:16:40.166 [5248] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:40.166 [11260] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=ea886822-d9c3-402c-bfd7-3bdb0b75fce4
Apr 16, 2019 15:16:40.166 [11260] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:16:40.166 [11260] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:16:40.182 [9360] DEBUG - MyPlex: Sending Server Info to myPlex (user=mike1825@gmail.com, ip=, port=20877)
Apr 16, 2019 15:16:40.182 [9360] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=b17bf157-033c-4c93-98b2-f57aa7c23c30
Apr 16, 2019 15:16:40.245 [9824] 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:16:40.260 [10112] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:16:40.260 [9824] DEBUG - CERT: Certificate will not expire soon.
Apr 16, 2019 15:16:40.276 [6468] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:16:40.291 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 1% - Refreshing guide data
Apr 16, 2019 15:16:40.323 [6468] DEBUG - Destroying metadata item 106752 (Episode 04-16)
Apr 16, 2019 15:16:40.323 [6468] DEBUG - Destroying metadata item 106753 (Episode 375)
Apr 16, 2019 15:16:40.323 [6468] DEBUG - Destroying metadata item 106761 (Episode 04-16)
Apr 16, 2019 15:16:40.323 [6468] DEBUG - Destroying metadata item 106762 (Episode 04-16)
Apr 16, 2019 15:16:40.338 [6468] DEBUG - Destroying metadata item 106766 (The Creek)
Apr 16, 2019 15:16:40.338 [6468] DEBUG - Destroying metadata item 106767 (The Warden)
Apr 16, 2019 15:16:40.338 [6468] DEBUG - Destroying metadata item 106768 (Gallagher's Sons)
Apr 16, 2019 15:16:40.338 [5828] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:16:40.338 [6468] DEBUG - Destroying metadata item 106771 (The Avenging Angel)
Apr 16, 2019 15:16:40.354 [6468] DEBUG - Destroying metadata item 106772 (The Winds of Change)
Apr 16, 2019 15:16:40.354 [6468] DEBUG - Destroying metadata item 106773 (Lazarus)
Apr 16, 2019 15:16:40.354 [6468] DEBUG - Destroying metadata item 106775 (Episode 119)
Apr 16, 2019 15:16:40.370 [6468] DEBUG - Destroying metadata item 106776 (Episode 04-16)
Apr 16, 2019 15:16:40.370 [5540] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Apr 16, 2019 15:16:40.370 [5540] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 199.66.67.126
Apr 16, 2019 15:16:40.385 [6468] DEBUG - Destroying metadata item 106778 (La venganza de scarface; Sexo con doppelganger)
Apr 16, 2019 15:16:40.385 [6468] DEBUG - Destroying metadata item 106781 (Episode 04-16)
Apr 16, 2019 15:16:40.385 [6468] DEBUG - Destroying metadata item 106786 (Episode 106)
Apr 16, 2019 15:16:40.401 [6468] DEBUG - Destroying metadata item 106788 (Abstinence and Pudding)
Apr 16, 2019 15:16:40.401 [6468] DEBUG - Destroying metadata item 106536 (Episode 04-16)
Apr 16, 2019 15:16:40.416 [6468] DEBUG - Destroying metadata item 106796 (Episode 04-16)
Apr 16, 2019 15:16:40.432 [6468] DEBUG - Destroying metadata item 106797 (Episode 32)
Apr 16, 2019 15:16:40.448 [6468] DEBUG - Destroying metadata item 106798 (Episode 04-16)
Apr 16, 2019 15:16:40.448 [6468] DEBUG - Destroying metadata item 106799 (Episode 04-16)
Apr 16, 2019 15:16:40.448 [6468] DEBUG - Destroying metadata item 106800 (Ride 'Em Astronaut)
Apr 16, 2019 15:16:40.448 [6468] DEBUG - Destroying metadata item 106801 (Invisible House for Sale)
Apr 16, 2019 15:16:40.463 [6468] DEBUG - Destroying metadata item 106804 (Fast Friends; T. Rex Teeth)
Apr 16, 2019 15:16:40.463 [6468] DEBUG - Destroying metadata item 106805 (Superstar Tips and Tricks)
Apr 16, 2019 15:16:40.463 [6468] DEBUG - Destroying metadata item 106806 (Southern Italian Salads)
Apr 16, 2019 15:16:40.463 [6468] DEBUG - Destroying metadata item 106807 (The Torreon)
Apr 16, 2019 15:16:40.479 [6468] DEBUG - Destroying metadata item 106808 (Brain and Balance)
Apr 16, 2019 15:16:40.479 [6468] DEBUG - Destroying metadata item 105033 (Puerto Rico Recovery)
Apr 16, 2019 15:16:40.495 [6468] DEBUG - Destroying metadata item 106660 (Stress-Free Quilting With Machine Embroidery, Part 2)
Apr 16, 2019 15:16:40.495 [6468] DEBUG - Destroying metadata item 106661 (Mirrored Images)
Apr 16, 2019 15:16:40.495 [6468] DEBUG - Destroying metadata item 106662 (Episode 13)
Apr 16, 2019 15:16:40.495 [6468] DEBUG - Destroying metadata item 54924 (Season 3)
Apr 16, 2019 15:16:40.495 [6468] DEBUG - Destroying metadata item 54923 (Healthful Indian Flavors With Alamelu)
Apr 16, 2019 15:16:40.511 [6468] DEBUG - Destroying metadata item 106663 (Fish, Spice & Everything Nice)
Apr 16, 2019 15:16:40.511 [6468] DEBUG - Destroying metadata item 100812 (Norman Lear, Television Producer)
Apr 16, 2019 15:16:40.511 [6468] DEBUG - Destroying metadata item 106639 (Episode 99)
Apr 16, 2019 15:16:40.511 [6468] DEBUG - Destroying metadata item 106640 (Episode 99)
Apr 16, 2019 15:16:40.526 [6468] DEBUG - Destroying metadata item 104485 (Episode 273)
Apr 16, 2019 15:16:40.526 [6468] DEBUG - Destroying metadata item 104486 (Episode 274)
Apr 16, 2019 15:16:40.526 [6468] DEBUG - Destroying metadata item 106813 (Episode 04-16)
Apr 16, 2019 15:16:40.542 [6468] DEBUG - Destroying metadata item 106815 (Episode 04-16)
Apr 16, 2019 15:16:40.542 [6468] DEBUG - Destroying metadata item 106816 (Episode 04-16)
Apr 16, 2019 15:16:40.542 [6468] DEBUG - Destroying metadata item 106819 (Episode 04-16)
Apr 16, 2019 15:16:40.542 [6468] DEBUG - Destroying metadata item 114651 (Episode 51)
Apr 16, 2019 15:16:40.557 [6468] DEBUG - Destroying metadata item 114652 (Episode 12)
Apr 16, 2019 15:16:40.557 [6468] DEBUG - Destroying metadata item 114657 (Episode 144)
Apr 16, 2019 15:16:40.557 [6468] DEBUG - Destroying metadata item 114659 (True Crime: Twisted Real-Life Case of Fatal Attraction: Who Killed Cari Farver?)
Apr 16, 2019 15:16:40.557 [6468] DEBUG - Destroying metadata item 114658 (Season 10)
Apr 16, 2019 15:16:40.557 [6468] DEBUG - Destroying metadata item 114660 (Episode 2)
Apr 16, 2019 15:16:40.589 [6468] DEBUG - Destroying metadata item 114661 (Episode 156)
Apr 16, 2019 15:16:40.589 [6468] DEBUG - Destroying metadata item 114662 (Episode 144)
Apr 16, 2019 15:16:40.589 [6468] DEBUG - Destroying metadata item 114664 (Episode 142)
Apr 16, 2019 15:16:40.589 [6468] DEBUG - Destroying metadata item 114663 (Season 9)
Apr 16, 2019 15:16:40.589 [6468] DEBUG - Destroying metadata item 114665 (Redneck Chariot; Japanese Antique Daggers)
Apr 16, 2019 15:16:40.604 [6468] DEBUG - Destroying metadata item 114666 (Jackie Robinson Memorabilia; 1875 NYC Map)
Apr 16, 2019 15:16:40.604 [6468] DEBUG - Destroying metadata item 114667 (The Grey Ghost)
Apr 16, 2019 15:16:40.604 [6468] DEBUG - Destroying metadata item 114668 (Founding Fathers)
Apr 16, 2019 15:16:40.604 [6468] DEBUG - Destroying metadata item 114561 (Saikyo-zuke With Spanish Mackerel)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114673 (Episode 04-16)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114672 (Season 2019)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114671 (How soon do you want to be debt free? National Debt Relief can help.)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114467 (Episode 04-16)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114466 (Season 2019)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114465 (Everstrong)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114473 (Episode 04-16)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114472 (Season 2019)
Apr 16, 2019 15:16:40.620 [6468] DEBUG - Destroying metadata item 114471 (Maintain Your Health)
Apr 16, 2019 15:16:40.636 [6468] DEBUG - Destroying metadata item 114656 (Neil Patrick Harris' Better Half, David Burtka, Is Rachael's Co-Host for the Day -- and It's a Party All Hour Long!)
Apr 16, 2019 15:16:40.636 [6468] DEBUG - Destroying metadata item 114655 (Season 13)
Apr 16, 2019 15:16:40.636 [6468] DEBUG - Destroying metadata item 106758 (Hot-Tempered Sisters)
Apr 16, 2019 15:16:40.651 [6468] DEBUG - Destroying metadata item 106759 (Episode 124)
Apr 16, 2019 15:16:40.651 [6468] DEBUG - EPG[onconnect]: Step 1/84 (2019-04-16T16:00Z) (network: 1.9 sec, database: 2.2 sec total: 2.3 sec)
Apr 16, 2019 15:16:40.651 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 1% - Refreshing guide data
Apr 16, 2019 15:16:40.651 [9360] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=b17bf157-033c-4c93-98b2-f57aa7c23c30
Apr 16, 2019 15:16:40.651 [9360] DEBUG - MyPlex: Published Mapping State response was 201
Apr 16, 2019 15:16:40.651 [9360] DEBUG - MyPlex: Got response for 9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f ~ registered :0
Apr 16, 2019 15:16:40.667 [9360] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:16:40.854 [6468] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:16:40.885 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 2% - Refreshing guide data
Apr 16, 2019 15:16:40.917 [6468] DEBUG - EPG: Found a dupe show 'Have Thinning Hair? Keranique Can Help Regrow Beautiful, Thicker Hair!' with a different GUID.
Apr 16, 2019 15:16:40.964 [6468] DEBUG - EPG[onconnect]: Step 2/84 (2019-04-16T20:00Z) (network: 2.0 sec, database: 2.4 sec total: 2.6 sec)
Apr 16, 2019 15:16:40.964 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 2% - Refreshing guide data
Apr 16, 2019 15:16:41.011 [1780] DEBUG - Request: [34.245.172.51:34982 (WAN)] GET /identity (7 live) TLS Signed-in Token (Mike1825)
Apr 16, 2019 15:16:41.011 [3684] DEBUG - Completed: [34.245.172.51:34982] 200 GET /identity (7 live) TLS 1ms 385 bytes (pipelined: 1)
Apr 16, 2019 15:16:41.198 [6468] DEBUG - EPG: Found a dupe show 'Healthy Living' with a different GUID.
Apr 16, 2019 15:16:41.198 [6468] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:16:41.214 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 3% - Refreshing guide data
Apr 16, 2019 15:16:41.260 [3684] DEBUG - EventSource: Got event [data] '<Message address="199.66.67.126" port="20877" asyncIdentifier="b17bf157-033c-4c93-98b2-f57aa7c23c30" connectivity="1" command="notifyConnectivity"/>'
Apr 16, 2019 15:16:41.260 [3684] DEBUG - PubSub: Got notified of reachability for async identifier b17bf157-033c-4c93-98b2-f57aa7c23c30: 1 for 199.66.67.126:20877 (responded in 1 seconds)
Apr 16, 2019 15:16:41.276 [6468] DEBUG - EPG[onconnect]: Step 3/84 (2019-04-17T00:00Z) (network: 2.1 sec, database: 2.5 sec total: 2.9 sec)
Apr 16, 2019 15:16:41.276 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 3% - Refreshing guide data
Apr 16, 2019 15:16:41.760 [6468] DEBUG - EPG[onconnect]: Failed to retrieve universal details for 'House Flipping 101' show
Apr 16, 2019 15:16:41.792 [6116] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.86.123 (Media-PC)
Apr 16, 2019 15:16:41.792 [6116] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:16:41.792 [6116] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:16:41.792 [6116] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:16:41.807 [5696] DEBUG - Request: [127.0.0.1:60692 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:41.807 [4544] DEBUG - Completed: [127.0.0.1:60692] 200 GET /servers (6 live) GZIP 0ms 492 bytes
Apr 16, 2019 15:16:41.807 [6468] DEBUG - EPG: Found a dupe show 'Eyewitness News Nightbeat' with a different GUID.
Apr 16, 2019 15:16:41.807 [6956] DEBUG - Request: [127.0.0.1:60693 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:41.807 [4544] DEBUG - Completed: [127.0.0.1:60693] 200 GET /library/sections (6 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:16:41.807 [11544] DEBUG - Request: [127.0.0.1:60694 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:16:41.807 [4544] DEBUG - Completed: [127.0.0.1:60694] 200 GET /channels/all (6 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:16:41.823 [6116] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/notify/serverUpdated?host=192.168.86.123
Apr 16, 2019 15:16:41.823 [6116] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Apr 16, 2019 15:16:41.823 [6116] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.27
Apr 16, 2019 15:16:41.885 [6116] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.58
Apr 16, 2019 15:16:41.885 [6468] DEBUG - EPG: Found a dupe show 'Healthy Living' with a different GUID.
Apr 16, 2019 15:16:41.917 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 4% - Refreshing guide data
Apr 16, 2019 15:16:42.073 [6116] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.250
Apr 16, 2019 15:16:42.402 [6468] DEBUG - EPG[onconnect]: Failed to retrieve universal details for 'Drive Medical Compact Mobility Scooter' show
Apr 16, 2019 15:16:42.417 [6468] DEBUG - Destroying metadata item 107117 (Episode 04-17)
Apr 16, 2019 15:16:42.480 [6468] DEBUG - Destroying metadata item 107123 (Episode 04-17)
Apr 16, 2019 15:16:42.480 [6468] DEBUG - Destroying metadata item 107125 (Episode 04-17)
Apr 16, 2019 15:16:42.495 [6468] DEBUG - Destroying metadata item 107126 (Episode 04-17)
Apr 16, 2019 15:16:42.495 [6468] DEBUG - Destroying metadata item 107135 (Born to Walk)
Apr 16, 2019 15:16:42.495 [6468] DEBUG - Destroying metadata item 107136 (Alley of the Dolls)
Apr 16, 2019 15:16:42.495 [6468] DEBUG - Destroying metadata item 107137 (The Razor's Edge)
Apr 16, 2019 15:16:42.511 [6468] DEBUG - Destroying metadata item 107138 (How Do You Spell Revenge?)
Apr 16, 2019 15:16:42.511 [6468] DEBUG - Destroying metadata item 104779 (Season 2)
Apr 16, 2019 15:16:42.511 [6468] DEBUG - Destroying metadata item 107142 (Episode 04-17)
Apr 16, 2019 15:16:42.542 [6468] DEBUG - Destroying metadata item 107150 (Episode 04-17)
Apr 16, 2019 15:16:42.542 [6468] DEBUG - Destroying metadata item 98648 (Season 2019)
Apr 16, 2019 15:16:42.542 [6468] DEBUG - Destroying metadata item 107151 (Episode 04-17)
Apr 16, 2019 15:16:42.558 [6468] DEBUG - Destroying metadata item 107172 (Episode 04-17)
Apr 16, 2019 15:16:42.558 [6468] DEBUG - Destroying metadata item 107171 (Season 2019)
Apr 16, 2019 15:16:42.558 [6468] DEBUG - Destroying metadata item 107170 (Garden Fresh Super Drinks)
Apr 16, 2019 15:16:42.589 [6468] DEBUG - Destroying metadata item 107178 (Episode 04-17)
Apr 16, 2019 15:16:42.589 [6468] DEBUG - Destroying metadata item 107179 (Episode 04-17)
Apr 16, 2019 15:16:42.589 [6468] DEBUG - Destroying metadata item 107183 (Episode 04-17)
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 4/84 (2019-04-17T04:00Z) (network: 2.2 sec, database: 3.7 sec total: 4.2 sec)
Apr 16, 2019 15:16:42.589 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 4% - Refreshing guide data
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 5/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 6/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 7/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 8/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 9/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 10/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 11/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.589 [6468] DEBUG - EPG[onconnect]: Step 12/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 13/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 14/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 15/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 16/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 17/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 18/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 19/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 20/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 21/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 22/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 23/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 24/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 25/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 26/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 27/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 28/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 29/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 30/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 31/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 32/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 33/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 34/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 35/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 36/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 37/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 38/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 39/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 40/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 41/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 42/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 43/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 44/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 45/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 46/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 47/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 48/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 49/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 50/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 51/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 52/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 53/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 54/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 55/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 56/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 57/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 58/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 59/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 60/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 61/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 62/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 63/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 64/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 65/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 66/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 67/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 68/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 69/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 70/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 71/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 72/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 73/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 74/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 75/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 76/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 77/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 78/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 79/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 80/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 81/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 82/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.605 [6468] DEBUG - EPG[onconnect]: Step 83/84: skipping chunk for incremental loading
Apr 16, 2019 15:16:42.855 [6468] DEBUG - EPG: Found a dupe show 'On the Homefront' with a different GUID.
Apr 16, 2019 15:16:42.901 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 5% - Refreshing guide data
Apr 16, 2019 15:16:43.230 [6692] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:16:43.230 [6692] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:16:43.230 [6692] DEBUG - NAT: UPnP, mapped port 20877 to 192.168.86.123:32400.
Apr 16, 2019 15:16:43.230 [6692] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.86.123:20877.
Apr 16, 2019 15:16:43.245 [6692] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:16:43.433 [5540] DEBUG - NAT: UPnP, found device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>
Apr 16, 2019 15:16:43.433 [5540] DEBUG - NAT: UPnP, usable device <http://192.168.86.1:5000/rootDesc.xml> with private address <192.168.86.123>.
Apr 16, 2019 15:16:43.433 [5540] DEBUG - NAT: UPnP, public address is 199.66.67.126
Apr 16, 2019 15:16:43.448 [5540] DEBUG - MyPlex: Last published value didn't change, we're done.
Apr 16, 2019 15:16:43.495 [6468] DEBUG - EPG[onconnect]: Failed to retrieve universal details for 'Pedal America' show
Apr 16, 2019 15:16:43.620 [6468] DEBUG - EPG[onconnect]: Step 84/84 (2019-04-30T12:00Z) (network: 2.5 sec, database: 4.6 sec total: 5.3 sec)
Apr 16, 2019 15:16:43.620 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 100% - Refreshing guide data
Apr 16, 2019 15:16:43.620 [6468] DEBUG - Activity: updated activity b6c605cf-9532-4dd4-b737-acfc35e9237c - completed 100% - Refreshing guide data
Apr 16, 2019 15:16:44.026 [9360] DEBUG - Database analyze: starting analyze (all tables)
Apr 16, 2019 15:16:44.026 [6468] DEBUG - EPG[onconnect]: Total time to load EPG was 5.7 (HTTP details cached 33.3%, CloudFlare grid cached: 100.0%, 0 HTTP errors)
Apr 16, 2019 15:16:44.026 [6468] DEBUG - Activity: Ended activity b6c605cf-9532-4dd4-b737-acfc35e9237c.
Apr 16, 2019 15:16:44.026 [6468] DEBUG - Activity: registered new activity 517cf404-68ab-4b7e-a465-e40f27b0b449 - Processing subscriptions
Apr 16, 2019 15:16:44.026 [5828] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - Activity: registered new activity 7cbe5ffb-8cc0-4052-8ccc-edebd90c762d - Processing subscriptions
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - Activity: Ended activity 7cbe5ffb-8cc0-4052-8ccc-edebd90c762d.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - Activity: Ended activity 517cf404-68ab-4b7e-a465-e40f27b0b449.
Apr 16, 2019 15:16:44.042 [5828] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Apr 16, 2019 15:16:44.089 [9360] DEBUG - Database analyze: analyze complete
Apr 16, 2019 15:16:44.355 [6468] DEBUG - DVR:Device: Discovering and refreshing all devices.
Apr 16, 2019 15:16:44.355 [6468] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Apr 16, 2019 15:16:44.355 [6468] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:16:44.355 [6468] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:16:44.355 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:44.355 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:44.355 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:44.370 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:44.386 [6468] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:16:44.386 [6468] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:16:44.683 [5248] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:16:45.120 [5248] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 173.230.131.250 was 33 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 45.79.197.109 was 36 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 172.104.217.190 was 52 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 45.79.18.204 was 66 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 184.105.148.117 was 100 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 178.79.132.174 was 185 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 82.94.168.22 was 205 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 139.162.177.42 was 222 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 346 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Time to connect to 172.104.44.222 was 425 ms.
Apr 16, 2019 15:16:45.761 [5248] DEBUG - PubSubManager: Updating best ping time for 173.230.131.250 to 33 ms.
Apr 16, 2019 15:16:46.823 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:16:46.823 [6116] DEBUG - HTTP requesting GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:16:46.823 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:16:46.823 [6116] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.86.1:5000/rootDesc.xml
Apr 16, 2019 15:16:46.823 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (OnHub)
Apr 16, 2019 15:16:46.823 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANDevice)
Apr 16, 2019 15:16:46.823 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.1 (WANConnectionDevice)
Apr 16, 2019 15:16:46.886 [6468] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Apr 16, 2019 15:16:46.901 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:46.901 [6116] DEBUG - HTTP requesting GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:46.917 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:46.917 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:46.917 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.233 (Master Bedroom Soundbar)
Apr 16, 2019 15:16:46.979 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:16:46.979 [6116] DEBUG - HTTP requesting GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:16:47.011 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:16:47.011 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:16:47.011 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.39 (Home Theater Room)
Apr 16, 2019 15:16:47.307 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:47.307 [6116] DEBUG - HTTP requesting GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:47.307 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:47.307 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.27:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:47.307 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.27 (SHIELD)
Apr 16, 2019 15:16:47.636 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:16:47.636 [6116] DEBUG - HTTP requesting GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:16:47.667 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.53:60000/upnp/dev/03bf54f4-4472-0d27-0000-00000ac47a63/desc
Apr 16, 2019 15:16:47.667 [6116] 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:16:47.667 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.53 (Guest Room)
Apr 16, 2019 15:16:47.714 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:16:47.714 [6116] DEBUG - HTTP requesting GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:16:47.745 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.250:60000/upnp/dev/76d46eb0-6e60-8cc8-ffff-fffffeaaedb3/desc
Apr 16, 2019 15:16:47.745 [6116] 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:16:47.745 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.250 (Mike's 3rd Fire TV)
Apr 16, 2019 15:16:47.886 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:16:47.886 [6116] DEBUG - HTTP requesting GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:16:47.901 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:16:47.901 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.125:80/dms/device.xml
Apr 16, 2019 15:16:47.901 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.125 (HDHomeRun DMS 104C05F1)
Apr 16, 2019 15:16:47.901 [6468] DEBUG - DVR:Device: Discovering and refreshing devices with identifier tv.plex.grabbers.hdhomerun
Apr 16, 2019 15:16:47.901 [6468] DEBUG - DVR:Grabber: HDHomerun discovered a model HDHR4-2US.
Apr 16, 2019 15:16:47.901 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:47.932 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:47.932 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:47.932 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:47.964 [6468] DEBUG - DVR:Grabber: HDHomerun discovered 1 compatible devices.
Apr 16, 2019 15:16:47.964 [6468] DEBUG - DVR:Device: Testing grabber HDHomerun device device://tv.plex.grabbers.hdhomerun/104C05F1 at http://192.168.86.125:80
Apr 16, 2019 15:16:47.964 [6468] DEBUG - DVR:Device: Device device://tv.plex.grabbers.hdhomerun/104C05F1 was already known, refreshing database info
Apr 16, 2019 15:16:47.964 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:47.964 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/discover.json
Apr 16, 2019 15:16:47.964 [6468] DEBUG - HTTP requesting GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:47.979 [6468] DEBUG - HTTP 200 response from GET http://192.168.86.125:80/lineup_status.json
Apr 16, 2019 15:16:48.011 [6468] DEBUG - DVR:Device: Device was alive, refreshed device://tv.plex.grabbers.hdhomerun/104C05F1.
Apr 16, 2019 15:16:48.339 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.339 [6116] DEBUG - HTTP requesting GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.339 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.339 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.49:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.339 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.49 (Family Room Display)
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - Activity: registered new activity d75381f3-48da-46c2-824a-6411e5574a22 - Processing subscriptions
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: Scheduling subscriptions.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: Scheduled all subscriptions in 1 ms.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - Activity: Ended activity d75381f3-48da-46c2-824a-6411e5574a22.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - Activity: Ended activity e38fc6ae-5c35-42a1-ab37-04a9bdd6ceb9.
Apr 16, 2019 15:16:48.354 [6692] DEBUG - DVR:Subscription: Refreshed all content in 2 ms.
Apr 16, 2019 15:16:48.854 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.854 [6116] DEBUG - HTTP requesting GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.870 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.870 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.38:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:48.870 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.38 (Master Bedroom)
Apr 16, 2019 15:16:49.198 [6116] 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:16:49.198 [6116] DEBUG - HTTP requesting GET http://192.168.86.150:2869/upnphost/udhisapi.dll?content=uuid:d61930e3-e93f-4a1c-9700-3ef6c8e82e9d
Apr 16, 2019 15:16:49.214 [6116] 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:16:49.214 [6116] 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:16:49.214 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.150 (MIKE-PERSONAL: mike1825@gmail.com:)
Apr 16, 2019 15:16:49.479 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.479 [6116] DEBUG - HTTP requesting GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.479 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.479 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.50:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.479 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.50 (Guest Room)
Apr 16, 2019 15:16:49.729 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:16:49.729 [6116] DEBUG - HTTP requesting GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:16:49.745 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.40:60000/upnp/dev/2cc4529e-7b8e-d8a9-0000-00002460b017/desc
Apr 16, 2019 15:16:49.745 [6116] 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:16:49.745 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.40 (Family Room)
Apr 16, 2019 15:16:49.760 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.760 [6116] DEBUG - HTTP requesting GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.760 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.760 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.33:8008/ssdp/device-desc.xml
Apr 16, 2019 15:16:49.760 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.33 (Bar)
Apr 16, 2019 15:16:50.807 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:16:50.807 [6116] DEBUG - HTTP requesting GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:16:50.838 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.35:60000/upnp/dev/4e5439b9-7746-1d8c-ffff-ffff88e7851d/desc
Apr 16, 2019 15:16:50.838 [6116] 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:16:50.838 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.35 (Loft Fire TV)
Apr 16, 2019 15:17:30.602 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:30.602 [6116] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:30.649 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:30.649 [6116] 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:17:30.649 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.49.1 (Mike's 2nd Fire TV)
Apr 16, 2019 15:17:38.382 [9360] DEBUG - BPQ: deferring onLibraryChanged callback, only 54 seconds since last library change
Apr 16, 2019 15:17:41.741 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:41.741 [6116] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:41.788 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:17:41.788 [6116] 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:17:41.788 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:17:44.398 [10580] DEBUG - BPQ: onConsiderProcessing: Starting (true)
Apr 16, 2019 15:17:44.398 [10580] DEBUG - BPQ: garbageCollect - sync - forced - 1
Apr 16, 2019 15:17:44.398 [10580] DEBUG - Sync: starting Sync garbage collection: 1, forced
Apr 16, 2019 15:17:44.398 [10580] DEBUG - Sync: finished Sync garbage collection: 1
Apr 16, 2019 15:17:44.398 [10580] DEBUG - BPQ: garbageCollect - version - forced - -1
Apr 16, 2019 15:17:44.398 [10580] DEBUG - Versions: garbage collecting
Apr 16, 2019 15:17:44.398 [10580] DEBUG - Versions: garbage collected in 0.0 seconds
Apr 16, 2019 15:18:33.503 [1904] DEBUG - BPQ: [Starting] -> [Processing]
Apr 16, 2019 15:18:33.503 [1904] DEBUG - BPQ: generating queue items from 3 generator(s)
Apr 16, 2019 15:18:33.503 [1904] DEBUG - Versions: updating status for generator 468
Apr 16, 2019 15:18:33.503 [1904] DEBUG - Versions: updating status for generator 470
Apr 16, 2019 15:18:33.503 [1904] DEBUG - Versions: updating status for generator 471
Apr 16, 2019 15:18:33.503 [1904] DEBUG - BPQ: generated 0 item(s) for queue
Apr 16, 2019 15:18:33.503 [1904] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Apr 16, 2019 15:18:33.503 [1904] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Apr 16, 2019 15:18:33.503 [1904] DEBUG - BPQ: [Processing] -> [Idle]
Apr 16, 2019 15:18:36.753 [4736] DEBUG - Request: [127.0.0.1:60783 (Loopback)] GET /servers (3 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:18:36.753 [4544] DEBUG - Completed: [127.0.0.1:60783] 200 GET /servers (3 live) GZIP 0ms 492 bytes
Apr 16, 2019 15:18:36.753 [4736] DEBUG - Request: [127.0.0.1:60785 (Loopback)] GET /library/sections (4 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:18:36.753 [4544] DEBUG - Completed: [127.0.0.1:60785] 200 GET /library/sections (4 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:18:36.753 [7644] DEBUG - Request: [127.0.0.1:60786 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:18:36.753 [4544] DEBUG - Completed: [127.0.0.1:60786] 200 GET /channels/all (5 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:18:36.769 [4736] DEBUG - Request: [127.0.0.1:60787 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:18:36.769 [3684] DEBUG - Completed: [127.0.0.1:60787] 200 GET /library/sections (6 live) GZIP 1ms 1013 bytes
Apr 16, 2019 15:18:36.769 [7644] DEBUG - Request: [127.0.0.1:60788 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:18:36.769 [4544] DEBUG - Completed: [127.0.0.1:60788] 200 GET /channels/all (7 live) GZIP 0ms 387 bytes
Apr 16, 2019 15:19:59.075 [9360] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 28.669795 seconds: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:21:38.912 [5248] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Apr 16, 2019 15:23:19.561 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:19.561 [6116] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:19.608 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:19.608 [6116] 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:23:19.608 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:23:49.418 [9360] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 27.185528 seconds: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:23:49.449 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:49.449 [6116] DEBUG - HTTP requesting GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:49.527 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.41:60000/upnp/dev/5e0cfe1c-634a-e0d0-ffff-ffff98a07676/desc
Apr 16, 2019 15:23:49.527 [6116] 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:23:49.527 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.41 (Mike's 2nd Fire TV)
Apr 16, 2019 15:24:25.181 [5540] DEBUG - NetworkServiceBrowser: PLAYER departed after not being seen for 182.502398 seconds: 192.168.86.58
Apr 16, 2019 15:26:38.922 [9360] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Apr 16, 2019 15:28:59.880 [5248] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.920287 seconds: 192.168.86.233 (Master Bedroom Soundbar)
Apr 16, 2019 15:29:00.037 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:29:00.037 [6116] DEBUG - HTTP requesting GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:29:00.068 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:29:00.068 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.233:8008/ssdp/device-desc.xml
Apr 16, 2019 15:29:00.068 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.233 (Master Bedroom Soundbar)
Apr 16, 2019 15:30:01.376 [9360] DEBUG - EPG[onconnect]: Next thing to start/end is at 2019-04-16 19:58:00 GMT (in 1680 seconds)
Apr 16, 2019 15:31:38.931 [5248] DEBUG - EPG[onconnect]: Purging 0 airings which completed in the past.
Apr 16, 2019 15:32:50.223 [5248] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.623433 seconds: 192.168.86.39 (Home Theater Room)
Apr 16, 2019 15:32:58.754 [6116] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:32:58.754 [6116] DEBUG - HTTP requesting GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:32:58.816 [6116] DEBUG - HTTP 200 response from GET http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:32:58.816 [6116] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.86.39:49154/MediaRenderer/desc.xml
Apr 16, 2019 15:32:58.816 [6116] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.86.39 (Home Theater Room)
Apr 16, 2019 15:34:41.637 [4544] DEBUG - CERT: incomplete TLS handshake: sslv3 alert certificate unknown
Apr 16, 2019 15:34:43.105 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.105 [9360] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Apr 16, 2019 15:34:43.105 [9360] DEBUG - HTTP requesting GET https://plex.tv/servers/9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Apr 16, 2019 15:34:43.105 [4736] DEBUG - Request: [192.168.86.150:33071 (Subnet)] GET /media/providers (4 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.121 [3684] DEBUG - Completed: [192.168.86.150:33071] 200 GET /media/providers (4 live) TLS GZIP 6ms 3676 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.184 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.184 [6104] DEBUG - Request: [192.168.86.150:33072 (Subnet)] GET /:/websockets/notifications (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.184 [6104] DEBUG - WebSocket: Performing handshake from origin https://app.plex.tv
Apr 16, 2019 15:34:43.184 [6104] DEBUG - Beginning read from WebSocket
Apr 16, 2019 15:34:43.199 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.199 [4736] DEBUG - Request: [192.168.86.150:33071 (Subnet)] GET /:/prefs (5 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.199 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.199 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.199 [6104] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /updater/status (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.199 [12168] DEBUG - Request: [192.168.86.150:33073 (Subnet)] GET /status/sessions (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.199 [12168] DEBUG - [Now] Adding 0 sessions.
Apr 16, 2019 15:34:43.199 [3684] DEBUG - Completed: [192.168.86.150:33074] 200 GET /updater/status (7 live) TLS GZIP 2ms 526 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.199 [4544] DEBUG - Completed: [192.168.86.150:33073] 200 GET /status/sessions (7 live) TLS GZIP 2ms 429 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.215 [4544] DEBUG - Completed: [192.168.86.150:33071] 200 GET /:/prefs (7 live) TLS GZIP 12ms 7484 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.215 [5056] DEBUG - Request: [192.168.86.150:33071 (Subnet)] OPTIONS /updater/check?download=0 (7 live) TLS GZIP Signed-in Token ()
Apr 16, 2019 15:34:43.215 [4544] DEBUG - Completed: [192.168.86.150:33071] 200 OPTIONS /updater/check?download=0 (7 live) TLS GZIP 1ms 349 bytes (pipelined: 3)
Apr 16, 2019 15:34:43.293 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.293 [6104] DEBUG - Request: [192.168.86.150:33073 (Subnet)] PUT /updater/check?download=0 (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.293 [6104] 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:34:43.293 [6104] 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:34:43.293 [6104] 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:34:43.309 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.309 [12168] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /activities (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.309 [3684] DEBUG - Completed: [192.168.86.150:33074] 200 GET /activities (7 live) TLS GZIP 2ms 429 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.355 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.355 [4736] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /library/sections (6 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.355 [3684] DEBUG - Completed: [192.168.86.150:33074] 200 GET /library/sections (7 live) TLS GZIP 3ms 1100 bytes (pipelined: 3)
Apr 16, 2019 15:34:43.355 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.355 [5056] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /media/providers (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.355 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /media/providers (7 live) TLS GZIP 3ms 3676 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.434 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.434 [4736] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /music/iTunes (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.434 [3684] DEBUG - Completed: [192.168.86.150:33076] 404 GET /music/iTunes (7 live) TLS GZIP 2ms 458 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.449 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.449 [12168] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.449 [12168] 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:34:43.449 [12168] 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:34:43.449 [12168] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 6 ms.
Apr 16, 2019 15:34:43.449 [12168] DEBUG - HubCache: 2 hubs cached, 0.0% hit ratio.
Apr 16, 2019 15:34:43.449 [12168] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=17&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1' in 6 ms.
Apr 16, 2019 15:34:43.449 [12168] 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:34:43.449 [12168] 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:34:43.449 [12168] 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 4 ms.
Apr 16, 2019 15:34:43.449 [12168] 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:34:43.465 [12168] 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 2 ms.
Apr 16, 2019 15:34:43.465 [12168] 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:34:43.465 [12168] 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:34:43.465 [12168] 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:34:43.465 [12168] 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 0 ms.
Apr 16, 2019 15:34:43.465 [12168] 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:34:43.465 [12168] 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:34:43.465 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /hubs?excludeFields=summary&count=17&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1 (7 live) TLS GZIP 23ms 5906 bytes (pipelined: 3)
Apr 16, 2019 15:34:43.574 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.574 [5056] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /hubs/home/recentlyAdded?type=1 (7 live) TLS Page 16-50 GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.574 [5056] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Apr 16, 2019 15:34:43.590 [5056] DEBUG - Setting container serialization range to [16, 50] (total=134)
Apr 16, 2019 15:34:43.590 [3684] DEBUG - Completed: [192.168.86.150:33076] 200 GET /hubs/home/recentlyAdded?type=1 (7 live) TLS GZIP Page 16-50 14ms 14743 bytes (pipelined: 4)
Apr 16, 2019 15:34:43.605 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.605 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.605 [4736] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fart%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.605 [5056] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F3%2Fart%2F1554962703%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (7 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.605 [4736] DEBUG - Photo transcoder: Request for url [/library/metadata/1631/art/1554083914?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.605 [5056] DEBUG - Photo transcoder: Request for url [/library/metadata/3/art/1554962703?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.605 [4736] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\ad\adc9a497676028dbb76d51bb406a2dc27e7dc57d.jpg is 15635.
Apr 16, 2019 15:34:43.605 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.605 [5056] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.605 [12168] DEBUG - Request: [192.168.86.150:33078 (Subnet)] GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F814%2Fart%2F1554962732%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.605 [12168] DEBUG - Photo transcoder: Request for url [/library/metadata/814/art/1554962732?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.605 [3684] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fart%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 4ms 15635 bytes (pipelined: 5)
Apr 16, 2019 15:34:43.605 [12168] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.605 [5056] DEBUG - Computed media url for item 3: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:34:43.605 [5056] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:34:43.621 [12168] DEBUG - Computed media url for item 814: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:34:43.621 [12168] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:34:43.621 [5056] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1698641%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41
Apr 16, 2019 15:34:43.621 [5056] DEBUG - Calculated media file path for path [metadata://art/com.plexapp.agents.imdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\7\fb3cf9f5089b9a24986c9da60ec9fbccef91939.bundle\Contents\_combined/art/com.plexapp.agents.imdb_9ed3073899ad7570eb1bbb830df0cfadd3a8be41"]
Apr 16, 2019 15:34:43.621 [5056] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.621 [5056] DEBUG - Photo cache obtained 139917 bytes from /library/metadata/3/art/1554962703?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.621 [5056] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\0c\0c9dc81eab128d77f627e55c2b84c3427be92e52.jpg
Apr 16, 2019 15:34:43.637 [12168] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1485796%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_f999c2b40847843c2212477057e693d08c147be0
Apr 16, 2019 15:34:43.637 [12168] DEBUG - Calculated media file path for path [metadata://art/com.plexapp.agents.imdb_f999c2b40847843c2212477057e693d08c147be0]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\a\8d4e53cc92a042f37eb7ffcd452e76daa87bc07.bundle\Contents\_combined/art/com.plexapp.agents.imdb_f999c2b40847843c2212477057e693d08c147be0"]
Apr 16, 2019 15:34:43.637 [12168] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.637 [12168] DEBUG - Photo cache obtained 216223 bytes from /library/metadata/814/art/1554962732?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.637 [12168] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e1\e1b0ec86cb9280bb94ad9ef8cb8ffa09aa57b908.jpg
Apr 16, 2019 15:34:43.668 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1671%2Fthumb%2F1554837735%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Photo transcoder: Request for url [/library/metadata/1671/thumb/1554837735?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\0c\0c2e3aa5ef379c225e8c19f6bb6a02e8fd25262f.jpg is 3446.
Apr 16, 2019 15:34:43.668 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1671%2Fthumb%2F1554837735%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (8 live) TLS GZIP 2ms 3446 bytes (pipelined: 6)
Apr 16, 2019 15:34:43.668 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [4736] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1657%2Fthumb%2F1554962753%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.668 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [4736] DEBUG - Photo transcoder: Request for url [/library/metadata/1657/thumb/1554962753?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Request: [192.168.86.150:33080 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Photo transcoder: Request for url [/library/metadata/1626/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.668 [6352] DEBUG - Request: [192.168.86.150:33079 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fthumb%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.668 [4736] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.668 [4736] DEBUG - Computed media url for item 1657: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:34:43.668 [4736] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:34:43.668 [6352] DEBUG - Photo transcoder: Request for url [/library/metadata/1631/thumb/1554083914?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.668 [5056] DEBUG - Created thumbnail of size 300x169, has pixels: 1
Apr 16, 2019 15:34:43.668 [1904] DEBUG - Computed media url for item 1626: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:34:43.668 [1904] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:34:43.668 [6352] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\e4\e4a836e160410b8e18723f4f2b5a391aef1573de.jpg is 12207.
Apr 16, 2019 15:34:43.668 [4544] DEBUG - Completed: [192.168.86.150:33079] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1631%2Fthumb%2F1554083914%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 6ms 12207 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.683 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.683 [2396] DEBUG - Request: [192.168.86.150:33079 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1610%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.683 [2396] DEBUG - Photo transcoder: Request for url [/library/metadata/1610/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.683 [2396] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.683 [3684] DEBUG - Completed: [192.168.86.150:33074] 200 GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F3%2Fart%2F1554962703%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 78ms 11441 bytes (pipelined: 4)
Apr 16, 2019 15:34:43.683 [4736] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6823368%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_16d3730524dae47c7f22128fef528b94159a3ab9
Apr 16, 2019 15:34:43.683 [4736] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_16d3730524dae47c7f22128fef528b94159a3ab9]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\c\a5f0f555fb087a5fb2befba2d1b3e62225eae34.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_16d3730524dae47c7f22128fef528b94159a3ab9"]
Apr 16, 2019 15:34:43.683 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.683 [9360] 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:34:43.683 [4736] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.683 [1904] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_9a3bd802222bf6ce92926b73991ece46007f2a20
Apr 16, 2019 15:34:43.683 [4736] DEBUG - Photo cache obtained 99127 bytes from /library/metadata/1657/thumb/1554962753?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.683 [4736] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\d5\d5fd62f8e4ab2f63b2f32b72ea466bea11a2ae0f.jpg
Apr 16, 2019 15:34:43.683 [2396] DEBUG - Computed media url for item 1610: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:34:43.683 [1904] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_9a3bd802222bf6ce92926b73991ece46007f2a20]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\6\ad3d46b2dcf9610b668cd066a790daa478b6212.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_9a3bd802222bf6ce92926b73991ece46007f2a20"]
Apr 16, 2019 15:34:43.683 [1904] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.683 [6352] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1609%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.683 [2396] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:34:43.683 [1904] DEBUG - Photo cache obtained 590590 bytes from /library/metadata/1626/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.683 [1904] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\56\56d05403cd500b5ca5c7560d4f1c563f2cd39491.jpg
Apr 16, 2019 15:34:43.683 [6352] DEBUG - Photo transcoder: Request for url [/library/metadata/1609/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.683 [9360] DEBUG - MyPlex: updating with 15 access tokens
Apr 16, 2019 15:34:43.683 [6352] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.699 [7712] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.699 [6352] DEBUG - Computed media url for item 1609: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:34:43.699 [6352] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:34:43.699 [12168] DEBUG - Created thumbnail of size 300x169, has pixels: 1
Apr 16, 2019 15:34:43.699 [2396] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt6343314%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2
Apr 16, 2019 15:34:43.699 [2396] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\e\9ecd41e5268418d58875c603783bde66df5af73.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_5bd83b07e9996a2fe4670bc1f0de885f8e13bcf2"]
Apr 16, 2019 15:34:43.699 [4544] DEBUG - Completed: [192.168.86.150:33078] 200 GET /photo/:/transcode?width=300&height=169&minSize=1&url=%2Flibrary%2Fmetadata%2F814%2Fart%2F1554962732%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 99ms 9713 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.715 [2396] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.715 [2396] DEBUG - Photo cache obtained 147983 bytes from /library/metadata/1610/thumb/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.715 [2396] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\f3\f3b8219fa89939a6fe2bf97c37a3021f473c3718.jpg
Apr 16, 2019 15:34:43.715 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.715 [5056] DEBUG - Request: [192.168.86.150:33078 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1603%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.715 [5056] DEBUG - Photo transcoder: Request for url [/library/metadata/1603/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.715 [4736] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:34:43.715 [5056] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.715 [5056] DEBUG - Computed media url for item 1603: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:34:43.715 [5056] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:34:43.715 [6104] 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:34:43.715 [6104] INFO - AutoUpdate: no updates available
Apr 16, 2019 15:34:43.715 [4544] DEBUG - Completed: [192.168.86.150:33073] 200 PUT /updater/check?download=0 (10 live) TLS GZIP 430ms 274 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.715 [6352] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt4701182%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf
Apr 16, 2019 15:34:43.730 [6352] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\d\f1960f8ef8b6ce96cbde090457e2da7f192865b.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_aea7c5959dfb7dc1f372114d8b0c6611e4ceedbf"]
Apr 16, 2019 15:34:43.730 [6352] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.730 [6352] DEBUG - Photo cache obtained 924010 bytes from /library/metadata/1609/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.730 [6352] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\f0\f0fbd1477c840a443d72de85cdd2f6de24a53eca.jpg
Apr 16, 2019 15:34:43.730 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1657%2Fthumb%2F1554962753%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 58ms 7004 bytes (pipelined: 7)
Apr 16, 2019 15:34:43.730 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.730 [12168] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1601%2Fthumb%2F1553202090%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.730 [12168] DEBUG - Photo transcoder: Request for url [/library/metadata/1601/thumb/1553202090?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.730 [12168] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\fd\fd387456fa9b6f24970bf3ce599c52f6ab75abcf.jpg is 8447.
Apr 16, 2019 15:34:43.730 [3684] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1601%2Fthumb%2F1553202090%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 2ms 8447 bytes (pipelined: 8)
Apr 16, 2019 15:34:43.730 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.730 [6104] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1597%2Fthumb%2F1553185444%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.730 [6104] DEBUG - Photo transcoder: Request for url [/library/metadata/1597/thumb/1553185444?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.730 [6104] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\01\01cfb08a5355126045c331dd0c469f6d5c213949.jpg is 13783.
Apr 16, 2019 15:34:43.730 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1597%2Fthumb%2F1553185444%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 3ms 13783 bytes (pipelined: 9)
Apr 16, 2019 15:34:43.746 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1598%2Fthumb%2F1553185445%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Photo transcoder: Request for url [/library/metadata/1598/thumb/1553185445?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\58\58be67a333fa6d619a47e41b5987a0ba14204104.jpg is 18954.
Apr 16, 2019 15:34:43.746 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1598%2Fthumb%2F1553185445%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 4ms 18954 bytes (pipelined: 10)
Apr 16, 2019 15:34:43.746 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.746 [6104] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1599%2Fthumb%2F1553185452%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.746 [6104] DEBUG - Photo transcoder: Request for url [/library/metadata/1599/thumb/1553185452?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.746 [2396] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:34:43.746 [6104] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\d4\d47c37f7d9a98f27596661b29315616674752c32.jpg is 13925.
Apr 16, 2019 15:34:43.746 [3684] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1599%2Fthumb%2F1553185452%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 4ms 13925 bytes (pipelined: 11)
Apr 16, 2019 15:34:43.746 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F636%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Photo transcoder: Request for url [/library/metadata/636/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.746 [5056] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt0141369%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fposters%2Fcom%2Eplexapp%2Eagents%2Eimdb_71bd30588db65552507992a01270c39a7704a3e4
Apr 16, 2019 15:34:43.746 [5056] DEBUG - Calculated media file path for path [metadata://posters/com.plexapp.agents.imdb_71bd30588db65552507992a01270c39a7704a3e4]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\2\d7628129ab5ce440b25ae374315d125f6808ec6.bundle\Contents\_combined/posters/com.plexapp.agents.imdb_71bd30588db65552507992a01270c39a7704a3e4"]
Apr 16, 2019 15:34:43.746 [4736] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\9d\9d59406513b774b482abb11517feb1c9a23b4e70.jpg is 7442.
Apr 16, 2019 15:34:43.746 [5056] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:34:43.746 [5056] DEBUG - Photo cache obtained 256707 bytes from /library/metadata/1603/thumb/1554962751?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:43.746 [5056] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\6a\6aedbf12097004e27a132eec162e79bcd8f8d331.jpg
Apr 16, 2019 15:34:43.762 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F636%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 3ms 7442 bytes (pipelined: 12)
Apr 16, 2019 15:34:43.762 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.762 [6104] DEBUG - Request: [192.168.86.150:33076 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F666%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.762 [3684] DEBUG - Completed: [192.168.86.150:33079] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1610%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 82ms 2750 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.762 [6104] DEBUG - Photo transcoder: Request for url [/library/metadata/666/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.762 [6104] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\32\327f359f3ee025fa863508e6a8d44668ec97d1b1.jpg is 5942.
Apr 16, 2019 15:34:43.762 [1904] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:34:43.762 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:43.762 [4736] DEBUG - Request: [192.168.86.150:33079 (Subnet)] GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F665%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:43.762 [4736] DEBUG - Photo transcoder: Request for url [/library/metadata/665/thumb/1551908649?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:34:43.762 [4736] DEBUG - Content-Length of C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\55\55e353e0bb9ccafc85d172a3e52ff62eb153ae77.jpg is 5911.
Apr 16, 2019 15:34:43.762 [4544] DEBUG - Completed: [192.168.86.150:33076] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F666%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 4ms 5942 bytes (pipelined: 13)
Apr 16, 2019 15:34:43.762 [3684] DEBUG - Completed: [192.168.86.150:33079] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F665%2Fthumb%2F1551908649%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 2ms 5911 bytes (pipelined: 3)
Apr 16, 2019 15:34:43.777 [3684] DEBUG - Completed: [192.168.86.150:33080] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fthumb%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 100ms 8520 bytes (pipelined: 1)
Apr 16, 2019 15:34:43.793 [5056] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:34:43.808 [3684] DEBUG - Completed: [192.168.86.150:33078] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1603%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 92ms 9537 bytes (pipelined: 2)
Apr 16, 2019 15:34:43.808 [6352] DEBUG - Created thumbnail of size 141x211, has pixels: 1
Apr 16, 2019 15:34:43.808 [3684] DEBUG - Completed: [192.168.86.150:33074] 200 GET /photo/:/transcode?width=140&height=211&minSize=1&url=%2Flibrary%2Fmetadata%2F1609%2Fthumb%2F1554962751%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 127ms 12186 bytes (pipelined: 5)
Apr 16, 2019 15:34:44.168 [7712] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:34:45.840 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:34:45.840 [12168] DEBUG - Request: [192.168.86.150:33074 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:34:45.840 [12168] DEBUG - [CompanionProxy] registered new player: 01svskz8w8tvlqnxb32uwh55 (192.168.86.150)
Apr 16, 2019 15:34:50.043 [6116] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.86.123
Apr 16, 2019 15:35:02.386 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.386 [6104] DEBUG - Request: [192.168.86.150:33078 (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 (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.386 [6104] DEBUG - PlayQueue: Converted 'server://9b5f780d8577f02e755f09a1e9e4d5ff4e606b4f/com.plexapp.plugins.library/library/metadata/1626' to 'library://x/item/%2Flibrary%2Fmetadata%2F1626'
Apr 16, 2019 15:35:02.386 [6104] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Apr 16, 2019 15:35:02.386 [6104] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Apr 16, 2019 15:35:02.386 [6104] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Apr 16, 2019 15:35:02.386 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.386 [7008] DEBUG - Request: [192.168.86.150:33080 (Subnet)] GET /photo/:/transcode?width=1920&height=937&opacity=30&background=36383b&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fart%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.386 [7008] DEBUG - Photo transcoder: Request for url [/library/metadata/1626/art/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Apr 16, 2019 15:35:02.402 [6104] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Apr 16, 2019 15:35:02.402 [7008] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.402 [7008] DEBUG - Computed media url for item 1626: http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_b9abc6803ef2ef5cbb4298320e82ec7190560d03
Apr 16, 2019 15:35:02.402 [7008] DEBUG - HTTP requesting GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_b9abc6803ef2ef5cbb4298320e82ec7190560d03
Apr 16, 2019 15:35:02.402 [6104] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.402 [6104] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.402 [6104] 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:35:02.402 [6104] DEBUG - Extras: Using allowable content ratings: G, PG, PG-13.
Apr 16, 2019 15:35:02.402 [6104] DEBUG - Extras: Using genres: Adventure, Comedy.
Apr 16, 2019 15:35:02.402 [6104] DEBUG - Extras: Found 0 extras from library.
Apr 16, 2019 15:35:02.417 [6104] DEBUG - It took 15.625000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.417 [6104] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.417 [6104] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.417 [6104] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:35:02.417 [7008] DEBUG - HTTP 200 response from GET http://127.0.0.1:60627/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt1255919%3Flang%3Den&mediaType=1&url=metadata%3A%2F%2Fart%2Fcom%2Eplexapp%2Eagents%2Eimdb_b9abc6803ef2ef5cbb4298320e82ec7190560d03
Apr 16, 2019 15:35:02.417 [7008] DEBUG - Calculated media file path for path [metadata://art/com.plexapp.agents.imdb_b9abc6803ef2ef5cbb4298320e82ec7190560d03]: ["C:\Users\mike1\AppData\Local\Plex Media Server\Metadata\Movies\6\ad3d46b2dcf9610b668cd066a790daa478b6212.bundle\Contents\_combined/art/com.plexapp.agents.imdb_b9abc6803ef2ef5cbb4298320e82ec7190560d03"]
Apr 16, 2019 15:35:02.417 [7008] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Apr 16, 2019 15:35:02.417 [7008] DEBUG - Photo cache obtained 288250 bytes from /library/metadata/1626/art/1554962752?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Apr 16, 2019 15:35:02.417 [7008] DEBUG - Saving original media file to C:\Users\mike1\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\9f\9f878f6a0c50b562c64f5110b11ba7bab7b54824.jpg
Apr 16, 2019 15:35:02.417 [4544] DEBUG - Completed: [192.168.86.150:33078] 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 (10 live) TLS GZIP 28ms 5137 bytes (pipelined: 3)
Apr 16, 2019 15:35:02.464 [7008] DEBUG - Created thumbnail of size 1920x1080, has pixels: 1
Apr 16, 2019 15:35:02.496 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.496 [6200] DEBUG - Request: [192.168.86.150:33078 (Subnet)] GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - It took 31.250000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.511 [7008] DEBUG - Converted 32-bit thumbnail of type 1 to 24-bit with pixels
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3305 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8168, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3306 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8170, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3307 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8172, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3308 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8174, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3309 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8176, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Selecting best audio stream for part ID 3310 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Audio Stream: 8178, Subtitle Stream: -1
Apr 16, 2019 15:35:02.511 [6200] DEBUG - Activity: registered new activity 8181b8a9-699e-4908-bdc3-a37bf0f66d76 - Refreshing
Apr 16, 2019 15:35:02.511 [3232] DEBUG - Activity: updated activity 8181b8a9-699e-4908-bdc3-a37bf0f66d76 - completed 0% - Refreshing
Apr 16, 2019 15:35:02.511 [3232] DEBUG - Activity: Ended activity 8181b8a9-699e-4908-bdc3-a37bf0f66d76.
Apr 16, 2019 15:35:02.511 [4544] DEBUG - Completed: [192.168.86.150:33078] 200 GET /library/metadata/1626?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1 (10 live) TLS GZIP 14ms 5909 bytes (pipelined: 4)
Apr 16, 2019 15:35:02.574 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.574 [6104] DEBUG - Request: [192.168.86.150:33078 (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=r7gjzxs0cr0kuom6b3licgbu&subtitles=burn&Accept-Language=en (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.574 [6104] DEBUG - Found session GUID of r7gjzxs0cr0kuom6b3licgbu in session start.
Apr 16, 2019 15:35:02.574 [6104] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Apr 16, 2019 15:35:02.574 [6104] 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=ku51x4jfbmfc2vktkogxv7gn
Apr 16, 2019 15:35:02.574 [6104] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.574 [6104] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.574 [6104] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.574 [6104] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.574 [6104] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:35:02.589 [6104] DEBUG - Codecs: testing h264_qsv (encoder)
Apr 16, 2019 15:35:02.589 [6104] DEBUG - Codecs: hardware transcoding: testing API qsv
Apr 16, 2019 15:35:02.605 [4544] DEBUG - Completed: [192.168.86.150:33080] 200 GET /photo/:/transcode?width=1920&height=937&opacity=30&background=36383b&minSize=1&url=%2Flibrary%2Fmetadata%2F1626%2Fart%2F1554962752%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (10 live) TLS GZIP 210ms 138985 bytes (pipelined: 2)
Apr 16, 2019 15:35:02.605 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.605 [6200] DEBUG - Request: [192.168.86.150:33079 (Subnet)] GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1101&state=playing&hasMDE=1&time=0&duration=5435000 (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.605 [6200] DEBUG - Client [ku51x4jfbmfc2vktkogxv7gn] 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:35:02.605 [6200] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.621 [6200] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.621 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.621 [6200] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.621 [6200] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:35:02.621 [6200] DEBUG - [Now] User is Mike1825 (ID: 1)
Apr 16, 2019 15:35:02.621 [6200] DEBUG - [Now] Device is Chrome (Chrome).
Apr 16, 2019 15:35:02.621 [6200] DEBUG - [Now] Profile is Web
Apr 16, 2019 15:35:02.621 [6200] DEBUG - [Now] Updated play state for /library/metadata/1626.
Apr 16, 2019 15:35:02.621 [6200] 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:35:02.621 [6200] 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:35:02.621 [6200] 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:35:02.621 [6200] DEBUG - Statistics: (01svskz8w8tvlqnxb32uwh55) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Apr 16, 2019 15:35:02.621 [4544] DEBUG - Completed: [192.168.86.150:33079] 200 GET /:/timeline?ratingKey=1626&key=%2Flibrary%2Fmetadata%2F1626&playbackTime=0&playQueueItemID=1101&state=playing&hasMDE=1&time=0&duration=5435000 (10 live) TLS GZIP 22ms 500 bytes (pipelined: 4)
Apr 16, 2019 15:35:02.621 [3684] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.621 [7008] DEBUG - Request: [192.168.86.150:33080 (Subnet)] GET /status/sessions (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.636 [7008] DEBUG - [Now] Adding 1 sessions.
Apr 16, 2019 15:35:02.636 [4544] DEBUG - Completed: [192.168.86.150:33080] 200 GET /status/sessions (10 live) TLS GZIP 2ms 5299 bytes (pipelined: 3)
Apr 16, 2019 15:35:02.636 [4544] DEBUG - Auth: authenticated user 1 as Mike1825
Apr 16, 2019 15:35:02.636 [6200] DEBUG - Request: [192.168.86.150:33080 (Subnet)] GET /playQueues/601?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (10 live) TLS GZIP Signed-in Token (Mike1825)
Apr 16, 2019 15:35:02.636 [6200] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Apr 16, 2019 15:35:02.636 [6200] DEBUG - PlayQueue: No need to refresh, active window is operational.
Apr 16, 2019 15:35:02.636 [6200] DEBUG - It took 0.000000 ms to retrieve 125 items.
Apr 16, 2019 15:35:02.636 [6200] DEBUG - PlayQueue: Client 01svskz8w8tvlqnxb32uwh55 requested ownership of play queue 601, but already had it.
Apr 16, 2019 15:35:02.636 [6200] DEBUG - We're going to try to auto-select an audio stream for account 1.
Apr 16, 2019 15:35:02.636 [6200] DEBUG - Selecting best audio stream for part ID 3303 (autoselect: 0 language: en)
Apr 16, 2019 15:35:02.636 [6200] DEBUG - Audio Stream: 8180, Subtitle Stream: -1
Apr 16, 2019 15:35:02.652 [3684] DEBUG - Completed: [192.168.86.150:33080] 200 GET /playQueues/601?repeat=0&own=1&includeChapters=1&includeGeolocation=1&includeExternalMedia=1 (10 live) TLS GZIP 9ms 5137 bytes (pipelined: 4)
Apr 16, 2019 15:35:02.714 [6104] ERROR - [FFMPEG] - Error initializing an MFX session: -3.
Apr 16, 2019 15:35:02.714 [6104] DEBUG - Codecs: hardware transcoding: opening hw device failed - probably not supported by this system, error: Unknown error occurred
Apr 16, 2019 15:35:02.714 [6104] DEBUG - Codecs: testing h264_nvenc (encoder)
Apr 16, 2019 15:35:02.714 [6104] ERROR - [FFMPEG] - Cannot load nvEncodeAPI.dll
Apr 16, 2019 15:35:02.714 [6104] ERROR - [FFMPEG] - The minimum required Nvidia driver for nvenc is 378.66 or newer
Apr 16, 2019 15:35:02.714 [6104] WARN - avcodec_open2 returned -1313558101 for encoder 'h264_nvenc'
Apr 16, 2019 15:35:02.714 [6104] DEBUG - Codecs: testing h264_mf (encoder)
Apr 16, 2019 15:35:02.886 [6104] INFO - [FFMPEG] - MFT name: 'Intel® Quick Sync Video H.264 Encoder MFT'
Apr 16, 2019 15:35:02.949 [7612] 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:35:02.949 [7612] DEBUG - Jobs: Starting child process with pid 5232
