Jan 09, 2019 20:00:09.347 [23848] INFO - Plex Media Server v1.14.1.5488-cc260c476 - Microsoft PC x64 - build: windows-i386 english - GMT 02:00
Jan 09, 2019 20:00:09.347 [23848] INFO - Windows version: 10.0 (Build 17763), language en-US
Jan 09, 2019 20:00:09.347 [23848] INFO - 8 3592 MHz processor(s): Architecture=0, Level=6, Revision=15363 Processor Identifier=Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
Jan 09, 2019 20:00:09.347 [23848] DEBUG - "D:\Program files\Plex\Plex Media Server\Plex Media Server.exe" 
Jan 09, 2019 20:00:09.347 [14608] DEBUG - Plex for Windows Store application is not installed
Jan 09, 2019 20:00:09.348 [14608] DEBUG - BPQ: [Idle] -> [Starting]
Jan 09, 2019 20:00:09.351 [14608] DEBUG - FeatureManager: Using cached data for features list
Jan 09, 2019 20:00:09.352 [14608] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 09, 2019 20:00:09.406 [14608] DEBUG - Running migrations. (EPG 0)
Jan 09, 2019 20:00:09.409 [14608] DEBUG - ChangestampAllocator: initialized to 200915
Jan 09, 2019 20:00:09.409 [14608] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.13.0, threadsafe=1
Jan 09, 2019 20:00:09.416 [14608] DEBUG - Running migrations. (EPG 0)
Jan 09, 2019 20:00:10.209 [14608] DEBUG - CERT: Installed certificate with fingerprint e8:ca:28:85:68:61:23:71:c9:fc:81:ac:17:5a:c9:df:22:59:c1:93.
Jan 09, 2019 20:00:10.209 [14608] DEBUG - CERT: Installed new private key.
Jan 09, 2019 20:00:10.209 [14608] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.ec29a578914146b9ab131214a938675f.plex.direct
Jan 09, 2019 20:00:10.209 [14608] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Jan 09, 2019 20:00:10.210 [14608] INFO - OCSP: Successfully retrieved response from cache.
Jan 09, 2019 20:00:10.210 [14608] DEBUG - CERT: Installed intermediate certificate.
Jan 09, 2019 20:00:10.211 [14608] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Jan 09, 2019 20:00:10.211 [14608] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Jan 09, 2019 20:00:10.298 [22184] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Jan 09, 2019 20:00:10.298 [14608] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Jan 09, 2019 20:00:10.299 [14608] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 09, 2019 20:00:10.299 [22184] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Jan 09, 2019 20:00:10.299 [11312] DEBUG - Relay: read 8 cached entries from hosts file
Jan 09, 2019 20:00:10.300 [11312] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:10.300 [14608] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 09, 2019 20:00:10.300 [14608] DEBUG - MyPlex: updating with 5 access tokens
Jan 09, 2019 20:00:10.301 [17452] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 09, 2019 20:00:10.301 [17452] DEBUG - HTTP requesting GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 09, 2019 20:00:10.301 [12844] DEBUG - File "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476" changed: -1 => 2019-01-09 19:41:06 (1547055666).
Jan 09, 2019 20:00:10.302 [12844] DEBUG - File "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2018-12-16 02:55:47 (1544921747).
Jan 09, 2019 20:00:10.302 [12844] DEBUG - Scanning for plug-ins in "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476"
Jan 09, 2019 20:00:10.307 [12844] DEBUG - Scanning for plug-ins in "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins"
Jan 09, 2019 20:00:10.309 [12844] DEBUG - PluginRepository::setStartState: 1, startingSystem
Jan 09, 2019 20:00:10.309 [12844] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\System.bundle.
Jan 09, 2019 20:00:10.309 [12844] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Jan 09, 2019 20:00:10.311 [12844] DEBUG - Starting file watcher for com.plexapp.system
Jan 09, 2019 20:00:10.312 [12844] DEBUG - Plugin::createProcess: Creating plugin process: "D:\Program files\Plex\Plex Media Server\PlexScriptHost.exe" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\System.bundle"
Jan 09, 2019 20:00:10.317 [12844] DEBUG - Spawned plug-in com.plexapp.system with PID 22532
Jan 09, 2019 20:00:10.317 [12844] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Jan 09, 2019 20:00:10.841 [11312] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:10.941 [17452] DEBUG - HTTP 200 response from GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 09, 2019 20:00:10.946 [17452] DEBUG - MyPlex: updating with 5 access tokens
Jan 09, 2019 20:00:10.947 [22184] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:11.330 [22184] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:11.331 [22184] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:11.331 [27124] DEBUG - HTTP requesting GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.019 [27124] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.172 [22184] DEBUG - HTTP 200 response from GET https://music.provider.plex.tv?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.173 [22184] DEBUG - Job running: D:\Program files\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\Administrator\AppData\Local\Plex Media Server\Crash Reports\1.14.1.5488-cc260c476" --version=1.14.1.5488-cc260c476 "--platform=windows-i386 (10.0 (Build 17763))" --serverUuid=bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea --userId=dvir@gassner.co.il --url=https://crashreport.plexapp.com 
Jan 09, 2019 20:00:12.177 [22184] DEBUG - Jobs: Starting child process with pid 12384
Jan 09, 2019 20:00:12.220 [11312] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.292 [12844] DEBUG - Reading 486 bytes in the body, code is 200
Jan 09, 2019 20:00:12.292 [12844] DEBUG - [com.plexapp.system] Plug-in running on port 54840.
Jan 09, 2019 20:00:12.292 [12844] DEBUG -  * Plug-in handles prefix: /system
Jan 09, 2019 20:00:12.293 [12844] DEBUG -  * Plug-in handles prefix: /player
Jan 09, 2019 20:00:12.293 [12844] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Jan 09, 2019 20:00:12.297 [12844] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Jan 09, 2019 20:00:12.297 [12844] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Jan 09, 2019 20:00:12.297 [12844] DEBUG - Updating the list of agents known by the system.
Jan 09, 2019 20:00:12.298 [12844] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Jan 09, 2019 20:00:12.298 [14608] DEBUG - Image transcode cache directory: "C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Jan 09, 2019 20:00:12.298 [12844] DEBUG - HTTP requesting GET http://127.0.0.1:54840/system/agents
Jan 09, 2019 20:00:12.298 [14608] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 09, 2019 20:00:12.301 [14608] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 09, 2019 20:00:12.301 [14608] DEBUG - MyPlex: updating with 5 access tokens
Jan 09, 2019 20:00:12.302 [27124] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\CrashUploader.exe' exit code for process 12384 is 0 (success)
Jan 09, 2019 20:00:12.302 [14608] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 09, 2019 20:00:12.303 [14608] DEBUG - MyPlex: username is dvirgassner, login is dvir@gassner.co.il, home is 0, no pin
Jan 09, 2019 20:00:12.304 [14608] DEBUG - PublicAddressManager: Starting.
Jan 09, 2019 20:00:12.304 [14608] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 09, 2019 20:00:12.304 [17452] DEBUG - PublicAddressManager: Obtaining public IP.
Jan 09, 2019 20:00:12.304 [14608] DEBUG - NetworkInterface: Notified of network changed (force=0)
Jan 09, 2019 20:00:12.305 [5744] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 09, 2019 20:00:12.305 [17452] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Jan 09, 2019 20:00:12.317 [14608] DEBUG - NetworkInterface: ignoring adapter 'Ethernet' (fe80::1b6:65fd:c452:c37f%9,169.254.195.127) because it has no gateway
Jan 09, 2019 20:00:12.317 [14608] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 3' (fe80::5dd1:18af:1576:1db3%5,169.254.29.179) because it has no gateway
Jan 09, 2019 20:00:12.317 [14608] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Jan 09, 2019 20:00:12.317 [14608] DEBUG - Detected primary interface: 192.168.0.62
Jan 09, 2019 20:00:12.317 [14608] DEBUG - Network interfaces:
Jan 09, 2019 20:00:12.317 [14608] DEBUG -  * 2 Local Area Connection {03D1339B-4691-4434-A8CB-297F3805EEB0} (192.168.0.62) (loopback: 0)
Jan 09, 2019 20:00:12.318 [14608] DEBUG - Creating NetworkServices singleton.
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkServices: Initializing...
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.318 [24196] DEBUG - Network change for advertiser.
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.318 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.318 [24196] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.319 [24196] DEBUG - Network change for advertiser.
Jan 09, 2019 20:00:12.319 [22184] DEBUG - NAT: UPnP, attempting port mapping.
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.319 [24196] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Jan 09, 2019 20:00:12.319 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.320 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.320 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.320 [24196] DEBUG - Network change for advertiser.
Jan 09, 2019 20:00:12.320 [24196] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Jan 09, 2019 20:00:12.320 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.320 [24196] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 09, 2019 20:00:12.320 [14608] DEBUG - NetworkService: Creating new service.
Jan 09, 2019 20:00:12.321 [24196] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Jan 09, 2019 20:00:12.321 [14608] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 09, 2019 20:00:12.321 [14608] DEBUG - NetworkService: Quick dispatch of network change.
Jan 09, 2019 20:00:12.321 [24196] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 192.168.0.255 (index: 0)
Jan 09, 2019 20:00:12.321 [24196] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Jan 09, 2019 20:00:12.321 [24196] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 192.168.0.255 (index: 0)
Jan 09, 2019 20:00:12.322 [24196] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 09, 2019 20:00:12.322 [24196] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Jan 09, 2019 20:00:12.322 [24196] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 239.255.255.250 (index: 0)
Jan 09, 2019 20:00:12.434 [12844] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/system/agents
Jan 09, 2019 20:00:12.434 [12844] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9133 bytes of content.
Jan 09, 2019 20:00:12.437 [12844] DEBUG - The system knows about 14 agents.
Jan 09, 2019 20:00:12.437 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Framework.bundle.
Jan 09, 2019 20:00:12.437 [25660] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Fanart-TV.bundle.
Jan 09, 2019 20:00:12.437 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\HTbackdrops.bundle.
Jan 09, 2019 20:00:12.437 [12844] DEBUG - Waiting for 21 plug-ins to finish starting.
Jan 09, 2019 20:00:12.438 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\LastFM.bundle.
Jan 09, 2019 20:00:12.439 [14236] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Jan 09, 2019 20:00:12.439 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\LocalMedia.bundle.
Jan 09, 2019 20:00:12.439 [25660] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Jan 09, 2019 20:00:12.439 [25660] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\LyricFind.bundle.
Jan 09, 2019 20:00:12.440 [25168] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Jan 09, 2019 20:00:12.440 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Media-Flags.bundle.
Jan 09, 2019 20:00:12.440 [25660] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Jan 09, 2019 20:00:12.440 [25660] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\MoviePosterDB.bundle.
Jan 09, 2019 20:00:12.441 [14236] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Jan 09, 2019 20:00:12.441 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\OpenSubtitles.bundle.
Jan 09, 2019 20:00:12.441 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\PersonalMedia.bundle.
Jan 09, 2019 20:00:12.442 [25660] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Jan 09, 2019 20:00:12.442 [14236] DEBUG - It took 62.500000 ms to retrieve 1 items.
Jan 09, 2019 20:00:12.442 [25660] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\PlexMovie.bundle.
Jan 09, 2019 20:00:12.442 [25168] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Jan 09, 2019 20:00:12.442 [14236] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Jan 09, 2019 20:00:12.442 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\PlexMusic.bundle.
Jan 09, 2019 20:00:12.442 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\PlexThemeMusic.bundle.
Jan 09, 2019 20:00:12.444 [14236] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Jan 09, 2019 20:00:12.444 [25660] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Jan 09, 2019 20:00:12.444 [25168] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Jan 09, 2019 20:00:12.444 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Scanners.bundle.
Jan 09, 2019 20:00:12.444 [25660] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\TheMovieDB.bundle.
Jan 09, 2019 20:00:12.444 [25168] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\TheTVDB.bundle.
Jan 09, 2019 20:00:12.445 [14236] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\WebClient.bundle.
Jan 09, 2019 20:00:12.445 [25660] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Jan 09, 2019 20:00:12.445 [25168] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Jan 09, 2019 20:00:12.445 [25660] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Jan 09, 2019 20:00:12.445 [25168] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\SiteConfigurations.bundle.
Jan 09, 2019 20:00:12.446 [14236] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\WebManager.bundle.
Jan 09, 2019 20:00:12.446 [25168] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Wikipedia.bundle.
Jan 09, 2019 20:00:12.447 [25168] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.wikipedia] yet.
Jan 09, 2019 20:00:12.448 [12844] DEBUG - Starting scheduled updates, every 900 seconds
Jan 09, 2019 20:00:12.448 [12844] INFO - LibraryUpdateManager path watching is disabled
Jan 09, 2019 20:00:12.449 [23212] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Jan 09, 2019 20:00:12.449 [12844] DEBUG - Done waiting for all plug-ins to start.
Jan 09, 2019 20:00:12.449 [12844] DEBUG - PluginRepository::setStartState: 3, complete
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale ar
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale cs-CZ
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale cs_CZ
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale da
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale de
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale en
Jan 09, 2019 20:00:12.450 [23212] DEBUG - Translator: Found locale es
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale et_EE
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale fr
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale he
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale hr_HR
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale hu_HU
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale it
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale ja_JP
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale ko
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale lt
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale nl
Jan 09, 2019 20:00:12.451 [23212] DEBUG - Translator: Found locale nl-BE
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale nl_BE
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale no
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale pt-BR
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale pt_BR
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale ro
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale ru
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale sr
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale sv
Jan 09, 2019 20:00:12.452 [23212] DEBUG - Translator: Found locale zh_CN
Jan 09, 2019 20:00:12.470 [17452] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Jan 09, 2019 20:00:12.470 [17452] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 5.29.101.237
Jan 09, 2019 20:00:12.477 [23212] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Jan 09, 2019 20:00:12.478 [23212] DEBUG - Plugin::createProcess: Creating plugin process: "D:\Program files\Plex\Plex Media Server\PlexScriptHost.exe" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-cc260c476\PlexMovie.bundle"
Jan 09, 2019 20:00:12.480 [23212] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 23576
Jan 09, 2019 20:00:12.480 [23212] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Jan 09, 2019 20:00:12.493 [14608] DEBUG - Gracenote Version 3.07.7.3686 (build: 2015-07-05 11:53-0700)
Jan 09, 2019 20:00:12.494 [14608] DEBUG - Gracenote: Using SQLite 3.13.0
Jan 09, 2019 20:00:12.495 [9764] DEBUG - Plex Dlna Server.exe starting.
Jan 09, 2019 20:00:12.495 [9764] DEBUG - Job running: D:\Program files\Plex\Plex Media Server\Plex Dlna Server.exe 
Jan 09, 2019 20:00:12.495 [14608] DEBUG - Starting HTTP server.
Jan 09, 2019 20:00:12.495 [24492] DEBUG - Plex Tuner Service.exe starting.
Jan 09, 2019 20:00:12.496 [14608] DEBUG - Running server...
Jan 09, 2019 20:00:12.496 [14608] DEBUG - Waiting for server condition to be signaled.
Jan 09, 2019 20:00:12.496 [27456] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Jan 09, 2019 20:00:12.497 [27456] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.498 [26644] DEBUG - Request: [127.0.0.1:54831 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.498 [26644] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:12.498 [26644] DEBUG - HTTP requesting GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:12.498 [12012] DEBUG - Request: [127.0.0.1:54830 (Loopback)] GET / (5 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.499 [9764] DEBUG - Jobs: Starting child process with pid 26368
Jan 09, 2019 20:00:12.499 [24492] DEBUG - Job running: D:\Program files\Plex\Plex Media Server\Plex Tuner Service.exe "D:\Program files\Plex\Plex Media Server\Resources\Tuner\Private" "D:\Program files\Plex\Plex Media Server\Resources\Tuner\Shared" 1.14.1.5488-cc260c476 32600 /waitmutex 
Jan 09, 2019 20:00:12.502 [24492] DEBUG - Jobs: Starting child process with pid 22680
Jan 09, 2019 20:00:12.518 [23708] DEBUG - Completed: [127.0.0.1:54830] 200 GET / (6 live) GZIP 20ms 1844 bytes
Jan 09, 2019 20:00:12.520 [27328] DEBUG - Request: [127.0.0.1:54837 (Loopback)] GET / (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.523 [25964] DEBUG - Request: [127.0.0.1:54838 (Loopback)] GET /servers (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.524 [23708] DEBUG - Completed: [127.0.0.1:54837] 200 GET / (7 live) GZIP 5ms 1844 bytes
Jan 09, 2019 20:00:12.525 [23708] DEBUG - Completed: [127.0.0.1:54838] 200 GET /servers (7 live) GZIP 4ms 485 bytes
Jan 09, 2019 20:00:12.543 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:12.543 [27328] DEBUG - Request: [127.0.0.1:54848 (Loopback)] GET / (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.544 [12012] DEBUG - Request: [192.168.0.88:52053 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.547 [20576] DEBUG - Request: [127.0.0.1:54849 (Loopback)] GET /library/sections (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.548 [23708] DEBUG - Completed: [127.0.0.1:54848] 200 GET / (7 live) GZIP 5ms 1844 bytes
Jan 09, 2019 20:00:12.552 [12012] DEBUG - [CompanionProxy] registered new player: 7fm9wk07z5fcjwvj3oel0ssq (192.168.0.88)
Jan 09, 2019 20:00:12.553 [12012] DEBUG - Beginning read from two-way stream.
Jan 09, 2019 20:00:12.556 [23708] DEBUG - Completed: [127.0.0.1:54849] 200 GET /library/sections (7 live) GZIP 12ms 908 bytes
Jan 09, 2019 20:00:12.569 [27328] DEBUG - Request: [127.0.0.1:54850 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.570 [23708] DEBUG - Completed: [127.0.0.1:54850] 200 GET /channels/all (7 live) GZIP 1ms 387 bytes
Jan 09, 2019 20:00:12.592 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:12.592 [25964] DEBUG - Request: [192.168.0.88:52054 (Subnet)] GET /media/providers (6 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.593 [26644] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:12.594 [26644] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59982 bytes of content.
Jan 09, 2019 20:00:12.596 [27328] DEBUG - Request: [127.0.0.1:54852 (Loopback)] GET /library/sections (6 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.597 [27184] DEBUG - Completed: [192.168.0.88:52054] 200 GET /media/providers (6 live) TLS GZIP 4ms 2116 bytes (pipelined: 1)
Jan 09, 2019 20:00:12.598 [27184] DEBUG - Completed: [127.0.0.1:54831] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 100ms 19459 bytes
Jan 09, 2019 20:00:12.598 [23708] DEBUG - Completed: [127.0.0.1:54852] 200 GET /library/sections (6 live) GZIP 2ms 908 bytes
Jan 09, 2019 20:00:12.628 [12012] DEBUG - Request: [127.0.0.1:54853 (Loopback)] GET /channels/all (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:12.629 [23708] DEBUG - Completed: [127.0.0.1:54853] 200 GET /channels/all (7 live) GZIP 1ms 387 bytes
Jan 09, 2019 20:00:12.693 [11312] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.824 [24748] DEBUG - Gracenote: Loading 29 locales in 0.2 seconds.
Jan 09, 2019 20:00:12.877 [27456] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.884 [27456] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:12.965 [5744] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 09, 2019 20:00:12.972 [5744] DEBUG - MyPlex: username is dvirgassner, login is dvir@gassner.co.il, home is 0, no pin
Jan 09, 2019 20:00:12.973 [5744] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.033 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:13.034 [20576] DEBUG - Request: [127.0.0.1:54890 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.034 [20576] DEBUG - [CompanionProxy] registered new player: dg8r5z6hx7f6x4jz5de35dp6 (127.0.0.1)
Jan 09, 2019 20:00:13.034 [20576] DEBUG - Beginning read from two-way stream.
Jan 09, 2019 20:00:13.365 [27456] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.370 [27456] DEBUG - Running database fix-ups.
Jan 09, 2019 20:00:13.370 [27456] DEBUG - Database Fixups: Destroyed 0 transient items.
Jan 09, 2019 20:00:13.371 [27456] DEBUG - Collections: Found 1 collections tags in section 1.
Jan 09, 2019 20:00:13.372 [27456] DEBUG - Collections: We had 1 collections in the library already.
Jan 09, 2019 20:00:13.372 [27456] DEBUG - Collections: Synced tags in section 1 in 0ms.
Jan 09, 2019 20:00:13.376 [27456] DEBUG - Collections: Refreshed attributes for 1 collections in section 1 in 3ms.
Jan 09, 2019 20:00:13.376 [27456] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Jan 09, 2019 20:00:13.376 [27456] DEBUG - Collections: Found 0 collections tags in section 2.
Jan 09, 2019 20:00:13.377 [27456] DEBUG - Collections: We had 0 collections in the library already.
Jan 09, 2019 20:00:13.377 [27456] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 09, 2019 20:00:13.377 [27456] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Jan 09, 2019 20:00:13.377 [5744] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.378 [27456] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Jan 09, 2019 20:00:13.378 [27456] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 09, 2019 20:00:13.378 [27456] DEBUG - Collections: We had 0 collections in the library already.
Jan 09, 2019 20:00:13.378 [27456] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 09, 2019 20:00:13.379 [27456] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 09, 2019 20:00:13.380 [27456] DEBUG - Collection: Refreshed visibility in section 3 in 0ms.
Jan 09, 2019 20:00:13.380 [27456] DEBUG - Running database fix-ups completed in 0.0 seconds.
Jan 09, 2019 20:00:13.383 [27456] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.383 [5744] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.385 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:13.386 [25964] DEBUG - Request: [127.0.0.1:54897 (Loopback)] GET /music/iTunes (8 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.387 [23708] DEBUG - Completed: [127.0.0.1:54897] 404 GET /music/iTunes (8 live) GZIP 0ms 379 bytes (pipelined: 1)
Jan 09, 2019 20:00:13.388 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:13.389 [27328] DEBUG - Request: [127.0.0.1:54900 (Loopback)] GET /library/sections (8 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.389 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:13.390 [26644] DEBUG - Request: [127.0.0.1:54897 (Loopback)] GET /media/providers (8 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.392 [23708] DEBUG - Completed: [127.0.0.1:54900] 200 GET /library/sections (8 live) GZIP 2ms 919 bytes (pipelined: 1)
Jan 09, 2019 20:00:13.392 [27184] DEBUG - Completed: [127.0.0.1:54897] 200 GET /media/providers (8 live) GZIP 2ms 2127 bytes (pipelined: 2)
Jan 09, 2019 20:00:13.395 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:13.396 [12012] DEBUG - Request: [127.0.0.1:54897 (Loopback)] GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (7 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.397 [12012] DEBUG - HubCache: Adding '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:13.397 [12012] DEBUG - HubCache: Adding '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:13.410 [12012] DEBUG - HubCache: Computed '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 12 ms.
Jan 09, 2019 20:00:13.410 [12012] DEBUG - HubCache: Computed '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 13 ms.
Jan 09, 2019 20:00:13.410 [12012] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 09, 2019 20:00:13.411 [12012] DEBUG - HubCache: Adding '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:13.419 [12012] DEBUG - HubCache: Computed '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 7 ms.
Jan 09, 2019 20:00:13.419 [12012] DEBUG - HubCache: Adding '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:13.420 [12012] ERROR - QueryParser: Invalid field 'onlyTransient' found, ignoring.
Jan 09, 2019 20:00:13.424 [12012] DEBUG - HubCache: Computed '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 4 ms.
Jan 09, 2019 20:00:13.424 [12012] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 09, 2019 20:00:13.431 [12012] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 6 ms.
Jan 09, 2019 20:00:13.432 [12012] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 09, 2019 20:00:13.432 [12012] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 09, 2019 20:00:13.433 [12012] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' to the cache (1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10).
Jan 09, 2019 20:00:13.433 [12012] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&limit=200&onlyTransient=1&sort=addedAt%3Adesc&type=4%2C10' in 0 ms.
Jan 09, 2019 20:00:13.441 [12012] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:13.444 [23708] DEBUG - Completed: [127.0.0.1:54897] 200 GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (7 live) GZIP 48ms 8097 bytes (pipelined: 3)
Jan 09, 2019 20:00:13.753 [20576] DEBUG - Request: [127.0.0.1:54954 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (8 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.753 [20576] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Jan 09, 2019 20:00:13.754 [20576] DEBUG - HTTP requesting GET http://127.0.0.1:54840/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 09, 2019 20:00:13.789 [20576] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 09, 2019 20:00:13.789 [20576] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 09, 2019 20:00:13.790 [27184] DEBUG - Completed: [127.0.0.1:54954] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (8 live) GZIP 36ms 330 bytes
Jan 09, 2019 20:00:13.830 [27456] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.842 [27456] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 09, 2019 20:00:13.842 [27124] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.853 [25964] DEBUG - Request: [127.0.0.1:54957 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (9 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:13.854 [25964] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Jan 09, 2019 20:00:13.854 [25964] DEBUG - HTTP requesting GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/resourceHashes
Jan 09, 2019 20:00:13.926 [5744] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:13.934 [5744] DEBUG - Sync: onMyPlexSignIn
Jan 09, 2019 20:00:13.934 [5744] WARN - Sync: Couldn't get sync lists without subscription.
Jan 09, 2019 20:00:13.935 [5744] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.201 [25964] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/resourceHashes
Jan 09, 2019 20:00:14.201 [25964] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Jan 09, 2019 20:00:14.202 [27184] DEBUG - Completed: [127.0.0.1:54957] 200 GET /:/plugins/com.plexapp.system/resourceHashes (9 live) GZIP 348ms 3498 bytes
Jan 09, 2019 20:00:14.217 [26644] DEBUG - Request: [127.0.0.1:54965 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (11 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:14.218 [26644] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:14.218 [26644] DEBUG - HTTP requesting GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:14.218 [12012] DEBUG - Request: [127.0.0.1:54966 (Loopback)] GET / (10 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:14.219 [27184] DEBUG - Completed: [127.0.0.1:54966] 200 GET / (10 live) GZIP 1ms 1855 bytes
Jan 09, 2019 20:00:14.240 [26644] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 09, 2019 20:00:14.240 [26644] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59982 bytes of content.
Jan 09, 2019 20:00:14.244 [27184] DEBUG - Completed: [127.0.0.1:54965] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (10 live) GZIP 27ms 19459 bytes
Jan 09, 2019 20:00:14.269 [27124] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.269 [27124] DEBUG - DVR: Starting up.
Jan 09, 2019 20:00:14.270 [24748] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.270 [27124] DEBUG - Activity: registered new activity 145122d6-eddc-48f3-8096-846c370f4e17 - Processing subscriptions
Jan 09, 2019 20:00:14.339 [5744] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.343 [5744] DEBUG - EventSource: Resolving 151.236.217.85 port 443s
Jan 09, 2019 20:00:14.344 [5744] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.344 [27184] DEBUG - EventSource: Resolved 151.236.217.85 to 151.236.217.85
Jan 09, 2019 20:00:14.344 [27124] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.547 [27184] DEBUG - EventSource: Connected in 142 ms.
Jan 09, 2019 20:00:14.548 [23708] DEBUG - EventSource: Wrote data, reading reply.
Jan 09, 2019 20:00:14.707 [24748] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.751 [23708] DEBUG - EventSource: Read HTTP reply header.
Jan 09, 2019 20:00:14.751 [23708] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Jan 09, 2019 20:00:14.751 [23708] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 09, 2019 20:00:14.752 [23708] DEBUG - EventSource: Successfully connected to 151.236.217.85.
Jan 09, 2019 20:00:14.809 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:14.810 [27328] DEBUG - Request: [192.168.0.88:52058 (Subnet)] GET /:/websockets/notifications (10 live) TLS Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:14.810 [27328] DEBUG - WebSocket: Performing handshake from origin https://caf9e577.plexforxbox:0
Jan 09, 2019 20:00:14.811 [27328] DEBUG - Beginning read from WebSocket
Jan 09, 2019 20:00:14.834 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:14.834 [20576] DEBUG - Request: [192.168.0.88:52054 (Subnet)] GET /activities (9 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:14.836 [23708] DEBUG - Completed: [192.168.0.88:52054] 200 GET /activities (9 live) TLS GZIP 2ms 491 bytes (pipelined: 2)
Jan 09, 2019 20:00:14.837 [11312] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.861 [23708] DEBUG - EventSource: Got event [data] '<Message host="178.79.148.211" port="443" command="startRelay"/>'
Jan 09, 2019 20:00:14.862 [23708] DEBUG - HTTP requesting GET https://downloads.plex.tv/relay/relay_v1.pub
Jan 09, 2019 20:00:14.885 [5744] DEBUG - HTTP 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.898 [5744] DEBUG - MyPlex: Updating device connections (from timer: 0)
Jan 09, 2019 20:00:14.898 [22744] DEBUG - MyPlex: Sending Server Info to myPlex (user=dvir@gassner.co.il, ip=, port=0)
Jan 09, 2019 20:00:14.898 [5744] DEBUG - HTTP requesting PUT https://plex.tv/devices/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea?Connection[][uri]=http://192.168.0.62:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:14.899 [22744] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=3f4d31ff-274a-4d27-ae8c-9c1b59a5c8e5
Jan 09, 2019 20:00:14.956 [27124] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:15.169 [25964] DEBUG - Request: [127.0.0.1:55099 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:15.170 [25964] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 09, 2019 20:00:15.170 [25964] DEBUG - HTTP requesting GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 09, 2019 20:00:15.175 [25964] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 09, 2019 20:00:15.176 [25964] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Jan 09, 2019 20:00:15.176 [27184] DEBUG - Completed: [127.0.0.1:55099] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP 6ms 465 bytes
Jan 09, 2019 20:00:15.206 [23212] DEBUG - Reading 148 bytes in the body, code is 200
Jan 09, 2019 20:00:15.206 [23212] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 55101.
Jan 09, 2019 20:00:15.206 [23212] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Jan 09, 2019 20:00:15.209 [23212] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Jan 09, 2019 20:00:15.209 [23212] DEBUG - HTTP requesting GET http://127.0.0.1:55101/:/plugins/com.plexapp.agents.imdb/prefs
Jan 09, 2019 20:00:15.234 [23212] DEBUG - HTTP 200 response from GET http://127.0.0.1:55101/:/plugins/com.plexapp.agents.imdb/prefs
Jan 09, 2019 20:00:15.234 [23212] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Jan 09, 2019 20:00:15.234 [23212] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as d231c449cdb9805d47360720d7ee891a5d0610ad
Jan 09, 2019 20:00:15.234 [23212] DEBUG - IVA: Downloading trailer lists.
Jan 09, 2019 20:00:15.278 [11312] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:15.278 [11312] DEBUG - Media Server: Tested all servers in 0.0 seconds.
Jan 09, 2019 20:00:15.306 [5744] DEBUG - HTTP 200 response from PUT https://plex.tv/devices/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea?Connection[][uri]=http://192.168.0.62:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:15.312 [5744] DEBUG - CERT: Certificate will not expire soon.
Jan 09, 2019 20:00:15.325 [22184] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 09, 2019 20:00:15.328 [22184] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 09, 2019 20:00:15.329 [22184] DEBUG - NAT: UPnP, mapped port 18609 to 192.168.0.62:32400.
Jan 09, 2019 20:00:15.329 [22184] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.0.62:18609.
Jan 09, 2019 20:00:15.338 [22184] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 09, 2019 20:00:15.341 [23708] DEBUG - HTTP 200 response from GET https://downloads.plex.tv/relay/relay_v1.pub
Jan 09, 2019 20:00:15.341 [23708] DEBUG - Relay: refreshed host key from https://downloads.plex.tv/relay/relay_v1.pub
Jan 09, 2019 20:00:15.347 [23708] DEBUG - Relay: starting relay.
Jan 09, 2019 20:00:15.348 [23708] DEBUG - Job running: PLEXTOKEN='xxxxxxxxxxxxxxxxxxxx' D:\Program files\Plex\Plex Media Server\PlexRelay.exe -p 443 -N -R 0:127.0.0.1:32401 -o StrictHostKeyChecking=yes -o "UserKnownHostsFile=C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\relayHostKey.txt" -o NoneSwitch=yes -o NoneEnabled=yes -o LogLevel=VERBOSE -o PreferredAuthentications=password -o PubkeyAuthentication=no -l dvirgassner 178.79.148.211 
Jan 09, 2019 20:00:15.350 [23708] DEBUG - Jobs: Starting child process with pid 25780
Jan 09, 2019 20:00:15.394 [22744] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=3f4d31ff-274a-4d27-ae8c-9c1b59a5c8e5
Jan 09, 2019 20:00:15.394 [22744] DEBUG - MyPlex: Published Mapping State response was 201
Jan 09, 2019 20:00:15.395 [22744] DEBUG - MyPlex: Got response for bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea ~ registered :0
Jan 09, 2019 20:00:15.403 [27184] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="3f4d31ff-274a-4d27-ae8c-9c1b59a5c8e5" connectivity="0" command="notifyConnectivity"/>'
Jan 09, 2019 20:00:15.403 [27184] DEBUG - MyPlex: inaccessibility report ignored because NAT punch is still pending.
Jan 09, 2019 20:00:15.403 [27184] DEBUG - PubSub: Got notified of reachability: 0 for :0
Jan 09, 2019 20:00:15.478 [17452] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 09, 2019 20:00:15.481 [17452] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 09, 2019 20:00:15.482 [17452] DEBUG - NAT: UPnP, public address is 5.29.101.237
Jan 09, 2019 20:00:15.511 [17452] DEBUG - MyPlex: Sending Server Info to myPlex (user=dvir@gassner.co.il, ip=, port=18609)
Jan 09, 2019 20:00:15.512 [17452] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=87374b54-945f-49d4-a70f-2a86a18b3eda
Jan 09, 2019 20:00:15.720 [23212] DEBUG - IVA: Downloaded 13 trailers for source 1.
Jan 09, 2019 20:00:16.044 [23212] DEBUG - IVA: Downloaded 26 trailers for source 2.
Jan 09, 2019 20:00:16.230 [17452] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=87374b54-945f-49d4-a70f-2a86a18b3eda
Jan 09, 2019 20:00:16.230 [17452] DEBUG - MyPlex: Published Mapping State response was 201
Jan 09, 2019 20:00:16.231 [17452] DEBUG - MyPlex: Got response for bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea ~ registered :0
Jan 09, 2019 20:00:16.249 [17452] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 09, 2019 20:00:16.287 [27328] DEBUG - [PlexRelay] Authenticated to 178.79.148.211 ([178.79.148.211]:443).
Jan 09, 2019 20:00:16.506 [12012] DEBUG - Request: [34.248.59.52:57858 (WAN)] GET /identity (10 live) TLS Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.507 [27184] DEBUG - Completed: [34.248.59.52:57858] 200 GET /identity (10 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 09, 2019 20:00:16.548 [26644] ERROR - [PlexRelay] kex protocol error: type 7 seq 11
Jan 09, 2019 20:00:16.731 [27184] DEBUG - EventSource: Got event [data] '<Message address="5.29.101.237" port="18609" asyncIdentifier="87374b54-945f-49d4-a70f-2a86a18b3eda" connectivity="1" command="notifyConnectivity"/>'
Jan 09, 2019 20:00:16.731 [27184] DEBUG - PubSub: Got notified of reachability: 1 for 5.29.101.237:18609
Jan 09, 2019 20:00:16.732 [25964] INFO - [PlexRelay] Allocated port 23683 for remote forward to 127.0.0.1:32401
Jan 09, 2019 20:00:16.954 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:16.955 [20576] DEBUG - Request: [127.0.0.1:55127 (Loopback)] GET /media/providers (12 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.957 [23708] DEBUG - Completed: [127.0.0.1:55127] 200 GET /media/providers (12 live) GZIP 2ms 2126 bytes (pipelined: 1)
Jan 09, 2019 20:00:16.964 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:16.965 [27328] DEBUG - Request: [192.168.0.62:55126 (Subnet)] GET /media/providers (11 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.970 [23708] DEBUG - Completed: [192.168.0.62:55126] 200 GET /media/providers (11 live) TLS GZIP 4ms 2199 bytes (pipelined: 1)
Jan 09, 2019 20:00:16.983 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:16.984 [12012] DEBUG - Request: [127.0.0.1:55127 (Loopback)] GET /updater/status (11 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.984 [27184] DEBUG - Completed: [127.0.0.1:55127] 200 GET /updater/status (12 live) GZIP 0ms 447 bytes (pipelined: 2)
Jan 09, 2019 20:00:16.985 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:16.986 [25964] DEBUG - Request: [127.0.0.1:55130 (Loopback)] GET /:/websockets/notifications (11 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.986 [25964] DEBUG - WebSocket: Performing handshake from origin http://127.0.0.1:32400
Jan 09, 2019 20:00:16.987 [25964] DEBUG - Beginning read from WebSocket
Jan 09, 2019 20:00:16.998 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:16.999 [26644] DEBUG - Request: [127.0.0.1:55127 (Loopback)] PUT /updater/check?download=0 (10 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:16.999 [26644] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476, download: NO
Jan 09, 2019 20:00:17.000 [26644] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:17.000 [26644] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:17.000 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.000 [20576] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /activities (11 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.001 [23708] DEBUG - Completed: [127.0.0.1:55131] 200 GET /activities (11 live) GZIP 0ms 491 bytes (pipelined: 1)
Jan 09, 2019 20:00:17.043 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.043 [27328] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /music/iTunes (11 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.044 [27184] DEBUG - Completed: [127.0.0.1:55131] 404 GET /music/iTunes (11 live) GZIP 0ms 379 bytes (pipelined: 2)
Jan 09, 2019 20:00:17.045 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.046 [12012] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /library/sections (12 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.047 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.047 [25964] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /media/providers (12 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.048 [23708] DEBUG - Completed: [127.0.0.1:55133] 200 GET /library/sections (12 live) GZIP 2ms 919 bytes (pipelined: 1)
Jan 09, 2019 20:00:17.050 [23708] DEBUG - Completed: [127.0.0.1:55131] 200 GET /media/providers (12 live) GZIP 2ms 2126 bytes (pipelined: 3)
Jan 09, 2019 20:00:17.050 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.051 [20576] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (12 live) Page 16-50 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.052 [20576] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Jan 09, 2019 20:00:17.053 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.054 [25964] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1 (12 live) Page 16-50 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.055 [25964] DEBUG - Setting container serialization range to [16, 50] (total=-1)
Jan 09, 2019 20:00:17.057 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.058 [12012] DEBUG - Request: [127.0.0.1:55141 (Loopback)] GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (13 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.058 [12012] DEBUG - HubCache: Retrieving '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 09, 2019 20:00:17.058 [12012] DEBUG - HubCache: Retrieving '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 09, 2019 20:00:17.059 [12012] DEBUG - HubCache: Retrieving '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 09, 2019 20:00:17.059 [12012] DEBUG - HubCache: Retrieving '1/home.television.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' from the cache.
Jan 09, 2019 20:00:17.059 [12012] DEBUG - HubCache: Adding '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:17.064 [12012] DEBUG - HubCache: Computed '1/home.videos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 5 ms.
Jan 09, 2019 20:00:17.065 [12012] DEBUG - HubCache: Adding '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:17.066 [20576] DEBUG - Setting container serialization range to [16, 50] (total=44)
Jan 09, 2019 20:00:17.066 [12012] DEBUG - HubCache: Computed '1/home.photos.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 0 ms.
Jan 09, 2019 20:00:17.066 [12012] DEBUG - HubCache: Adding '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' to the cache (1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1).
Jan 09, 2019 20:00:17.067 [25964] DEBUG - Setting container serialization range to [16, 50] (total=47)
Jan 09, 2019 20:00:17.067 [12012] DEBUG - HubCache: Computed '1/home.music.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' in 0 ms.
Jan 09, 2019 20:00:17.072 [23708] DEBUG - Completed: [127.0.0.1:55131] 200 GET /hubs/home/recentlyAdded?type=1&personal=1 (13 live) GZIP Page 16-50 18ms 3918 bytes (pipelined: 4)
Jan 09, 2019 20:00:17.072 [23708] DEBUG - Completed: [127.0.0.1:55133] 200 GET /hubs/home/recentlyAdded?type=1 (13 live) GZIP Page 16-50 21ms 11286 bytes (pipelined: 2)
Jan 09, 2019 20:00:17.073 [12012] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:17.075 [23708] DEBUG - Completed: [127.0.0.1:55141] 200 GET /hubs?excludeFields=summary&count=16&includeEmpty=1&includeFeaturedTags=1&includeStations=1&includeExternalMetadata=1&excludePlaylists=1&onlyTransient=1 (13 live) GZIP 17ms 8097 bytes (pipelined: 1)
Jan 09, 2019 20:00:17.320 [24196] DEBUG - NetworkServiceBrowser: PLAYER arrived: 192.168.0.62
Jan 09, 2019 20:00:17.321 [24196] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.0.62 (Fox)
Jan 09, 2019 20:00:17.321 [24196] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.0.62
Jan 09, 2019 20:00:17.322 [24196] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.0.62
Jan 09, 2019 20:00:17.322 [24196] DEBUG - HTTP requesting GET http://127.0.0.1:54840/system/notify/serverUpdated?host=192.168.0.62
Jan 09, 2019 20:00:17.331 [27328] DEBUG - Request: [127.0.0.1:55146 (Loopback)] GET /servers (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.332 [27184] DEBUG - Completed: [127.0.0.1:55146] 200 GET /servers (15 live) GZIP 1ms 485 bytes
Jan 09, 2019 20:00:17.340 [27328] DEBUG - Request: [127.0.0.1:55147 (Loopback)] GET /library/sections (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.343 [23708] DEBUG - Completed: [127.0.0.1:55147] 200 GET /library/sections (15 live) GZIP 3ms 908 bytes
Jan 09, 2019 20:00:17.349 [12012] DEBUG - Request: [127.0.0.1:55148 (Loopback)] GET /channels/all (16 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.350 [23708] DEBUG - Completed: [127.0.0.1:55148] 200 GET /channels/all (16 live) GZIP 1ms 387 bytes
Jan 09, 2019 20:00:17.358 [24196] DEBUG - HTTP 200 response from GET http://127.0.0.1:54840/system/notify/serverUpdated?host=192.168.0.62
Jan 09, 2019 20:00:17.358 [24196] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 09, 2019 20:00:17.360 [26644] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:17.361 [26644] INFO - AutoUpdate: no updates available
Jan 09, 2019 20:00:17.361 [23708] DEBUG - Completed: [127.0.0.1:55127] 200 PUT /updater/check?download=0 (16 live) GZIP 362ms 268 bytes (pipelined: 3)
Jan 09, 2019 20:00:17.472 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:17.473 [27328] DEBUG - Request: [127.0.0.1:55142 (WAN)] GET /media/providers (15 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:17.477 [27184] DEBUG - Completed: [127.0.0.1:55142] 200 GET /media/providers (15 live) TLS GZIP 4ms 2267 bytes (pipelined: 1)
Jan 09, 2019 20:00:19.343 [27180] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Jan 09, 2019 20:00:19.670 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:19.670 [20576] DEBUG - Request: [127.0.0.1:55127 (Loopback)] POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2Fbb7b1b3d8dd2f1546869f50ba0c70e4e620374ea%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F670&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:19.671 [20576] DEBUG - PlayQueue: Converted 'server://bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/com.plexapp.plugins.library/library/metadata/670' to 'library://x/item/%2Flibrary%2Fmetadata%2F670'
Jan 09, 2019 20:00:19.672 [20576] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 09, 2019 20:00:19.674 [20576] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Jan 09, 2019 20:00:19.674 [20576] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Jan 09, 2019 20:00:19.675 [20576] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Jan 09, 2019 20:00:19.680 [20576] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:19.685 [20576] DEBUG - Extras: Preroll values looks like a file, and we had 1 of them.
Jan 09, 2019 20:00:19.685 [20576] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
Jan 09, 2019 20:00:19.685 [20576] DEBUG - Extras: Looking for extras for item com.plexapp.agents.imdb://tt1119646?lang=en with unwatched: 1 fromLibrary: 1 fromTheater: 0 fromBluray: 0 and preroll count: 0.
Jan 09, 2019 20:00:19.685 [20576] DEBUG - Extras: Using allowable content ratings: , NC-17, NR, PG-13, R.
Jan 09, 2019 20:00:19.685 [20576] DEBUG - Extras: Using genres: Comedy.
Jan 09, 2019 20:00:19.687 [20576] DEBUG - Extras: Found 0 extras from library.
Jan 09, 2019 20:00:19.690 [20576] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:19.692 [20576] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:19.692 [20576] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:19.692 [20576] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:19.692 [20576] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:19.692 [20576] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:19.694 [23708] DEBUG - Completed: [127.0.0.1:55127] 200 POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2Fbb7b1b3d8dd2f1546869f50ba0c70e4e620374ea%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F670&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (15 live) GZIP 23ms 5730 bytes (pipelined: 4)
Jan 09, 2019 20:00:19.738 [27180] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Jan 09, 2019 20:00:19.777 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:19.778 [25964] DEBUG - Request: [127.0.0.1:55127 (Loopback)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=v2xtuv3qymqbxhwjpnxkmbd6&subtitles=sidecar&Accept-Language=en (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:19.779 [25964] DEBUG - Found session GUID of v2xtuv3qymqbxhwjpnxkmbd6 in session start.
Jan 09, 2019 20:00:19.782 [25964] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2019 20:00:19.783 [25964] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/670?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:19.784 [25964] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:19.794 [25964] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:19.795 [25964] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:19.795 [25964] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:19.795 [25964] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:19.795 [25964] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:19.795 [25964] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: Selected protocol http; container: ass
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: analyzing media item 10703
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: The Hangover (2009): no direct play video profile exists for http/mp4/h264
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: The Hangover (2009): no direct play video profile exists for http/mp4/h264/aac
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: The Hangover (2009): transcoding selected subtitle stream
Jan 09, 2019 20:00:19.798 [25964] DEBUG - MDE: The Hangover (2009): selected media 0 / 10703
Jan 09, 2019 20:00:19.798 [25964] DEBUG - Streaming Resource: Added session 0x58a09d8:udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:19.798 [25964] DEBUG - Streaming Resource: Reached Decision id=670 codes=(MDE=1000,Direct play OK.) media=(id=10703 part=(id=10751 decision=direct play protocol=dash streams=(Video=(id=21754 decision= width=0 height=0) Audio=(id=21755 decision= channels=0 rate=0) Subtitle=(id=21769 decision=transcode bitrate=0 encoder=ass languageCode=eng location=sidecar))))
Jan 09, 2019 20:00:19.800 [27184] DEBUG - Completed: [127.0.0.1:55127] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=v2xtuv3qymqbxhwjpnxkmbd6&subtitles=sidecar&Accept-Language=en (15 live) GZIP 22ms 5590 bytes (pipelined: 5)
Jan 09, 2019 20:00:20.008 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:20.009 [26644] DEBUG - Request: [127.0.0.1:55127 (Loopback)] GET /subtitles/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=v2xtuv3qymqbxhwjpnxkmbd6&subtitles=sidecar&copyts=1&Accept-Language=en (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.009 [26644] DEBUG - Found session GUID of v2xtuv3qymqbxhwjpnxkmbd6 in session start.
Jan 09, 2019 20:00:20.010 [26644] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2019 20:00:20.010 [26644] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/670?checkFiles=1&includeBandwidths=1&offset=0&X-Plex-Session-Identifier=udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:20.011 [26644] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:20.015 [26644] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:20.016 [26644] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:20.016 [26644] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:20.016 [26644] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:20.016 [26644] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:20.016 [26644] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:20.018 [26644] DEBUG - Cleaning directory for session v2xtuv3qymqbxhwjpnxkmbd6 ()
Jan 09, 2019 20:00:20.019 [26644] DEBUG - Starting a transcode session v2xtuv3qymqbxhwjpnxkmbd6 at offset -1.0 (state=3)
Jan 09, 2019 20:00:20.021 [26644] DEBUG - [Universal] Using local file path instead of URL: D:\Plex\Movies\The.Hangover.2009.UNRATED.720p.BrRip.x264.YIFY.mp4
Jan 09, 2019 20:00:20.021 [26644] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:20.023 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:20.023 [12012] DEBUG - Request: [127.0.0.1:55176 (Loopback)] GET /library/streams/21769 (16 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.026 [12012] DEBUG - Calculated media file path for path [media://f/23445b050ec9e65accfc90cca7cca6e8fda093b.bundle/Contents/Subtitles/en/com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt]: ["C:\Users\Administrator\AppData\Local\Plex Media Server\Media\localhost/f/23445b050ec9e65accfc90cca7cca6e8fda093b.bundle/Contents/Subtitles/en/com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt"]
Jan 09, 2019 20:00:20.027 [12012] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Media\localhost\f\23445b050ec9e65accfc90cca7cca6e8fda093b.bundle\Contents\Subtitles\en\com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt is 50066.
Jan 09, 2019 20:00:20.028 [26644] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:20.028 [27184] DEBUG - Completed: [127.0.0.1:55176] 200 GET /library/streams/21769 (16 live) GZIP 4ms 50066 bytes
Jan 09, 2019 20:00:20.029 [26644] DEBUG - Detected character set of UTF-8.
Jan 09, 2019 20:00:20.048 [26644] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: srt) to temporary file [C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-v2xtuv3qymqbxhwjpnxkmbd6-8069a08a-28ab-48c2-bdda-65419814e5b0\temp-0.srt]
Jan 09, 2019 20:00:20.050 [26644] DEBUG - Job running: EAE_ROOT='\\?\C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='C\:\\Users\\Administrator\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\531e313-1328-windows-i386\\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' D:\Program files\Plex\Plex Media Server\PlexTranscoder.exe -probesize 10000000 -i D:\Plex\Movies\The.Hangover.2009.UNRATED.720p.BrRip.x264.YIFY.mp4 -probesize 10000000 -i "C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-v2xtuv3qymqbxhwjpnxkmbd6-8069a08a-28ab-48c2-bdda-65419814e5b0\temp-0.srt" -map 1:s:0 -metadata:s:0 language=eng -codec:0 ass -f segment -segment_format ass -segment_time 1 -segment_header_filename header -segment_start_number 0 -segment_list http://127.0.0.1:32400/video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/seglist -segment_list_type csv -segment_list_size 2147483647 -segment_list_separate_stream_times 1 -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 chunk-%05d -start_at_zero -copyts -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress 
Jan 09, 2019 20:00:20.058 [26644] DEBUG - Jobs: Starting child process with pid 27552
Jan 09, 2019 20:00:20.119 [27328] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=startup (17 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.119 [23708] DEBUG - Completed: [127.0.0.1:55177] 204 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=startup (17 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Jan 09, 2019 20:00:20.120 [20576] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opening (17 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.121 [23708] DEBUG - Completed: [127.0.0.1:55177] 204 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opening (17 live) 1ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Jan 09, 2019 20:00:20.179 [25964] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opened (17 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.181 [27184] DEBUG - Completed: [127.0.0.1:55177] 204 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opened (17 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Jan 09, 2019 20:00:20.182 [12012] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/stream?index=0&id=1&codec=h264&type=video (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.182 [27184] DEBUG - Completed: [127.0.0.1:55177] 206 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/stream?index=0&id=1&codec=h264&type=video (16 live) 0ms 256 bytes (pipelined: 4) (range: bytes=0-) 
Jan 09, 2019 20:00:20.196 [27328] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/stream?index=1&id=2&codec=aac&type=audio&profile=HE-AAC (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.196 [23708] DEBUG - Completed: [127.0.0.1:55177] 206 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/stream?index=1&id=2&codec=aac&type=audio&profile=HE-AAC (16 live) 0ms 256 bytes (pipelined: 5) (range: bytes=0-) 
Jan 09, 2019 20:00:20.197 [20576] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=772860&profile=High&language=und&width=1280&height=536&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.197 [23708] DEBUG - Completed: [127.0.0.1:55177] 206 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=772860&profile=High&language=und&width=1280&height=536&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (16 live) 0ms 256 bytes (pipelined: 6) (range: bytes=0-) 
Jan 09, 2019 20:00:20.198 [25964] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/streamDetail?index=1&id=2&codec=aac&type=audio&profile=HE-AAC&language=und&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.198 [23708] DEBUG - Completed: [127.0.0.1:55177] 206 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress/streamDetail?index=1&id=2&codec=aac&type=audio&profile=HE-AAC&language=und&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (16 live) 0ms 256 bytes (pipelined: 7) (range: bytes=0-) 
Jan 09, 2019 20:00:20.198 [12012] DEBUG - Request: [127.0.0.1:55177 (Loopback)] PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opening (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.198 [23708] DEBUG - Completed: [127.0.0.1:55177] 204 PUT /video/:/transcode/session/v2xtuv3qymqbxhwjpnxkmbd6/8069a08a-28ab-48c2-bdda-65419814e5b0/progress?status=opening (16 live) 0ms 203 bytes (pipelined: 8) (range: bytes=0-) 
Jan 09, 2019 20:00:20.214 [20576] ERROR - [Transcoder] C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-v2xtuv3qymqbxhwjpnxkmbd6-8069a08a-28ab-48c2-bdda-65419814e5b0\temp-0.srt: Invalid data found when processing input
Jan 09, 2019 20:00:20.225 [27124] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\PlexTranscoder.exe' exit code for process 27552 is 1 (failure)
Jan 09, 2019 20:00:20.226 [26644] DEBUG - Using default transcode duration of 120 minutes
Jan 09, 2019 20:00:20.226 [17452] DEBUG - Streaming Resource: Terminated session 0x58a09d8:udka48lwvq4u6sf9rdjb9yrg with reason Conversion failed. The transcoder exited due to an error.
Jan 09, 2019 20:00:20.226 [26644] DEBUG - Started session successfully: v2xtuv3qymqbxhwjpnxkmbd6
Jan 09, 2019 20:00:20.226 [11312] DEBUG - Killing job.
Jan 09, 2019 20:00:20.227 [11312] DEBUG - Job was already killed, not killing again.
Jan 09, 2019 20:00:20.227 [11312] DEBUG - Stopping transcode session v2xtuv3qymqbxhwjpnxkmbd6
Jan 09, 2019 20:00:20.227 [17452] DEBUG - Cleaning directory for session v2xtuv3qymqbxhwjpnxkmbd6 (C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-v2xtuv3qymqbxhwjpnxkmbd6-8069a08a-28ab-48c2-bdda-65419814e5b0)
Jan 09, 2019 20:00:20.227 [26644] ERROR - [TranscodeOutputStream] Streaming Session 0x58a09d8 appears to have died from under us
Jan 09, 2019 20:00:20.229 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:20.230 [27328] DEBUG - Request: [127.0.0.1:55141 (Loopback)] GET /status/sessions (16 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.230 [27328] DEBUG - [Now] Adding 0 sessions.
Jan 09, 2019 20:00:20.231 [23708] DEBUG - Completed: [127.0.0.1:55141] 200 GET /status/sessions (16 live) GZIP 1ms 397 bytes (pipelined: 2)
Jan 09, 2019 20:00:20.241 [11312] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 09, 2019 20:00:20.243 [11312] DEBUG - Whacked session v2xtuv3qymqbxhwjpnxkmbd6, 0 remaining.
Jan 09, 2019 20:00:20.298 [24748] DEBUG - DVR:Device: Discovering and refreshing all devices.
Jan 09, 2019 20:00:20.298 [24748] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Jan 09, 2019 20:00:20.298 [24748] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 151.236.217.85 was 154 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 178.79.176.52 was 159 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 172.104.225.166 was 188 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 82.94.168.54 was 190 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 45.33.75.206 was 289 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 45.79.197.109 was 354 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 66.228.52.149 was 393 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 184.105.148.101 was 416 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 139.162.117.249 was 656 ms.
Jan 09, 2019 20:00:20.920 [27180] DEBUG - PubSubManager: Time to connect to 172.104.183.85 was 790 ms.
Jan 09, 2019 20:00:20.921 [27180] DEBUG - PubSubManager: Updating best ping time for 151.236.217.85 to 154 ms.
Jan 09, 2019 20:00:20.998 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:20.999 [25964] DEBUG - Request: [127.0.0.1:55141 (Loopback)] GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1001&playQueueItemID=841&state=playing&hasMDE=1&time=0&duration=6473000 (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:20.999 [25964] DEBUG - Client [udka48lwvq4u6sf9rdjb9yrg] reporting timeline state playing, progress of 0/6473000ms for guid=, ratingKey=670 url=, key=/library/metadata/670, containerKey=, metadataId=670, source=
Jan 09, 2019 20:00:21.003 [25964] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.008 [25964] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.009 [25964] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:21.009 [25964] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:21.009 [25964] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:21.009 [25964] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:21.009 [25964] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:21.011 [25964] DEBUG - [Now] User is dvirgassner (ID: 1)
Jan 09, 2019 20:00:21.011 [25964] DEBUG - [Now] Device is Chrome (Chrome).
Jan 09, 2019 20:00:21.012 [25964] DEBUG - [Now] Profile is Web
Jan 09, 2019 20:00:21.012 [25964] DEBUG - [Now] Updated play state for /library/metadata/670.
Jan 09, 2019 20:00:21.013 [25964] DEBUG - HubCache: Expiring '1/home.continue/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' because of event plex.event.play.session.state.update.
Jan 09, 2019 20:00:21.013 [25964] DEBUG - HubCache: Expiring '1/home.movies.recent/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' because event plex.event.play.session.state.update referenced an item the hub contained
Jan 09, 2019 20:00:21.013 [25964] DEBUG - HubCache: Expiring '1/home.ondeck/1/hubs/en/count=16&excludeFields=summary&excludePlaylists=1&includeEmpty=1&includeExternalMetadata=1&includeFeaturedTags=1&includeStations=1&onlyTransient=1' because of event plex.event.play.session.state.update.
Jan 09, 2019 20:00:21.013 [25964] DEBUG - Statistics: (dg8r5z6hx7f6x4jz5de35dp6) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 09, 2019 20:00:21.016 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.016 [12012] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /status/sessions (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.018 [12012] DEBUG - [Now] Adding 1 sessions.
Jan 09, 2019 20:00:21.018 [27184] DEBUG - Completed: [127.0.0.1:55141] 200 GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1001&playQueueItemID=841&state=playing&hasMDE=1&time=0&duration=6473000 (15 live) GZIP 20ms 519 bytes (pipelined: 3)
Jan 09, 2019 20:00:21.019 [27184] DEBUG - Completed: [127.0.0.1:55133] 200 GET /status/sessions (15 live) GZIP 3ms 5720 bytes (pipelined: 3)
Jan 09, 2019 20:00:21.023 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.023 [26644] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /playQueues/459?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.024 [26644] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Jan 09, 2019 20:00:21.024 [26644] DEBUG - PlayQueue: No need to refresh, active window is operational.
Jan 09, 2019 20:00:21.028 [26644] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.028 [26644] DEBUG - PlayQueue: Client dg8r5z6hx7f6x4jz5de35dp6 requested ownership of play queue 459, but already had it.
Jan 09, 2019 20:00:21.029 [26644] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:21.029 [26644] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:21.029 [26644] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:21.029 [26644] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:21.029 [26644] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:21.030 [23708] DEBUG - Completed: [127.0.0.1:55133] 200 GET /playQueues/459?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (15 live) GZIP 7ms 5657 bytes (pipelined: 4)
Jan 09, 2019 20:00:21.049 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.050 [27328] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1001&playQueueItemID=841&state=stopped&hasMDE=1&time=0&duration=6473000 (15 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.051 [27328] DEBUG - Client [udka48lwvq4u6sf9rdjb9yrg] reporting timeline state stopped, progress of 0/6473000ms for guid=, ratingKey=670 url=, key=/library/metadata/670, containerKey=, metadataId=670, source=
Jan 09, 2019 20:00:21.059 [27328] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.061 [27328] DEBUG - [Now] User is dvirgassner (ID: 1)
Jan 09, 2019 20:00:21.061 [27328] DEBUG - [Now] Device is Chrome (Chrome).
Jan 09, 2019 20:00:21.063 [27328] DEBUG - [Now] Profile is Web
Jan 09, 2019 20:00:21.063 [27328] DEBUG - [Now] Updated play state for /library/metadata/670.
Jan 09, 2019 20:00:21.063 [17452] DEBUG - Cleaning directory for session v2xtuv3qymqbxhwjpnxkmbd6 (C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-v2xtuv3qymqbxhwjpnxkmbd6-8069a08a-28ab-48c2-bdda-65419814e5b0)
Jan 09, 2019 20:00:21.064 [27328] DEBUG - Statistics: (dg8r5z6hx7f6x4jz5de35dp6) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Jan 09, 2019 20:00:21.064 [27328] DEBUG - Streaming Resource: Removing session 0x58a09d8:udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:21.065 [23708] DEBUG - Completed: [127.0.0.1:55133] 200 GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1001&playQueueItemID=841&state=stopped&hasMDE=1&time=0&duration=6473000 (14 live) GZIP 15ms 519 bytes (pipelined: 5)
Jan 09, 2019 20:00:21.077 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.079 [20576] DEBUG - Request: [192.168.0.88:52054 (Subnet)] GET /hubs/home/continueWatching?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (13 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.085 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.086 [27328] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /hubs/home/continueWatching (13 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.087 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.088 [26644] DEBUG - Request: [127.0.0.1:55141 (Loopback)] GET /hubs/home/onDeck (13 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.093 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.094 [12012] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (14 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.095 [12012] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.096 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.097 [27356] DEBUG - Request: [192.168.0.88:52059 (Subnet)] GET /hubs/home/recentlyAdded?type=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (15 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.098 [20576] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:21.098 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.098 [27356] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:21.100 [25536] DEBUG - Request: [127.0.0.1:55180 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1 (17 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.100 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.101 [27328] DEBUG - It took 78.125000 ms to retrieve 10 items.
Jan 09, 2019 20:00:21.102 [23708] DEBUG - Completed: [192.168.0.88:52054] 200 GET /hubs/home/continueWatching?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (17 live) TLS GZIP Page 0-13 23ms 1037 bytes (pipelined: 3)
Jan 09, 2019 20:00:21.102 [25964] DEBUG - Request: [127.0.0.1:55181 (Loopback)] GET /hubs/home/recentlyAdded?type=2 (17 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.102 [26644] DEBUG - It took 78.125000 ms to retrieve 10 items.
Jan 09, 2019 20:00:21.103 [25536] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.105 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.106 [27328] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.107 [26644] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.108 [27184] DEBUG - Completed: [127.0.0.1:55141] 200 GET /hubs/home/onDeck (17 live) GZIP Page 0-15 20ms 480 bytes (pipelined: 4)
Jan 09, 2019 20:00:21.108 [12012] DEBUG - It took 78.125000 ms to retrieve 16 items.
Jan 09, 2019 20:00:21.109 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.110 [27356] DEBUG - Setting container serialization range to [0, 13] (total=44)
Jan 09, 2019 20:00:21.110 [25964] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.111 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.111 [20576] DEBUG - Request: [192.168.0.88:52054 (Subnet)] GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.113 [25332] DEBUG - Request: [192.168.0.88:52060 (Subnet)] GET /hubs/home/recentlyAdded?type=1&personal=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.113 [12012] DEBUG - Setting container serialization range to [0, 15] (total=44)
Jan 09, 2019 20:00:21.113 [23708] DEBUG - Completed: [127.0.0.1:55133] 200 GET /hubs/home/continueWatching (18 live) GZIP Page 0-15 25ms 2184 bytes (pipelined: 6)
Jan 09, 2019 20:00:21.114 [25964] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:21.114 [27328] DEBUG - Request: [127.0.0.1:55141 (Loopback)] GET /hubs/home/recentlyAdded?type=13 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.116 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.116 [25332] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:21.117 [23708] DEBUG - Completed: [192.168.0.88:52059] 200 GET /hubs/home/recentlyAdded?type=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 19ms 1855 bytes (pipelined: 1)
Jan 09, 2019 20:00:21.117 [27328] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.117 [27184] DEBUG - Completed: [127.0.0.1:55181] 200 GET /hubs/home/recentlyAdded?type=2 (18 live) GZIP Page 0-15 14ms 1014 bytes (pipelined: 1)
Jan 09, 2019 20:00:21.118 [26644] DEBUG - Request: [127.0.0.1:55133 (Loopback)] GET /hubs/home/recentlyAdded?type=8 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.118 [25536] DEBUG - It took 109.375000 ms to retrieve 16 items.
Jan 09, 2019 20:00:21.119 [27184] DEBUG - Completed: [127.0.0.1:55141] 200 GET /hubs/home/recentlyAdded?type=13 (18 live) GZIP Page 0-15 6ms 480 bytes (pipelined: 5)
Jan 09, 2019 20:00:21.119 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.119 [25536] DEBUG - Setting container serialization range to [0, 15] (total=47)
Jan 09, 2019 20:00:21.120 [26644] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:21.120 [27184] DEBUG - Completed: [127.0.0.1:55131] 200 GET /hubs/home/recentlyAdded?type=1 (18 live) GZIP Page 0-15 25ms 7698 bytes (pipelined: 5)
Jan 09, 2019 20:00:21.121 [25964] DEBUG - Request: [127.0.0.1:55181 (Loopback)] GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.122 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.122 [27184] DEBUG - Completed: [127.0.0.1:55133] 200 GET /hubs/home/recentlyAdded?type=8 (18 live) GZIP Page 0-15 4ms 480 bytes (pipelined: 7)
Jan 09, 2019 20:00:21.123 [20576] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.123 [27356] DEBUG - Request: [192.168.0.88:52061 (Subnet)] GET /hubs/home/recentlyAdded?type=2&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.124 [20576] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:21.124 [20576] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:21.125 [20576] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:21.125 [20576] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:21.125 [20576] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:21.125 [27184] DEBUG - Completed: [127.0.0.1:55180] 200 GET /hubs/home/recentlyAdded?type=1&personal=1 (18 live) GZIP Page 0-15 26ms 2531 bytes (pipelined: 1)
Jan 09, 2019 20:00:21.126 [25332] DEBUG - Setting container serialization range to [0, 13] (total=47)
Jan 09, 2019 20:00:21.129 [25964] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.129 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:21.130 [27356] DEBUG - It took 78.125000 ms to retrieve 0 items.
Jan 09, 2019 20:00:21.130 [26644] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /hubs/metadata/670/related?excludeFields=summary&count=12&includeExternalMetadata=1&asyncAugmentMetadata=1 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:21.130 [23708] DEBUG - Completed: [192.168.0.88:52060] 200 GET /hubs/home/recentlyAdded?type=1&personal=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 24ms 1408 bytes (pipelined: 1)
Jan 09, 2019 20:00:21.130 [20576] DEBUG - Activity: registered new activity 82dea647-ac27-4f16-a5d6-659653f375eb - Refreshing
Jan 09, 2019 20:00:21.130 [25964] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:21.131 [25964] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:21.131 [25964] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:21.131 [27124] DEBUG - Activity: updated activity 82dea647-ac27-4f16-a5d6-659653f375eb - completed 0% - Refreshing
Jan 09, 2019 20:00:21.131 [25964] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:21.131 [25964] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:21.131 [27356] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:21.133 [27124] DEBUG - Activity: updated activity 82dea647-ac27-4f16-a5d6-659653f375eb - completed 25% - Refreshing
Jan 09, 2019 20:00:21.133 [27124] DEBUG - Activity: updated activity 82dea647-ac27-4f16-a5d6-659653f375eb - completed 50% - Refreshing
Jan 09, 2019 20:00:21.134 [27124] DEBUG - Activity: updated activity 82dea647-ac27-4f16-a5d6-659653f375eb - completed 50% - Refreshing
Jan 09, 2019 20:00:21.134 [27124] DEBUG - Activity: Ended activity 82dea647-ac27-4f16-a5d6-659653f375eb.
Jan 09, 2019 20:00:21.134 [27356] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:21.134 [27184] DEBUG - Completed: [192.168.0.88:52054] 200 GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) TLS GZIP 24ms 5766 bytes (pipelined: 4)
Jan 09, 2019 20:00:21.136 [25964] DEBUG - Activity: registered new activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d - Refreshing
Jan 09, 2019 20:00:21.136 [27180] DEBUG - Activity: updated activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d - completed 0% - Refreshing
Jan 09, 2019 20:00:21.137 [23708] DEBUG - Completed: [127.0.0.1:55181] 200 GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) GZIP 17ms 5768 bytes (pipelined: 2)
Jan 09, 2019 20:00:21.137 [27184] DEBUG - Completed: [192.168.0.88:52061] 200 GET /hubs/home/recentlyAdded?type=2&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 14ms 1025 bytes (pipelined: 1)
Jan 09, 2019 20:00:21.138 [27180] DEBUG - Activity: updated activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d - completed 25% - Refreshing
Jan 09, 2019 20:00:21.138 [27180] DEBUG - Activity: updated activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d - completed 50% - Refreshing
Jan 09, 2019 20:00:21.138 [27180] DEBUG - Activity: updated activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d - completed 50% - Refreshing
Jan 09, 2019 20:00:21.138 [27180] DEBUG - Activity: Ended activity ed3f8fdc-c1c1-4b46-af7a-b2637546578d.
Jan 09, 2019 20:00:21.139 [26644] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:21.150 [27184] DEBUG - Completed: [127.0.0.1:55131] 200 GET /hubs/metadata/670/related?excludeFields=summary&count=12&includeExternalMetadata=1&asyncAugmentMetadata=1 (18 live) GZIP 21ms 473 bytes (pipelined: 6)
Jan 09, 2019 20:00:22.035 [12012] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.035 [12012] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.036 [12012] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\a1\a145abbb1d17c835232a38a0929328259b7df774.png is 32624.
Jan 09, 2019 20:00:22.036 [23708] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?format=png&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=260 (19 live) 1ms 32624 bytes (pipelined: 1)
Jan 09, 2019 20:00:22.039 [27328] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.040 [27328] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.040 [27328] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\d2\d20a8c1601a1a8facc7b25cb2f3735827a5ba0cc.png is 10683.
Jan 09, 2019 20:00:22.041 [27184] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?format=png&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=120 (19 live) 1ms 10683 bytes (pipelined: 2)
Jan 09, 2019 20:00:22.044 [25964] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.044 [25964] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.044 [25964] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\ab\ab96e8964b94322a84d0f7fd2490bb16259396ac.png is 3097.
Jan 09, 2019 20:00:22.045 [23708] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?format=png&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Epng&width=48 (19 live) 1ms 3097 bytes (pipelined: 3)
Jan 09, 2019 20:00:22.047 [25536] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.047 [25536] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.048 [25536] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\f9\f97ac7d1835a4f3a37b19ab76cfeea8e4e4fe733.jpg is 7852.
Jan 09, 2019 20:00:22.048 [27184] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=260&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=260 (19 live) 1ms 7852 bytes (pipelined: 4)
Jan 09, 2019 20:00:22.057 [12012] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.057 [12012] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.058 [12012] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\dd\dd48254469847a37e81af334403590fb6b815270.jpg is 3078.
Jan 09, 2019 20:00:22.058 [23708] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=120&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=120 (19 live) 1ms 3078 bytes (pipelined: 5)
Jan 09, 2019 20:00:22.069 [25332] DEBUG - Request: [127.0.0.1:55194 (Loopback)] GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48 (19 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:22.069 [25332] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 09, 2019 20:00:22.070 [25332] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\c8\c8901481ac00cfedfc4a23133e930a37c2df8459.jpg is 1137.
Jan 09, 2019 20:00:22.070 [27184] DEBUG - Completed: [127.0.0.1:55194] 200 GET /photo/:/transcode?chromaSubsampling=4%3A2%3A2&format=jpg&height=48&url=http%3A%2F%2F127%2E0%2E0%2E1%3A32400%2F%3A%2Fresources%2Fdlna-icon-260%2Ejpg&width=48 (19 live) 1ms 1137 bytes (pipelined: 6)
Jan 09, 2019 20:00:22.323 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.1:59891/rootDesc.xml
Jan 09, 2019 20:00:22.324 [24196] DEBUG - HTTP requesting GET http://192.168.0.1:59891/rootDesc.xml
Jan 09, 2019 20:00:22.325 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.1:59891/rootDesc.xml
Jan 09, 2019 20:00:22.326 [24196] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.1:59891/rootDesc.xml
Jan 09, 2019 20:00:22.326 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (DIR-878)
Jan 09, 2019 20:00:22.326 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANDevice)
Jan 09, 2019 20:00:22.326 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANConnectionDevice)
Jan 09, 2019 20:00:22.326 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.2:49152/description.xml
Jan 09, 2019 20:00:22.326 [24196] DEBUG - HTTP requesting GET http://192.168.0.2:49152/description.xml
Jan 09, 2019 20:00:22.343 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.2:49152/description.xml
Jan 09, 2019 20:00:22.344 [24196] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.2:49152/description.xml
Jan 09, 2019 20:00:22.344 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.2 (eufy Genie-16E8)
Jan 09, 2019 20:00:22.736 [24748] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Jan 09, 2019 20:00:22.945 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.37:1400/xml/device_description.xml
Jan 09, 2019 20:00:22.945 [24196] DEBUG - HTTP requesting GET http://192.168.0.37:1400/xml/device_description.xml
Jan 09, 2019 20:00:22.957 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.37:1400/xml/device_description.xml
Jan 09, 2019 20:00:22.959 [24196] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.37:1400/xml/device_description.xml
Jan 09, 2019 20:00:22.959 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (192.168.0.37 - Sonos One)
Jan 09, 2019 20:00:22.959 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (192.168.0.37 - Sonos One Media Server)
Jan 09, 2019 20:00:22.960 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (Kitchen - Sonos One Media Renderer)
Jan 09, 2019 20:00:23.050 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.63:1400/xml/device_description.xml
Jan 09, 2019 20:00:23.050 [24196] DEBUG - HTTP requesting GET http://192.168.0.63:1400/xml/device_description.xml
Jan 09, 2019 20:00:23.065 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.63:1400/xml/device_description.xml
Jan 09, 2019 20:00:23.066 [24196] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.63:1400/xml/device_description.xml
Jan 09, 2019 20:00:23.066 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (192.168.0.63 - Sonos Connect:Amp)
Jan 09, 2019 20:00:23.066 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (192.168.0.63 - Sonos Connect:Amp Media Server)
Jan 09, 2019 20:00:23.066 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (Living Room - Sonos Connect:Amp Media Renderer)
Jan 09, 2019 20:00:23.274 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 09, 2019 20:00:23.274 [24196] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 09, 2019 20:00:23.290 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 09, 2019 20:00:23.291 [24196] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 09, 2019 20:00:23.291 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 09, 2019 20:00:23.612 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.612 [26644] DEBUG - Request: [127.0.0.1:55131 (Loopback)] POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2Fbb7b1b3d8dd2f1546869f50ba0c70e4e620374ea%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F670&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.613 [26644] DEBUG - PlayQueue: Converted 'server://bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/com.plexapp.plugins.library/library/metadata/670' to 'library://x/item/%2Flibrary%2Fmetadata%2F670'
Jan 09, 2019 20:00:23.613 [26644] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 09, 2019 20:00:23.618 [26644] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Jan 09, 2019 20:00:23.618 [26644] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Jan 09, 2019 20:00:23.619 [26644] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Jan 09, 2019 20:00:23.624 [26644] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:23.626 [26644] DEBUG - Extras: Preroll values looks like a file, and we had 1 of them.
Jan 09, 2019 20:00:23.626 [26644] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
Jan 09, 2019 20:00:23.626 [26644] DEBUG - Extras: Looking for extras for item com.plexapp.agents.imdb://tt1119646?lang=en with unwatched: 1 fromLibrary: 1 fromTheater: 0 fromBluray: 0 and preroll count: 0.
Jan 09, 2019 20:00:23.626 [26644] DEBUG - Extras: Using allowable content ratings: , NC-17, NR, PG-13, R.
Jan 09, 2019 20:00:23.627 [26644] DEBUG - Extras: Using genres: Comedy.
Jan 09, 2019 20:00:23.628 [26644] DEBUG - Extras: Found 0 extras from library.
Jan 09, 2019 20:00:23.631 [26644] DEBUG - It took 15.625000 ms to retrieve 132 items.
Jan 09, 2019 20:00:23.633 [26644] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:23.633 [26644] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:23.633 [26644] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:23.633 [26644] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:23.633 [26644] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:23.635 [23708] DEBUG - Completed: [127.0.0.1:55131] 200 POST /playQueues?type=video&extrasPrefixCount=0&uri=server%3A%2F%2Fbb7b1b3d8dd2f1546869f50ba0c70e4e620374ea%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F670&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (19 live) GZIP 22ms 5729 bytes (pipelined: 7)
Jan 09, 2019 20:00:23.709 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.710 [20576] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=km9yixm57gsfa1zokcvg3tpt&subtitles=sidecar&Accept-Language=en (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.710 [20576] DEBUG - Found session GUID of km9yixm57gsfa1zokcvg3tpt in session start.
Jan 09, 2019 20:00:23.712 [20576] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2019 20:00:23.713 [20576] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/670?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:23.713 [20576] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.720 [20576] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:23.722 [20576] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:23.723 [20576] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:23.723 [20576] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:23.723 [20576] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:23.723 [20576] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: Selected protocol http; container: ass
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: analyzing media item 10703
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: The Hangover (2009): no direct play video profile exists for http/mp4/h264
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: The Hangover (2009): no direct play video profile exists for http/mp4/h264/aac
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: The Hangover (2009): transcoding selected subtitle stream
Jan 09, 2019 20:00:23.731 [20576] DEBUG - MDE: The Hangover (2009): selected media 0 / 10703
Jan 09, 2019 20:00:23.731 [20576] DEBUG - Streaming Resource: Added session 0x7faf808:udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:23.731 [20576] DEBUG - Streaming Resource: Reached Decision id=670 codes=(MDE=1000,Direct play OK.) media=(id=10703 part=(id=10751 decision=direct play protocol=dash streams=(Video=(id=21754 decision= width=0 height=0) Audio=(id=21755 decision= channels=0 rate=0) Subtitle=(id=21769 decision=transcode bitrate=0 encoder=ass languageCode=eng location=sidecar))))
Jan 09, 2019 20:00:23.733 [27184] DEBUG - Completed: [127.0.0.1:55131] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=km9yixm57gsfa1zokcvg3tpt&subtitles=sidecar&Accept-Language=en (19 live) GZIP 23ms 5590 bytes (pipelined: 8)
Jan 09, 2019 20:00:23.893 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.894 [25964] DEBUG - Request: [127.0.0.1:55131 (Loopback)] GET /subtitles/:/transcode/universal/start?hasMDE=1&path=%2Flibrary%2Fmetadata%2F670&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=1&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&mediaBufferSize=102400&session=km9yixm57gsfa1zokcvg3tpt&subtitles=sidecar&copyts=1&Accept-Language=en (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.895 [25964] DEBUG - Found session GUID of km9yixm57gsfa1zokcvg3tpt in session start.
Jan 09, 2019 20:00:23.896 [25964] DEBUG - TranscodeUniversalRequest: using augmented profile Web
Jan 09, 2019 20:00:23.897 [25964] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/670?checkFiles=1&includeBandwidths=1&offset=0&X-Plex-Session-Identifier=udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:23.897 [25964] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.903 [25964] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:23.904 [25964] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:23.904 [25964] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:23.905 [25964] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:23.905 [25964] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:23.905 [25964] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:23.907 [25964] DEBUG - Cleaning directory for session km9yixm57gsfa1zokcvg3tpt ()
Jan 09, 2019 20:00:23.907 [25964] DEBUG - Starting a transcode session km9yixm57gsfa1zokcvg3tpt at offset -1.0 (state=3)
Jan 09, 2019 20:00:23.910 [25964] DEBUG - [Universal] Using local file path instead of URL: D:\Plex\Movies\The.Hangover.2009.UNRATED.720p.BrRip.x264.YIFY.mp4
Jan 09, 2019 20:00:23.910 [25964] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:23.911 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:23.911 [27356] DEBUG - Request: [127.0.0.1:55206 (Loopback)] GET /library/streams/21769 (20 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.913 [27356] DEBUG - Calculated media file path for path [media://f/23445b050ec9e65accfc90cca7cca6e8fda093b.bundle/Contents/Subtitles/en/com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt]: ["C:\Users\Administrator\AppData\Local\Plex Media Server\Media\localhost/f/23445b050ec9e65accfc90cca7cca6e8fda093b.bundle/Contents/Subtitles/en/com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt"]
Jan 09, 2019 20:00:23.913 [27356] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Media\localhost\f\23445b050ec9e65accfc90cca7cca6e8fda093b.bundle\Contents\Subtitles\en\com.plexapp.agents.opensubtitles_7ca465ec20cc9d2c6e56da916b51262f13a8cbe2.srt is 50066.
Jan 09, 2019 20:00:23.914 [25964] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 09, 2019 20:00:23.914 [23708] DEBUG - Completed: [127.0.0.1:55206] 200 GET /library/streams/21769 (20 live) GZIP 2ms 50066 bytes
Jan 09, 2019 20:00:23.914 [25964] DEBUG - Detected character set of UTF-8.
Jan 09, 2019 20:00:23.921 [25964] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/21769?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: srt) to temporary file [C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-km9yixm57gsfa1zokcvg3tpt-4e87b05d-fc8e-4cb4-be2c-fae3e3321de5\temp-0.srt]
Jan 09, 2019 20:00:23.923 [25964] DEBUG - Job running: EAE_ROOT='\\?\C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='C\:\\Users\\Administrator\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\531e313-1328-windows-i386\\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' D:\Program files\Plex\Plex Media Server\PlexTranscoder.exe -probesize 10000000 -i D:\Plex\Movies\The.Hangover.2009.UNRATED.720p.BrRip.x264.YIFY.mp4 -probesize 10000000 -i "C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-km9yixm57gsfa1zokcvg3tpt-4e87b05d-fc8e-4cb4-be2c-fae3e3321de5\temp-0.srt" -map 1:s:0 -metadata:s:0 language=eng -codec:0 ass -f segment -segment_format ass -segment_time 1 -segment_header_filename header -segment_start_number 0 -segment_list http://127.0.0.1:32400/video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/seglist -segment_list_type csv -segment_list_size 2147483647 -segment_list_separate_stream_times 1 -avoid_negative_ts disabled -map_metadata -1 -map_chapters -1 chunk-%05d -start_at_zero -copyts -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress 
Jan 09, 2019 20:00:23.929 [25964] DEBUG - Jobs: Starting child process with pid 26936
Jan 09, 2019 20:00:23.987 [27328] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=startup (21 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.988 [23708] DEBUG - Completed: [127.0.0.1:55207] 204 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=startup (21 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Jan 09, 2019 20:00:23.989 [25536] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opening (21 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:23.989 [23708] DEBUG - Completed: [127.0.0.1:55207] 204 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opening (21 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Jan 09, 2019 20:00:24.042 [12012] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opened (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.042 [23708] DEBUG - Completed: [127.0.0.1:55207] 204 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opened (16 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Jan 09, 2019 20:00:24.043 [25332] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/stream?index=0&id=1&codec=h264&type=video (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.043 [23708] DEBUG - Completed: [127.0.0.1:55207] 206 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/stream?index=0&id=1&codec=h264&type=video (16 live) 0ms 256 bytes (pipelined: 4) (range: bytes=0-) 
Jan 09, 2019 20:00:24.057 [26644] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/stream?index=1&id=2&codec=aac&type=audio&profile=HE-AAC (16 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.058 [27184] DEBUG - Completed: [127.0.0.1:55207] 206 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/stream?index=1&id=2&codec=aac&type=audio&profile=HE-AAC (16 live) 0ms 256 bytes (pipelined: 5) (range: bytes=0-) 
Jan 09, 2019 20:00:24.059 [27356] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=772860&profile=High&language=und&width=1280&height=536&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (15 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.059 [27184] DEBUG - Completed: [127.0.0.1:55207] 206 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/streamDetail?index=0&id=1&codec=h264&type=video&bitrate=772860&profile=High&language=und&width=1280&height=536&interlaced=0&sar=1:1&level=31&frameRate=23.976&disp_default=1 (15 live) 0ms 256 bytes (pipelined: 6) (range: bytes=0-) 
Jan 09, 2019 20:00:24.060 [27328] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/streamDetail?index=1&id=2&codec=aac&type=audio&profile=HE-AAC&language=und&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (15 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.060 [27184] DEBUG - Completed: [127.0.0.1:55207] 206 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress/streamDetail?index=1&id=2&codec=aac&type=audio&profile=HE-AAC&language=und&channels=2&layout=2%20channels&sampleRate=48000&disp_default=1 (15 live) 0ms 256 bytes (pipelined: 7) (range: bytes=0-) 
Jan 09, 2019 20:00:24.061 [20576] DEBUG - Request: [127.0.0.1:55207 (Loopback)] PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opening (15 live) Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.061 [23708] DEBUG - Completed: [127.0.0.1:55207] 204 PUT /video/:/transcode/session/km9yixm57gsfa1zokcvg3tpt/4e87b05d-fc8e-4cb4-be2c-fae3e3321de5/progress?status=opening (15 live) 0ms 203 bytes (pipelined: 8) (range: bytes=0-) 
Jan 09, 2019 20:00:24.077 [25536] ERROR - [Transcoder] C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-km9yixm57gsfa1zokcvg3tpt-4e87b05d-fc8e-4cb4-be2c-fae3e3321de5\temp-0.srt: Invalid data found when processing input
Jan 09, 2019 20:00:24.087 [24748] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\PlexTranscoder.exe' exit code for process 26936 is 1 (failure)
Jan 09, 2019 20:00:24.087 [22184] DEBUG - Streaming Resource: Terminated session 0x7faf808:udka48lwvq4u6sf9rdjb9yrg with reason Conversion failed. The transcoder exited due to an error.
Jan 09, 2019 20:00:24.087 [25964] DEBUG - Using default transcode duration of 120 minutes
Jan 09, 2019 20:00:24.087 [25964] DEBUG - Started session successfully: km9yixm57gsfa1zokcvg3tpt
Jan 09, 2019 20:00:24.088 [11312] DEBUG - Killing job.
Jan 09, 2019 20:00:24.088 [11312] DEBUG - Job was already killed, not killing again.
Jan 09, 2019 20:00:24.088 [11312] DEBUG - Stopping transcode session km9yixm57gsfa1zokcvg3tpt
Jan 09, 2019 20:00:24.088 [27124] DEBUG - Cleaning directory for session km9yixm57gsfa1zokcvg3tpt (C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-km9yixm57gsfa1zokcvg3tpt-4e87b05d-fc8e-4cb4-be2c-fae3e3321de5)
Jan 09, 2019 20:00:24.088 [25964] ERROR - [TranscodeOutputStream] Streaming Session 0x7faf808 appears to have died from under us
Jan 09, 2019 20:00:24.091 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:24.091 [25332] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /status/sessions (16 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.092 [25332] DEBUG - [Now] Adding 0 sessions.
Jan 09, 2019 20:00:24.092 [23708] DEBUG - Completed: [127.0.0.1:55208] 200 GET /status/sessions (16 live) GZIP 1ms 397 bytes (pipelined: 1)
Jan 09, 2019 20:00:24.111 [11312] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 09, 2019 20:00:24.112 [11312] DEBUG - Whacked session km9yixm57gsfa1zokcvg3tpt, 0 remaining.
Jan 09, 2019 20:00:24.270 [27180] DEBUG - Activity: Ended activity 145122d6-eddc-48f3-8096-846c370f4e17.
Jan 09, 2019 20:00:24.270 [27180] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 09, 2019 20:00:24.924 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:24.925 [12012] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1002&playQueueItemID=842&state=playing&hasMDE=1&time=0&duration=6473000 (16 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.927 [12012] DEBUG - Client [udka48lwvq4u6sf9rdjb9yrg] reporting timeline state playing, progress of 0/6473000ms for guid=, ratingKey=670 url=, key=/library/metadata/670, containerKey=, metadataId=670, source=
Jan 09, 2019 20:00:24.932 [12012] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:24.938 [12012] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:24.939 [12012] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:24.939 [12012] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:24.939 [12012] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:24.939 [12012] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:24.939 [12012] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:24.941 [12012] DEBUG - [Now] User is dvirgassner (ID: 1)
Jan 09, 2019 20:00:24.941 [12012] DEBUG - [Now] Device is Chrome (Chrome).
Jan 09, 2019 20:00:24.943 [12012] DEBUG - [Now] Profile is Web
Jan 09, 2019 20:00:24.943 [12012] DEBUG - [Now] Updated play state for /library/metadata/670.
Jan 09, 2019 20:00:24.943 [11312] DEBUG - Cleaning directory for session km9yixm57gsfa1zokcvg3tpt (C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-km9yixm57gsfa1zokcvg3tpt-4e87b05d-fc8e-4cb4-be2c-fae3e3321de5)
Jan 09, 2019 20:00:24.943 [12012] DEBUG - Statistics: (dg8r5z6hx7f6x4jz5de35dp6) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1
Jan 09, 2019 20:00:24.945 [23708] DEBUG - Completed: [127.0.0.1:55208] 200 GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1002&playQueueItemID=842&state=playing&hasMDE=1&time=0&duration=6473000 (16 live) GZIP 20ms 519 bytes (pipelined: 2)
Jan 09, 2019 20:00:24.948 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:24.948 [26644] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /status/sessions (17 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.948 [26644] DEBUG - [Now] Adding 1 sessions.
Jan 09, 2019 20:00:24.950 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:24.951 [27184] DEBUG - Completed: [127.0.0.1:55208] 200 GET /status/sessions (17 live) GZIP 2ms 5721 bytes (pipelined: 3)
Jan 09, 2019 20:00:24.951 [27356] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /playQueues/460?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (17 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:24.953 [27356] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0
Jan 09, 2019 20:00:24.953 [27356] DEBUG - PlayQueue: No need to refresh, active window is operational.
Jan 09, 2019 20:00:24.967 [27356] DEBUG - It took 15.625000 ms to retrieve 132 items.
Jan 09, 2019 20:00:24.968 [27356] DEBUG - PlayQueue: Client dg8r5z6hx7f6x4jz5de35dp6 requested ownership of play queue 460, but already had it.
Jan 09, 2019 20:00:24.970 [27356] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:24.970 [27356] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:24.971 [27356] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:24.971 [27356] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:24.971 [27356] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:24.973 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 GET /playQueues/460?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (17 live) GZIP 21ms 5656 bytes (pipelined: 1)
Jan 09, 2019 20:00:25.016 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.017 [27328] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1002&playQueueItemID=842&state=stopped&hasMDE=1&time=1000&duration=6473000 (17 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.018 [27328] DEBUG - Client [udka48lwvq4u6sf9rdjb9yrg] reporting timeline state stopped, progress of 1000/6473000ms for guid=, ratingKey=670 url=, key=/library/metadata/670, containerKey=, metadataId=670, source=
Jan 09, 2019 20:00:25.021 [27328] DEBUG - It took 15.625000 ms to retrieve 132 items.
Jan 09, 2019 20:00:25.023 [27328] DEBUG - [Now] User is dvirgassner (ID: 1)
Jan 09, 2019 20:00:25.023 [27328] DEBUG - [Now] Device is Chrome (Chrome).
Jan 09, 2019 20:00:25.025 [27328] DEBUG - [Now] Profile is Web
Jan 09, 2019 20:00:25.025 [27328] DEBUG - [Now] Updated play state for /library/metadata/670.
Jan 09, 2019 20:00:25.025 [27328] DEBUG - Statistics: (dg8r5z6hx7f6x4jz5de35dp6) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1
Jan 09, 2019 20:00:25.026 [27328] DEBUG - Streaming Resource: Removing session 0x7faf808:udka48lwvq4u6sf9rdjb9yrg
Jan 09, 2019 20:00:25.027 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 GET /:/timeline?ratingKey=670&key=%2Flibrary%2Fmetadata%2F670&playbackTime=1002&playQueueItemID=842&state=stopped&hasMDE=1&time=1000&duration=6473000 (17 live) GZIP 10ms 519 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.040 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.042 [25964] DEBUG - Request: [192.168.0.88:52061 (Subnet)] GET /hubs/home/continueWatching?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (16 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.048 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.048 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.049 [20576] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /hubs/home/continueWatching (16 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.049 [12012] DEBUG - Request: [192.168.0.88:52054 (Subnet)] GET /hubs/home/recentlyAdded?type=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (16 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.050 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.050 [25536] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /hubs/home/onDeck (15 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.051 [12012] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:25.052 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.053 [26644] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (16 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.054 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.054 [26644] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.055 [25332] DEBUG - Request: [127.0.0.1:55223 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.055 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.056 [25332] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.057 [25964] DEBUG - It took 62.500000 ms to retrieve 10 items.
Jan 09, 2019 20:00:25.057 [27356] DEBUG - Request: [127.0.0.1:55224 (Loopback)] GET /hubs/home/recentlyAdded?type=2 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.058 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.060 [20576] DEBUG - It took 62.500000 ms to retrieve 9 items.
Jan 09, 2019 20:00:25.061 [25964] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:25.063 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.064 [27184] DEBUG - Completed: [192.168.0.88:52061] 200 GET /hubs/home/continueWatching?excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 22ms 1037 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.064 [27328] DEBUG - Request: [192.168.0.88:52060 (Subnet)] GET /hubs/home/recentlyAdded?type=1&personal=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.065 [27328] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:25.066 [27356] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.067 [12012] DEBUG - Setting container serialization range to [0, 13] (total=44)
Jan 09, 2019 20:00:25.067 [25332] DEBUG - It took 93.750000 ms to retrieve 16 items.
Jan 09, 2019 20:00:25.068 [25536] DEBUG - It took 93.750000 ms to retrieve 10 items.
Jan 09, 2019 20:00:25.068 [15768] DEBUG - Request: [192.168.0.88:52059 (Subnet)] GET /hubs/home/recentlyAdded?type=2&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS Page 0-13 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.068 [26644] DEBUG - Setting container serialization range to [0, 15] (total=44)
Jan 09, 2019 20:00:25.069 [25332] DEBUG - Setting container serialization range to [0, 15] (total=47)
Jan 09, 2019 20:00:25.070 [27356] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:25.070 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.071 [25536] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.072 [23708] DEBUG - Completed: [127.0.0.1:55224] 200 GET /hubs/home/recentlyAdded?type=2 (18 live) GZIP Page 0-15 15ms 1014 bytes (pipelined: 1)
Jan 09, 2019 20:00:25.073 [27184] DEBUG - Completed: [127.0.0.1:55208] 200 GET /hubs/home/onDeck (18 live) GZIP Page 0-15 22ms 480 bytes (pipelined: 4)
Jan 09, 2019 20:00:25.073 [20576] DEBUG - It took 93.750000 ms to retrieve 10 items.
Jan 09, 2019 20:00:25.074 [26980] DEBUG - Request: [192.168.0.88:52061 (Subnet)] GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.075 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.075 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.076 [23708] DEBUG - Completed: [127.0.0.1:55223] 200 GET /hubs/home/recentlyAdded?type=1&personal=1 (18 live) GZIP Page 0-15 21ms 2531 bytes (pipelined: 1)
Jan 09, 2019 20:00:25.077 [27356] DEBUG - Request: [127.0.0.1:55224 (Loopback)] GET /hubs/home/recentlyAdded?type=8 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.078 [27328] DEBUG - Setting container serialization range to [0, 13] (total=47)
Jan 09, 2019 20:00:25.078 [27184] DEBUG - Completed: [127.0.0.1:55222] 200 GET /hubs/home/recentlyAdded?type=1 (18 live) GZIP Page 0-15 23ms 7698 bytes (pipelined: 1)
Jan 09, 2019 20:00:25.079 [20576] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.079 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.079 [15768] DEBUG - Setting container serialization range to [0, 13] (total=-1)
Jan 09, 2019 20:00:25.079 [25332] DEBUG - Request: [127.0.0.1:55223 (Loopback)] GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.080 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:25.081 [25964] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /hubs/home/recentlyAdded?type=13 (18 live) Page 0-15 GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.081 [23708] DEBUG - Completed: [192.168.0.88:52054] 200 GET /hubs/home/recentlyAdded?type=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 32ms 1855 bytes (pipelined: 5)
Jan 09, 2019 20:00:25.082 [26644] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /hubs/metadata/670/related?excludeFields=summary&count=12&includeExternalMetadata=1&asyncAugmentMetadata=1 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:25.082 [25964] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.082 [27356] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Jan 09, 2019 20:00:25.084 [23708] DEBUG - Completed: [127.0.0.1:55224] 200 GET /hubs/home/recentlyAdded?type=8 (18 live) GZIP Page 0-15 7ms 480 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.083 [15768] DEBUG - There were 1 top-level paths for Fauda Season 2.
Jan 09, 2019 20:00:25.084 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 GET /hubs/home/continueWatching (18 live) GZIP Page 0-15 35ms 2184 bytes (pipelined: 3)
Jan 09, 2019 20:00:25.085 [27184] DEBUG - Completed: [127.0.0.1:55208] 200 GET /hubs/home/recentlyAdded?type=13 (18 live) GZIP Page 0-15 8ms 480 bytes (pipelined: 5)
Jan 09, 2019 20:00:25.085 [23708] DEBUG - Completed: [192.168.0.88:52060] 200 GET /hubs/home/recentlyAdded?type=1&personal=1&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 25ms 1408 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.085 [26980] DEBUG - It took 31.250000 ms to retrieve 132 items.
Jan 09, 2019 20:00:25.087 [26980] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:25.087 [26980] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:25.087 [26980] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:25.088 [26980] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:25.088 [26980] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:25.088 [23708] DEBUG - Completed: [192.168.0.88:52059] 200 GET /hubs/home/recentlyAdded?type=2&excludeElements=Actor%2CCollection%2CCountry%2CDirector%2CGenre%2CLabel%2CMood%2CPart%2CProducer%2CRole%2CSimilar%2CWriter%2CPhoto%2CMedia&excludeFields=file%2Csummary%2Ctagline (18 live) TLS GZIP Page 0-13 24ms 1025 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.092 [26980] DEBUG - Activity: registered new activity 040b59bc-5911-4476-a6d6-58136daba74f - Refreshing
Jan 09, 2019 20:00:25.092 [24748] DEBUG - Activity: updated activity 040b59bc-5911-4476-a6d6-58136daba74f - completed 0% - Refreshing
Jan 09, 2019 20:00:25.092 [26644] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:25.094 [24748] DEBUG - Activity: updated activity 040b59bc-5911-4476-a6d6-58136daba74f - completed 25% - Refreshing
Jan 09, 2019 20:00:25.095 [25332] DEBUG - It took 0.000000 ms to retrieve 132 items.
Jan 09, 2019 20:00:25.095 [23708] DEBUG - Completed: [192.168.0.88:52061] 200 GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) TLS GZIP 23ms 5768 bytes (pipelined: 3)
Jan 09, 2019 20:00:25.095 [24748] DEBUG - Activity: updated activity 040b59bc-5911-4476-a6d6-58136daba74f - completed 50% - Refreshing
Jan 09, 2019 20:00:25.095 [24748] DEBUG - Activity: updated activity 040b59bc-5911-4476-a6d6-58136daba74f - completed 50% - Refreshing
Jan 09, 2019 20:00:25.096 [24748] DEBUG - Activity: Ended activity 040b59bc-5911-4476-a6d6-58136daba74f.
Jan 09, 2019 20:00:25.096 [25332] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jan 09, 2019 20:00:25.096 [25332] DEBUG - Selecting best audio stream for part ID 10751 (autoselect: 1 language: en)
Jan 09, 2019 20:00:25.097 [25332] DEBUG - We're going to try to auto-select a subtitle.
Jan 09, 2019 20:00:25.097 [25332] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Jan 09, 2019 20:00:25.097 [25332] DEBUG - Audio Stream: 21755, Subtitle Stream: 21769
Jan 09, 2019 20:00:25.100 [25332] DEBUG - Activity: registered new activity 229131fa-84f8-4a31-94e8-eb14bd83842b - Refreshing
Jan 09, 2019 20:00:25.100 [27180] DEBUG - Activity: updated activity 229131fa-84f8-4a31-94e8-eb14bd83842b - completed 0% - Refreshing
Jan 09, 2019 20:00:25.101 [27180] DEBUG - Activity: updated activity 229131fa-84f8-4a31-94e8-eb14bd83842b - completed 25% - Refreshing
Jan 09, 2019 20:00:25.101 [27184] DEBUG - Completed: [127.0.0.1:55223] 200 GET /library/metadata/670?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&asyncAugmentMetadata=1&checkFiles=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (18 live) GZIP 21ms 5768 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.102 [27180] DEBUG - Activity: updated activity 229131fa-84f8-4a31-94e8-eb14bd83842b - completed 50% - Refreshing
Jan 09, 2019 20:00:25.102 [27180] DEBUG - Activity: updated activity 229131fa-84f8-4a31-94e8-eb14bd83842b - completed 50% - Refreshing
Jan 09, 2019 20:00:25.102 [23708] DEBUG - Completed: [127.0.0.1:55222] 200 GET /hubs/metadata/670/related?excludeFields=summary&count=12&includeExternalMetadata=1&asyncAugmentMetadata=1 (18 live) GZIP 20ms 473 bytes (pipelined: 2)
Jan 09, 2019 20:00:25.102 [27180] DEBUG - Activity: Ended activity 229131fa-84f8-4a31-94e8-eb14bd83842b.
Jan 09, 2019 20:00:26.360 [24196] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.62:32469/DeviceDescription.xml
Jan 09, 2019 20:00:26.360 [24196] DEBUG - HTTP requesting GET http://192.168.0.62:32469/DeviceDescription.xml
Jan 09, 2019 20:00:26.362 [24196] DEBUG - HTTP 200 response from GET http://192.168.0.62:32469/DeviceDescription.xml
Jan 09, 2019 20:00:26.363 [24196] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.62:32469/DeviceDescription.xml
Jan 09, 2019 20:00:26.363 [24196] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.62 (Plex Media Server: Fox)
Jan 09, 2019 20:00:29.091 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.091 [25536] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /:/prefs (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.091 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.092 [12012] DEBUG - Request: [127.0.0.1:55223 (Loopback)] GET /accounts/1 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.092 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.093 [27356] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /myplex/account (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.093 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.094 [27184] DEBUG - Completed: [127.0.0.1:55223] 200 GET /accounts/1 (18 live) GZIP 2ms 521 bytes (pipelined: 3)
Jan 09, 2019 20:00:29.094 [25964] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /system/:/prefs (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.095 [25964] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Jan 09, 2019 20:00:29.095 [27184] DEBUG - Completed: [127.0.0.1:55208] 200 GET /myplex/account (18 live) GZIP 1ms 1152 bytes (pipelined: 6)
Jan 09, 2019 20:00:29.095 [25964] DEBUG - HTTP requesting GET http://127.0.0.1:54840/system/:/prefs
Jan 09, 2019 20:00:29.102 [25964] DEBUG - HTTP 304 response from GET http://127.0.0.1:54840/system/:/prefs
Jan 09, 2019 20:00:29.102 [25964] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
Jan 09, 2019 20:00:29.102 [27184] DEBUG - Completed: [127.0.0.1:55220] 304 GET /system/:/prefs (18 live) GZIP 8ms 324 bytes (pipelined: 4)
Jan 09, 2019 20:00:29.114 [27184] DEBUG - Completed: [127.0.0.1:55222] 200 GET /:/prefs (18 live) GZIP 23ms 6799 bytes (pipelined: 3)
Jan 09, 2019 20:00:29.205 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.206 [15768] DEBUG - Request: [127.0.0.1:55222 (Loopback)] PUT /myplex/refreshReachability (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.206 [15768] DEBUG - MyPlex: Requesting reachability check.
Jan 09, 2019 20:00:29.207 [15768] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=4845c598-4f02-408f-8b55-f19348d48168
Jan 09, 2019 20:00:29.212 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.213 [26980] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /updater/status (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.213 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 GET /updater/status (18 live) GZIP 1ms 455 bytes (pipelined: 5)
Jan 09, 2019 20:00:29.239 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:29.239 [25332] DEBUG - Request: [127.0.0.1:55220 (Loopback)] PUT /updater/check?download=0 (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:29.240 [25332] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476, download: NO
Jan 09, 2019 20:00:29.240 [25332] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:29.240 [25332] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:29.599 [25332] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.14.1.5488-cc260c476
Jan 09, 2019 20:00:29.600 [25332] INFO - AutoUpdate: no updates available
Jan 09, 2019 20:00:29.600 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 PUT /updater/check?download=0 (18 live) GZIP 360ms 268 bytes (pipelined: 6)
Jan 09, 2019 20:00:29.601 [15768] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=4845c598-4f02-408f-8b55-f19348d48168
Jan 09, 2019 20:00:29.601 [27184] DEBUG - Completed: [127.0.0.1:55222] 200 PUT /myplex/refreshReachability (18 live) GZIP 395ms 268 bytes (pipelined: 4)
Jan 09, 2019 20:00:30.115 [26644] DEBUG - Request: [52.49.250.227:39286 (WAN)] GET /identity (19 live) TLS Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.116 [23708] DEBUG - Completed: [52.49.250.227:39286] 200 GET /identity (19 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 09, 2019 20:00:30.322 [23708] DEBUG - EventSource: Got event [data] '<Message address="5.29.101.237" port="18609" asyncIdentifier="4845c598-4f02-408f-8b55-f19348d48168" connectivity="1" command="notifyConnectivity"/>'
Jan 09, 2019 20:00:30.322 [23708] DEBUG - PubSub: Got notified of reachability: 1 for 5.29.101.237:18609
Jan 09, 2019 20:00:30.329 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:30.329 [27328] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /myplex/account (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.330 [27184] DEBUG - Completed: [127.0.0.1:55222] 200 GET /myplex/account (19 live) GZIP 1ms 1152 bytes (pipelined: 5)
Jan 09, 2019 20:00:30.704 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:30.705 [20576] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /:/prefs (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.706 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:30.707 [15768] DEBUG - Request: [127.0.0.1:55220 (Loopback)] GET /accounts/1 (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.708 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:30.709 [25536] DEBUG - Request: [127.0.0.1:55208 (Loopback)] GET /myplex/account (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.709 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:30.709 [27184] DEBUG - Completed: [127.0.0.1:55220] 200 GET /accounts/1 (19 live) GZIP 2ms 521 bytes (pipelined: 7)
Jan 09, 2019 20:00:30.710 [26980] DEBUG - Request: [127.0.0.1:55223 (Loopback)] GET /system/:/prefs (19 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:30.710 [27184] DEBUG - Completed: [127.0.0.1:55208] 200 GET /myplex/account (19 live) GZIP 2ms 1152 bytes (pipelined: 7)
Jan 09, 2019 20:00:30.711 [26980] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Jan 09, 2019 20:00:30.711 [26980] DEBUG - HTTP requesting GET http://127.0.0.1:54840/system/:/prefs
Jan 09, 2019 20:00:30.717 [26980] DEBUG - HTTP 304 response from GET http://127.0.0.1:54840/system/:/prefs
Jan 09, 2019 20:00:30.718 [26980] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.
Jan 09, 2019 20:00:30.718 [27184] DEBUG - Completed: [127.0.0.1:55223] 304 GET /system/:/prefs (19 live) GZIP 8ms 324 bytes (pipelined: 4)
Jan 09, 2019 20:00:30.731 [27184] DEBUG - Completed: [127.0.0.1:55222] 200 GET /:/prefs (19 live) GZIP 25ms 6799 bytes (pipelined: 6)
Jan 09, 2019 20:00:32.553 [27184] DEBUG - handleStreamRead code 335544539: short read
Jan 09, 2019 20:00:32.563 [23708] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:32.563 [25332] DEBUG - Request: [192.168.0.88:52061 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (18 live) TLS GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:32.564 [25332] DEBUG - Beginning read from two-way stream.
Jan 09, 2019 20:00:32.770 [27184] DEBUG - Auth: authenticated user 1 as dvirgassner
Jan 09, 2019 20:00:32.771 [25964] DEBUG - Request: [127.0.0.1:55222 (Loopback)] GET /diagnostics/logs (18 live) GZIP Signed-in Token (dvirgassner)
Jan 09, 2019 20:00:32.771 [25964] DEBUG - Diagnostics: Building logfile zip
