Jan 07, 2019 22:19:51.324 [0x7f06aa2d5700] INFO - Plex Media Server v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 redhat - GMT -07:00
Jan 07, 2019 22:19:51.324 [0x7f06aa2d5700] INFO - Linux version: 3.10.0-957.1.3.el7.x86_64 (#1 SMP Thu Nov 29 14:49:43 UTC 2018), language: en-US
Jan 07, 2019 22:19:51.325 [0x7f06aa2d5700] INFO - Processor Intel(R) Xeon(R) CPU           E5645  @ 2.40GHz
Jan 07, 2019 22:19:51.325 [0x7f06aa2d5700] INFO - /usr/lib/plexmediaserver/Plex Media Server
Jan 07, 2019 22:19:51.324 [0x7f06bc55b840] DEBUG - BPQ: [Idle] -> [Starting]
Jan 07, 2019 22:19:51.326 [0x7f06bc55b840] DEBUG - FeatureManager: Using cached data for features list
Jan 07, 2019 22:19:51.327 [0x7f06bc55b840] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 07, 2019 22:19:51.435 [0x7f06bc55b840] DEBUG - Running migrations. (EPG 0)
Jan 07, 2019 22:19:51.451 [0x7f06bc55b840] DEBUG - ChangestampAllocator: initialized to 101283
Jan 07, 2019 22:19:51.451 [0x7f06bc55b840] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.13.0, threadsafe=1
Jan 07, 2019 22:19:51.463 [0x7f06bc55b840] DEBUG - Running migrations. (EPG 0)
Jan 07, 2019 22:19:51.479 [0x7f06bc55b840] DEBUG - HttpServer: Listening on port 32400 with queue of 128 connections.
Jan 07, 2019 22:19:51.480 [0x7f06bc55b840] DEBUG - HttpServer: Listening on port 32401 with queue of 128 connections.
Jan 07, 2019 22:19:51.505 [0x7f06a6bfd700] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Jan 07, 2019 22:19:51.508 [0x7f06bc55b840] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Jan 07, 2019 22:19:51.508 [0x7f06bc55b840] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 07, 2019 22:19:51.511 [0x7f06bc55b840] DEBUG - Relay: read 9 cached entries from hosts file
Jan 07, 2019 22:19:51.511 [0x7f06bc55b840] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 07, 2019 22:19:51.511 [0x7f06a57ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:51.511 [0x7f06bc55b840] DEBUG - MyPlex: updating with 12 access tokens
Jan 07, 2019 22:19:51.513 [0x7f06a6bfd700] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Jan 07, 2019 22:19:51.514 [0x7f06a37ff700] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 07, 2019 22:19:51.514 [0x7f06a37ff700] DEBUG - HTTP requesting GET https://plex.tv/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 07, 2019 22:19:51.516 [0x7f06a0fff700] DEBUG - File "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476" changed: -1 => 2019-01-04 23:47:14 (1546670834).
Jan 07, 2019 22:19:51.516 [0x7f06a0fff700] DEBUG - File "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins" changed: -1 => 2018-12-15 08:52:57 (1544889177).
Jan 07, 2019 22:19:51.516 [0x7f06a0fff700] DEBUG - Scanning for plug-ins in "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476"
Jan 07, 2019 22:19:51.526 [0x7f06a0fff700] DEBUG - Scanning for plug-ins in "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins"
Jan 07, 2019 22:19:51.529 [0x7f06a0fff700] DEBUG - PluginRepository::setStartState: 1, startingSystem
Jan 07, 2019 22:19:51.529 [0x7f06a0fff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/System.bundle.
Jan 07, 2019 22:19:51.530 [0x7f06a0fff700] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Jan 07, 2019 22:19:51.531 [0x7f06a0fff700] DEBUG - Starting file watcher for com.plexapp.system
Jan 07, 2019 22:19:51.533 [0x7f06a0fff700] DEBUG - Spawned plug-in com.plexapp.system with PID 21138
Jan 07, 2019 22:19:51.533 [0x7f06a0fff700] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes
Jan 07, 2019 22:19:52.256 [0x7f06a57ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:52.285 [0x7f06a37ff700] DEBUG - HTTP 200 response from GET https://plex.tv/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 07, 2019 22:19:52.286 [0x7f06a37ff700] DEBUG - MyPlex: updating with 12 access tokens
Jan 07, 2019 22:19:52.286 [0x7f06a8bff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:52.930 [0x7f06a8bff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:52.931 [0x7f06a8bff700] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:52.931 [0x7f06a6bfd700] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:53.638 [0x7f06a6bfd700] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:53.646 [0x7f06a8bff700] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:53.647 [0x7f06a8bff700] DEBUG - Job running: '/usr/lib/plexmediaserver/CrashUploader' '--directory=/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Crash Reports/1.14.1.5488-cc260c476' '--version=1.14.1.5488-cc260c476' '--platform=linux-ubuntu-x86_64 (3.10.0-957.1.3.el7.x86_64 (#1 SMP Thu Nov 29 14:49:43 UTC 2018))' '--serverUuid=9137ee3c856455d82ce048f60bd6e5b64d0ae2ab' '--userId=crazyjpeters@gmail.com' '--url=https://crashreport.plexapp.com'
Jan 07, 2019 22:19:53.650 [0x7f06a8bff700] DEBUG - Jobs: Starting child process with pid 21152
Jan 07, 2019 22:19:53.684 [0x7f06a1ffe700] DEBUG - Jobs: '/usr/lib/plexmediaserver/CrashUploader' exit code for process 21152 is 0 (success)
Jan 07, 2019 22:19:54.565 [0x7f06a0fff700] DEBUG - Reading 486 bytes in the body, code is 200
Jan 07, 2019 22:19:54.565 [0x7f06a0fff700] DEBUG - [com.plexapp.system] Plug-in running on port 33212.
Jan 07, 2019 22:19:54.565 [0x7f06a0fff700] DEBUG -  * Plug-in handles prefix: /system
Jan 07, 2019 22:19:54.565 [0x7f06a0fff700] DEBUG -  * Plug-in handles prefix: /player
Jan 07, 2019 22:19:54.565 [0x7f06a0fff700] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Jan 07, 2019 22:19:54.568 [0x7f06a0fff700] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Jan 07, 2019 22:19:54.568 [0x7f06a0fff700] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Jan 07, 2019 22:19:54.568 [0x7f06a0fff700] DEBUG - Updating the list of agents known by the system.
Jan 07, 2019 22:19:54.568 [0x7f06a0fff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Jan 07, 2019 22:19:54.568 [0x7f06bc55b840] DEBUG - Image transcode cache directory: "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder"
Jan 07, 2019 22:19:54.568 [0x7f06a0fff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/agents
Jan 07, 2019 22:19:54.569 [0x7f06bc55b840] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 07, 2019 22:19:54.570 [0x7f06bc55b840] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 07, 2019 22:19:54.571 [0x7f06bc55b840] DEBUG - MyPlex: updating with 12 access tokens
Jan 07, 2019 22:19:54.571 [0x7f06bc55b840] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 07, 2019 22:19:54.571 [0x7f06a57ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:54.572 [0x7f06bc55b840] DEBUG - MyPlex: username is crazyjpeters, login is crazyjpeters@gmail.com, home is 0, no pin
Jan 07, 2019 22:19:54.574 [0x7f06bc55b840] DEBUG - NetworkInterface: Starting watch thread.
Jan 07, 2019 22:19:54.574 [0x7f06bc55b840] DEBUG - Network change.
Jan 07, 2019 22:19:54.574 [0x7f06bc55b840] DEBUG - NetworkInterface: Notified of network changed (force=0)
Jan 07, 2019 22:19:54.574 [0x7f069e7fe700] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 07, 2019 22:19:54.574 [0x7f069d7ff700] DEBUG - NetworkInterface: Watching for changes on the interfaces.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - Detected primary interface: 192.168.2.143
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - Network interfaces:
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG -  * 2 ens160 (192.168.2.143) (loopback: 0)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG -  * 3 ens192 (192.168.20.12) (loopback: 0)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - Creating NetworkServices singleton.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkServices: Initializing...
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.577 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Creating new service.
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 07, 2019 22:19:54.578 [0x7f06bc55b840] DEBUG - NetworkService: Quick dispatch of network change.
Jan 07, 2019 22:19:54.578 [0x7f069c7ff700] DEBUG - Network change for advertiser.
Jan 07, 2019 22:19:54.578 [0x7f069c7ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - Network change for advertiser.
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - Network change for advertiser.
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Jan 07, 2019 22:19:54.579 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.2.143 on broadcast address 192.168.2.255 (index: 1)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.20.12 on broadcast address 192.168.20.255 (index: 2)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 127.0.0.1 on broadcast address 127.255.255.255 (index: 0)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.2.143 on broadcast address 192.168.2.255 (index: 1)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.20.12 on broadcast address 192.168.20.255 (index: 2)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.2.143 on broadcast address 239.255.255.250 (index: 0)
Jan 07, 2019 22:19:54.580 [0x7f069c7ff700] DEBUG - NetworkService: Browsing on interface 192.168.20.12 on broadcast address 239.255.255.250 (index: 1)
Jan 07, 2019 22:19:54.651 [0x7f06bc55b840] DEBUG - Gracenote Version 3.07.7.3686 (build: 2015-07-05 11:45-0700)
Jan 07, 2019 22:19:54.653 [0x7f06bc55b840] DEBUG - Gracenote: Using SQLite 3.13.0
Jan 07, 2019 22:19:54.653 [0x7f06bc55b840] DEBUG - Starting HTTP server.
Jan 07, 2019 22:19:54.653 [0x7f06bc55b840] DEBUG - Running server...
Jan 07, 2019 22:19:54.653 [0x7f06bc55b840] DEBUG - Waiting for server condition to be signaled.
Jan 07, 2019 22:19:54.654 [0x7f069b7ff700] DEBUG - Plex Tuner Service starting.
Jan 07, 2019 22:19:54.654 [0x7f069b7ff700] DEBUG - Job running: '/usr/lib/plexmediaserver/Plex Tuner Service' '/usr/lib/plexmediaserver/Resources/Tuner/Private' '/usr/lib/plexmediaserver/Resources/Tuner/Shared' '1.14.1.5488-cc260c476' '32600' '/waitmutex'
Jan 07, 2019 22:19:54.654 [0x7f069a7ff700] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Jan 07, 2019 22:19:54.657 [0x7f069b7ff700] DEBUG - Jobs: Starting child process with pid 21184
Jan 07, 2019 22:19:54.658 [0x7f069a7ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:54.663 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.665 [0x7f0698fff700] DEBUG - Request: [127.0.0.1:45060 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.665 [0x7f06987fe700] DEBUG - Request: [192.168.2.199:52739 (Allowed Network)] GET /media/providers (5 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale ar
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale cs_CZ
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale da
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale de
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale en
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale es
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale et_EE
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale fr
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale he
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale hr_HR
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale hu_HU
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale it
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale ja_JP
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale ko
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale lt
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale nl
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale nl_BE
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale no
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale pt_BR
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale ro
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale ru
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale sr
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale sv
Jan 07, 2019 22:19:54.666 [0x7f0698fff700] DEBUG - Translator: Found locale zh_CN
Jan 07, 2019 22:19:54.666 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45062 (Loopback)] GET / (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.667 [0x7f0696bfe700] DEBUG - Request: [127.0.0.1:45068 (Loopback)] GET / (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.667 [0x7f0695bff700] DEBUG - Request: [127.0.0.1:45070 (Loopback)] GET /servers (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.677 [0x7f0698fff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:54.677 [0x7f0698fff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:54.683 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45062] 200 GET / (6 live) GZIP 18ms 1854 bytes
Jan 07, 2019 22:19:54.684 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52739] 200 GET /media/providers (6 live) GZIP 20ms 2236 bytes (pipelined: 1)
Jan 07, 2019 22:19:54.684 [0x7f06a6bfd700] DEBUG - DVR: Starting up.
Jan 07, 2019 22:19:54.685 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45068] 200 GET / (6 live) GZIP 19ms 1854 bytes
Jan 07, 2019 22:19:54.685 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45070] 200 GET /servers (6 live) GZIP 19ms 505 bytes
Jan 07, 2019 22:19:54.686 [0x7f06a6bfd700] DEBUG - Activity: registered new activity a6377a20-15dc-4494-bfeb-f45d33fbec05 - Processing subscriptions
Jan 07, 2019 22:19:54.727 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45082 (Loopback)] GET / (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.728 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45082] 200 GET / (6 live) GZIP 1ms 1854 bytes
Jan 07, 2019 22:19:54.753 [0x7f06987fe700] DEBUG - Request: [127.0.0.1:45084 (Loopback)] GET / (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.754 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45084] 200 GET / (7 live) GZIP 1ms 1854 bytes
Jan 07, 2019 22:19:54.779 [0x7f0696bfe700] DEBUG - Request: [127.0.0.1:45086 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.790 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45086] 200 GET /library/sections (7 live) GZIP 11ms 1067 bytes
Jan 07, 2019 22:19:54.794 [0x7f0695bff700] DEBUG - Request: [127.0.0.1:45088 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.795 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45088] 200 GET /channels/all (7 live) GZIP 0ms 387 bytes
Jan 07, 2019 22:19:54.803 [0x7f06a47ff700] DEBUG - Gracenote: Loading 29 locales in 0.1 seconds.
Jan 07, 2019 22:19:54.806 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45090 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.810 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45090] 200 GET /library/sections (7 live) GZIP 3ms 1067 bytes
Jan 07, 2019 22:19:54.818 [0x7f06987fe700] DEBUG - Request: [127.0.0.1:45092 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.819 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45092] 200 GET /channels/all (7 live) GZIP 0ms 387 bytes
Jan 07, 2019 22:19:54.886 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.886 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.886 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52742 (Allowed Network)] GET /:/websockets/notifications (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.886 [0x7f0695bff700] DEBUG - Request: [192.168.2.199:52739 (Allowed Network)] GET /updater/status (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.887 [0x7f0696bfe700] DEBUG - WebSocket: Performing handshake from origin http://192.168.2.143:32400
Jan 07, 2019 22:19:54.887 [0x7f0696bfe700] DEBUG - Beginning read from WebSocket
Jan 07, 2019 22:19:54.887 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52739] 200 GET /updater/status (6 live) GZIP 1ms 433 bytes (pipelined: 2)
Jan 07, 2019 22:19:54.908 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.908 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52739 (Allowed Network)] GET /activities (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.909 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52739] 200 GET /activities (6 live) GZIP 1ms 490 bytes (pipelined: 3)
Jan 07, 2019 22:19:54.909 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.910 [0x7f06987fe700] DEBUG - Request: [192.168.2.199:52743 (Allowed Network)] PUT /updater/check?download=0 (5 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.910 [0x7f06987fe700] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476, download: NO
Jan 07, 2019 22:19:54.910 [0x7f06987fe700] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:19:54.911 [0x7f06987fe700] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:19:54.915 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.915 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52739 (Allowed Network)] GET /:/prefs (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.917 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.917 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.917 [0x7f0695bff700] DEBUG - Request: [192.168.2.199:52744 (Allowed Network)] GET /accounts/1 (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.917 [0x7f06a0fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/system/agents
Jan 07, 2019 22:19:54.918 [0x7f0696bfe700] WARN - [Preferences] No update channels loaded, so showing the previously-selected channel, labelled 'unknown' since we don't really know the name.
Jan 07, 2019 22:19:54.918 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:54.918 [0x7f06a0fff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9036 bytes of content.
Jan 07, 2019 22:19:54.918 [0x7f06947ff700] DEBUG - Request: [192.168.2.199:52745 (Allowed Network)] GET /myplex/account (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.918 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52744] 200 GET /accounts/1 (8 live) GZIP 1ms 521 bytes (pipelined: 1)
Jan 07, 2019 22:19:54.918 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52746 (Allowed Network)] GET /system/:/prefs (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:54.918 [0x7f06973ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Jan 07, 2019 22:19:54.919 [0x7f06973ff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/:/prefs
Jan 07, 2019 22:19:54.919 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52745] 200 GET /myplex/account (8 live) GZIP 2ms 1058 bytes (pipelined: 1)
Jan 07, 2019 22:19:54.922 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52739] 200 GET /:/prefs (8 live) GZIP 6ms 6325 bytes (pipelined: 4)
Jan 07, 2019 22:19:54.925 [0x7f06a0fff700] DEBUG - The system knows about 13 agents.
Jan 07, 2019 22:19:54.925 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Fanart-TV.bundle.
Jan 07, 2019 22:19:54.925 [0x7f06a0fff700] DEBUG - Waiting for 20 plug-ins to finish starting.
Jan 07, 2019 22:19:54.925 [0x7f0691fff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/HTbackdrops.bundle.
Jan 07, 2019 22:19:54.925 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Framework.bundle.
Jan 07, 2019 22:19:54.927 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LastFM.bundle.
Jan 07, 2019 22:19:54.927 [0x7f0691fff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Jan 07, 2019 22:19:54.927 [0x7f06937ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Jan 07, 2019 22:19:54.927 [0x7f0691fff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LocalMedia.bundle.
Jan 07, 2019 22:19:54.927 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/LyricFind.bundle.
Jan 07, 2019 22:19:54.928 [0x7f0692ffe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Jan 07, 2019 22:19:54.928 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Media-Flags.bundle.
Jan 07, 2019 22:19:54.928 [0x7f06937ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Jan 07, 2019 22:19:54.928 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/MoviePosterDB.bundle.
Jan 07, 2019 22:19:54.928 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/OpenSubtitles.bundle.
Jan 07, 2019 22:19:54.929 [0x7f06937ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Jan 07, 2019 22:19:54.929 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PersonalMedia.bundle.
Jan 07, 2019 22:19:54.929 [0x7f0692ffe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Jan 07, 2019 22:19:54.930 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexMovie.bundle.
Jan 07, 2019 22:19:54.930 [0x7f06937ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Jan 07, 2019 22:19:54.930 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexMusic.bundle.
Jan 07, 2019 22:19:54.930 [0x7f0691fff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Jan 07, 2019 22:19:54.930 [0x7f0691fff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/PlexThemeMusic.bundle.
Jan 07, 2019 22:19:54.931 [0x7f06937ff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Jan 07, 2019 22:19:54.931 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/Scanners.bundle.
Jan 07, 2019 22:19:54.931 [0x7f0692ffe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Jan 07, 2019 22:19:54.931 [0x7f0691fff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Jan 07, 2019 22:19:54.931 [0x7f0692ffe700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/TheMovieDB.bundle.
Jan 07, 2019 22:19:54.931 [0x7f0691fff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/TheTVDB.bundle.
Jan 07, 2019 22:19:54.932 [0x7f06937ff700] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle.
Jan 07, 2019 22:19:54.932 [0x7f0691fff700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Jan 07, 2019 22:19:54.932 [0x7f0691fff700] DEBUG - Starting plug-in /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins/WebTools.bundle.
Jan 07, 2019 22:19:54.932 [0x7f0692ffe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Jan 07, 2019 22:19:54.932 [0x7f0692ffe700] DEBUG - Starting plug-in /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins/PBS Kids.bundle.
Jan 07, 2019 22:19:54.933 [0x7f0691fff700] DEBUG - [com.plexapp.plugins.WebTools] Setting plug-in to always running (daemon mode).
Jan 07, 2019 22:19:54.933 [0x7f06937ff700] DEBUG - Starting plug-in /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Plug-ins/Services.bundle.
Jan 07, 2019 22:19:54.934 [0x7f0692ffe700] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.plugins.pbskids] yet.
Jan 07, 2019 22:19:54.934 [0x7f0692ffe700] DEBUG -  * Added PBS Kids at /video/pbskids
Jan 07, 2019 22:19:54.936 [0x7f0691fff700] DEBUG - Starting file watcher for com.plexapp.plugins.WebTools
Jan 07, 2019 22:19:54.937 [0x7f0691fff700] DEBUG - Spawned plug-in com.plexapp.plugins.WebTools with PID 21210
Jan 07, 2019 22:19:54.937 [0x7f0691fff700] DEBUG - [com.plexapp.plugins.WebTools] Sending command: GET /:/prefixes
Jan 07, 2019 22:19:54.982 [0x7f0698fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:54.982 [0x7f0698fff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60580 bytes of content.
Jan 07, 2019 22:19:54.989 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45060] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (8 live) GZIP 325ms 19579 bytes
Jan 07, 2019 22:19:55.108 [0x7f06973ff700] DEBUG - HTTP 304 response from GET http://127.0.0.1:33212/system/:/prefs
Jan 07, 2019 22:19:55.109 [0x7f06973ff700] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
Jan 07, 2019 22:19:55.109 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52746] 304 GET /system/:/prefs (8 live) GZIP 191ms 324 bytes (pipelined: 1)
Jan 07, 2019 22:19:55.224 [0x7f06a57ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.269 [0x7f069a7ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.271 [0x7f069a7ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.299 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:55.299 [0x7f0695bff700] DEBUG - Request: [192.168.2.199:52747 (Allowed Network)] GET /:/websockets/notifications?filters=log (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:55.300 [0x7f0695bff700] DEBUG - WebSocket: Performing handshake from origin http://192.168.2.143:32400
Jan 07, 2019 22:19:55.300 [0x7f0695bff700] DEBUG - Beginning read from WebSocket
Jan 07, 2019 22:19:55.300 [0x7f06a37ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.304 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:55.304 [0x7f06947ff700] DEBUG - Request: [192.168.2.199:52746 (Allowed Network)] GET /activities (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:55.305 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52746] 200 GET /activities (9 live) GZIP 1ms 490 bytes (pipelined: 2)
Jan 07, 2019 22:19:55.364 [0x7f069e7fe700] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 07, 2019 22:19:55.368 [0x7f069e7fe700] DEBUG - MyPlex: username is crazyjpeters, login is crazyjpeters@gmail.com, home is 0, no pin
Jan 07, 2019 22:19:55.369 [0x7f069e7fe700] DEBUG - EventSource: Resolving 184.105.148.84 port 443s
Jan 07, 2019 22:19:55.369 [0x7f069e7fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.369 [0x7f06a73fe700] DEBUG - EventSource: Resolved 184.105.148.84 to 184.105.148.84
Jan 07, 2019 22:19:55.369 [0x7f06a27ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.372 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:55.372 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52746 (Allowed Network)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:55.373 [0x7f0696bfe700] DEBUG - MyPlex: Requesting reachability check.
Jan 07, 2019 22:19:55.374 [0x7f0696bfe700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=8985a7f1-053b-4c81-a0b6-367ed9f6b8ed
Jan 07, 2019 22:19:55.491 [0x7f06a7bff700] DEBUG - EventSource: Connected in 82 ms.
Jan 07, 2019 22:19:55.492 [0x7f06a7bff700] DEBUG - EventSource: Wrote data, reading reply.
Jan 07, 2019 22:19:55.563 [0x7f06987fe700] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:19:55.564 [0x7f06987fe700] INFO - AutoUpdate: no updates available
Jan 07, 2019 22:19:55.564 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52743] 200 PUT /updater/check?download=0 (9 live) GZIP 654ms 268 bytes (pipelined: 1)
Jan 07, 2019 22:19:55.896 [0x7f069a7ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.899 [0x7f069a7ff700] DEBUG - Running database fix-ups.
Jan 07, 2019 22:19:55.901 [0x7f069a7ff700] DEBUG - Database Fixups: Destroyed 0 transient items.
Jan 07, 2019 22:19:55.904 [0x7f069a7ff700] DEBUG - Collections: Found 1 collections tags in section 2.
Jan 07, 2019 22:19:55.904 [0x7f069a7ff700] DEBUG - Collections: We had 1 collections in the library already.
Jan 07, 2019 22:19:55.904 [0x7f069a7ff700] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 07, 2019 22:19:55.908 [0x7f069a7ff700] DEBUG - Collections: Refreshed attributes for 1 collections in section 2 in 3ms.
Jan 07, 2019 22:19:55.909 [0x7f069a7ff700] DEBUG - Collection: Refreshed visibility in section 2 in 1ms.
Jan 07, 2019 22:19:55.910 [0x7f069a7ff700] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 07, 2019 22:19:55.910 [0x7f069a7ff700] DEBUG - Collections: We had 0 collections in the library already.
Jan 07, 2019 22:19:55.911 [0x7f069a7ff700] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 07, 2019 22:19:55.911 [0x7f069a7ff700] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 07, 2019 22:19:55.912 [0x7f069a7ff700] DEBUG - Collection: Refreshed visibility in section 3 in 1ms.
Jan 07, 2019 22:19:55.913 [0x7f069a7ff700] DEBUG - Collections: Found 0 collections tags in section 4.
Jan 07, 2019 22:19:55.913 [0x7f069a7ff700] DEBUG - Collections: We had 0 collections in the library already.
Jan 07, 2019 22:19:55.913 [0x7f069a7ff700] DEBUG - Collections: Synced tags in section 4 in 0ms.
Jan 07, 2019 22:19:55.914 [0x7f069a7ff700] DEBUG - Collections: Refreshed attributes for 0 collections in section 4 in 0ms.
Jan 07, 2019 22:19:55.915 [0x7f069a7ff700] DEBUG - Collection: Refreshed visibility in section 4 in 1ms.
Jan 07, 2019 22:19:55.916 [0x7f069a7ff700] DEBUG - Collections: Found 0 collections tags in section 5.
Jan 07, 2019 22:19:55.916 [0x7f069a7ff700] DEBUG - Collections: We had 0 collections in the library already.
Jan 07, 2019 22:19:55.916 [0x7f069a7ff700] DEBUG - Collections: Synced tags in section 5 in 0ms.
Jan 07, 2019 22:19:55.917 [0x7f069a7ff700] DEBUG - Collections: Refreshed attributes for 0 collections in section 5 in 0ms.
Jan 07, 2019 22:19:55.918 [0x7f069a7ff700] DEBUG - Collection: Refreshed visibility in section 5 in 0ms.
Jan 07, 2019 22:19:55.919 [0x7f069a7ff700] DEBUG - Collections: Found 1 collections tags in section 6.
Jan 07, 2019 22:19:55.919 [0x7f069a7ff700] DEBUG - Collections: We had 1 collections in the library already.
Jan 07, 2019 22:19:55.919 [0x7f069a7ff700] DEBUG - Collections: Synced tags in section 6 in 0ms.
Jan 07, 2019 22:19:55.921 [0x7f069a7ff700] DEBUG - Collections: Refreshed attributes for 1 collections in section 6 in 1ms.
Jan 07, 2019 22:19:55.922 [0x7f069a7ff700] DEBUG - Collection: Refreshed visibility in section 6 in 1ms.
Jan 07, 2019 22:19:55.922 [0x7f069a7ff700] DEBUG - Running database fix-ups completed in 0.0 seconds.
Jan 07, 2019 22:19:55.923 [0x7f069a7ff700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.927 [0x7f06a37ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:55.936 [0x7f0698fff700] DEBUG - Request: [127.0.0.1:45112 (Loopback)] GET /system/messaging/clear_events/com.plexapp.plugins.WebTools (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:55.937 [0x7f0698fff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.plugins.WebTools
Jan 07, 2019 22:19:55.937 [0x7f0698fff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/messaging/clear_events/com.plexapp.plugins.WebTools
Jan 07, 2019 22:19:55.989 [0x7f0698fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/system/messaging/clear_events/com.plexapp.plugins.WebTools
Jan 07, 2019 22:19:55.989 [0x7f0698fff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 07, 2019 22:19:55.990 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45112] 200 GET /system/messaging/clear_events/com.plexapp.plugins.WebTools (9 live) GZIP 53ms 330 bytes
Jan 07, 2019 22:19:56.009 [0x7f0696bfe700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=8985a7f1-053b-4c81-a0b6-367ed9f6b8ed
Jan 07, 2019 22:19:56.010 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52746] 200 PUT /myplex/refreshReachability (9 live) GZIP 638ms 268 bytes (pipelined: 3)
Jan 07, 2019 22:19:56.024 [0x7f069e7fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:56.026 [0x7f069e7fe700] DEBUG - Sync: onMyPlexSignIn
Jan 07, 2019 22:19:56.026 [0x7f069e7fe700] WARN - Sync: Couldn't get sync lists without subscription.
Jan 07, 2019 22:19:56.026 [0x7f069e7fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:56.098 [0x7f06a27ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:56.109 [0x7f06a7bff700] DEBUG - EventSource: Read HTTP reply header.
Jan 07, 2019 22:19:56.109 [0x7f06a7bff700] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Jan 07, 2019 22:19:56.109 [0x7f06a7bff700] DEBUG - EventSource: Successfully connected to 184.105.148.84.
Jan 07, 2019 22:19:56.119 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45120 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (10 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:56.119 [0x7f06973ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:56.120 [0x7f06973ff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:56.268 [0x7f06973ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:56.268 [0x7f06973ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Jan 07, 2019 22:19:56.269 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45120] 200 GET /:/plugins/com.plexapp.system/resourceHashes (10 live) GZIP 150ms 3498 bytes
Jan 07, 2019 22:19:56.292 [0x7f0695bff700] DEBUG - Request: [127.0.0.1:45126 (Loopback)] GET / (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:56.293 [0x7f06947ff700] DEBUG - Request: [127.0.0.1:45128 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:56.293 [0x7f06947ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:56.293 [0x7f06947ff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:56.294 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45126] 200 GET / (12 live) GZIP 1ms 1881 bytes
Jan 07, 2019 22:19:56.324 [0x7f06947ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:56.324 [0x7f06947ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60580 bytes of content.
Jan 07, 2019 22:19:56.331 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45128] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (12 live) GZIP 38ms 19579 bytes
Jan 07, 2019 22:19:56.620 [0x7f069a7ff700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:56.663 [0x7f069e7fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:56.664 [0x7f069e7fe700] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.276 [0x7f069e7fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.280 [0x7f069e7fe700] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.280 [0x7f06a27ff700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.918 [0x7f069e7fe700] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.919 [0x7f06a57ff700] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Jan 07, 2019 22:19:57.920 [0x7f069e7fe700] DEBUG - MyPlex: Updating device connections (from timer: 0)
Jan 07, 2019 22:19:57.920 [0x7f069e7fe700] DEBUG - HTTP requesting PUT https://plex.tv/devices/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab?Connection[][uri]=http://192.168.2.143:32400&httpsEnabled=0&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:57.923 [0x7f068ebff700] DEBUG - MyPlex: Sending Server Info to myPlex (user=crazyjpeters@gmail.com, ip=, port=0)
Jan 07, 2019 22:19:57.924 [0x7f068ebff700] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=945f3940-f904-4af0-a5fa-0596818a8578
Jan 07, 2019 22:19:57.942 [0x7f06a27ff700] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:58.508 [0x7f0691fff700] DEBUG - Reading 455 bytes in the body, code is 200
Jan 07, 2019 22:19:58.508 [0x7f0691fff700] DEBUG - [com.plexapp.plugins.WebTools] Plug-in running on port 41092.
Jan 07, 2019 22:19:58.509 [0x7f0691fff700] DEBUG -  * Plug-in handles prefix: /applications/webtools
Jan 07, 2019 22:19:58.509 [0x7f0691fff700] DEBUG - Read configuration for [com.plexapp.plugins.WebTools], had 1 prefixes
Jan 07, 2019 22:19:58.511 [0x7f06a0fff700] DEBUG - Starting scheduled updates, every 86400 seconds
Jan 07, 2019 22:19:58.511 [0x7f06a0fff700] INFO - Refreshing paths watched by LibraryUpdateManager
Jan 07, 2019 22:19:58.513 [0x7f06a0fff700] DEBUG - LibraryUpdateManager will start watching /mnt/nfs/media/Videos/Movies/Documentary and Music
Jan 07, 2019 22:19:58.513 [0x7f06a0fff700] DEBUG - LibraryUpdateManager will start watching /mnt/nfs/media/My Home Movies
Jan 07, 2019 22:19:58.513 [0x7f06a0fff700] DEBUG - LibraryUpdateManager will start watching /mnt/nfs/media/Videos/Movies
Jan 07, 2019 22:19:58.514 [0x7f06a0fff700] DEBUG - LibraryUpdateManager will start watching /mnt/nfs/media/Videos/Music Video
Jan 07, 2019 22:19:58.514 [0x7f06a0fff700] DEBUG - LibraryUpdateManager will start watching /mnt/nfs/media/Videos/TV Shows
Jan 07, 2019 22:19:58.514 [0x7f06a0fff700] DEBUG - [Notify] Initializing.
Jan 07, 2019 22:19:58.514 [0x7f06a0fff700] DEBUG - Done waiting for all plug-ins to start.
Jan 07, 2019 22:19:58.514 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies
Jan 07, 2019 22:19:58.515 [0x7f06a0fff700] DEBUG - PluginRepository::setStartState: 3, complete
Jan 07, 2019 22:19:58.515 [0x7f068dbff700] DEBUG - [Notify] Waiting for inotify notifications...
Jan 07, 2019 22:19:58.516 [0x7f068d3fe700] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Jan 07, 2019 22:19:58.517 [0x7f068d3fe700] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Jan 07, 2019 22:19:58.517 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Wedding Stuff
Jan 07, 2019 22:19:58.518 [0x7f068d3fe700] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 21238
Jan 07, 2019 22:19:58.518 [0x7f068d3fe700] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes
Jan 07, 2019 22:19:58.520 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Wedding Stuff/Vegas Projects
Jan 07, 2019 22:19:58.521 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Wedding Stuff/Invitation Art
Jan 07, 2019 22:19:58.522 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Wedding Stuff/PowerPoint
Jan 07, 2019 22:19:58.524 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Wedding Stuff/Wedding Pics
Jan 07, 2019 22:19:58.527 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/DV-CAPTURE
Jan 07, 2019 22:19:58.535 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Abby
Jan 07, 2019 22:19:58.536 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/My Home Movies/Olivia
Jan 07, 2019 22:19:58.538 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies
Jan 07, 2019 22:19:58.543 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/The Little Mermaid (1989)
Jan 07, 2019 22:19:58.545 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Incredibles 2 (2018)
Jan 07, 2019 22:19:58.546 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Documentary and Music
Jan 07, 2019 22:19:58.549 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Despicable Me (2010)
Jan 07, 2019 22:19:58.551 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Indiana Jones and the Last Crusade (1989)
Jan 07, 2019 22:19:58.553 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Minions (2015)
Jan 07, 2019 22:19:58.556 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Despicable Me 2 (2013)
Jan 07, 2019 22:19:58.558 [0x7f069e7fe700] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab?Connection[][uri]=http://192.168.2.143:32400&httpsEnabled=0&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 07, 2019 22:19:58.558 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Star Wars The Empire Strikes Back (1980)
Jan 07, 2019 22:19:58.561 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Indiana Jones and the Temple of Doom (1984)
Jan 07, 2019 22:19:58.562 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Cars (2006)
Jan 07, 2019 22:19:58.563 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Star Wars A New Hope (1977)
Jan 07, 2019 22:19:58.569 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Cinderella (1950) Diamond Edition (2012)
Jan 07, 2019 22:19:58.570 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/The Peanuts Movie (2015)
Jan 07, 2019 22:19:58.571 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Toy Story (1995)
Jan 07, 2019 22:19:58.573 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Star Wars Return of the Jedi (1980)
Jan 07, 2019 22:19:58.575 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Star Wars Return of the Jedi (1980)/FINAL
Jan 07, 2019 22:19:58.576 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Cinderella (2015)
Jan 07, 2019 22:19:58.578 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Indiana Jones and the Raiders of the Lost Ark (1981)
Jan 07, 2019 22:19:58.581 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Hotel Transylvania 3 (2018)
Jan 07, 2019 22:19:58.583 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Movies/Documentary and Music
Jan 07, 2019 22:19:58.583 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/Music Video
Jan 07, 2019 22:19:58.585 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows
Jan 07, 2019 22:19:58.586 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Disney Animated Shorts
Jan 07, 2019 22:19:58.592 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Disney Animated Shorts/Season 00
Jan 07, 2019 22:19:58.593 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Disney Animated Shorts/Extras
Jan 07, 2019 22:19:58.597 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Muppet Show
Jan 07, 2019 22:19:58.598 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Muppet Show/Season 3
Jan 07, 2019 22:19:58.599 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Muppet Show/Season 4
Jan 07, 2019 22:19:58.601 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Muppet Show/Season 1
Jan 07, 2019 22:19:58.603 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Muppet Show/Season 2
Jan 07, 2019 22:19:58.604 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Corner Gas
Jan 07, 2019 22:19:58.607 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes
Jan 07, 2019 22:19:58.608 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1944
Jan 07, 2019 22:19:58.609 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1957
Jan 07, 2019 22:19:58.610 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1950
Jan 07, 2019 22:19:58.612 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1943
Jan 07, 2019 22:19:58.613 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1949
Jan 07, 2019 22:19:58.614 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1947
Jan 07, 2019 22:19:58.615 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1954
Jan 07, 2019 22:19:58.616 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1953
Jan 07, 2019 22:19:58.617 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1959
Jan 07, 2019 22:19:58.618 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1961
Jan 07, 2019 22:19:58.619 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1940
Jan 07, 2019 22:19:58.620 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1948
Jan 07, 2019 22:19:58.621 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1942
Jan 07, 2019 22:19:58.622 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1951
Jan 07, 2019 22:19:58.624 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1963
Jan 07, 2019 22:19:58.624 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1964
Jan 07, 2019 22:19:58.625 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1980
Jan 07, 2019 22:19:58.626 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1956
Jan 07, 2019 22:19:58.627 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1945
Jan 07, 2019 22:19:58.628 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1941
Jan 07, 2019 22:19:58.629 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1958
Jan 07, 2019 22:19:58.630 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1952
Jan 07, 2019 22:19:58.630 [0x7f068ebff700] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=945f3940-f904-4af0-a5fa-0596818a8578
Jan 07, 2019 22:19:58.631 [0x7f068ebff700] DEBUG - MyPlex: Published Mapping State response was 201
Jan 07, 2019 22:19:58.631 [0x7f068ebff700] DEBUG - MyPlex: Got response for 9137ee3c856455d82ce048f60bd6e5b64d0ae2ab ~ registered :0
Jan 07, 2019 22:19:58.631 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1955
Jan 07, 2019 22:19:58.633 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Looney Tunes/Season 1946
Jan 07, 2019 22:19:58.634 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Seinfeld
Jan 07, 2019 22:19:58.634 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:58.635 [0x7f06987fe700] DEBUG - Request: [192.168.2.199:52746 (Allowed Network)] PUT /myplex/refreshReachability (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:58.635 [0x7f06987fe700] DEBUG - MyPlex: Requesting reachability check.
Jan 07, 2019 22:19:58.636 [0x7f06987fe700] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=86ade146-4429-41e1-a5f9-5e5bfdde9745
Jan 07, 2019 22:19:58.640 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Great Planes
Jan 07, 2019 22:19:58.641 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Family Guy
Jan 07, 2019 22:19:58.644 [0x7f06a73fe700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="945f3940-f904-4af0-a5fa-0596818a8578" connectivity="0" command="notifyConnectivity"/>'
Jan 07, 2019 22:19:58.645 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/MASH
Jan 07, 2019 22:19:58.646 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/MASH/Season 2
Jan 07, 2019 22:19:58.647 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/MASH/Season 1
Jan 07, 2019 22:19:58.649 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/MASH/Season 3
Jan 07, 2019 22:19:58.651 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/MASH/Season 4
Jan 07, 2019 22:19:58.652 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/A Racing Car Is Born
Jan 07, 2019 22:19:58.653 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/Little House on the Prairie
Jan 07, 2019 22:19:58.655 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio
Jan 07, 2019 22:19:58.656 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio/Season 03
Jan 07, 2019 22:19:58.657 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio/Season 04
Jan 07, 2019 22:19:58.659 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio/Season 05
Jan 07, 2019 22:19:58.661 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio/Season 02
Jan 07, 2019 22:19:58.662 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/NewsRadio/Season 01
Jan 07, 2019 22:19:58.663 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati
Jan 07, 2019 22:19:58.664 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 01
Jan 07, 2019 22:19:58.666 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Special Features
Jan 07, 2019 22:19:58.667 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 02
Jan 07, 2019 22:19:58.669 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 04
Jan 07, 2019 22:19:58.671 [0x7f06a47ff700] DEBUG - [Notify] Now watching /mnt/nfs/media/Videos/TV Shows/WKRP in Cincinnati/Season 03
Jan 07, 2019 22:19:59.269 [0x7f06987fe700] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/9137ee3c856455d82ce048f60bd6e5b64d0ae2ab/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=86ade146-4429-41e1-a5f9-5e5bfdde9745
Jan 07, 2019 22:19:59.270 [0x7f06a73fe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (945f3940-f904-4af0-a5fa-0596818a8578, expected 86ade146-4429-41e1-a5f9-5e5bfdde9745)
Jan 07, 2019 22:19:59.270 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52746] 200 PUT /myplex/refreshReachability (12 live) GZIP 635ms 268 bytes (pipelined: 4)
Jan 07, 2019 22:19:59.285 [0x7f06a7bff700] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="86ade146-4429-41e1-a5f9-5e5bfdde9745" connectivity="0" command="notifyConnectivity"/>'
Jan 07, 2019 22:19:59.286 [0x7f06a7bff700] DEBUG - PubSub: Got notified of reachability: 0 for :0
Jan 07, 2019 22:19:59.289 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:19:59.290 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52746 (Allowed Network)] GET /myplex/account (11 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.291 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52746] 200 GET /myplex/account (11 live) GZIP 1ms 1095 bytes (pipelined: 5)
Jan 07, 2019 22:19:59.526 [0x7f0696bfe700] DEBUG - Request: [127.0.0.1:45144 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.526 [0x7f0696bfe700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Jan 07, 2019 22:19:59.526 [0x7f0696bfe700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 07, 2019 22:19:59.531 [0x7f0696bfe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 07, 2019 22:19:59.532 [0x7f0696bfe700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 07, 2019 22:19:59.532 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45144] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (12 live) GZIP 6ms 330 bytes
Jan 07, 2019 22:19:59.579 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: SERVER arrived: 127.0.0.1 (PETERS_HOME_PLEX)
Jan 07, 2019 22:19:59.579 [0x7f069c7ff700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=127.0.0.1
Jan 07, 2019 22:19:59.579 [0x7f069c7ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=127.0.0.1
Jan 07, 2019 22:19:59.579 [0x7f069c7ff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/notify/serverUpdated?host=127.0.0.1
Jan 07, 2019 22:19:59.586 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45150 (Loopback)] GET /servers (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.587 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45150] 200 GET /servers (12 live) GZIP 1ms 505 bytes
Jan 07, 2019 22:19:59.592 [0x7f0695bff700] DEBUG - Request: [127.0.0.1:45152 (Loopback)] GET /library/sections (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.596 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45152] 200 GET /library/sections (12 live) GZIP 4ms 1067 bytes
Jan 07, 2019 22:19:59.601 [0x7f06947ff700] DEBUG - Request: [127.0.0.1:45154 (Loopback)] GET /channels/all (12 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.602 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45154] 200 GET /channels/all (12 live) GZIP 1ms 670 bytes
Jan 07, 2019 22:19:59.607 [0x7f069c7ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/system/notify/serverUpdated?host=127.0.0.1
Jan 07, 2019 22:19:59.607 [0x7f069c7ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 07, 2019 22:19:59.714 [0x7f06987fe700] DEBUG - Request: [127.0.0.1:45160 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (13 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.714 [0x7f06987fe700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:59.714 [0x7f06987fe700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:59.720 [0x7f06987fe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/resourceHashes
Jan 07, 2019 22:19:59.720 [0x7f06987fe700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Jan 07, 2019 22:19:59.722 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45160] 200 GET /:/plugins/com.plexapp.system/resourceHashes (13 live) GZIP 8ms 3498 bytes
Jan 07, 2019 22:19:59.743 [0x7f0698fff700] DEBUG - Request: [127.0.0.1:45164 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (14 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.743 [0x7f0698fff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:59.743 [0x7f0698fff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:59.744 [0x7f0696bfe700] DEBUG - Request: [127.0.0.1:45166 (Loopback)] GET / (14 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:19:59.746 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45166] 200 GET / (14 live) GZIP 1ms 1874 bytes
Jan 07, 2019 22:19:59.777 [0x7f0698fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 07, 2019 22:19:59.777 [0x7f0698fff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 60580 bytes of content.
Jan 07, 2019 22:19:59.784 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45164] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (14 live) GZIP 41ms 19579 bytes
Jan 07, 2019 22:20:01.063 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45170 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (14 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:01.064 [0x7f06973ff700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 07, 2019 22:20:01.064 [0x7f06973ff700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 07, 2019 22:20:01.072 [0x7f06973ff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:33212/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 07, 2019 22:20:01.073 [0x7f06973ff700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Jan 07, 2019 22:20:01.073 [0x7f06a73fe700] DEBUG - Completed: [127.0.0.1:45170] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (14 live) GZIP 10ms 465 bytes
Jan 07, 2019 22:20:01.081 [0x7f068d3fe700] DEBUG - Reading 148 bytes in the body, code is 200
Jan 07, 2019 22:20:01.081 [0x7f068d3fe700] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 40587.
Jan 07, 2019 22:20:01.082 [0x7f068d3fe700] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Jan 07, 2019 22:20:01.083 [0x7f068d3fe700] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Jan 07, 2019 22:20:01.083 [0x7f068d3fe700] DEBUG - HTTP requesting GET http://127.0.0.1:40587/:/plugins/com.plexapp.agents.imdb/prefs
Jan 07, 2019 22:20:01.111 [0x7f068d3fe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:40587/:/plugins/com.plexapp.agents.imdb/prefs
Jan 07, 2019 22:20:01.111 [0x7f068d3fe700] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Jan 07, 2019 22:20:01.111 [0x7f068d3fe700] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as 874aecb4a130fba701914f7aab419330af96e063
Jan 07, 2019 22:20:01.112 [0x7f068d3fe700] DEBUG - IVA: Downloading trailer lists.
Jan 07, 2019 22:20:01.314 [0x7f068d3fe700] DEBUG - IVA: Downloaded 13 trailers for source 1.
Jan 07, 2019 22:20:01.453 [0x7f068d3fe700] DEBUG - IVA: Downloaded 26 trailers for source 2.
Jan 07, 2019 22:20:01.504 [0x7f06a27ff700] DEBUG - DVR:Device: Discovering and refreshing all devices.
Jan 07, 2019 22:20:01.505 [0x7f06a27ff700] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Jan 07, 2019 22:20:01.505 [0x7f06a27ff700] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Jan 07, 2019 22:20:02.920 [0x7f06a57ff700] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Jan 07, 2019 22:20:03.575 [0x7f06a57ff700] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Jan 07, 2019 22:20:03.925 [0x7f06a27ff700] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 184.105.148.98 was 80 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 184.105.148.84 was 83 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 45.79.10.123 was 135 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 45.79.137.197 was 136 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 50.116.44.223 was 139 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 228 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 139.162.227.137 was 283 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 82.94.168.60 was 293 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 172.104.129.32 was 337 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Time to connect to 172.104.183.85 was 430 ms.
Jan 07, 2019 22:20:04.221 [0x7f06a57ff700] DEBUG - PubSubManager: Another server (184.105.148.98) beat the current one (184.105.148.84).
Jan 07, 2019 22:20:04.222 [0x7f06a57ff700] DEBUG - EventSource: Stopping.
Jan 07, 2019 22:20:04.222 [0x7f06a57ff700] DEBUG - EventSource: Resolving 184.105.148.98 port 443s
Jan 07, 2019 22:20:04.222 [0x7f06a57ff700] DEBUG - PubSubManager: Updating best host to 184.105.148.98.
Jan 07, 2019 22:20:04.222 [0x7f06a7bff700] DEBUG - EventSource: Resolved 184.105.148.98 to 184.105.148.98
Jan 07, 2019 22:20:04.222 [0x7f06a57ff700] DEBUG - PubSubManager: Updating best ping time for 184.105.148.98 to 80 ms.
Jan 07, 2019 22:20:04.338 [0x7f06a7bff700] DEBUG - EventSource: Connected in 78 ms.
Jan 07, 2019 22:20:04.338 [0x7f06a7bff700] DEBUG - EventSource: Wrote data, reading reply.
Jan 07, 2019 22:20:04.687 [0x7f06a27ff700] DEBUG - Activity: Ended activity a6377a20-15dc-4494-bfeb-f45d33fbec05.
Jan 07, 2019 22:20:04.687 [0x7f06a27ff700] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 07, 2019 22:20:04.939 [0x7f06a73fe700] DEBUG - EventSource: Read HTTP reply header.
Jan 07, 2019 22:20:04.940 [0x7f06a73fe700] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Jan 07, 2019 22:20:04.940 [0x7f06a73fe700] DEBUG - EventSource: Successfully connected to 184.105.148.98.
Jan 07, 2019 22:20:05.782 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.20.12:9000/plugins/UPnP/MediaRenderer.xml?player=00%3A04%3A20%3A23%3A80%3A23
Jan 07, 2019 22:20:05.782 [0x7f069c7ff700] DEBUG - HTTP requesting GET http://192.168.20.12:9000/plugins/UPnP/MediaRenderer.xml?player=00%3A04%3A20%3A23%3A80%3A23
Jan 07, 2019 22:20:05.789 [0x7f069c7ff700] DEBUG - HTTP 200 response from GET http://192.168.20.12:9000/plugins/UPnP/MediaRenderer.xml?player=00%3A04%3A20%3A23%3A80%3A23
Jan 07, 2019 22:20:05.790 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.20.12:9000/plugins/UPnP/MediaRenderer.xml?player=00%3A04%3A20%3A23%3A80%3A23
Jan 07, 2019 22:20:05.790 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.20.12 (SB-Touch-Downstairs)
Jan 07, 2019 22:20:06.087 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.2.143:9000/plugins/UPnP/MediaServer.xml
Jan 07, 2019 22:20:06.087 [0x7f069c7ff700] DEBUG - HTTP requesting GET http://192.168.2.143:9000/plugins/UPnP/MediaServer.xml
Jan 07, 2019 22:20:06.093 [0x7f069c7ff700] DEBUG - HTTP 200 response from GET http://192.168.2.143:9000/plugins/UPnP/MediaServer.xml
Jan 07, 2019 22:20:06.094 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.2.143:9000/plugins/UPnP/MediaServer.xml
Jan 07, 2019 22:20:06.094 [0x7f069c7ff700] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.2.143 (Logitech Media Server [localhost])
Jan 07, 2019 22:20:23.712 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET /web/index.html (6 live) GZIP Signed-in
Jan 07, 2019 22:20:23.713 [0x7f06973ff700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/index.html"
Jan 07, 2019 22:20:23.713 [0x7f06973ff700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/index.html is 9330.
Jan 07, 2019 22:20:23.715 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52751] 200 GET /web/index.html (6 live) GZIP 3ms 9330 bytes (pipelined: 1)
Jan 07, 2019 22:20:23.723 [0x7f06a7bff700] DEBUG - WebSocket: client initiated close
Jan 07, 2019 22:20:23.723 [0x7f06a73fe700] DEBUG - WebSocket: client initiated close
Jan 07, 2019 22:20:23.723 [0x7f06a7bff700] DEBUG - handleStreamRead code 2: End of file
Jan 07, 2019 22:20:23.723 [0x7f06a7bff700] DEBUG - NotificationStream: Removing because of error
Jan 07, 2019 22:20:23.723 [0x7f06a73fe700] DEBUG - handleStreamRead code 2: End of file
Jan 07, 2019 22:20:23.723 [0x7f06a73fe700] DEBUG - NotificationStream: Removing because of error
Jan 07, 2019 22:20:23.735 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET /web/main.a2efbcad3b1e8234d8dccb7b807ff0ed.css (4 live) GZIP Signed-in
Jan 07, 2019 22:20:23.735 [0x7f0698fff700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/main.a2efbcad3b1e8234d8dccb7b807ff0ed.css"
Jan 07, 2019 22:20:23.736 [0x7f0698fff700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/main.a2efbcad3b1e8234d8dccb7b807ff0ed.css is 763220.
Jan 07, 2019 22:20:23.736 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52752 (Allowed Network)] GET /web/main.v3.19e0546a6eddfac6ae7f8caffe74e22e.css (4 live) GZIP Signed-in
Jan 07, 2019 22:20:23.736 [0x7f0696bfe700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/main.v3.19e0546a6eddfac6ae7f8caffe74e22e.css"
Jan 07, 2019 22:20:23.736 [0x7f0696bfe700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/main.v3.19e0546a6eddfac6ae7f8caffe74e22e.css is 290842.
Jan 07, 2019 22:20:23.741 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52752] 200 GET /web/main.v3.19e0546a6eddfac6ae7f8caffe74e22e.css (6 live) GZIP 6ms 290842 bytes (pipelined: 1)
Jan 07, 2019 22:20:23.742 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] GET /web/js/vendors-2-87d7020ee812c1be8194-plex-3.77.4-ae9ba53.js (6 live) GZIP Signed-in
Jan 07, 2019 22:20:23.742 [0x7f0698fff700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/js/vendors-2-87d7020ee812c1be8194-plex-3.77.4-ae9ba53.js"
Jan 07, 2019 22:20:23.742 [0x7f0698fff700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/js/vendors-2-87d7020ee812c1be8194-plex-3.77.4-ae9ba53.js is 304503.
Jan 07, 2019 22:20:23.742 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /web/js/main-1-690b3309d4d2a263720a-plex-3.77.4-ae9ba53.js (6 live) GZIP Signed-in
Jan 07, 2019 22:20:23.742 [0x7f0696bfe700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/js/main-1-690b3309d4d2a263720a-plex-3.77.4-ae9ba53.js"
Jan 07, 2019 22:20:23.743 [0x7f0696bfe700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/js/main-1-690b3309d4d2a263720a-plex-3.77.4-ae9ba53.js is 4102129.
Jan 07, 2019 22:20:23.746 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52753] 200 GET /web/js/vendors-2-87d7020ee812c1be8194-plex-3.77.4-ae9ba53.js (6 live) GZIP 4ms 304503 bytes (pipelined: 1)
Jan 07, 2019 22:20:23.747 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52751] 200 GET /web/main.a2efbcad3b1e8234d8dccb7b807ff0ed.css (6 live) GZIP 12ms 763220 bytes (pipelined: 2)
Jan 07, 2019 22:20:24.157 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /web/js/main-1-690b3309d4d2a263720a-plex-3.77.4-ae9ba53.js (6 live) GZIP 414ms 4102129 bytes (pipelined: 1)
Jan 07, 2019 22:20:25.284 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (6 live) GZIP Signed-in
Jan 07, 2019 22:20:25.284 [0x7f0698fff700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png"
Jan 07, 2019 22:20:25.286 [0x7f0698fff700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png is 31365.
Jan 07, 2019 22:20:25.287 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (6 live) GZIP 3ms 31365 bytes (pipelined: 2)
Jan 07, 2019 22:20:25.332 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /web/translations/en.json (6 live) GZIP Signed-in
Jan 07, 2019 22:20:25.332 [0x7f0696bfe700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/translations/en.json"
Jan 07, 2019 22:20:25.334 [0x7f0696bfe700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/translations/en.json is 2.
Jan 07, 2019 22:20:25.335 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /web/translations/en.json (6 live) GZIP 2ms 2 bytes (pipelined: 3)
Jan 07, 2019 22:20:25.339 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (6 live) GZIP Signed-in
Jan 07, 2019 22:20:25.339 [0x7f06973ff700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png"
Jan 07, 2019 22:20:25.339 [0x7f06973ff700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png is 54413.
Jan 07, 2019 22:20:25.341 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (6 live) GZIP 1ms 54413 bytes (pipelined: 4)
Jan 07, 2019 22:20:25.467 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.468 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /media/providers (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.471 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /media/providers (6 live) GZIP 3ms 2234 bytes (pipelined: 5)
Jan 07, 2019 22:20:25.480 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /media/providers (6 live) GZIP Signed-in
Jan 07, 2019 22:20:25.481 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET / (6 live) GZIP Signed-in
Jan 07, 2019 22:20:25.482 [0x7f06973ff700] DEBUG - (Capabilities) Platform 'Chrome' not matched by plugin platform requirements
Jan 07, 2019 22:20:25.483 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52751] 200 GET / (6 live) GZIP 2ms 1890 bytes (pipelined: 3)
Jan 07, 2019 22:20:25.483 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /media/providers (6 live) GZIP 3ms 2234 bytes (pipelined: 6)
Jan 07, 2019 22:20:25.580 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.580 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52759 (Allowed Network)] GET /:/websockets/notifications (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.581 [0x7f06973ff700] DEBUG - WebSocket: Performing handshake from origin http://192.168.2.143:32400
Jan 07, 2019 22:20:25.581 [0x7f06973ff700] DEBUG - Beginning read from WebSocket
Jan 07, 2019 22:20:25.609 [0x7f06a73fe700] DEBUG - WebSocket: client initiated close
Jan 07, 2019 22:20:25.609 [0x7f06a73fe700] DEBUG - handleStreamRead code 2: End of file
Jan 07, 2019 22:20:25.609 [0x7f06a73fe700] DEBUG - NotificationStream: Removing because of error
Jan 07, 2019 22:20:25.612 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.612 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52761 (Allowed Network)] GET /:/websockets/notifications (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.613 [0x7f0698fff700] DEBUG - WebSocket: Performing handshake from origin http://192.168.2.143:32400
Jan 07, 2019 22:20:25.613 [0x7f0698fff700] DEBUG - Beginning read from WebSocket
Jan 07, 2019 22:20:25.655 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.655 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /:/prefs (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.656 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.656 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET /status/sessions (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.657 [0x7f0698fff700] DEBUG - [Now] Adding 0 sessions.
Jan 07, 2019 22:20:25.657 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52751] 200 GET /status/sessions (7 live) GZIP 1ms 397 bytes (pipelined: 4)
Jan 07, 2019 22:20:25.658 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.658 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] GET /updater/status (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.659 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52753] 200 GET /updater/status (7 live) GZIP 0ms 440 bytes (pipelined: 2)
Jan 07, 2019 22:20:25.661 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /:/prefs (7 live) GZIP 6ms 6324 bytes (pipelined: 7)
Jan 07, 2019 22:20:25.665 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.665 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] GET /activities (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.666 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52753] 200 GET /activities (7 live) GZIP 1ms 350 bytes (pipelined: 3)
Jan 07, 2019 22:20:25.677 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:25.677 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] PUT /updater/check?download=0 (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:25.677 [0x7f06973ff700] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476, download: NO
Jan 07, 2019 22:20:25.678 [0x7f06973ff700] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:20:25.678 [0x7f06973ff700] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:20:26.130 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /web/favicon.ico (7 live) GZIP Signed-in
Jan 07, 2019 22:20:26.130 [0x7f0696bfe700] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/favicon.ico"
Jan 07, 2019 22:20:26.131 [0x7f0696bfe700] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-cc260c476/WebClient.bundle/Contents/Resources/favicon.ico is 5430.
Jan 07, 2019 22:20:26.132 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /web/favicon.ico (7 live) GZIP 2ms 5430 bytes (pipelined: 8)
Jan 07, 2019 22:20:26.299 [0x7f06973ff700] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=linux-ubuntu-x86_64&channel=16&distribution=redhat&version=1.14.1.5488-cc260c476
Jan 07, 2019 22:20:26.300 [0x7f06973ff700] INFO - AutoUpdate: no updates available
Jan 07, 2019 22:20:26.300 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52753] 200 PUT /updater/check?download=0 (7 live) GZIP 623ms 268 bytes (pipelined: 4)
Jan 07, 2019 22:20:26.591 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.592 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] GET /:/prefs (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.598 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52753] 200 GET /:/prefs (7 live) GZIP 7ms 6324 bytes (pipelined: 5)
Jan 07, 2019 22:20:26.621 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.621 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52753 (Allowed Network)] GET /accounts/1 (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.622 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.622 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52754 (Allowed Network)] GET /myplex/account (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.623 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52753] 200 GET /accounts/1 (7 live) GZIP 1ms 521 bytes (pipelined: 6)
Jan 07, 2019 22:20:26.623 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.623 [0x7f0696bfe700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET /system/:/prefs (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.624 [0x7f0696bfe700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Jan 07, 2019 22:20:26.624 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52754] 200 GET /myplex/account (7 live) GZIP 1ms 1095 bytes (pipelined: 9)
Jan 07, 2019 22:20:26.624 [0x7f0696bfe700] DEBUG - HTTP requesting GET http://127.0.0.1:33212/system/:/prefs
Jan 07, 2019 22:20:26.631 [0x7f0696bfe700] DEBUG - HTTP 304 response from GET http://127.0.0.1:33212/system/:/prefs
Jan 07, 2019 22:20:26.631 [0x7f0696bfe700] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
Jan 07, 2019 22:20:26.631 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.199:52751] 304 GET /system/:/prefs (7 live) GZIP 8ms 324 bytes (pipelined: 5)
Jan 07, 2019 22:20:26.684 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.684 [0x7f06973ff700] DEBUG - Request: [192.168.2.199:52763 (Allowed Network)] GET /:/websockets/notifications?filters=log (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.685 [0x7f06973ff700] DEBUG - WebSocket: Performing handshake from origin http://192.168.2.143:32400
Jan 07, 2019 22:20:26.685 [0x7f06973ff700] DEBUG - Beginning read from WebSocket
Jan 07, 2019 22:20:26.720 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:20:26.721 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52751 (Allowed Network)] GET /activities (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:20:26.722 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52751] 200 GET /activities (8 live) GZIP 1ms 350 bytes (pipelined: 6)
Jan 07, 2019 22:21:38.867 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:38.867 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:38.868 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50178 (Allowed Network)] GET / (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:38.868 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50176 (Allowed Network)] GET / (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:38.868 [0x7f0696bfe700] DEBUG - (Capabilities) Platform 'Kodi' not matched by plugin platform requirements
Jan 07, 2019 22:21:38.869 [0x7f06973ff700] DEBUG - (Capabilities) Platform 'Kodi' not matched by plugin platform requirements
Jan 07, 2019 22:21:38.870 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50178] 200 GET / (6 live) GZIP 2ms 1903 bytes (pipelined: 1)
Jan 07, 2019 22:21:38.870 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50176] 200 GET / (6 live) GZIP 2ms 1903 bytes (pipelined: 1)
Jan 07, 2019 22:21:40.903 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50188 (Allowed Network)] GET /identity (8 live) GZIP Signed-in
Jan 07, 2019 22:21:40.904 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50190 (Allowed Network)] GET /identity (8 live) GZIP Signed-in
Jan 07, 2019 22:21:40.904 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50188] 200 GET /identity (8 live) GZIP 1ms 471 bytes (pipelined: 1)
Jan 07, 2019 22:21:40.904 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50190] 200 GET /identity (8 live) GZIP 0ms 471 bytes (pipelined: 1)
Jan 07, 2019 22:21:41.545 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50198 (Allowed Network)] GET /identity (9 live) GZIP Signed-in
Jan 07, 2019 22:21:41.546 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50198] 200 GET /identity (9 live) GZIP 0ms 471 bytes (pipelined: 1)
Jan 07, 2019 22:21:41.561 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50200 (Allowed Network)] GET /identity (10 live) GZIP Signed-in
Jan 07, 2019 22:21:41.562 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50200] 200 GET /identity (10 live) GZIP 0ms 471 bytes (pipelined: 1)
Jan 07, 2019 22:21:41.914 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:41.914 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50202 (Allowed Network)] GET /playlists/all (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:41.918 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50202] 200 GET /playlists/all (9 live) GZIP 3ms 397 bytes (pipelined: 1)
Jan 07, 2019 22:21:41.946 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:41.947 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50204 (Allowed Network)] GET /playlists/all (10 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:41.950 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50204] 200 GET /playlists/all (10 live) GZIP 3ms 397 bytes (pipelined: 1)
Jan 07, 2019 22:21:41.965 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:41.966 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50202 (Allowed Network)] GET /library/ (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:41.967 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50202] 200 GET /library/ (9 live) GZIP 1ms 599 bytes (pipelined: 2)
Jan 07, 2019 22:21:41.992 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:41.993 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50202 (Allowed Network)] GET /library/sections (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:41.997 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50202] 200 GET /library/sections (9 live) GZIP 4ms 1096 bytes (pipelined: 3)
Jan 07, 2019 22:21:42.018 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.019 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50204 (Allowed Network)] GET /library/ (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.020 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50204] 200 GET /library/ (9 live) GZIP 1ms 599 bytes (pipelined: 2)
Jan 07, 2019 22:21:42.039 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.040 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50202 (Allowed Network)] GET /hubs (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.040 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.continue/1/hubs//count=10' to the cache (1/home.continue/1/hubs//count=10).
Jan 07, 2019 22:21:42.040 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs//count=10' to the cache (1/home.ondeck/1/hubs//count=10).
Jan 07, 2019 22:21:42.066 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.067 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50204 (Allowed Network)] GET /library/sections (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.068 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.069 [0x7f069e7fe700] DEBUG - Request: [192.168.2.37:50206 (Allowed Network)] GET /playlists/all?playlistType=audio (9 live) Page 0-9 GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.072 [0x7f069e7fe700] DEBUG - Setting container serialization range to [0, 9] (total=-1)
Jan 07, 2019 22:21:42.072 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50204] 200 GET /library/sections (9 live) GZIP 5ms 1096 bytes (pipelined: 3)
Jan 07, 2019 22:21:42.073 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50206] 200 GET /playlists/all?playlistType=audio (9 live) GZIP Page 0-9 4ms 474 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.086 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs//count=10' in 45 ms.
Jan 07, 2019 22:21:42.086 [0x7f06973ff700] DEBUG - HubCache: 2 hubs cached, 0.0% hit ratio.
Jan 07, 2019 22:21:42.086 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.continue/1/hubs//count=10' in 45 ms.
Jan 07, 2019 22:21:42.086 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.086 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.playlists/1/hubs//count=10' to the cache (1/home.playlists/1/hubs//count=10).
Jan 07, 2019 22:21:42.088 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.playlists/1/hubs//count=10' in 1 ms.
Jan 07, 2019 22:21:42.088 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs//count=10' to the cache (1/home.movies.recent/1/hubs//count=10).
Jan 07, 2019 22:21:42.101 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs//count=10' in 12 ms.
Jan 07, 2019 22:21:42.101 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs//count=10' to the cache (1/home.television.recent/1/hubs//count=10).
Jan 07, 2019 22:21:42.113 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.113 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50208 (Allowed Network)] GET /hubs/sections/5 (10 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.114 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.recentlyreleased.5/1/hubs/sections/5//count=10' to the cache (1/movie.recentlyreleased.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.117 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.recentlyreleased.5/1/hubs/sections/5//count=10' in 3 ms.
Jan 07, 2019 22:21:42.117 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.recentlyadded.5/1/hubs/sections/5//count=10' to the cache (1/movie.recentlyadded.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.122 [0x7f06973ff700] DEBUG - It took 40.000000 ms to retrieve 200 items.
Jan 07, 2019 22:21:42.125 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.recentlyadded.5/1/hubs/sections/5//count=10' in 7 ms.
Jan 07, 2019 22:21:42.125 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.inprogress.5/1/hubs/sections/5//count=10' to the cache (1/movie.inprogress.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.126 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.126 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50206 (Allowed Network)] GET /hubs/sections/2 (10 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.128 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.recentlyreleased.2/1/hubs/sections/2//count=10' to the cache (1/movie.recentlyreleased.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.128 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.inprogress.5/1/hubs/sections/5//count=10' in 3 ms.
Jan 07, 2019 22:21:42.128 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.genre.5/1/hubs/sections/5//count=10' to the cache (1/movie.genre.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.136 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.recentlyreleased.2/1/hubs/sections/2//count=10' in 8 ms.
Jan 07, 2019 22:21:42.136 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.recentlyadded.2/1/hubs/sections/2//count=10' to the cache (1/movie.recentlyadded.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.137 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.genre.5/1/hubs/sections/5//count=10' in 9 ms.
Jan 07, 2019 22:21:42.137 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.by.actor.or.director.5/1/hubs/sections/5//count=10' to the cache (1/movie.by.actor.or.director.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.141 [0x7f06973ff700] DEBUG - It took 60.000000 ms to retrieve 405 items.
Jan 07, 2019 22:21:42.146 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.recentlyadded.2/1/hubs/sections/2//count=10' in 9 ms.
Jan 07, 2019 22:21:42.146 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.inprogress.2/1/hubs/sections/2//count=10' to the cache (1/movie.inprogress.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.156 [0x7f06973ff700] DEBUG - It took 40.000000 ms to retrieve 320 items.
Jan 07, 2019 22:21:42.156 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.inprogress.2/1/hubs/sections/2//count=10' in 10 ms.
Jan 07, 2019 22:21:42.157 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.genre.2/1/hubs/sections/2//count=10' to the cache (1/movie.genre.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.169 [0x7f0698fff700] DEBUG - It took 90.000000 ms to retrieve 1 items.
Jan 07, 2019 22:21:42.172 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.by.actor.or.director.5/1/hubs/sections/5//count=10' in 34 ms.
Jan 07, 2019 22:21:42.172 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.topunwatched.5/1/hubs/sections/5//count=10' to the cache (1/movie.topunwatched.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.175 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.topunwatched.5/1/hubs/sections/5//count=10' in 2 ms.
Jan 07, 2019 22:21:42.175 [0x7f0698fff700] DEBUG - HubCache: Adding '1/movie.recentlyviewed.5/1/hubs/sections/5//count=10' to the cache (1/movie.recentlyviewed.5/1/hubs/sections/5//count=10).
Jan 07, 2019 22:21:42.176 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.genre.2/1/hubs/sections/2//count=10' in 19 ms.
Jan 07, 2019 22:21:42.176 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.by.actor.or.director.2/1/hubs/sections/2//count=10' to the cache (1/movie.by.actor.or.director.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.178 [0x7f0698fff700] DEBUG - HubCache: Computed '1/movie.recentlyviewed.5/1/hubs/sections/5//count=10' in 3 ms.
Jan 07, 2019 22:21:42.180 [0x7f06973ff700] DEBUG - It took 70.000000 ms to retrieve 35 items.
Jan 07, 2019 22:21:42.182 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50208] 200 GET /hubs/sections/5 (10 live) GZIP 69ms 2539 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.216 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs//count=10' in 114 ms.
Jan 07, 2019 22:21:42.216 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.225 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 8 ms.
Jan 07, 2019 22:21:42.225 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.226 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 07, 2019 22:21:42.226 [0x7f06973ff700] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.227 [0x7f06973ff700] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 1 ms.
Jan 07, 2019 22:21:42.231 [0x7f06973ff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:21:42.231 [0x7f06973ff700] DEBUG - Selecting best audio stream for part ID 26641 (autoselect: 1 language: en)
Jan 07, 2019 22:21:42.231 [0x7f06973ff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:42.231 [0x7f06973ff700] DEBUG - Audio Stream: 39745, Subtitle Stream: -1
Jan 07, 2019 22:21:42.232 [0x7f06973ff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:21:42.232 [0x7f06973ff700] DEBUG - Selecting best audio stream for part ID 26537 (autoselect: 1 language: en)
Jan 07, 2019 22:21:42.232 [0x7f06973ff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:42.232 [0x7f06973ff700] DEBUG - Audio Stream: 39522, Subtitle Stream: -1
Jan 07, 2019 22:21:42.245 [0x7f06973ff700] DEBUG - There were 1 top-level paths for M*A*S*H.
Jan 07, 2019 22:21:42.249 [0x7f06973ff700] DEBUG - There were 1 top-level paths for Corner Gas.
Jan 07, 2019 22:21:42.252 [0x7f0696bfe700] DEBUG - It took 180.000000 ms to retrieve 30 items.
Jan 07, 2019 22:21:42.255 [0x7f06973ff700] DEBUG - There were 1 top-level paths for The Muppet Show.
Jan 07, 2019 22:21:42.258 [0x7f06973ff700] DEBUG - There were 1 top-level paths for Family Guy.
Jan 07, 2019 22:21:42.260 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.by.actor.or.director.2/1/hubs/sections/2//count=10' in 83 ms.
Jan 07, 2019 22:21:42.260 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.topunwatched.2/1/hubs/sections/2//count=10' to the cache (1/movie.topunwatched.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.262 [0x7f06973ff700] DEBUG - There were 1 top-level paths for Little House on the Prairie.
Jan 07, 2019 22:21:42.270 [0x7f06973ff700] DEBUG - There were 1 top-level paths for Disney Animated Shorts.
Jan 07, 2019 22:21:42.272 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.topunwatched.2/1/hubs/sections/2//count=10' in 12 ms.
Jan 07, 2019 22:21:42.272 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/movie.recentlyviewed.2/1/hubs/sections/2//count=10' to the cache (1/movie.recentlyviewed.2/1/hubs/sections/2//count=10).
Jan 07, 2019 22:21:42.275 [0x7f06973ff700] DEBUG - There were 1 top-level paths for Looney Tunes.
Jan 07, 2019 22:21:42.280 [0x7f06973ff700] DEBUG - There were 1 top-level paths for WKRP in Cincinnati.
Jan 07, 2019 22:21:42.282 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/movie.recentlyviewed.2/1/hubs/sections/2//count=10' in 10 ms.
Jan 07, 2019 22:21:42.292 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50202] 200 GET /hubs (10 live) GZIP 252ms 20233 bytes (pipelined: 4)
Jan 07, 2019 22:21:42.306 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50206] 200 GET /hubs/sections/2 (10 live) GZIP 179ms 22301 bytes (pipelined: 2)
Jan 07, 2019 22:21:42.476 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.476 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50216 (Allowed Network)] GET /hubs/sections/6 (13 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.477 [0x7f0698fff700] DEBUG - HubCache: Adding '1/video.recent.6/1/hubs/sections/6//count=10' to the cache (1/video.recent.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.482 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.482 [0x7f069e7fe700] DEBUG - Request: [192.168.2.37:50214 (Allowed Network)] GET /hubs/sections/3 (13 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.483 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.ondeck.3/1/hubs/sections/3//count=10' to the cache (1/tv.ondeck.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:42.484 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.recentlyaired.3/1/hubs/sections/3//count=10' to the cache (1/tv.recentlyaired.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:42.486 [0x7f0698fff700] DEBUG - HubCache: Computed '1/video.recent.6/1/hubs/sections/6//count=10' in 8 ms.
Jan 07, 2019 22:21:42.486 [0x7f0698fff700] DEBUG - HubCache: Adding '1/photo.random.year.6/1/hubs/sections/6//count=10' to the cache (1/photo.random.year.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.492 [0x7f0698fff700] DEBUG - HubCache: Computed '1/photo.random.year.6/1/hubs/sections/6//count=10' in 6 ms.
Jan 07, 2019 22:21:42.492 [0x7f0698fff700] DEBUG - HubCache: Adding '1/photo.random.decade.6/1/hubs/sections/6//count=10' to the cache (1/photo.random.decade.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.499 [0x7f0698fff700] DEBUG - HubCache: Computed '1/photo.random.decade.6/1/hubs/sections/6//count=10' in 6 ms.
Jan 07, 2019 22:21:42.499 [0x7f0698fff700] DEBUG - HubCache: Adding '1/photo.random.dayormonth.6/1/hubs/sections/6//count=10' to the cache (1/photo.random.dayormonth.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.501 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.501 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50212 (Allowed Network)] GET /hubs/sections/4 (14 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.502 [0x7f06973ff700] DEBUG - HubCache: Adding '1/video.recent.4/1/hubs/sections/4//count=10' to the cache (1/video.recent.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.504 [0x7f0698fff700] DEBUG - HubCache: Adding '1/video.inprogress.6/1/hubs/sections/6//count=10' to the cache (1/video.inprogress.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.508 [0x7f0698fff700] DEBUG - HubCache: Computed '1/video.inprogress.6/1/hubs/sections/6//count=10' in 3 ms.
Jan 07, 2019 22:21:42.508 [0x7f0698fff700] DEBUG - HubCache: Adding '1/video.unwatched.random.6/1/hubs/sections/6//count=10' to the cache (1/video.unwatched.random.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.511 [0x7f06973ff700] DEBUG - HubCache: Computed '1/video.recent.4/1/hubs/sections/4//count=10' in 8 ms.
Jan 07, 2019 22:21:42.511 [0x7f06973ff700] DEBUG - HubCache: Adding '1/photo.random.year.4/1/hubs/sections/4//count=10' to the cache (1/photo.random.year.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.512 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.512 [0x7f06947ff700] DEBUG - Request: [192.168.2.37:50218 (Allowed Network)] GET /playlists/all?playlistType=video (14 live) Page 0-9 GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.513 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.recentlyaired.3/1/hubs/sections/3//count=10' in 29 ms.
Jan 07, 2019 22:21:42.514 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.recentlyadded.3/1/hubs/sections/3//count=10' to the cache (1/tv.recentlyadded.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:42.515 [0x7f06947ff700] DEBUG - Setting container serialization range to [0, 9] (total=-1)
Jan 07, 2019 22:21:42.515 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50218] 200 GET /playlists/all?playlistType=video (14 live) GZIP Page 0-9 3ms 474 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.516 [0x7f0698fff700] DEBUG - HubCache: Computed '1/video.unwatched.random.6/1/hubs/sections/6//count=10' in 8 ms.
Jan 07, 2019 22:21:42.516 [0x7f0698fff700] DEBUG - HubCache: Adding '1/video.recentlyviewed.6/1/hubs/sections/6//count=10' to the cache (1/video.recentlyviewed.6/1/hubs/sections/6//count=10).
Jan 07, 2019 22:21:42.518 [0x7f06973ff700] DEBUG - HubCache: Computed '1/photo.random.year.4/1/hubs/sections/4//count=10' in 6 ms.
Jan 07, 2019 22:21:42.518 [0x7f06973ff700] DEBUG - HubCache: Adding '1/photo.random.decade.4/1/hubs/sections/4//count=10' to the cache (1/photo.random.decade.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.520 [0x7f0698fff700] DEBUG - HubCache: Computed '1/video.recentlyviewed.6/1/hubs/sections/6//count=10' in 3 ms.
Jan 07, 2019 22:21:42.529 [0x7f06973ff700] DEBUG - HubCache: Computed '1/photo.random.decade.4/1/hubs/sections/4//count=10' in 11 ms.
Jan 07, 2019 22:21:42.529 [0x7f06973ff700] DEBUG - HubCache: Adding '1/photo.random.dayormonth.4/1/hubs/sections/4//count=10' to the cache (1/photo.random.dayormonth.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.535 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50216] 200 GET /hubs/sections/6 (14 live) GZIP 59ms 3879 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.535 [0x7f06973ff700] DEBUG - HubCache: Adding '1/video.inprogress.4/1/hubs/sections/4//count=10' to the cache (1/video.inprogress.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.537 [0x7f069e7fe700] DEBUG - It took 80.000000 ms to retrieve 200 items.
Jan 07, 2019 22:21:42.542 [0x7f06973ff700] DEBUG - HubCache: Computed '1/video.inprogress.4/1/hubs/sections/4//count=10' in 6 ms.
Jan 07, 2019 22:21:42.542 [0x7f06973ff700] DEBUG - HubCache: Adding '1/video.unwatched.random.4/1/hubs/sections/4//count=10' to the cache (1/video.unwatched.random.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.553 [0x7f06973ff700] DEBUG - HubCache: Computed '1/video.unwatched.random.4/1/hubs/sections/4//count=10' in 11 ms.
Jan 07, 2019 22:21:42.553 [0x7f06973ff700] DEBUG - HubCache: Adding '1/video.recentlyviewed.4/1/hubs/sections/4//count=10' to the cache (1/video.recentlyviewed.4/1/hubs/sections/4//count=10).
Jan 07, 2019 22:21:42.557 [0x7f069e7fe700] DEBUG - It took 40.000000 ms to retrieve 405 items.
Jan 07, 2019 22:21:42.565 [0x7f06973ff700] DEBUG - HubCache: Computed '1/video.recentlyviewed.4/1/hubs/sections/4//count=10' in 12 ms.
Jan 07, 2019 22:21:42.578 [0x7f069e7fe700] DEBUG - It took 30.000000 ms to retrieve 320 items.
Jan 07, 2019 22:21:42.582 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50212] 200 GET /hubs/sections/4 (14 live) GZIP 81ms 5064 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.635 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.recentlyadded.3/1/hubs/sections/3//count=10' in 121 ms.
Jan 07, 2019 22:21:42.635 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.inprogress.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.inprogress.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.646 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.inprogress.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 11 ms.
Jan 07, 2019 22:21:42.646 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.startwatching.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.startwatching.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.654 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.startwatching.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 7 ms.
Jan 07, 2019 22:21:42.655 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.rediscover.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.rediscover.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.664 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.rediscover.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 9 ms.
Jan 07, 2019 22:21:42.665 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.morefromnetwork.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.morefromnetwork.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.672 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.morefromnetwork.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 7 ms.
Jan 07, 2019 22:21:42.672 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.toprated.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.toprated.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.680 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.toprated.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 7 ms.
Jan 07, 2019 22:21:42.680 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.moreingenre.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.moreingenre.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.689 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.moreingenre.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 8 ms.
Jan 07, 2019 22:21:42.689 [0x7f069e7fe700] DEBUG - HubCache: Adding '1/tv.recentlyviewed.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/tv.recentlyviewed.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10).
Jan 07, 2019 22:21:42.705 [0x7f069e7fe700] DEBUG - HubCache: Computed '1/tv.recentlyviewed.3/1/hubs/sections/3//count=10&limit=600&sort=addedAt%3Adesc&type=4%2C10' in 15 ms.
Jan 07, 2019 22:21:42.711 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for M*A*S*H.
Jan 07, 2019 22:21:42.715 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for Corner Gas.
Jan 07, 2019 22:21:42.720 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for The Muppet Show.
Jan 07, 2019 22:21:42.724 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for Family Guy.
Jan 07, 2019 22:21:42.727 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for Little House on the Prairie.
Jan 07, 2019 22:21:42.737 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for Disney Animated Shorts.
Jan 07, 2019 22:21:42.741 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for Looney Tunes.
Jan 07, 2019 22:21:42.747 [0x7f069e7fe700] DEBUG - There were 1 top-level paths for WKRP in Cincinnati.
Jan 07, 2019 22:21:42.764 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50214] 200 GET /hubs/sections/3 (14 live) GZIP 281ms 24929 bytes (pipelined: 1)
Jan 07, 2019 22:21:42.815 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.816 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50204 (Allowed Network)] GET /hubs (14 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.816 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/home.continue/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.816 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.816 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/home.playlists/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.816 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/home.movies.recent/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.817 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/home.television.recent/1/hubs//count=10' from the cache.
Jan 07, 2019 22:21:42.817 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs//count=10' to the cache (1/home.videos.recent/1/hubs//count=10).
Jan 07, 2019 22:21:42.826 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs//count=10' in 9 ms.
Jan 07, 2019 22:21:42.826 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs//count=10' to the cache (1/home.photos.recent/1/hubs//count=10).
Jan 07, 2019 22:21:42.827 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs//count=10' in 1 ms.
Jan 07, 2019 22:21:42.827 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs//count=10' to the cache (1/home.music.recent/1/hubs//count=10).
Jan 07, 2019 22:21:42.828 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs//count=10' in 1 ms.
Jan 07, 2019 22:21:42.831 [0x7f0696bfe700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:21:42.831 [0x7f0696bfe700] DEBUG - Selecting best audio stream for part ID 26641 (autoselect: 1 language: en)
Jan 07, 2019 22:21:42.831 [0x7f0696bfe700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:42.831 [0x7f0696bfe700] DEBUG - Audio Stream: 39745, Subtitle Stream: -1
Jan 07, 2019 22:21:42.832 [0x7f0696bfe700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:21:42.833 [0x7f0696bfe700] DEBUG - Selecting best audio stream for part ID 26537 (autoselect: 1 language: en)
Jan 07, 2019 22:21:42.833 [0x7f0696bfe700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:42.833 [0x7f0696bfe700] DEBUG - Audio Stream: 39522, Subtitle Stream: -1
Jan 07, 2019 22:21:42.839 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for M*A*S*H.
Jan 07, 2019 22:21:42.842 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Corner Gas.
Jan 07, 2019 22:21:42.846 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for The Muppet Show.
Jan 07, 2019 22:21:42.850 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Family Guy.
Jan 07, 2019 22:21:42.852 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Little House on the Prairie.
Jan 07, 2019 22:21:42.859 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Disney Animated Shorts.
Jan 07, 2019 22:21:42.863 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Looney Tunes.
Jan 07, 2019 22:21:42.866 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for WKRP in Cincinnati.
Jan 07, 2019 22:21:42.877 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50204] 200 GET /hubs (14 live) GZIP 61ms 20233 bytes (pipelined: 4)
Jan 07, 2019 22:21:42.907 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:42.907 [0x7f06947ff700] DEBUG - Request: [192.168.2.37:50222 (Allowed Network)] GET /playlists/all?playlistType=audio (15 live) Page 0-9 GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:42.910 [0x7f06947ff700] DEBUG - Setting container serialization range to [0, 9] (total=-1)
Jan 07, 2019 22:21:42.910 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50222] 200 GET /playlists/all?playlistType=audio (15 live) GZIP Page 0-9 3ms 474 bytes (pipelined: 1)
Jan 07, 2019 22:21:43.085 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.085 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50222 (Allowed Network)] GET /hubs/sections/5 (15 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.086 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.recentlyreleased.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.086 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.recentlyadded.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.087 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.inprogress.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.087 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.genre.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.087 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.by.actor.or.director.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.087 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.topunwatched.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.087 [0x7f0698fff700] DEBUG - HubCache: Retrieving '1/movie.recentlyviewed.5/1/hubs/sections/5//count=10' from the cache.
Jan 07, 2019 22:21:43.090 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50222] 200 GET /hubs/sections/5 (15 live) GZIP 5ms 2537 bytes (pipelined: 2)
Jan 07, 2019 22:21:43.109 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.110 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50204 (Allowed Network)] GET /hubs/sections/4 (16 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.110 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.110 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50224 (Allowed Network)] GET /playlists/all?playlistType=video (16 live) Page 0-9 GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/video.recent.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/photo.random.year.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/photo.random.decade.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/photo.random.dayormonth.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/video.inprogress.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.111 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/video.unwatched.random.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.112 [0x7f06973ff700] DEBUG - HubCache: Retrieving '1/video.recentlyviewed.4/1/hubs/sections/4//count=10' from the cache.
Jan 07, 2019 22:21:43.113 [0x7f0696bfe700] DEBUG - Setting container serialization range to [0, 9] (total=-1)
Jan 07, 2019 22:21:43.113 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50224] 200 GET /playlists/all?playlistType=video (17 live) GZIP Page 0-9 3ms 474 bytes (pipelined: 1)
Jan 07, 2019 22:21:43.124 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50204] 200 GET /hubs/sections/4 (17 live) GZIP 14ms 5064 bytes (pipelined: 5)
Jan 07, 2019 22:21:43.150 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.150 [0x7f069e7fe700] DEBUG - Request: [192.168.2.37:50226 (Allowed Network)] GET /hubs/sections/6 (18 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.151 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/video.recent.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.151 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/photo.random.year.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.151 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/photo.random.decade.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.152 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/photo.random.dayormonth.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.152 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/video.inprogress.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.152 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/video.unwatched.random.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.152 [0x7f069e7fe700] DEBUG - HubCache: Retrieving '1/video.recentlyviewed.6/1/hubs/sections/6//count=10' from the cache.
Jan 07, 2019 22:21:43.159 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.160 [0x7f06947ff700] DEBUG - Request: [192.168.2.37:50222 (Allowed Network)] GET /hubs/sections/2 (18 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.161 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.recentlyreleased.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.161 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.recentlyadded.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.161 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.inprogress.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.161 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.genre.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.161 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.by.actor.or.director.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.162 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50226] 200 GET /hubs/sections/6 (18 live) GZIP 11ms 3879 bytes (pipelined: 1)
Jan 07, 2019 22:21:43.162 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.topunwatched.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.162 [0x7f06947ff700] DEBUG - HubCache: Retrieving '1/movie.recentlyviewed.2/1/hubs/sections/2//count=10' from the cache.
Jan 07, 2019 22:21:43.173 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:43.173 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50228 (Allowed Network)] GET /hubs/sections/3 (18 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:43.174 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/tv.ondeck.3/1/hubs/sections/3//count=10' from the cache.
Jan 07, 2019 22:21:43.174 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/tv.recentlyaired.3/1/hubs/sections/3//count=10' from the cache.
Jan 07, 2019 22:21:43.174 [0x7f0696bfe700] DEBUG - HubCache: Retrieving '1/tv.recentlyadded.3/1/hubs/sections/3//count=10' from the cache.
Jan 07, 2019 22:21:43.175 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.inprogress.3/1/hubs/sections/3//count=10' to the cache (1/tv.inprogress.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.186 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50222] 200 GET /hubs/sections/2 (18 live) GZIP 26ms 22301 bytes (pipelined: 3)
Jan 07, 2019 22:21:43.190 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.inprogress.3/1/hubs/sections/3//count=10' in 15 ms.
Jan 07, 2019 22:21:43.190 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.startwatching.3/1/hubs/sections/3//count=10' to the cache (1/tv.startwatching.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.198 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.startwatching.3/1/hubs/sections/3//count=10' in 7 ms.
Jan 07, 2019 22:21:43.198 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.rediscover.3/1/hubs/sections/3//count=10' to the cache (1/tv.rediscover.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.208 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.rediscover.3/1/hubs/sections/3//count=10' in 9 ms.
Jan 07, 2019 22:21:43.208 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.morefromnetwork.3/1/hubs/sections/3//count=10' to the cache (1/tv.morefromnetwork.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.215 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.morefromnetwork.3/1/hubs/sections/3//count=10' in 7 ms.
Jan 07, 2019 22:21:43.215 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.toprated.3/1/hubs/sections/3//count=10' to the cache (1/tv.toprated.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.223 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.toprated.3/1/hubs/sections/3//count=10' in 7 ms.
Jan 07, 2019 22:21:43.223 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.moreingenre.3/1/hubs/sections/3//count=10' to the cache (1/tv.moreingenre.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.235 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.moreingenre.3/1/hubs/sections/3//count=10' in 11 ms.
Jan 07, 2019 22:21:43.235 [0x7f0696bfe700] DEBUG - HubCache: Adding '1/tv.recentlyviewed.3/1/hubs/sections/3//count=10' to the cache (1/tv.recentlyviewed.3/1/hubs/sections/3//count=10).
Jan 07, 2019 22:21:43.250 [0x7f0696bfe700] DEBUG - HubCache: Computed '1/tv.recentlyviewed.3/1/hubs/sections/3//count=10' in 14 ms.
Jan 07, 2019 22:21:43.257 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for M*A*S*H.
Jan 07, 2019 22:21:43.260 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Corner Gas.
Jan 07, 2019 22:21:43.264 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for The Muppet Show.
Jan 07, 2019 22:21:43.268 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Family Guy.
Jan 07, 2019 22:21:43.270 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Little House on the Prairie.
Jan 07, 2019 22:21:43.277 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Disney Animated Shorts.
Jan 07, 2019 22:21:43.281 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for Looney Tunes.
Jan 07, 2019 22:21:43.285 [0x7f0696bfe700] DEBUG - There were 1 top-level paths for WKRP in Cincinnati.
Jan 07, 2019 22:21:43.304 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50228] 200 GET /hubs/sections/3 (18 live) GZIP 130ms 25746 bytes (pipelined: 1)
Jan 07, 2019 22:21:50.043 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:50.043 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50228 (Allowed Network)] GET /library/metadata/10125 (18 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:50.067 [0x7f0698fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:50.067 [0x7f0698fff700] DEBUG - Audio Stream: 46472, Subtitle Stream: -1
Jan 07, 2019 22:21:50.113 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50228] 200 GET /library/metadata/10125 (18 live) GZIP 70ms 7435 bytes (pipelined: 2)
Jan 07, 2019 22:21:51.324 [0x7f0691fff700] DEBUG - BPQ: [Starting] -> [Processing]
Jan 07, 2019 22:21:51.327 [0x7f0691fff700] DEBUG - BPQ: generating queue items from 0 generator(s)
Jan 07, 2019 22:21:51.327 [0x7f0691fff700] DEBUG - BPQ: generated 0 item(s) for queue
Jan 07, 2019 22:21:51.328 [0x7f0691fff700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 07, 2019 22:21:51.328 [0x7f0691fff700] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 07, 2019 22:21:51.330 [0x7f0691fff700] DEBUG - BPQ: [Processing] -> [Idle]
Jan 07, 2019 22:21:54.541 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45220 (Loopback)] GET /servers (19 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.543 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45220] 200 GET /servers (19 live) GZIP 1ms 505 bytes
Jan 07, 2019 22:21:54.548 [0x7f0698fff700] DEBUG - Request: [127.0.0.1:45222 (Loopback)] GET /library/sections (19 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.552 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45222] 200 GET /library/sections (19 live) GZIP 4ms 1067 bytes
Jan 07, 2019 22:21:54.559 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45224 (Loopback)] GET /channels/all (20 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.560 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45224] 200 GET /channels/all (19 live) GZIP 1ms 670 bytes
Jan 07, 2019 22:21:54.567 [0x7f0698fff700] DEBUG - Request: [127.0.0.1:45226 (Loopback)] GET /library/sections (19 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.572 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45226] 200 GET /library/sections (19 live) GZIP 4ms 1067 bytes
Jan 07, 2019 22:21:54.576 [0x7f06973ff700] DEBUG - Request: [127.0.0.1:45228 (Loopback)] GET /channels/all (19 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.577 [0x7f06a7bff700] DEBUG - Completed: [127.0.0.1:45228] 200 GET /channels/all (19 live) GZIP 1ms 670 bytes
Jan 07, 2019 22:21:54.645 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:54.646 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50242 (Allowed Network)] GET /video/:/transcode/universal/decision (19 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.646 [0x7f0698fff700] DEBUG - Found session GUID of b4c9c6e4-1210-4a05-8a53-a6cfc19f4398 in session start.
Jan 07, 2019 22:21:54.647 [0x7f0698fff700] ERROR - ClientProfileExtra: missing protocol parameter
Jan 07, 2019 22:21:54.647 [0x7f0698fff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=ac3)
Jan 07, 2019 22:21:54.647 [0x7f0698fff700] ERROR - ClientProfileExtra: missing protocol parameter
Jan 07, 2019 22:21:54.647 [0x7f0698fff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=eac3)
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] ERROR - ClientProfileExtra: missing protocol parameter
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-audio-codec(type=videoProfile&context=streaming&audioCodec=dca)
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] ERROR - ClientProfileExtra: missing protocol parameter
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] WARN - ClientProfileExtra: ignoring directive: append-transcode-target-codec(type=videoProfile&context=streaming&videoCodec=vp9)
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] WARN - TranscodeUniversalRequest: at least one profile extra directive could not be read
Jan 07, 2019 22:21:54.648 [0x7f0698fff700] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jan 07, 2019 22:21:54.649 [0x7f0698fff700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/10125?includeBandwidths=1&offset=1764&X-Plex-Session-Identifier=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398
Jan 07, 2019 22:21:54.649 [0x7f0698fff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:54.662 [0x7f0698fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:54.662 [0x7f0698fff700] DEBUG - Audio Stream: 46472, Subtitle Stream: -1
Jan 07, 2019 22:21:54.666 [0x7f0698fff700] DEBUG - Streaming Resource: Added session 0x7f069750c7a0:b4c9c6e4-1210-4a05-8a53-a6cfc19f4398
Jan 07, 2019 22:21:54.666 [0x7f0698fff700] DEBUG - Streaming Resource: Reached Decision id=10125 codes=(MDE=1000,Direct play OK.) media=(id=22585 part=(id=29889 decision=direct play protocol=http streams=(Video=(id=46471 decision= width=0 height=0) Audio=(id=46472 decision= channels=0 rate=0))))
Jan 07, 2019 22:21:54.667 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50242] 200 GET /video/:/transcode/universal/decision (19 live) GZIP 21ms 2926 bytes (pipelined: 1)
Jan 07, 2019 22:21:54.917 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:54.918 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50244 (Allowed Network)] HEAD /library/parts/29889/1546832912/file.mkv (20 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.924 [0x7f06973ff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:54.924 [0x7f06973ff700] DEBUG - Completed: [192.168.2.37:50244] 200 HEAD /library/parts/29889/1546832912/file.mkv (20 live) 6ms 0 bytes (pipelined: 1)
Jan 07, 2019 22:21:54.976 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:54.977 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50244 (Allowed Network)] HEAD /library/parts/29889/1546832912/file.mkv (20 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.981 [0x7f06973ff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:54.982 [0x7f06973ff700] DEBUG - Completed: [192.168.2.37:50244] 200 HEAD /library/parts/29889/1546832912/file.mkv (20 live) 5ms 0 bytes (pipelined: 2)
Jan 07, 2019 22:21:54.987 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:54.987 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50246 (Allowed Network)] GET /library/parts/29889/1546832912/file.mkv (21 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:54.991 [0x7f06973ff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:55.271 [0x7f06a7bff700] DEBUG - Failed to stream media, client probably disconnected after 86880 bytes: 104 - Connection reset by peer
Jan 07, 2019 22:21:55.271 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50246] 200 GET /library/parts/29889/1546832912/file.mkv (21 live) 284ms 86880 bytes (pipelined: 1) (range: bytes=0-) 
Jan 07, 2019 22:21:55.273 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.273 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50248 (Allowed Network)] GET /library/parts/29889/1546832912/file.mkv (22 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.278 [0x7f0698fff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:55.285 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.286 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50250 (Allowed Network)] GET /library/parts/29889/1546832912/file.mkv (22 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.290 [0x7f06973ff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:55.321 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50250] 200 GET /library/parts/29889/1546832912/file.mkv (22 live) 35ms 65536 bytes (pipelined: 1) (range: bytes=19857156516-) 
Jan 07, 2019 22:21:55.495 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.496 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50252 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1764000&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (23 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.496 [0x7f0698fff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state playing, progress of 1764000/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:21:55.499 [0x7f0698fff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1764000 ms by account 1!
Jan 07, 2019 22:21:55.533 [0x7f0698fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:21:55.533 [0x7f0698fff700] DEBUG - Audio Stream: 46472, Subtitle Stream: -1
Jan 07, 2019 22:21:55.535 [0x7f0698fff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:21:55.535 [0x7f0698fff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:21:55.536 [0x7f0698fff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:21:55.536 [0x7f0698fff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/home.continue/1/hubs//count=10' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/home.movies.recent/1/hubs//count=10' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/movie.inprogress.2/1/hubs/sections/2//count=10' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/movie.recentlyadded.2/1/hubs/sections/2//count=10' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/movie.recentlyviewed.2/1/hubs/sections/2//count=10' because of event plex.event.play.session.state.update.
Jan 07, 2019 22:21:55.537 [0x7f0698fff700] DEBUG - HubCache: Expiring '1/movie.topunwatched.2/1/hubs/sections/2//count=10' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 07, 2019 22:21:55.540 [0x7f0698fff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:21:55.548 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50252] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1764000&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (23 live) GZIP 52ms 492 bytes (pipelined: 1)
Jan 07, 2019 22:21:55.552 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.552 [0x7f0698fff700] DEBUG - Request: [192.168.2.199:52778 (Allowed Network)] GET /status/sessions (24 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.553 [0x7f0698fff700] DEBUG - [Now] Adding 1 sessions.
Jan 07, 2019 22:21:55.554 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.199:52778] 200 GET /status/sessions (24 live) GZIP 2ms 3160 bytes (pipelined: 1)
Jan 07, 2019 22:21:55.646 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.646 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50250 (Allowed Network)] GET /library/parts/29889/1546832912/file.mkv (24 live) Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.651 [0x7f0698fff700] DEBUG - Content-Length of /mnt/nfs/media/Videos/Movies/Dr. No (1962).mkv is 19857222052.
Jan 07, 2019 22:21:55.708 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:55.708 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50254 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=paused&key=/library/metadata/10125&time=1764000&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (24 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:55.709 [0x7f0698fff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state paused, progress of 1764000/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:21:55.712 [0x7f0698fff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1764000 ms by account 1!
Jan 07, 2019 22:21:55.732 [0x7f0698fff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:21:55.732 [0x7f0698fff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:21:55.732 [0x7f0698fff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:21:55.732 [0x7f0698fff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:21:55.733 [0x7f0698fff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 1 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:21:55.735 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50254] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=paused&key=/library/metadata/10125&time=1764000&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (24 live) GZIP 27ms 492 bytes (pipelined: 1)
Jan 07, 2019 22:21:57.092 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:21:57.092 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50260 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1764379&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (25 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:21:57.093 [0x7f06973ff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state playing, progress of 1764379/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:21:57.095 [0x7f06973ff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1764379 ms by account 1!
Jan 07, 2019 22:21:57.113 [0x7f06973ff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:21:57.113 [0x7f06973ff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:21:57.113 [0x7f06973ff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:21:57.113 [0x7f06973ff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:21:57.114 [0x7f06973ff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:21:57.116 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50260] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1764379&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (24 live) GZIP 23ms 493 bytes (pipelined: 1)
Jan 07, 2019 22:22:12.579 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:12.579 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50268 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1770892&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (10 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:12.580 [0x7f06973ff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state playing, progress of 1770892/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:22:12.584 [0x7f06973ff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1770892 ms by account 1!
Jan 07, 2019 22:22:12.584 [0x7f06973ff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:22:12.584 [0x7f06973ff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:22:12.584 [0x7f06973ff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:22:12.585 [0x7f06973ff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:22:12.586 [0x7f06973ff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:22:12.588 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50268] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1770892&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (10 live) GZIP 9ms 493 bytes (pipelined: 1)
Jan 07, 2019 22:22:21.541 [0x7f06a57ff700] DEBUG - Statistics: Flushing 17 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:22:27.857 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:27.857 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50270 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1774375&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:27.858 [0x7f06973ff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state playing, progress of 1774375/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:22:27.860 [0x7f06973ff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1774375 ms by account 1!
Jan 07, 2019 22:22:27.860 [0x7f06973ff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:22:27.861 [0x7f06973ff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:22:27.861 [0x7f06973ff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:22:27.861 [0x7f06973ff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:22:27.863 [0x7f06973ff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:22:27.865 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50270] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=playing&key=/library/metadata/10125&time=1774375&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (7 live) GZIP 8ms 493 bytes (pipelined: 1)
Jan 07, 2019 22:22:37.358 [0x7f06a7bff700] DEBUG - Failed to stream media, client probably disconnected after 83713976 bytes: 104 - Connection reset by peer
Jan 07, 2019 22:22:37.359 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50250] 200 GET /library/parts/29889/1546832912/file.mkv (7 live) 41712ms 83713976 bytes (pipelined: 2) (range: bytes=5205618416-) 
Jan 07, 2019 22:22:37.359 [0x7f06a7bff700] DEBUG - Failed to stream media, client probably disconnected after 1647880 bytes: 104 - Connection reset by peer
Jan 07, 2019 22:22:37.359 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50248] 200 GET /library/parts/29889/1546832912/file.mkv (6 live) 42086ms 1647880 bytes (pipelined: 1) (range: bytes=0-) 
Jan 07, 2019 22:22:37.470 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:37.470 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50272 (Allowed Network)] GET /hubs/home/onDeck (5 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:37.477 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:37.477 [0x7f0691fff700] DEBUG - Request: [192.168.2.37:50274 (Allowed Network)] GET /hubs/home/continueWatching (6 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:37.517 [0x7f0698fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:22:37.517 [0x7f0698fff700] DEBUG - Selecting best audio stream for part ID 26641 (autoselect: 1 language: en)
Jan 07, 2019 22:22:37.517 [0x7f0698fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:22:37.517 [0x7f0698fff700] DEBUG - Audio Stream: 39745, Subtitle Stream: -1
Jan 07, 2019 22:22:37.518 [0x7f0698fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:22:37.518 [0x7f0698fff700] DEBUG - Selecting best audio stream for part ID 26537 (autoselect: 1 language: en)
Jan 07, 2019 22:22:37.518 [0x7f0698fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:22:37.518 [0x7f0698fff700] DEBUG - Audio Stream: 39522, Subtitle Stream: -1
Jan 07, 2019 22:22:37.520 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50272] 200 GET /hubs/home/onDeck (6 live) GZIP 50ms 5410 bytes (pipelined: 1)
Jan 07, 2019 22:22:37.525 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50274] 200 GET /hubs/home/continueWatching (6 live) GZIP 48ms 4466 bytes (pipelined: 1)
Jan 07, 2019 22:22:38.110 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:38.110 [0x7f06973ff700] DEBUG - Request: [192.168.2.37:50276 (Allowed Network)] GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=stopped&key=/library/metadata/10125&time=1780024&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (7 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:38.111 [0x7f06973ff700] DEBUG - Client [b4c9c6e4-1210-4a05-8a53-a6cfc19f4398] reporting timeline state stopped, progress of 1780024/6590464ms for guid=com.plexapp.agents.imdb://tt0055928?lang=en, ratingKey=10125 url=, key=/library/metadata/10125, containerKey=/video/:/transcode/universal/decision?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&copyts=1&path=%2Flibrary%2Fmetadata%2F10125&offset=1764&session=b4c9c6e4-1210-4a05-8a53-a6cfc19f4398&directStream=1&directPlay=1&videoQuality=100&videoResolution=1920x1080&maxVideoBitrate=200000&mediaIndex=0&partIndex=0&X-Plex-Client-Profile-Extra=append-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Dac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Dac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Deac3%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Deac3%29%2Bappend-transcode-target-audio-codec%28type%3DvideoProfile%26context%3Dstreaming%26audioCodec%3Ddca%29%2Badd-direct-play-profile%28type%3DvideoProfile%26container%3Dmatroska%26videoCodec%3D%2A%26audioCodec%3Ddca%29%2Badd-limitation%28scope%3DvideoAudioCodec%26scopeName%3Ddca%26type%3DupperBound%26name%3Daudio.channels%26value%3D6%26isRequired%3Dfalse%29%2Bappend-transcode-target-codec%28type%3DvideoProfile%26context%3Dstreaming%26videoCodec%3Dvp9%29&location=lan&subtitles=sidecar&mediaBufferSize=20971&hasMDE=1&X-Plex-Platform=Chrome, metadataId=10125, source=
Jan 07, 2019 22:22:38.114 [0x7f06973ff700] DEBUG - Play progress on 10125 'Dr. No' - got played 1780024 ms by account 1!
Jan 07, 2019 22:22:38.134 [0x7f06973ff700] DEBUG - [Now] User is crazyjpeters (ID: 1)
Jan 07, 2019 22:22:38.134 [0x7f06973ff700] DEBUG - [Now] Device is Kodi (Kodi Add-on (raspberrypi)).
Jan 07, 2019 22:22:38.135 [0x7f06973ff700] ERROR - Unable to find client profile for device; platform=Kodi, platformVersion=17.6 Git:20171115-d69a54a, device=RaspberryPi, model=Unknown
Jan 07, 2019 22:22:38.135 [0x7f06973ff700] DEBUG - [Now] Updated play state for /library/metadata/10125.
Jan 07, 2019 22:22:38.136 [0x7f06973ff700] DEBUG - Statistics: (b4c9c6e4-1210-4a05-8a53-a6cfc19f4398) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Jan 07, 2019 22:22:38.137 [0x7f06973ff700] DEBUG - Streaming Resource: Terminated session 0x7f069750c7a0:b4c9c6e4-1210-4a05-8a53-a6cfc19f4398 with reason Client stopped playback.
Jan 07, 2019 22:22:38.137 [0x7f06973ff700] DEBUG - Streaming Resource: Removing session 0x7f069750c7a0:b4c9c6e4-1210-4a05-8a53-a6cfc19f4398
Jan 07, 2019 22:22:38.138 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50276] 200 GET /:/timeline?containerKey=/video/:/transcode/universal/decision%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx%26copyts%3D1%26path%3D%252Flibrary%252Fmetadata%252F10125%26offset%3D1764%26session%3Db4c9c6e4-1210-4a05-8a53-a6cfc19f4398%26directStream%3D1%26directPlay%3D1%26videoQuality%3D100%26videoResolution%3D1920x1080%26maxVideoBitrate%3D200000%26mediaIndex%3D0%26partIndex%3D0%26X-Plex-Client-Profile-Extra%3Dappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Dac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Dac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Deac3%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Deac3%2529%252Bappend-transcode-target-audio-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526audioCodec%253Ddca%2529%252Badd-direct-play-profile%2528type%253DvideoProfile%2526container%253Dmatroska%2526videoCodec%253D%252A%2526audioCodec%253Ddca%2529%252Badd-limitation%2528scope%253DvideoAudioCodec%2526scopeName%253Ddca%2526type%253DupperBound%2526name%253Daudio.channels%2526value%253D6%2526isRequired%253Dfalse%2529%252Bappend-transcode-target-codec%2528type%253DvideoProfile%2526context%253Dstreaming%2526videoCodec%253Dvp9%2529%26location%3Dlan%26subtitles%3Dsidecar%26mediaBufferSize%3D20971%26hasMDE%3D1%26X-Plex-Platform%3DChrome&ratingKey=10125&state=stopped&key=/library/metadata/10125&time=1780024&duration=6590464&guid=com.plexapp.agents.imdb%3A//tt0055928%3Flang%3Den (7 live) GZIP 28ms 493 bytes (pipelined: 1)
Jan 07, 2019 22:22:38.620 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:38.620 [0x7f0691fff700] DEBUG - Request: [192.168.2.37:50278 (Allowed Network)] GET /hubs/home/onDeck (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:38.634 [0x7f06a73fe700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:38.634 [0x7f0698fff700] DEBUG - Request: [192.168.2.37:50280 (Allowed Network)] GET /hubs/home/continueWatching (8 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:38.636 [0x7f06a7bff700] DEBUG - Auth: authenticated user 1 as crazyjpeters
Jan 07, 2019 22:22:38.636 [0x7f0696bfe700] DEBUG - Request: [192.168.2.37:50282 (Allowed Network)] GET /library/metadata/10125 (9 live) GZIP Signed-in Token (crazyjpeters)
Jan 07, 2019 22:22:38.654 [0x7f0696bfe700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:22:38.654 [0x7f0696bfe700] DEBUG - Audio Stream: 46472, Subtitle Stream: -1
Jan 07, 2019 22:22:38.656 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50282] 200 GET /library/metadata/10125 (9 live) GZIP 19ms 2950 bytes (pipelined: 1)
Jan 07, 2019 22:22:38.669 [0x7f0691fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:22:38.669 [0x7f0691fff700] DEBUG - Selecting best audio stream for part ID 26641 (autoselect: 1 language: en)
Jan 07, 2019 22:22:38.669 [0x7f0691fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:22:38.669 [0x7f0691fff700] DEBUG - Audio Stream: 39745, Subtitle Stream: -1
Jan 07, 2019 22:22:38.670 [0x7f0691fff700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 07, 2019 22:22:38.670 [0x7f0691fff700] DEBUG - Selecting best audio stream for part ID 26537 (autoselect: 1 language: en)
Jan 07, 2019 22:22:38.670 [0x7f0691fff700] DEBUG - We're going to try to auto-select a subtitle.
Jan 07, 2019 22:22:38.670 [0x7f0691fff700] DEBUG - Audio Stream: 39522, Subtitle Stream: -1
Jan 07, 2019 22:22:38.672 [0x7f06a73fe700] DEBUG - Completed: [192.168.2.37:50278] 200 GET /hubs/home/onDeck (9 live) GZIP 52ms 5410 bytes (pipelined: 1)
Jan 07, 2019 22:22:38.687 [0x7f06a7bff700] DEBUG - Completed: [192.168.2.37:50280] 200 GET /hubs/home/continueWatching (9 live) GZIP 52ms 4465 bytes (pipelined: 1)
Jan 07, 2019 22:22:51.548 [0x7f068d3fe700] DEBUG - Statistics: Flushing 10 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:23:21.554 [0x7f06987fe700] DEBUG - Statistics: Flushing 6 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:23:51.559 [0x7f06a6bfd700] DEBUG - Statistics: Flushing 18 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:24:21.564 [0x7f06a8bff700] DEBUG - Statistics: Flushing 25 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:24:51.568 [0x7f068bfff700] DEBUG - Statistics: Flushing 25 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:25:21.573 [0x7f06a37ff700] DEBUG - Statistics: Flushing 7 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:25:51.578 [0x7f06a8bff700] DEBUG - Statistics: Flushing 7 expired bandwidth entries, 0 expired media entries.
Jan 07, 2019 22:25:57.572 [0x7f06bc55b840] DEBUG - Shutting down with signal 15
Jan 07, 2019 22:25:57.572 [0x7f06bc55b840] DEBUG - Ordered to stop server.
Jan 07, 2019 22:25:57.572 [0x7f06bc55b840] WARN - Timed out waiting for server to finish.
Jan 07, 2019 22:25:57.572 [0x7f06bc55b840] DEBUG - Stopping server...
Jan 07, 2019 22:25:57.572 [0x7f06bc55b840] DEBUG - HttpServer: Stopping server.
Jan 07, 2019 22:25:57.573 [0x7f069a7ff700] DEBUG - MetadataItemClusterRequestHandler: a shutdown event has been received
Jan 07, 2019 22:25:57.573 [0x7f069a7ff700] DEBUG - TranscodeJobManager: [Running] onServerShutdown
Jan 07, 2019 22:25:57.573 [0x7f069a7ff700] DEBUG - TranscodeJobManager: [Running] -> [Shutdown]
Jan 07, 2019 22:25:57.574 [0x7f06bc55b840] DEBUG - Server stopped.
Jan 07, 2019 22:25:57.574 [0x7f06bc55b840] DEBUG - NetworkService: Stopping advertisement.
Jan 07, 2019 22:25:57.574 [0x7f06bc55b840] DEBUG - Killing plug-ins.
Jan 07, 2019 22:25:57.574 [0x7f06bc55b840] DEBUG - [com.plexapp.agents.imdb] Killing.
Jan 07, 2019 22:25:57.575 [0x7f06bc55b840] DEBUG - [com.plexapp.system] Killing.
Jan 07, 2019 22:25:57.575 [0x7f06bc55b840] DEBUG - [com.plexapp.plugins.WebTools] Killing.
Jan 07, 2019 22:25:57.575 [0x7f06bc55b840] DEBUG - Waiting for Plex Tuner Service to exit.
Jan 07, 2019 22:25:57.581 [0x7f06a1ffe700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Tuner Service' exit code for process 21184 is 0 (success)
Jan 07, 2019 22:25:57.586 [0x7f069b7ff700] DEBUG - Plex Tuner Service exited.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Database: Shutting down.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 0.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 1.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 2.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 3.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 4.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 5.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 6.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 7.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 8.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 9.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 10.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 11.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 12.
Jan 07, 2019 22:25:57.620 [0x7f06bc55b840] DEBUG - Captured session 13.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 14.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 15.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 16.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 17.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 18.
Jan 07, 2019 22:25:57.621 [0x7f06bc55b840] DEBUG - Captured session 19.
Jan 07, 2019 22:25:57.640 [0x7f06bc55b840] DEBUG - Database: Shutting down.
Jan 07, 2019 22:25:57.640 [0x7f06bc55b840] DEBUG - Captured session 0.
Jan 07, 2019 22:25:57.640 [0x7f06bc55b840] DEBUG - Captured session 1.
