Mar 02, 2019 12:37:47.714 [5916] INFO - Plex Media Server v1.15.1.710-ece95b3a1 - Microsoft PC x64 - build: windows-x86 windows - GMT -05:00
Mar 02, 2019 12:37:47.714 [5916] INFO - Windows version: 10.0 (Build 17763), language en-US
Mar 02, 2019 12:37:47.715 [5916] INFO - 12 3192 MHz processor(s): Architecture=0, Level=6, Revision=40458 Processor Identifier=Intel64 Family 6 Model 158 Stepping 10, GenuineIntel
Mar 02, 2019 12:37:47.715 [5916] DEBUG - "C:\Program Files (x86)\Plex\Plex Media Server\Plex Media Server.exe" 
Mar 02, 2019 12:37:47.713 [12580] DEBUG - Granting application loopback exemption: "C:\Windows\system32\CheckNetIsolation.exe" LoopbackExempt -a "-n=caf9e577.plex_aam28m9va5cke"
Mar 02, 2019 12:37:47.811 [12580] ERROR - CheckNetIsolation.exe exited with code 0x5
Mar 02, 2019 12:37:47.811 [12580] ERROR - Failed to grant application loopback exemption
Mar 02, 2019 12:37:47.813 [12580] DEBUG - BPQ: [Idle] -> [Starting]
Mar 02, 2019 12:37:47.817 [12580] DEBUG - FeatureManager: Using cached data for features list
Mar 02, 2019 12:37:47.818 [12580] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.26.0, threadsafe=1
Mar 02, 2019 12:37:47.824 [12580] INFO - SQLITE3:0x5cbf5430, 283, recovered 215 frames from WAL file C:\Users\iPlex\AppData\Local\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.db-wal
Mar 02, 2019 12:37:47.900 [12580] DEBUG - Running migrations. (EPG 0)
Mar 02, 2019 12:37:48.325 [12580] DEBUG - ChangestampAllocator: initialized to 482872
Mar 02, 2019 12:37:48.325 [12580] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.26.0, threadsafe=1
Mar 02, 2019 12:37:48.334 [12580] DEBUG - Running migrations. (EPG 0)
Mar 02, 2019 12:37:49.452 [12580] DEBUG - CERT: Installed certificate with fingerprint 9a:a5:5e:3c:e5:a7:a3:05:ab:8c:8c:e9:3c:0f:58:e5:9d:a0:87:f4.
Mar 02, 2019 12:37:49.452 [12580] DEBUG - CERT: Installed new private key.
Mar 02, 2019 12:37:49.452 [12580] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.f99f76db00b042a28ae6d150db9e6bf1.plex.direct
Mar 02, 2019 12:37:49.453 [12580] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Mar 02, 2019 12:37:49.454 [12580] INFO - OCSP: Successfully retrieved response from cache.
Mar 02, 2019 12:37:49.454 [12580] DEBUG - CERT: Installed intermediate certificate.
Mar 02, 2019 12:37:49.454 [12580] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Mar 02, 2019 12:37:49.455 [12580] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Mar 02, 2019 12:37:49.456 [12580] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Mar 02, 2019 12:37:49.507 [9736] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Mar 02, 2019 12:37:49.507 [12580] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Mar 02, 2019 12:37:49.508 [12580] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Mar 02, 2019 12:37:49.509 [13640] DEBUG - Relay: read 15 cached entries from hosts file
Mar 02, 2019 12:37:49.509 [13640] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:49.509 [12580] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Mar 02, 2019 12:37:49.511 [12580] DEBUG - MyPlex: updating with 34 access tokens
Mar 02, 2019 12:37:49.513 [6796] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Mar 02, 2019 12:37:49.513 [12580] DEBUG - Opening 1 database sessions to library (), SQLite 3.26.0, threadsafe=1
Mar 02, 2019 12:37:49.514 [6796] DEBUG - HTTP requesting GET https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Mar 02, 2019 12:37:49.515 [8420] DEBUG - File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1" changed: -1 => 2019-02-27 15:44:33 (1551300273).
Mar 02, 2019 12:37:49.515 [8420] DEBUG - File "C:\Users\iPlex\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2019-02-23 20:06:57 (1550970417).
Mar 02, 2019 12:37:49.516 [8420] DEBUG - Scanning for plug-ins in "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1"
Mar 02, 2019 12:37:49.524 [8420] DEBUG - Scanning for plug-ins in "C:\Users\iPlex\AppData\Local\Plex Media Server\Plug-ins"
Mar 02, 2019 12:37:49.524 [8420] DEBUG - PluginRepository::setStartState: 1, startingSystem
Mar 02, 2019 12:37:49.525 [8420] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\System.bundle.
Mar 02, 2019 12:37:49.525 [8420] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Mar 02, 2019 12:37:49.529 [8420] DEBUG - Starting file watcher for com.plexapp.system
Mar 02, 2019 12:37:49.529 [8420] 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-ece95b3a1\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\System.bundle"
Mar 02, 2019 12:37:49.535 [8420] DEBUG - Spawned plug-in com.plexapp.system with PID 5096
Mar 02, 2019 12:37:49.535 [8420] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Mar 02, 2019 12:37:49.615 [9736] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.1 sec.
Mar 02, 2019 12:37:49.720 [10956] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\iPlex\AppData\Local\Plex Media Server\Crash Reports\1.15.1.710-ece95b3a1" --version=1.15.1.710-ece95b3a1 --platform=Windows "--platformVersion=10.0 (Build 17763)" --serverUuid=76ed9dfcde071d4f7c944ee568c6144a5afa3aaf --userId=sigfreund@cox.net --sentryUrl=https://sentry.io/api/1233455/minidump --sentryKey=a3e2688bd169450d821535e4c3a607bf --vendor=Microsoft --model=x64 --device=PC 
Mar 02, 2019 12:37:49.751 [10956] DEBUG - Jobs: Starting child process with pid 8592
Mar 02, 2019 12:37:49.841 [6048] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\CrashUploader.exe' exit code for process 8592 is 0 (success)
Mar 02, 2019 12:37:50.164 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:50.231 [6796] DEBUG - HTTP 200 response from GET https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Mar 02, 2019 12:37:50.236 [6796] DEBUG - MyPlex: updating with 34 access tokens
Mar 02, 2019 12:37:50.238 [13640] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:50.794 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:50.794 [13640] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.351 [13640] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.351 [13640] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.601 [13640] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.602 [13640] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.687 [8420] DEBUG - Reading 486 bytes in the body, code is 200
Mar 02, 2019 12:37:51.688 [8420] DEBUG - [com.plexapp.system] Plug-in running on port 50643.
Mar 02, 2019 12:37:51.688 [8420] DEBUG -  * Plug-in handles prefix: /system
Mar 02, 2019 12:37:51.688 [8420] DEBUG -  * Plug-in handles prefix: /player
Mar 02, 2019 12:37:51.688 [8420] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Mar 02, 2019 12:37:51.689 [8420] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Mar 02, 2019 12:37:51.689 [8420] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Mar 02, 2019 12:37:51.689 [8420] DEBUG - Updating the list of agents known by the system.
Mar 02, 2019 12:37:51.690 [12580] DEBUG - Image transcode cache directory: "C:\Users\iPlex\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Mar 02, 2019 12:37:51.690 [8420] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Mar 02, 2019 12:37:51.690 [12580] DEBUG - Transcoder: Cleaning old transcode directories.
Mar 02, 2019 12:37:51.690 [8420] DEBUG - HTTP requesting GET http://127.0.0.1:50643/system/agents
Mar 02, 2019 12:37:51.693 [12580] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Mar 02, 2019 12:37:51.694 [12580] DEBUG - MyPlex: updating with 34 access tokens
Mar 02, 2019 12:37:51.696 [10956] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.696 [12580] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Mar 02, 2019 12:37:51.698 [12580] DEBUG - MyPlex: username is sigfreund, login is sigfreund@cox.net, home is 1, has pin
Mar 02, 2019 12:37:51.698 [12580] DEBUG - PublicAddressManager: Starting.
Mar 02, 2019 12:37:51.699 [12580] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Mar 02, 2019 12:37:51.699 [12580] DEBUG - NetworkInterface: Notified of network changed (force=0)
Mar 02, 2019 12:37:51.699 [4856] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.699 [1444] DEBUG - PublicAddressManager: Obtaining public IP.
Mar 02, 2019 12:37:51.700 [1444] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:37:51.709 [12580] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 3' (fe80::4d4a:8e80:3522:9558%6,169.254.149.88) because it has no gateway
Mar 02, 2019 12:37:51.709 [12580] DEBUG - NetworkInterface: ignoring adapter 'Bluetooth Network Connection' (fe80::b42f:732b:4fef:8951%7,169.254.137.81) because it has no gateway
Mar 02, 2019 12:37:51.709 [12580] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Mar 02, 2019 12:37:51.709 [12580] DEBUG - Detected primary interface: 192.168.1.111
Mar 02, 2019 12:37:51.709 [12580] DEBUG - Network interfaces:
Mar 02, 2019 12:37:51.710 [12580] DEBUG -  * 15 Ethernet 2 {C7C7675D-C915-4604-B3DB-B61710F69819} (192.168.1.111) (loopback: 0)
Mar 02, 2019 12:37:51.710 [12580] DEBUG - Creating NetworkServices singleton.
Mar 02, 2019 12:37:51.710 [12580] DEBUG - NetworkServices: Initializing...
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.711 [1308] DEBUG - Network change for advertiser.
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.711 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.711 [1308] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Mar 02, 2019 12:37:51.712 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.712 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.712 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.712 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.712 [1308] DEBUG - Network change for advertiser.
Mar 02, 2019 12:37:51.712 [1308] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Mar 02, 2019 12:37:51.712 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.713 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.713 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.713 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.713 [1308] DEBUG - Network change for advertiser.
Mar 02, 2019 12:37:51.713 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.713 [1308] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Mar 02, 2019 12:37:51.714 [12580] DEBUG - NetworkService: Creating new service.
Mar 02, 2019 12:37:51.714 [12580] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Mar 02, 2019 12:37:51.714 [1308] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Mar 02, 2019 12:37:51.714 [12580] DEBUG - NetworkService: Quick dispatch of network change.
Mar 02, 2019 12:37:51.714 [1308] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Mar 02, 2019 12:37:51.714 [1308] DEBUG - NetworkService: Browsing on interface 192.168.1.111 on broadcast address 192.168.1.255 (index: 0)
Mar 02, 2019 12:37:51.715 [1308] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Mar 02, 2019 12:37:51.715 [1308] DEBUG - NetworkService: Browsing on interface 192.168.1.111 on broadcast address 192.168.1.255 (index: 0)
Mar 02, 2019 12:37:51.715 [1308] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Mar 02, 2019 12:37:51.715 [1308] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Mar 02, 2019 12:37:51.716 [1308] DEBUG - NetworkService: Browsing on interface 192.168.1.111 on broadcast address 239.255.255.250 (index: 0)
Mar 02, 2019 12:37:51.780 [13640] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.813 [12580] DEBUG - Gracenote Version 3.08.1.4717 (build: 2016-08-22 10:35-0700)
Mar 02, 2019 12:37:51.814 [12580] DEBUG - Gracenote: Using SQLite 3.26.0
Mar 02, 2019 12:37:51.815 [12580] DEBUG - Starting HTTP server.
Mar 02, 2019 12:37:51.815 [4140] DEBUG - Plex Tuner Service.exe starting.
Mar 02, 2019 12:37:51.816 [12580] DEBUG - Running server...
Mar 02, 2019 12:37:51.816 [4140] 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.1.710-ece95b3a1 32600 /waitmutex 
Mar 02, 2019 12:37:51.816 [14844] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Mar 02, 2019 12:37:51.816 [12580] DEBUG - Waiting for server condition to be signaled.
Mar 02, 2019 12:37:51.817 [14844] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:51.819 [15092] DEBUG - Request: [::ffff:127.0.0.1:50634 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.820 [2428] DEBUG - Request: [::ffff:127.0.0.1:50635 (Loopback)] GET / (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.820 [15092] DEBUG - Translator: Found locale ar
Mar 02, 2019 12:37:51.821 [15092] DEBUG - Translator: Found locale cs_CZ
Mar 02, 2019 12:37:51.821 [15092] DEBUG - Translator: Found locale da
Mar 02, 2019 12:37:51.821 [15092] DEBUG - Translator: Found locale de
Mar 02, 2019 12:37:51.822 [15092] DEBUG - Translator: Found locale en
Mar 02, 2019 12:37:51.821 [5724] DEBUG - Request: [::ffff:127.0.0.1:50641 (Loopback)] GET / (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.822 [17776] DEBUG - Request: [::ffff:127.0.0.1:50642 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.822 [15092] DEBUG - Translator: Found locale es
Mar 02, 2019 12:37:51.822 [15092] DEBUG - Translator: Found locale et_EE
Mar 02, 2019 12:37:51.823 [15092] DEBUG - Translator: Found locale fr
Mar 02, 2019 12:37:51.823 [15092] DEBUG - Translator: Found locale he
Mar 02, 2019 12:37:51.823 [15092] DEBUG - Translator: Found locale hr_HR
Mar 02, 2019 12:37:51.823 [15092] DEBUG - Translator: Found locale hu_HU
Mar 02, 2019 12:37:51.823 [15092] DEBUG - Translator: Found locale it
Mar 02, 2019 12:37:51.824 [15092] DEBUG - Translator: Found locale ja_JP
Mar 02, 2019 12:37:51.824 [15092] DEBUG - Translator: Found locale ko
Mar 02, 2019 12:37:51.824 [15092] DEBUG - Translator: Found locale lt
Mar 02, 2019 12:37:51.824 [15092] DEBUG - Translator: Found locale nl
Mar 02, 2019 12:37:51.824 [15092] DEBUG - Translator: Found locale nl_BE
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale no
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale pt_BR
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale ro
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale ru
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale sr
Mar 02, 2019 12:37:51.825 [15092] DEBUG - Translator: Found locale sv
Mar 02, 2019 12:37:51.826 [15092] DEBUG - Translator: Found locale zh_CN
Mar 02, 2019 12:37:51.852 [4140] DEBUG - Jobs: Starting child process with pid 1264
Mar 02, 2019 12:37:51.856 [15092] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:51.857 [15092] DEBUG - HTTP requesting GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:51.858 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50642] 200 GET /servers (5 live) GZIP 37ms 488 bytes
Mar 02, 2019 12:37:51.859 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50635] 200 GET / (5 live) GZIP 40ms 2864 bytes
Mar 02, 2019 12:37:51.859 [6936] DEBUG - Completed: [::ffff:127.0.0.1:50641] 200 GET / (5 live) GZIP 38ms 2864 bytes
Mar 02, 2019 12:37:51.876 [8420] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/system/agents
Mar 02, 2019 12:37:51.877 [8420] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Mar 02, 2019 12:37:51.884 [8420] DEBUG - The system knows about 13 agents.
Mar 02, 2019 12:37:51.885 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\Fanart-TV.bundle.
Mar 02, 2019 12:37:51.885 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\Framework.bundle.
Mar 02, 2019 12:37:51.885 [5796] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\HTbackdrops.bundle.
Mar 02, 2019 12:37:51.886 [8420] DEBUG - Waiting for 18 plug-ins to finish starting.
Mar 02, 2019 12:37:51.887 [17776] DEBUG - Request: [::ffff:127.0.0.1:50652 (Loopback)] GET / (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.888 [2428] DEBUG - Request: [::ffff:127.0.0.1:50653 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.890 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\LastFM.bundle.
Mar 02, 2019 12:37:51.891 [5796] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Mar 02, 2019 12:37:51.890 [9000] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Mar 02, 2019 12:37:51.891 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\LyricFind.bundle.
Mar 02, 2019 12:37:51.891 [5796] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\LocalMedia.bundle.
Mar 02, 2019 12:37:51.892 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50652] 200 GET / (5 live) GZIP 6ms 2864 bytes
Mar 02, 2019 12:37:51.893 [18400] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Mar 02, 2019 12:37:51.894 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\Media-Flags.bundle.
Mar 02, 2019 12:37:51.895 [9000] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Mar 02, 2019 12:37:51.895 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\MoviePosterDB.bundle.
Mar 02, 2019 12:37:51.897 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50653] 200 GET /library/sections (5 live) GZIP 9ms 1254 bytes
Mar 02, 2019 12:37:51.897 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\OpenSubtitles.bundle.
Mar 02, 2019 12:37:51.899 [9000] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Mar 02, 2019 12:37:51.899 [5796] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Mar 02, 2019 12:37:51.899 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\PersonalMedia.bundle.
Mar 02, 2019 12:37:51.899 [5796] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\PlexMovie.bundle.
Mar 02, 2019 12:37:51.901 [18400] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Mar 02, 2019 12:37:51.901 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\PlexMusic.bundle.
Mar 02, 2019 12:37:51.902 [9000] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Mar 02, 2019 12:37:51.903 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\PlexThemeMusic.bundle.
Mar 02, 2019 12:37:51.903 [5796] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Mar 02, 2019 12:37:51.903 [5796] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\Scanners.bundle.
Mar 02, 2019 12:37:51.905 [18400] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Mar 02, 2019 12:37:51.906 [18400] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\TheMovieDB.bundle.
Mar 02, 2019 12:37:51.906 [9000] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Mar 02, 2019 12:37:51.906 [5796] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\TheTVDB.bundle.
Mar 02, 2019 12:37:51.907 [9000] DEBUG - Starting plug-in C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\WebClient.bundle.
Mar 02, 2019 12:37:51.909 [5724] DEBUG - Request: [::ffff:127.0.0.1:50654 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.910 [18400] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Mar 02, 2019 12:37:51.910 [9000] DEBUG - Starting plug-in C:\Users\iPlex\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Mar 02, 2019 12:37:51.911 [5796] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Mar 02, 2019 12:37:51.912 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50654] 200 GET /channels/all (5 live) GZIP 2ms 387 bytes
Mar 02, 2019 12:37:51.913 [8420] DEBUG - Starting scheduled updates, every 86400 seconds
Mar 02, 2019 12:37:51.914 [8420] INFO - LibraryUpdateManager path watching is disabled
Mar 02, 2019 12:37:51.914 [8420] DEBUG - Done waiting for all plug-ins to start.
Mar 02, 2019 12:37:51.915 [8420] DEBUG - PluginRepository::setStartState: 3, complete
Mar 02, 2019 12:37:51.915 [0996] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Mar 02, 2019 12:37:51.917 [0996] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Mar 02, 2019 12:37:51.918 [0996] 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-ece95b3a1\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-ece95b3a1\PlexMovie.bundle"
Mar 02, 2019 12:37:51.925 [2428] DEBUG - Request: [::ffff:127.0.0.1:50655 (Loopback)] GET /library/sections (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.928 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50655] 200 GET /library/sections (5 live) GZIP 3ms 1254 bytes
Mar 02, 2019 12:37:51.936 [0996] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 1200
Mar 02, 2019 12:37:51.936 [0996] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Mar 02, 2019 12:37:51.937 [17776] DEBUG - Request: [::ffff:127.0.0.1:50656 (Loopback)] GET /channels/all (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:51.938 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50656] 200 GET /channels/all (5 live) GZIP 1ms 387 bytes
Mar 02, 2019 12:37:51.956 [15092] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:51.956 [15092] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Mar 02, 2019 12:37:51.960 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50634] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP 141ms 19472 bytes
Mar 02, 2019 12:37:51.962 [1444] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:37:51.962 [1444] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 70.171.4.132
Mar 02, 2019 12:37:52.043 [10992] DEBUG - Gracenote: Loading 29 locales in 0.2 seconds.
Mar 02, 2019 12:37:52.277 [10956] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.385 [14844] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.390 [14844] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.423 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.438 [4856] DEBUG - MyPlex: username is sigfreund, login is sigfreund@cox.net, home is 1, has pin
Mar 02, 2019 12:37:52.438 [4856] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.932 [14844] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:52.937 [14844] DEBUG - Running database fix-ups.
Mar 02, 2019 12:37:52.946 [14844] DEBUG - Database Fixups: Destroyed 0 transient items.
Mar 02, 2019 12:37:52.988 [14844] DEBUG - Collections: Found 0 collections tags in section 2.
Mar 02, 2019 12:37:52.989 [14844] DEBUG - Collections: We had 0 collections in the library already.
Mar 02, 2019 12:37:52.989 [17776] DEBUG - Request: [::ffff:127.0.0.1:50660 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:52.989 [14844] DEBUG - Collections: Synced tags in section 2 in 40ms.
Mar 02, 2019 12:37:52.990 [17776] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Mar 02, 2019 12:37:52.990 [17776] DEBUG - HTTP requesting GET http://127.0.0.1:50643/system/messaging/clear_events/com.plexapp.agents.imdb
Mar 02, 2019 12:37:52.994 [14844] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 4ms.
Mar 02, 2019 12:37:52.998 [14844] DEBUG - Collection: Refreshed visibility in section 2 in 4ms.
Mar 02, 2019 12:37:53.001 [14844] DEBUG - Collections: Found 282 collections tags in section 3.
Mar 02, 2019 12:37:53.002 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.005 [14844] DEBUG - Collections: We had 282 collections in the library already.
Mar 02, 2019 12:37:53.005 [14844] DEBUG - Collections: Synced tags in section 3 in 6ms.
Mar 02, 2019 12:37:53.017 [4856] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.034 [17776] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/system/messaging/clear_events/com.plexapp.agents.imdb
Mar 02, 2019 12:37:53.034 [17776] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Mar 02, 2019 12:37:53.034 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50660] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (5 live) GZIP 45ms 330 bytes
Mar 02, 2019 12:37:53.095 [5724] DEBUG - Request: [::ffff:127.0.0.1:50663 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:53.096 [5724] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Mar 02, 2019 12:37:53.097 [5724] DEBUG - HTTP requesting GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/resourceHashes
Mar 02, 2019 12:37:53.124 [14844] WARN - Held transaction for too long (..\Library\MetadataCollection.cpp:236): 0.140625 seconds
Mar 02, 2019 12:37:53.127 [14844] DEBUG - Collections: Refreshed attributes for 282 collections in section 3 in 121ms.
Mar 02, 2019 12:37:53.142 [14844] DEBUG - Collection: Refreshed visibility in section 3 in 15ms.
Mar 02, 2019 12:37:53.144 [14844] DEBUG - Collections: Found 0 collections tags in section 4.
Mar 02, 2019 12:37:53.144 [14844] DEBUG - Collections: We had 0 collections in the library already.
Mar 02, 2019 12:37:53.145 [14844] DEBUG - Collections: Synced tags in section 4 in 1ms.
Mar 02, 2019 12:37:53.146 [14844] DEBUG - Collections: Refreshed attributes for 0 collections in section 4 in 1ms.
Mar 02, 2019 12:37:53.148 [14844] DEBUG - Collection: Refreshed visibility in section 4 in 2ms.
Mar 02, 2019 12:37:53.150 [14844] DEBUG - Collections: Found 0 collections tags in section 6.
Mar 02, 2019 12:37:53.151 [14844] DEBUG - Collections: We had 0 collections in the library already.
Mar 02, 2019 12:37:53.151 [14844] DEBUG - Collections: Synced tags in section 6 in 2ms.
Mar 02, 2019 12:37:53.153 [14844] DEBUG - Collections: Refreshed attributes for 0 collections in section 6 in 1ms.
Mar 02, 2019 12:37:53.154 [14844] DEBUG - Collection: Refreshed visibility in section 6 in 1ms.
Mar 02, 2019 12:37:53.155 [14844] DEBUG - Running database fix-ups completed in 0.2 seconds.
Mar 02, 2019 12:37:53.156 [11044] DEBUG - DVR: Starting up.
Mar 02, 2019 12:37:53.156 [14844] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.157 [1476] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.158 [11044] DEBUG - Activity: registered new activity 93fa550c-8d8b-47d7-b22e-25bbf6114c1e - Processing subscriptions
Mar 02, 2019 12:37:53.221 [5724] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/resourceHashes
Mar 02, 2019 12:37:53.221 [5724] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Mar 02, 2019 12:37:53.222 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50663] 200 GET /:/plugins/com.plexapp.system/resourceHashes (5 live) GZIP 127ms 3498 bytes
Mar 02, 2019 12:37:53.239 [15092] DEBUG - Request: [::ffff:127.0.0.1:50668 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (7 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:53.240 [15092] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:53.240 [15092] DEBUG - HTTP requesting GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:53.240 [2428] DEBUG - Request: [::ffff:127.0.0.1:50669 (Loopback)] GET / (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:53.243 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50669] 200 GET / (6 live) GZIP 2ms 2855 bytes
Mar 02, 2019 12:37:53.267 [15092] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Mar 02, 2019 12:37:53.268 [15092] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60036 bytes of content.
Mar 02, 2019 12:37:53.271 [6936] DEBUG - Completed: [::ffff:127.0.0.1:50668] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 32ms 19472 bytes
Mar 02, 2019 12:37:53.555 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.560 [4856] DEBUG - Sync: onMyPlexSignIn
Mar 02, 2019 12:37:53.561 [4856] DEBUG - HTTP requesting GET https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.693 [14844] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:53.697 [14844] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Mar 02, 2019 12:37:53.751 [1476] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.041 [17776] DEBUG - Request: [::ffff:127.0.0.1:50674 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:54.042 [17776] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Mar 02, 2019 12:37:54.042 [17776] DEBUG - HTTP requesting GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Mar 02, 2019 12:37:54.048 [17776] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Mar 02, 2019 12:37:54.048 [17776] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Mar 02, 2019 12:37:54.049 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50674] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (6 live) GZIP 7ms 465 bytes
Mar 02, 2019 12:37:54.066 [0996] DEBUG - Reading 148 bytes in the body, code is 200
Mar 02, 2019 12:37:54.066 [0996] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 50676.
Mar 02, 2019 12:37:54.066 [0996] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Mar 02, 2019 12:37:54.067 [0996] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Mar 02, 2019 12:37:54.067 [0996] DEBUG - HTTP requesting GET http://127.0.0.1:50676/:/plugins/com.plexapp.agents.imdb/prefs
Mar 02, 2019 12:37:54.110 [0996] DEBUG - HTTP 200 response from GET http://127.0.0.1:50676/:/plugins/com.plexapp.agents.imdb/prefs
Mar 02, 2019 12:37:54.110 [0996] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2222 bytes of content.
Mar 02, 2019 12:37:54.110 [0996] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as f50b8d57814f06592f6359b11738126dc72f58a0
Mar 02, 2019 12:37:54.111 [0996] DEBUG - IVA: Downloading trailer lists.
Mar 02, 2019 12:37:54.149 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/servers/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.149 [4856] DEBUG - Sync: downloaded 0 sync lists, analyzing now...
Mar 02, 2019 12:37:54.150 [4856] DEBUG - Sync: synchronizing 0 sync lists to generators
Mar 02, 2019 12:37:54.150 [4856] DEBUG - Sync: synchronized 0 sync list(s) to 0 generators
Mar 02, 2019 12:37:54.151 [4856] INFO - Sync: downloaded 0 sync list(s) with 0 sync items(s): 0 new, 0 updated, 0 deleted
Mar 02, 2019 12:37:54.151 [12168] DEBUG - BPQ: garbageCollect - sync - forced - 10
Mar 02, 2019 12:37:54.151 [12168] DEBUG - Sync: starting Sync garbage collection: 10, forced
Mar 02, 2019 12:37:54.151 [12168] DEBUG - Sync: garbage collecting filesystem
Mar 02, 2019 12:37:54.151 [4856] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.152 [12168] WARN - Sync: local sync directory "C:\Users\iPlex\AppData\Local\Plex Media Server\Cache\Transcode\Sync+" does not exist
Mar 02, 2019 12:37:54.152 [12168] DEBUG - Sync: garbage collected filesystem
Mar 02, 2019 12:37:54.152 [12168] DEBUG - Sync: finished Sync garbage collection: 10
Mar 02, 2019 12:37:54.312 [0996] DEBUG - IVA: Downloaded 40 trailers for source 1.
Mar 02, 2019 12:37:54.475 [0996] DEBUG - IVA: Downloaded 61 trailers for source 2.
Mar 02, 2019 12:37:54.676 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.680 [11044] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.681 [10956] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.681 [4856] DEBUG - EventSource: Resolving 45.79.197.58 port 443
Mar 02, 2019 12:37:54.681 [12916] DEBUG - EventSource: Resolved 45.79.197.58 to 45.79.197.58
Mar 02, 2019 12:37:54.682 [4856] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:54.749 [12916] DEBUG - EventSource: Connected in 50 ms.
Mar 02, 2019 12:37:54.749 [6936] DEBUG - EventSource: Wrote data, reading reply.
Mar 02, 2019 12:37:55.023 [1444] DEBUG - NAT: UPnP, found device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>
Mar 02, 2019 12:37:55.031 [1444] DEBUG - NAT: UPnP, usable device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>.
Mar 02, 2019 12:37:55.034 [1444] DEBUG - NAT: UPnP, public address is 70.171.4.132
Mar 02, 2019 12:37:55.049 [1444] DEBUG - MyPlex: Sending Server Info to myPlex (user=sigfreund@cox.net, ip=, port=32600)
Mar 02, 2019 12:37:55.050 [1444] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=89ced1d4-2d8e-4d61-b7d8-bc2bf99a6bbb
Mar 02, 2019 12:37:55.254 [6936] DEBUG - EventSource: Read HTTP reply header.
Mar 02, 2019 12:37:55.254 [6936] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Mar 02, 2019 12:37:55.254 [6936] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Mar 02, 2019 12:37:55.255 [11044] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:55.255 [6936] DEBUG - EventSource: Successfully connected to 45.79.197.58.
Mar 02, 2019 12:37:55.255 [8612] DEBUG - PublicAddressManager: Obtaining public IP.
Mar 02, 2019 12:37:55.255 [8612] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:37:55.260 [4856] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:55.262 [3864] DEBUG - Connection Tester: Testing the server PlexNV with 3 URLs.
Mar 02, 2019 12:37:55.263 [15952] DEBUG - HTTP requesting GET https://192-168-1-128.9f479842fa3f4f87b7ede4f6b34565c3.plex.direct:32400
Mar 02, 2019 12:37:55.263 [15904] DEBUG - HTTP requesting GET https://192-168-49-1.9f479842fa3f4f87b7ede4f6b34565c3.plex.direct:32400
Mar 02, 2019 12:37:55.264 [0648] DEBUG - HTTP requesting GET https://70-171-4-132.9f479842fa3f4f87b7ede4f6b34565c3.plex.direct:32500
Mar 02, 2019 12:37:55.274 [4856] DEBUG - MyPlex: Updating device connections (from timer: 0)
Mar 02, 2019 12:37:55.275 [4856] DEBUG - HTTP requesting PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?Connection[][uri]=http://192.168.1.111:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:55.514 [8612] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:37:55.514 [8612] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 70.171.4.132
Mar 02, 2019 12:37:55.699 [1444] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=89ced1d4-2d8e-4d61-b7d8-bc2bf99a6bbb
Mar 02, 2019 12:37:55.699 [1444] DEBUG - MyPlex: Published Mapping State response was 201
Mar 02, 2019 12:37:55.700 [1444] DEBUG - MyPlex: Got response for 76ed9dfcde071d4f7c944ee568c6144a5afa3aaf ~ registered 70.171.9.141:32400
Mar 02, 2019 12:37:55.749 [5604] DEBUG - MyPlex: Sending Server Info to myPlex (user=sigfreund@cox.net, ip=, port=32600)
Mar 02, 2019 12:37:55.750 [5604] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=a34a16ad-6809-49bd-8743-c8d4e5e2217d
Mar 02, 2019 12:37:55.914 [4856] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?Connection[][uri]=http://192.168.1.111:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:55.914 [1444] DEBUG - MyPlex: Last published value didn't change, we're done.
Mar 02, 2019 12:37:55.919 [4856] DEBUG - CERT: Certificate will not expire soon.
Mar 02, 2019 12:37:55.927 [6936] DEBUG - EventSource: Got event [data] '<Message address="70.171.4.132" port="32600" asyncIdentifier="89ced1d4-2d8e-4d61-b7d8-bc2bf99a6bbb" connectivity="0" command="notifyConnectivity"/>'
Mar 02, 2019 12:37:56.192 [10956] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:37:56.395 [5604] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=a34a16ad-6809-49bd-8743-c8d4e5e2217d
Mar 02, 2019 12:37:56.395 [5604] DEBUG - MyPlex: Published Mapping State response was 201
Mar 02, 2019 12:37:56.395 [5604] DEBUG - MyPlex: Got response for 76ed9dfcde071d4f7c944ee568c6144a5afa3aaf ~ registered 70.171.9.141:32400
Mar 02, 2019 12:37:56.395 [6936] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (89ced1d4-2d8e-4d61-b7d8-bc2bf99a6bbb, expected a34a16ad-6809-49bd-8743-c8d4e5e2217d)
Mar 02, 2019 12:37:56.609 [12916] DEBUG - EventSource: Got event [data] '<Message address="70.171.4.132" port="32600" asyncIdentifier="a34a16ad-6809-49bd-8743-c8d4e5e2217d" connectivity="0" command="notifyConnectivity"/>'
Mar 02, 2019 12:37:56.610 [12916] DEBUG - PubSub: Got notified of reachability: 0 for 70.171.4.132:32600
Mar 02, 2019 12:37:56.715 [1308] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.1.111 (iPlex)
Mar 02, 2019 12:37:56.715 [1308] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.1.111
Mar 02, 2019 12:37:56.715 [1308] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.1.111
Mar 02, 2019 12:37:56.716 [1308] DEBUG - HTTP requesting GET http://127.0.0.1:50643/system/notify/serverUpdated?host=192.168.1.111
Mar 02, 2019 12:37:56.725 [17776] DEBUG - Request: [::ffff:127.0.0.1:50721 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:56.727 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50721] 200 GET /servers (6 live) GZIP 2ms 488 bytes
Mar 02, 2019 12:37:56.734 [17776] DEBUG - Request: [::ffff:127.0.0.1:50722 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:56.739 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50722] 200 GET /library/sections (6 live) GZIP 5ms 1254 bytes
Mar 02, 2019 12:37:56.746 [15092] DEBUG - Request: [::ffff:127.0.0.1:50723 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:37:56.747 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50723] 200 GET /channels/all (6 live) GZIP 1ms 387 bytes
Mar 02, 2019 12:37:56.753 [1308] DEBUG - HTTP 200 response from GET http://127.0.0.1:50643/system/notify/serverUpdated?host=192.168.1.111
Mar 02, 2019 12:37:56.753 [1308] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Mar 02, 2019 12:37:58.595 [8612] DEBUG - NAT: UPnP, found device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>
Mar 02, 2019 12:37:58.601 [8612] DEBUG - NAT: UPnP, usable device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>.
Mar 02, 2019 12:37:58.603 [8612] DEBUG - NAT: UPnP, public address is 70.171.4.132
Mar 02, 2019 12:37:58.613 [8612] DEBUG - MyPlex: Last published value didn't change, we're done.
Mar 02, 2019 12:37:59.508 [1444] DEBUG - DVR:Device: Discovering and refreshing all devices.
Mar 02, 2019 12:37:59.508 [1444] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Mar 02, 2019 12:37:59.508 [1444] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Mar 02, 2019 12:37:59.681 [10956] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Mar 02, 2019 12:38:00.246 [10956] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Mar 02, 2019 12:38:00.272 [15952] ERROR - Error issuing curl_easy_perform(handle): 28
Mar 02, 2019 12:38:00.272 [15952] DEBUG - HTTP simulating 408 after curl timeout
Mar 02, 2019 12:38:00.277 [15904] ERROR - Error issuing curl_easy_perform(handle): 28
Mar 02, 2019 12:38:00.277 [0648] ERROR - Error issuing curl_easy_perform(handle): 28
Mar 02, 2019 12:38:00.277 [15904] DEBUG - HTTP simulating 408 after curl timeout
Mar 02, 2019 12:38:00.277 [0648] DEBUG - HTTP simulating 408 after curl timeout
Mar 02, 2019 12:38:01.065 [10956] DEBUG - PubSubManager: Time to connect to 45.79.197.109 was 50 ms.
Mar 02, 2019 12:38:01.065 [10956] DEBUG - PubSubManager: Time to connect to 45.79.197.58 was 50 ms.
Mar 02, 2019 12:38:01.065 [10956] DEBUG - PubSubManager: Time to connect to 66.228.52.149 was 84 ms.
Mar 02, 2019 12:38:01.065 [10956] DEBUG - PubSubManager: Time to connect to 172.104.218.107 was 84 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 184.105.148.98 was 168 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 109.237.24.233 was 230 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 172.104.132.7 was 261 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 82.94.168.55 was 273 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 374 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Time to connect to 139.162.21.199 was 546 ms.
Mar 02, 2019 12:38:01.066 [10956] DEBUG - PubSubManager: Another server (45.79.197.109) beat the current one (45.79.197.58).
Mar 02, 2019 12:38:01.067 [10956] DEBUG - EventSource: Stopping.
Mar 02, 2019 12:38:01.067 [10956] DEBUG - EventSource: Resolving 45.79.197.109 port 443
Mar 02, 2019 12:38:01.068 [10956] DEBUG - PubSubManager: Updating best host to 45.79.197.109.
Mar 02, 2019 12:38:01.068 [10956] DEBUG - PubSubManager: Updating best ping time for 45.79.197.109 to 50 ms.
Mar 02, 2019 12:38:01.068 [6936] DEBUG - EventSource: Resolved 45.79.197.109 to 45.79.197.109
Mar 02, 2019 12:38:01.139 [6936] DEBUG - EventSource: Connected in 48 ms.
Mar 02, 2019 12:38:01.140 [12916] DEBUG - EventSource: Wrote data, reading reply.
Mar 02, 2019 12:38:01.572 [12916] DEBUG - EventSource: Read HTTP reply header.
Mar 02, 2019 12:38:01.572 [12916] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Mar 02, 2019 12:38:01.572 [12916] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Mar 02, 2019 12:38:01.573 [12916] DEBUG - EventSource: Successfully connected to 45.79.197.109.
Mar 02, 2019 12:38:01.573 [3324] DEBUG - PublicAddressManager: Obtaining public IP.
Mar 02, 2019 12:38:01.573 [3324] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:38:01.820 [3324] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:38:01.821 [3324] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 70.171.4.132
Mar 02, 2019 12:38:01.945 [1444] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Mar 02, 2019 12:38:03.160 [3568] DEBUG - Activity: registered new activity 22690c40-e85d-42fc-a57a-c573b6b20f90 - Processing subscriptions
Mar 02, 2019 12:38:03.160 [3568] DEBUG - DVR:Subscription: Scheduling subscriptions.
Mar 02, 2019 12:38:03.160 [3568] DEBUG - DVR:Subscription: Initialized 0 schedulers.
Mar 02, 2019 12:38:03.160 [3568] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Mar 02, 2019 12:38:03.161 [3568] DEBUG - DVR:Subscription: Scheduled all subscriptions in 0 ms.
Mar 02, 2019 12:38:03.161 [3568] DEBUG - Activity: Ended activity 22690c40-e85d-42fc-a57a-c573b6b20f90.
Mar 02, 2019 12:38:03.161 [3568] DEBUG - DVR:Subscription: There are 0 active grabs at the end.
Mar 02, 2019 12:38:03.161 [3568] DEBUG - Activity: Ended activity 93fa550c-8d8b-47d7-b22e-25bbf6114c1e.
Mar 02, 2019 12:38:03.161 [3568] DEBUG - DVR:Subscription: Refreshed all content in 3 ms.
Mar 02, 2019 12:38:04.884 [3324] DEBUG - NAT: UPnP, found device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>
Mar 02, 2019 12:38:04.889 [3324] DEBUG - NAT: UPnP, usable device <http://192.168.1.1:5000/rootDesc.xml> with private address <192.168.1.111>.
Mar 02, 2019 12:38:04.892 [3324] DEBUG - NAT: UPnP, public address is 70.171.4.132
Mar 02, 2019 12:38:04.901 [3324] DEBUG - MyPlex: Last published value didn't change, we're done.
Mar 02, 2019 12:38:05.247 [3864] WARN - Connection Tester: Error testing media server PlexNV in 9984 ms.
Mar 02, 2019 12:38:05.248 [9736] DEBUG - Media Server: Tested all servers in 10.0 seconds.
Mar 02, 2019 12:38:41.542 [6936] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:38:41.543 [4540] DEBUG - Request: [::ffff:127.0.0.1:50802 (Loopback)] GET / (3 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:38:41.549 [4540] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Mar 02, 2019 12:38:41.550 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50802] 200 GET / (3 live) GZIP 8ms 2881 bytes (pipelined: 1)
Mar 02, 2019 12:38:41.571 [12916] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:38:41.572 [4540] DEBUG - Request: [::ffff:127.0.0.1:50802 (Loopback)] GET /playQueues/255?center=4952&includeRelated=1 (3 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:38:41.573 [4540] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Mar 02, 2019 12:38:41.574 [4540] DEBUG - PlayQueue: No need to refresh, active window is operational.
Mar 02, 2019 12:38:41.594 [4540] DEBUG - It took 31.250000 ms to retrieve 129 items.
Mar 02, 2019 12:38:41.597 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.597 [4540] DEBUG - Selecting best audio stream for part ID 15154 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.597 [4540] DEBUG - Audio Stream: 34936, Subtitle Stream: -1
Mar 02, 2019 12:38:41.598 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.598 [4540] DEBUG - Selecting best audio stream for part ID 15155 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.599 [4540] DEBUG - Audio Stream: 35028, Subtitle Stream: -1
Mar 02, 2019 12:38:41.609 [6936] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:38:41.609 [6260] DEBUG - Request: [::ffff:127.0.0.1:50805 (Loopback)] GET /library/sections (4 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:38:41.614 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50805] 200 GET /library/sections (4 live) GZIP 4ms 1283 bytes (pipelined: 1)
Mar 02, 2019 12:38:41.644 [12916] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:38:41.645 [13944] DEBUG - Request: [::ffff:127.0.0.1:50805 (Loopback)] GET /playlists/all (4 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:38:41.648 [6936] DEBUG - Completed: [::ffff:127.0.0.1:50805] 200 GET /playlists/all (4 live) GZIP 3ms 636 bytes (pipelined: 2)
Mar 02, 2019 12:38:41.788 [4540] DEBUG - It took 0.000000 ms to retrieve 120 items.
Mar 02, 2019 12:38:41.800 [4540] DEBUG - It took 15.625000 ms to retrieve 108 items.
Mar 02, 2019 12:38:41.810 [4540] DEBUG - It took 31.250000 ms to retrieve 162 items.
Mar 02, 2019 12:38:41.823 [4540] DEBUG - It took 0.000000 ms to retrieve 152 items.
Mar 02, 2019 12:38:41.834 [4540] DEBUG - It took 15.625000 ms to retrieve 109 items.
Mar 02, 2019 12:38:41.844 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.844 [4540] DEBUG - Selecting best audio stream for part ID 17180 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.844 [4540] DEBUG - Audio Stream: 44118, Subtitle Stream: -1
Mar 02, 2019 12:38:41.845 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.845 [4540] DEBUG - Selecting best audio stream for part ID 17064 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.845 [4540] DEBUG - Audio Stream: 43198, Subtitle Stream: -1
Mar 02, 2019 12:38:41.847 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.847 [4540] DEBUG - Selecting best audio stream for part ID 15998 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.847 [4540] DEBUG - Audio Stream: 38885, Subtitle Stream: -1
Mar 02, 2019 12:38:41.848 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.849 [4540] DEBUG - Selecting best audio stream for part ID 16068 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.849 [4540] DEBUG - Audio Stream: 38887, Subtitle Stream: -1
Mar 02, 2019 12:38:41.850 [4540] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:38:41.850 [4540] DEBUG - Selecting best audio stream for part ID 15184 (autoselect: 1 language: en)
Mar 02, 2019 12:38:41.851 [4540] DEBUG - Audio Stream: 35187, Subtitle Stream: -1
Mar 02, 2019 12:38:41.855 [6936] DEBUG - Completed: [::ffff:127.0.0.1:50802] 200 GET /playQueues/255?center=4952&includeRelated=1 (4 live) GZIP 284ms 24983 bytes (pipelined: 2)
Mar 02, 2019 12:39:16.634 [6936] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:39:16.635 [6260] DEBUG - Request: [::ffff:127.0.0.1:50820 (Loopback)] GET / (3 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:16.636 [6260] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Mar 02, 2019 12:39:16.637 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50820] 200 GET / (3 live) GZIP 2ms 2881 bytes (pipelined: 1)
Mar 02, 2019 12:39:17.488 [12916] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:39:17.488 [14208] DEBUG - Request: [::ffff:127.0.0.1:50820 (Loopback)] GET /hubs?count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0 (3 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:17.489 [14208] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' to the cache (1/home.continue/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0).
Mar 02, 2019 12:39:17.489 [14208] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' to the cache (1/home.ondeck/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0).
Mar 02, 2019 12:39:17.593 [14208] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' in 103 ms.
Mar 02, 2019 12:39:17.593 [14208] DEBUG - HubCache: 2 hubs cached, 0.0% hit ratio.
Mar 02, 2019 12:39:17.594 [14208] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' in 104 ms.
Mar 02, 2019 12:39:17.594 [14208] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' from the cache.
Mar 02, 2019 12:39:17.594 [14208] DEBUG - HubCache: Adding '1/home.playlists/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' to the cache (1/home.playlists/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0).
Mar 02, 2019 12:39:17.596 [14208] DEBUG - HubCache: Computed '1/home.playlists/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' in 1 ms.
Mar 02, 2019 12:39:17.596 [14208] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' to the cache (1/home.movies.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0).
Mar 02, 2019 12:39:17.617 [14208] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' in 20 ms.
Mar 02, 2019 12:39:17.619 [14208] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' to the cache (1/home.television.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0).
Mar 02, 2019 12:39:17.619 [14208] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Mar 02, 2019 12:39:17.622 [14208] DEBUG - It took 31.250000 ms to retrieve 200 items.
Mar 02, 2019 12:39:17.623 [14208] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Mar 02, 2019 12:39:17.626 [14208] DEBUG - It took 0.000000 ms to retrieve 415 items.
Mar 02, 2019 12:39:17.626 [14208] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Mar 02, 2019 12:39:17.631 [14208] DEBUG - It took 0.000000 ms to retrieve 625 items.
Mar 02, 2019 12:39:17.631 [14208] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Mar 02, 2019 12:39:17.641 [14208] DEBUG - It took 31.250000 ms to retrieve 839 items.
Mar 02, 2019 12:39:17.642 [14208] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Mar 02, 2019 12:39:17.656 [14208] DEBUG - It took 31.250000 ms to retrieve 1048 items.
Mar 02, 2019 12:39:17.725 [14208] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0' in 106 ms.
Mar 02, 2019 12:39:17.726 [14208] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10).
Mar 02, 2019 12:39:17.744 [14208] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' in 17 ms.
Mar 02, 2019 12:39:17.745 [14208] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10).
Mar 02, 2019 12:39:18.153 [14208] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' in 408 ms.
Mar 02, 2019 12:39:18.154 [14208] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10).
Mar 02, 2019 12:39:18.165 [14208] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en-US/count=40&includeEmpty=0&includeTrailers=0&limit=1000&onlyTransient=0&sort=addedAt%3Adesc&type=4%2C10' in 10 ms.
Mar 02, 2019 12:39:18.168 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.168 [14208] DEBUG - Selecting best audio stream for part ID 36396 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.168 [14208] DEBUG - Audio Stream: 60636, Subtitle Stream: -1
Mar 02, 2019 12:39:18.169 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.170 [14208] DEBUG - Selecting best audio stream for part ID 112318 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.170 [14208] DEBUG - Audio Stream: 120414, Subtitle Stream: -1
Mar 02, 2019 12:39:18.170 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.171 [14208] DEBUG - Selecting best audio stream for part ID 112314 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.171 [14208] DEBUG - Audio Stream: 112324, Subtitle Stream: -1
Mar 02, 2019 12:39:18.516 [14208] DEBUG - There were 1 top-level paths for Frontline.
Mar 02, 2019 12:39:18.517 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.517 [14208] DEBUG - Selecting best audio stream for part ID 101101 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.517 [14208] DEBUG - Audio Stream: 96805, Subtitle Stream: -1
Mar 02, 2019 12:39:18.519 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.519 [14208] DEBUG - Selecting best audio stream for part ID 101072 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.519 [14208] DEBUG - Audio Stream: 96742, Subtitle Stream: -1
Mar 02, 2019 12:39:18.519 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.520 [14208] DEBUG - Selecting best audio stream for part ID 101071 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.520 [14208] DEBUG - Audio Stream: 96740, Subtitle Stream: -1
Mar 02, 2019 12:39:18.520 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.520 [14208] DEBUG - Selecting best audio stream for part ID 101070 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.521 [14208] DEBUG - Audio Stream: 96737, Subtitle Stream: -1
Mar 02, 2019 12:39:18.523 [14208] DEBUG - There were 1 top-level paths for Air Warriors.
Mar 02, 2019 12:39:18.526 [14208] DEBUG - There were 1 top-level paths for Atlantis (2013).
Mar 02, 2019 12:39:18.530 [14208] DEBUG - There were 1 top-level paths for Avatar: The Last Airbender.
Mar 02, 2019 12:39:18.534 [14208] DEBUG - There were 1 top-level paths for Batman Beyond.
Mar 02, 2019 12:39:18.542 [14208] DEBUG - There were 1 top-level paths for Batman: The Animated Series.
Mar 02, 2019 12:39:18.543 [14208] DEBUG - There were 1 top-level paths for BattleBots (2015).
Mar 02, 2019 12:39:18.548 [14208] DEBUG - There were 1 top-level paths for Brain Games.
Mar 02, 2019 12:39:18.552 [14208] DEBUG - There were 1 top-level paths for The Dog Whisperer.
Mar 02, 2019 12:39:18.555 [14208] DEBUG - There were 1 top-level paths for Downton Abbey.
Mar 02, 2019 12:39:18.561 [14208] DEBUG - There were 1 top-level paths for Dragons.
Mar 02, 2019 12:39:18.566 [14208] DEBUG - There were 1 top-level paths for Gilligan's Island.
Mar 02, 2019 12:39:18.567 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.567 [14208] DEBUG - Selecting best audio stream for part ID 100298 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.567 [14208] DEBUG - Audio Stream: 97076, Subtitle Stream: -1
Mar 02, 2019 12:39:18.576 [14208] DEBUG - There were 1 top-level paths for I Dream of Jeannie.
Mar 02, 2019 12:39:18.577 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:18.577 [14208] DEBUG - Selecting best audio stream for part ID 100101 (autoselect: 1 language: en)
Mar 02, 2019 12:39:18.577 [14208] DEBUG - Audio Stream: 98382, Subtitle Stream: -1
Mar 02, 2019 12:39:18.585 [14208] DEBUG - There were 1 top-level paths for Johnny Test.
Mar 02, 2019 12:39:18.590 [14208] DEBUG - There were 1 top-level paths for Kung Fu.
Mar 02, 2019 12:39:18.603 [14208] DEBUG - There were 1 top-level paths for Leave It to Beaver.
Mar 02, 2019 12:39:18.608 [14208] DEBUG - There were 1 top-level paths for LEGO NinjaGo: Masters of Spinjitzu.
Mar 02, 2019 12:39:18.636 [6936] DEBUG - Completed: [::ffff:127.0.0.1:50820] 200 GET /hubs?count=40&includeEmpty=0&includeTrailers=0&onlyTransient=0 (3 live) GZIP 1147ms 50228 bytes (pipelined: 2)
Mar 02, 2019 12:39:18.661 [6936] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:39:18.662 [6260] DEBUG - Request: [::ffff:127.0.0.1:50820 (Loopback)] GET /library/sections (3 live) Page 0-49 GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:18.663 [6260] DEBUG - Setting container serialization range to [0, 49] (total=-1)
Mar 02, 2019 12:39:18.665 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50820] 200 GET /library/sections (3 live) GZIP Page 0-49 3ms 1354 bytes (pipelined: 3)
Mar 02, 2019 12:39:34.078 [12916] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:39:34.079 [14208] DEBUG - Request: [::ffff:127.0.0.1:50820 (Loopback)] GET /library/onDeck (3 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:34.082 [6936] DEBUG - Auth: authenticated user 1 as sigfreund
Mar 02, 2019 12:39:34.082 [18096] DEBUG - Request: [::ffff:127.0.0.1:50844 (Loopback)] GET /library/recentlyAdded (4 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:34.109 [18096] DEBUG - It took 93.750000 ms to retrieve 49 items.
Mar 02, 2019 12:39:34.140 [18096] DEBUG - It took 93.750000 ms to retrieve 50 items.
Mar 02, 2019 12:39:34.162 [14208] DEBUG - We're going to try to auto-select an audio stream for account 1.
Mar 02, 2019 12:39:34.162 [14208] DEBUG - Selecting best audio stream for part ID 36396 (autoselect: 1 language: en)
Mar 02, 2019 12:39:34.163 [14208] DEBUG - Audio Stream: 60636, Subtitle Stream: -1
Mar 02, 2019 12:39:34.167 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50820] 200 GET /library/onDeck (4 live) GZIP 88ms 7527 bytes (pipelined: 4)
Mar 02, 2019 12:39:34.604 [18096] WARN - SLOW QUERY: It took 781.250000 ms to retrieve 0 items.
Mar 02, 2019 12:39:34.675 [18096] DEBUG - It took 140.625000 ms to retrieve 50 items.
Mar 02, 2019 12:39:34.704 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50844] 200 GET /library/recentlyAdded (4 live) GZIP 622ms 11885 bytes (pipelined: 1)
Mar 02, 2019 12:39:47.814 [10184] DEBUG - BPQ: [Starting] -> [Processing]
Mar 02, 2019 12:39:47.822 [10184] DEBUG - BPQ: generating queue items from 0 generator(s)
Mar 02, 2019 12:39:47.823 [10184] DEBUG - BPQ: generated 0 item(s) for queue
Mar 02, 2019 12:39:47.823 [10184] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Mar 02, 2019 12:39:47.823 [10184] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Mar 02, 2019 12:39:47.823 [10184] DEBUG - BPQ: [Processing] -> [Idle]
Mar 02, 2019 12:39:51.659 [13528] DEBUG - Request: [::ffff:127.0.0.1:50875 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:51.660 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50875] 200 GET /servers (5 live) GZIP 1ms 488 bytes
Mar 02, 2019 12:39:51.664 [5844] DEBUG - Request: [::ffff:127.0.0.1:50876 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:51.667 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50876] 200 GET /library/sections (6 live) GZIP 2ms 1254 bytes
Mar 02, 2019 12:39:51.671 [13528] DEBUG - Request: [::ffff:127.0.0.1:50877 (Loopback)] GET /channels/all (6 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:51.672 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50877] 200 GET /channels/all (6 live) GZIP 1ms 387 bytes
Mar 02, 2019 12:39:51.678 [5844] DEBUG - Request: [::ffff:127.0.0.1:50878 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:51.681 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50878] 200 GET /library/sections (7 live) GZIP 2ms 1254 bytes
Mar 02, 2019 12:39:51.685 [13528] DEBUG - Request: [::ffff:127.0.0.1:50879 (Loopback)] GET /channels/all (8 live) GZIP Signed-in Token (sigfreund)
Mar 02, 2019 12:39:51.686 [12916] DEBUG - Completed: [::ffff:127.0.0.1:50879] 200 GET /channels/all (8 live) GZIP 1ms 387 bytes
Mar 02, 2019 12:40:23.043 [1280] DEBUG - Network change.
Mar 02, 2019 12:40:23.044 [1280] DEBUG - NetworkInterface: Notified of network changed (force=0)
Mar 02, 2019 12:40:23.067 [1280] DEBUG - Detected primary interface: 192.168.1.111
Mar 02, 2019 12:40:23.067 [1280] DEBUG - Network interfaces:
Mar 02, 2019 12:40:23.067 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.068 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.068 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.068 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.068 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.069 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.069 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.069 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.069 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.070 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.070 [1280] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Mar 02, 2019 12:40:23.070 [1280] DEBUG - NetworkService: Dispatch network change after two second delay.
Mar 02, 2019 12:40:23.070 [1280] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Mar 02, 2019 12:40:23.071 [11508] DEBUG - PublicAddressManager: Obtaining public IP.
Mar 02, 2019 12:40:23.071 [11508] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Mar 02, 2019 12:40:23.077 [11508] ERROR - Error issuing curl_easy_perform(handle): 6
Mar 02, 2019 12:40:23.078 [11508] WARN - HTTP error requesting GET http://plex.tv/pms/:/ip (0, No error) (Could not resolve host: plex.tv)
Mar 02, 2019 12:40:23.078 [11508] ERROR - PublicAddressManager: Unable to get public IP adddress from myPlex (httpCode=-6): 
Mar 02, 2019 12:40:23.090 [11508] DEBUG - MyPlex: Updating device connections (from timer: 0)
Mar 02, 2019 12:40:23.091 [11508] DEBUG - HTTP requesting PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:40:23.096 [11508] ERROR - Error issuing curl_easy_perform(handle): 6
Mar 02, 2019 12:40:23.096 [11508] WARN - HTTP error requesting PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Could not resolve host: plex.tv)
Mar 02, 2019 12:40:23.096 [11508] WARN - MyPlex: Updating device connections failed, retrying in 10 seconds.
Mar 02, 2019 12:40:31.570 [12916] DEBUG - EventSource: Failure in IdleTimeout (0 - The operation completed successfully).
Mar 02, 2019 12:40:31.570 [12916] DEBUG - MyPlex: We appear to have lost Internet connectivity, resetting device URL cache.
Mar 02, 2019 12:40:31.571 [12916] ERROR - EventSource: Retrying in 15 seconds.
Mar 02, 2019 12:40:33.104 [11044] DEBUG - MyPlex: Updating device connections (from timer: 1)
Mar 02, 2019 12:40:33.104 [11044] DEBUG - HTTP requesting PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Mar 02, 2019 12:40:33.110 [11044] ERROR - Error issuing curl_easy_perform(handle): 6
Mar 02, 2019 12:40:33.110 [11044] WARN - HTTP error requesting PUT https://plex.tv/devices/76ed9dfcde071d4f7c944ee568c6144a5afa3aaf?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Could not resolve host: plex.tv)
Mar 02, 2019 12:40:33.110 [11044] WARN - MyPlex: Updating device connections failed, retrying in 20 seconds.
Mar 02, 2019 12:40:38.620 [8096] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Tuner Service.exe' exit code for process 1264 is 1073807364 (failure)
Mar 02, 2019 12:40:38.621 [4140] DEBUG - Plex Tuner Service.exe exited.
