Jan 05, 2019 21:37:17.007 [15972] INFO - Plex Media Server v1.13.9.5456-ecd600442 - Microsoft PC x64 - build: windows-i386 english - GMT 02:00
Jan 05, 2019 21:37:17.009 [15972] INFO - Windows version: 10.0 (Build 17763), language en-US
Jan 05, 2019 21:37:17.063 [15972] INFO - 8 3592 MHz processor(s): Architecture=0, Level=6, Revision=15363 Processor Identifier=Intel64 Family 6 Model 60 Stepping 3, GenuineIntel
Jan 05, 2019 21:37:17.063 [15972] DEBUG - "D:\Program files\Plex\Plex Media Server\Plex Media Server.exe" 
Jan 05, 2019 21:37:17.086 [15860] DEBUG - Plex for Windows Store application is not installed
Jan 05, 2019 21:37:17.174 [15860] DEBUG - BPQ: [Idle] -> [Starting]
Jan 05, 2019 21:37:17.234 [15860] DEBUG - FeatureManager: Using cached data for features list
Jan 05, 2019 21:37:17.260 [15860] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Jan 05, 2019 21:37:17.278 [15860] INFO - SQLITE3:0x3d6da5b1, 283, recovered 113 frames from WAL file C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.db-wal
Jan 05, 2019 21:37:17.332 [15860] DEBUG - Running migrations. (EPG 0)
Jan 05, 2019 21:37:17.355 [15860] DEBUG - ChangestampAllocator: initialized to 200773
Jan 05, 2019 21:37:17.386 [15860] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.13.0, threadsafe=1
Jan 05, 2019 21:37:17.413 [15860] INFO - SQLITE3:0x3d6da5b1, 283, recovered 945 frames from WAL file C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.blobs.db-wal
Jan 05, 2019 21:37:17.417 [15860] DEBUG - Running migrations. (EPG 0)
Jan 05, 2019 21:37:18.447 [15860] 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 05, 2019 21:37:18.447 [15860] DEBUG - CERT: Installed new private key.
Jan 05, 2019 21:37:18.447 [15860] DEBUG - CERT: Subject name is /C=US/ST=California/L=Los Gatos/O=Plex, Inc./CN=*.ec29a578914146b9ab131214a938675f.plex.direct
Jan 05, 2019 21:37:18.456 [15860] DEBUG - CERT: OCSP requests for stapling will be made to 'http://ocspx.digicert.com/'.
Jan 05, 2019 21:37:18.472 [15860] INFO - OCSP: Successfully retrieved response from cache.
Jan 05, 2019 21:37:18.472 [15860] DEBUG - CERT: Installed intermediate certificate.
Jan 05, 2019 21:37:18.474 [15860] DEBUG - HttpServer: Listening on port 32400 with queue of 2147483647 connections.
Jan 05, 2019 21:37:18.474 [15860] DEBUG - HttpServer: Listening on port 32401 with queue of 2147483647 connections.
Jan 05, 2019 21:37:19.009 [16868] DEBUG - Job running: D:\Program files\Plex\Plex Media Server\CrashUploader.exe "--directory=C:\Users\Administrator\AppData\Local\Plex Media Server\Crash Reports\1.13.9.5456-ecd600442" --version=1.13.9.5456-ecd600442 "--platform=windows-i386 (10.0 (Build 17763))" --serverUuid=bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea --userId=dvir@gassner.co.il --url=https://crashreport.plexapp.com 
Jan 05, 2019 21:37:19.082 [16868] DEBUG - Jobs: Starting child process with pid 16892
Jan 05, 2019 21:37:19.150 [15900] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\CrashUploader.exe' exit code for process 16892 is 0 (success)
Jan 05, 2019 21:37:19.203 [16868] DEBUG - DVR:Grabber: Cleaning up orphaned grabs.
Jan 05, 2019 21:37:19.231 [15860] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Jan 05, 2019 21:37:19.331 [15860] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 05, 2019 21:37:19.368 [17008] DEBUG - Relay: read 6 cached entries from hosts file
Jan 05, 2019 21:37:19.370 [15860] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:37:19.374 [15860] DEBUG - MyPlex: updating with 3 access tokens
Jan 05, 2019 21:37:19.376 [17016] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 05, 2019 21:37:19.385 [17016] DEBUG - HTTP requesting GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:37:19.385 [17008] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:19.491 [16868] DEBUG - DVR:Grabber: Cleaned up 0 decrepit directories in 0.2 sec.
Jan 05, 2019 21:37:19.564 [17056] DEBUG - File "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442" changed: -1 => 2018-11-17 20:29:08 (1542479348).
Jan 05, 2019 21:37:19.564 [17056] DEBUG - File "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins" changed: -1 => 2018-12-16 02:55:47 (1544921747).
Jan 05, 2019 21:37:19.564 [17056] DEBUG - Scanning for plug-ins in "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442"
Jan 05, 2019 21:37:19.968 [17008] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:20.023 [17016] DEBUG - HTTP 200 response from GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:37:20.027 [17016] DEBUG - MyPlex: updating with 3 access tokens
Jan 05, 2019 21:37:20.027 [15900] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:20.330 [17056] DEBUG - Scanning for plug-ins in "C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins"
Jan 05, 2019 21:37:20.356 [17056] DEBUG - PluginRepository::setStartState: 1, startingSystem
Jan 05, 2019 21:37:20.357 [17056] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\System.bundle.
Jan 05, 2019 21:37:20.357 [17056] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Jan 05, 2019 21:37:20.467 [15900] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:20.468 [17020] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:20.578 [17056] DEBUG - Starting file watcher for com.plexapp.system
Jan 05, 2019 21:37:20.579 [17056] 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-ecd600442\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\System.bundle"
Jan 05, 2019 21:37:20.597 [17056] DEBUG - Spawned plug-in com.plexapp.system with PID 17396
Jan 05, 2019 21:37:20.597 [17056] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Jan 05, 2019 21:37:20.872 [17020] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:26.524 [17056] DEBUG - Reading 486 bytes in the body, code is 200
Jan 05, 2019 21:37:26.525 [17056] DEBUG - [com.plexapp.system] Plug-in running on port 49227.
Jan 05, 2019 21:37:26.525 [17056] DEBUG -  * Plug-in handles prefix: /system
Jan 05, 2019 21:37:26.525 [17056] DEBUG -  * Plug-in handles prefix: /player
Jan 05, 2019 21:37:26.525 [17056] DEBUG - Read configuration for [com.plexapp.system], had 2 prefixes
Jan 05, 2019 21:37:26.551 [17056] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Jan 05, 2019 21:37:26.551 [17056] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Jan 05, 2019 21:37:26.552 [17056] DEBUG - Updating the list of agents known by the system.
Jan 05, 2019 21:37:26.552 [17056] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents
Jan 05, 2019 21:37:26.552 [17056] DEBUG - HTTP requesting GET http://127.0.0.1:49227/system/agents
Jan 05, 2019 21:37:26.624 [15860] DEBUG - Image transcode cache directory: "C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder"
Jan 05, 2019 21:37:26.625 [15860] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 05, 2019 21:37:26.635 [15860] DEBUG - MyPlex: using cached data for request for https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:37:26.635 [15860] DEBUG - MyPlex: updating with 3 access tokens
Jan 05, 2019 21:37:26.636 [15900] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:26.636 [15860] DEBUG - MyPlex: using cached data for request for https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 05, 2019 21:37:26.653 [15860] DEBUG - MyPlex: username is dvirgassner, login is dvir@gassner.co.il, home is 0, no pin
Jan 05, 2019 21:37:26.654 [15860] DEBUG - PublicAddressManager: Starting.
Jan 05, 2019 21:37:26.654 [15860] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 05, 2019 21:37:26.654 [15860] DEBUG - NetworkInterface: Notified of network changed (force=0)
Jan 05, 2019 21:37:26.654 [17984] DEBUG - HTTP requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 05, 2019 21:37:26.654 [17020] DEBUG - PublicAddressManager: Obtaining public IP.
Jan 05, 2019 21:37:26.656 [17012] DEBUG - NAT: UPnP, attempting port mapping.
Jan 05, 2019 21:37:26.656 [17020] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Jan 05, 2019 21:37:26.671 [15860] DEBUG - NetworkInterface: ignoring adapter 'Ethernet' (fe80::1b6:65fd:c452:c37f%9,169.254.195.127) because it has no gateway
Jan 05, 2019 21:37:26.672 [15860] DEBUG - NetworkInterface: ignoring adapter 'Ethernet 3' (fe80::5dd1:18af:1576:1db3%5,169.254.29.179) because it has no gateway
Jan 05, 2019 21:37:26.672 [15860] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Jan 05, 2019 21:37:26.672 [15860] DEBUG - Network interfaces:
Jan 05, 2019 21:37:26.672 [15860] DEBUG -  * 2 Local Area Connection {03D1339B-4691-4434-A8CB-297F3805EEB0} (192.168.0.62) (loopback: 0)
Jan 05, 2019 21:37:26.673 [15860] DEBUG - Creating NetworkServices singleton.
Jan 05, 2019 21:37:26.673 [15860] DEBUG - NetworkServices: Initializing...
Jan 05, 2019 21:37:26.674 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.674 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.674 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.675 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Creating new service.
Jan 05, 2019 21:37:26.676 [15860] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Jan 05, 2019 21:37:26.677 [15860] DEBUG - NetworkService: Quick dispatch of network change.
Jan 05, 2019 21:37:26.679 [18000] DEBUG - Network change for advertiser.
Jan 05, 2019 21:37:26.679 [18000] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414 (outbound: 0)
Jan 05, 2019 21:37:26.702 [18000] DEBUG - Network change for advertiser.
Jan 05, 2019 21:37:26.702 [18000] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410 (outbound: 0)
Jan 05, 2019 21:37:26.703 [18000] DEBUG - Network change for advertiser.
Jan 05, 2019 21:37:26.703 [18000] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412 (outbound: 0)
Jan 05, 2019 21:37:26.703 [18000] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 05, 2019 21:37:26.703 [18000] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413 (outbound: 0)
Jan 05, 2019 21:37:26.703 [18000] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 192.168.0.255 (index: 0)
Jan 05, 2019 21:37:26.704 [18000] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Jan 05, 2019 21:37:26.704 [18000] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 192.168.0.255 (index: 0)
Jan 05, 2019 21:37:26.704 [18000] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Jan 05, 2019 21:37:26.704 [18000] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901 (outbound: 0)
Jan 05, 2019 21:37:26.704 [18000] DEBUG - NetworkService: Browsing on interface 192.168.0.62 on broadcast address 239.255.255.250 (index: 0)
Jan 05, 2019 21:37:26.873 [15860] DEBUG - Gracenote Version 3.07.7.3686 (build: 2015-07-05 11:53-0700)
Jan 05, 2019 21:37:26.883 [15860] DEBUG - Gracenote: Using SQLite 3.13.0
Jan 05, 2019 21:37:26.902 [18008] DEBUG - Plex Dlna Server.exe starting.
Jan 05, 2019 21:37:26.903 [18008] DEBUG - Job running: D:\Program files\Plex\Plex Media Server\Plex Dlna Server.exe 
Jan 05, 2019 21:37:26.911 [17020] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Jan 05, 2019 21:37:26.920 [15860] DEBUG - Starting HTTP server.
Jan 05, 2019 21:37:26.920 [15860] DEBUG - Running server...
Jan 05, 2019 21:37:26.920 [18012] DEBUG - Plex Tuner Service.exe starting.
Jan 05, 2019 21:37:26.920 [15860] DEBUG - Waiting for server condition to be signaled.
Jan 05, 2019 21:37:26.921 [18016] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Jan 05, 2019 21:37:26.922 [18016] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:26.929 [18008] DEBUG - Jobs: Starting child process with pid 18024
Jan 05, 2019 21:37:26.930 [18012] 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.13.9.5456-ecd600442 32600 /waitmutex 
Jan 05, 2019 21:37:26.935 [17020] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 5.29.101.237
Jan 05, 2019 21:37:26.944 [18044] DEBUG - Request: [127.0.0.1:49222 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (5 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:26.945 [18048] DEBUG - Request: [127.0.0.1:49221 (Loopback)] GET / (5 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:26.945 [18052] DEBUG - Request: [127.0.0.1:49226 (Loopback)] GET /servers (5 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:26.946 [18056] DEBUG - Request: [127.0.0.1:49225 (Loopback)] GET / (5 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:26.960 [18012] DEBUG - Jobs: Starting child process with pid 18060
Jan 05, 2019 21:37:26.989 [18044] DEBUG - Translator: Found locale ar
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale cs-CZ
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale cs_CZ
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale da
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale de
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale en
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale es
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale et_EE
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale fr
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale he
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale hr_HR
Jan 05, 2019 21:37:26.990 [18044] DEBUG - Translator: Found locale hu_HU
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale it
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale ja_JP
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale ko
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale lt
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale nl
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale nl-BE
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale nl_BE
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale no
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale pt-BR
Jan 05, 2019 21:37:26.991 [18044] DEBUG - Translator: Found locale pt_BR
Jan 05, 2019 21:37:26.992 [18044] DEBUG - Translator: Found locale ro
Jan 05, 2019 21:37:26.992 [18044] DEBUG - Translator: Found locale ru
Jan 05, 2019 21:37:26.992 [18044] DEBUG - Translator: Found locale sr
Jan 05, 2019 21:37:26.992 [18044] DEBUG - Translator: Found locale sv
Jan 05, 2019 21:37:26.992 [18044] DEBUG - Translator: Found locale zh_CN
Jan 05, 2019 21:37:27.097 [17008] DEBUG - Gracenote: Loading 29 locales in 0.2 seconds.
Jan 05, 2019 21:37:27.210 [18044] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:27.210 [18044] DEBUG - HTTP requesting GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:27.211 [16584] DEBUG - Completed: [127.0.0.1:49226] 200 GET /servers (5 live) GZIP 265ms 485 bytes
Jan 05, 2019 21:37:27.222 [18144] DEBUG - Request: [127.0.0.1:49240 (Loopback)] GET / (6 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:27.236 [15900] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.268 [17984] DEBUG - HTTP 200 response from GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeGeolocation=1
Jan 05, 2019 21:37:27.275 [17984] DEBUG - MyPlex: username is dvirgassner, login is dvir@gassner.co.il, home is 0, no pin
Jan 05, 2019 21:37:27.276 [17984] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.339 [18016] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.346 [18016] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.389 [17056] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/system/agents
Jan 05, 2019 21:37:27.390 [17056] DEBUG - [com.plexapp.system] HTTP reply status 200, with 9133 bytes of content.
Jan 05, 2019 21:37:27.391 [17056] DEBUG - The system knows about 14 agents.
Jan 05, 2019 21:37:27.392 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\Fanart-TV.bundle.
Jan 05, 2019 21:37:27.392 [18196] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\HTbackdrops.bundle.
Jan 05, 2019 21:37:27.392 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\Framework.bundle.
Jan 05, 2019 21:37:27.392 [17056] DEBUG - Waiting for 21 plug-ins to finish starting.
Jan 05, 2019 21:37:27.393 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\LastFM.bundle.
Jan 05, 2019 21:37:27.403 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lastfm] yet.
Jan 05, 2019 21:37:27.403 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\LocalMedia.bundle.
Jan 05, 2019 21:37:27.404 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.localmedia] yet.
Jan 05, 2019 21:37:27.404 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\LyricFind.bundle.
Jan 05, 2019 21:37:27.405 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.lyricfind] yet.
Jan 05, 2019 21:37:27.405 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\Media-Flags.bundle.
Jan 05, 2019 21:37:27.406 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\MoviePosterDB.bundle.
Jan 05, 2019 21:37:27.407 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.movieposterdb] yet.
Jan 05, 2019 21:37:27.407 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\OpenSubtitles.bundle.
Jan 05, 2019 21:37:27.433 [18196] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.htbackdrops] yet.
Jan 05, 2019 21:37:27.433 [18196] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\PersonalMedia.bundle.
Jan 05, 2019 21:37:27.434 [18196] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.none] yet.
Jan 05, 2019 21:37:27.434 [18196] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\PlexMovie.bundle.
Jan 05, 2019 21:37:27.439 [18044] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:27.439 [18044] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59982 bytes of content.
Jan 05, 2019 21:37:27.441 [16584] DEBUG - Completed: [127.0.0.1:49222] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (6 live) GZIP 498ms 19459 bytes
Jan 05, 2019 21:37:27.444 [18188] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.fanarttv] yet.
Jan 05, 2019 21:37:27.444 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\PlexMusic.bundle.
Jan 05, 2019 21:37:27.446 [18188] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexmusic] yet.
Jan 05, 2019 21:37:27.446 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\PlexThemeMusic.bundle.
Jan 05, 2019 21:37:27.447 [18188] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.plexthememusic] yet.
Jan 05, 2019 21:37:27.447 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\Scanners.bundle.
Jan 05, 2019 21:37:27.447 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\TheMovieDB.bundle.
Jan 05, 2019 21:37:27.458 [18188] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.themoviedb] yet.
Jan 05, 2019 21:37:27.458 [18188] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\TheTVDB.bundle.
Jan 05, 2019 21:37:27.458 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.opensubtitles] yet.
Jan 05, 2019 21:37:27.458 [18192] DEBUG - Starting plug-in D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\WebClient.bundle.
Jan 05, 2019 21:37:27.459 [18192] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Services.bundle.
Jan 05, 2019 21:37:27.460 [18192] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\SiteConfigurations.bundle.
Jan 05, 2019 21:37:27.461 [18192] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\WebManager.bundle.
Jan 05, 2019 21:37:27.462 [18192] DEBUG - Starting plug-in C:\Users\Administrator\AppData\Local\Plex Media Server\Plug-ins\Wikipedia.bundle.
Jan 05, 2019 21:37:27.463 [18192] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.wikipedia] yet.
Jan 05, 2019 21:37:27.481 [18188] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.thetvdb] yet.
Jan 05, 2019 21:37:27.585 [18196] DEBUG - Perfect cache hit, we don't need to start [com.plexapp.agents.imdb] yet.
Jan 05, 2019 21:37:27.585 [17056] DEBUG - Starting scheduled updates, every 900 seconds
Jan 05, 2019 21:37:27.586 [17056] INFO - LibraryUpdateManager path watching is disabled
Jan 05, 2019 21:37:27.586 [17056] DEBUG - Done waiting for all plug-ins to start.
Jan 05, 2019 21:37:27.586 [17056] DEBUG - PluginRepository::setStartState: 3, complete
Jan 05, 2019 21:37:27.586 [18204] DEBUG - Downloading document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs
Jan 05, 2019 21:37:27.588 [18204] DEBUG - Starting file watcher for com.plexapp.agents.imdb
Jan 05, 2019 21:37:27.588 [18204] 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-ecd600442\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "D:\Program files\Plex\Plex Media Server\Resources\Plug-ins-ecd600442\PlexMovie.bundle"
Jan 05, 2019 21:37:27.594 [18204] DEBUG - Spawned plug-in com.plexapp.agents.imdb with PID 18212
Jan 05, 2019 21:37:27.594 [18204] DEBUG - [com.plexapp.agents.imdb] Sending command: GET /:/prefixes

Jan 05, 2019 21:37:27.671 [17984] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.675 [17984] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.766 [18016] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.818 [18016] DEBUG - Running database fix-ups.
Jan 05, 2019 21:37:27.819 [18016] DEBUG - Database Fixups: Destroyed 0 transient items.
Jan 05, 2019 21:37:27.939 [18016] DEBUG - Collections: Found 1 collections tags in section 1.
Jan 05, 2019 21:37:27.939 [18016] DEBUG - Collections: We had 1 collections in the library already.
Jan 05, 2019 21:37:27.939 [18016] DEBUG - Collections: Synced tags in section 1 in 1ms.
Jan 05, 2019 21:37:27.942 [18016] DEBUG - Collections: Refreshed attributes for 1 collections in section 1 in 2ms.
Jan 05, 2019 21:37:27.942 [18016] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Jan 05, 2019 21:37:27.943 [18016] DEBUG - Collections: Found 0 collections tags in section 2.
Jan 05, 2019 21:37:27.943 [18016] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 21:37:27.943 [18016] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 05, 2019 21:37:27.943 [18016] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Jan 05, 2019 21:37:27.944 [18016] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Jan 05, 2019 21:37:27.944 [18016] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 05, 2019 21:37:27.944 [18016] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 21:37:27.944 [18016] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 05, 2019 21:37:27.945 [18016] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 05, 2019 21:37:27.945 [18016] DEBUG - Collection: Refreshed visibility in section 3 in 0ms.
Jan 05, 2019 21:37:27.945 [18016] DEBUG - Running database fix-ups completed in 0.0 seconds.
Jan 05, 2019 21:37:27.946 [17016] DEBUG - DVR: Starting up.
Jan 05, 2019 21:37:27.946 [18016] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.946 [16868] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:27.985 [17016] DEBUG - Activity: registered new activity bba30512-6596-453e-b2e8-ef55668c0ca0 - Processing subscriptions
Jan 05, 2019 21:37:28.092 [17984] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.096 [17984] DEBUG - Sync: onMyPlexSignIn
Jan 05, 2019 21:37:28.097 [17984] WARN - Sync: Couldn't get sync lists without subscription.
Jan 05, 2019 21:37:28.097 [17984] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.348 [16868] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.352 [18016] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.479 [17984] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.483 [17008] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.483 [17984] DEBUG - EventSource: Resolving 178.79.132.174 port 443s
Jan 05, 2019 21:37:28.484 [15900] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.484 [16588] DEBUG - EventSource: Resolved 178.79.132.174 to 178.79.132.174
Jan 05, 2019 21:37:28.539 [18052] DEBUG - Request: [127.0.0.1:49254 (Loopback)] GET /system/messaging/clear_events/com.plexapp.agents.imdb (7 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:28.540 [18052] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/messaging/clear_events/com.plexapp.agents.imdb
Jan 05, 2019 21:37:28.540 [18052] DEBUG - HTTP requesting GET http://127.0.0.1:49227/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 05, 2019 21:37:28.568 [18052] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/system/messaging/clear_events/com.plexapp.agents.imdb
Jan 05, 2019 21:37:28.568 [18052] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 05, 2019 21:37:28.568 [16588] DEBUG - Completed: [127.0.0.1:49254] 200 GET /system/messaging/clear_events/com.plexapp.agents.imdb (7 live) GZIP 29ms 330 bytes
Jan 05, 2019 21:37:28.631 [18052] DEBUG - Request: [127.0.0.1:49257 (Loopback)] GET /:/plugins/com.plexapp.system/resourceHashes (7 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:28.631 [18052] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/resourceHashes
Jan 05, 2019 21:37:28.632 [18052] DEBUG - HTTP requesting GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/resourceHashes
Jan 05, 2019 21:37:28.697 [16584] DEBUG - EventSource: Connected in 141 ms.
Jan 05, 2019 21:37:28.697 [16588] DEBUG - EventSource: Wrote data, reading reply.
Jan 05, 2019 21:37:28.898 [16588] DEBUG - EventSource: Read HTTP reply header.
Jan 05, 2019 21:37:28.898 [16588] DEBUG - MyPlex: We appear to have regained Internet connectivity.
Jan 05, 2019 21:37:28.950 [18052] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/resourceHashes
Jan 05, 2019 21:37:28.951 [18052] DEBUG - [com.plexapp.system] HTTP reply status 200, with 13276 bytes of content.
Jan 05, 2019 21:37:28.951 [16584] DEBUG - Completed: [127.0.0.1:49257] 200 GET /:/plugins/com.plexapp.system/resourceHashes (7 live) GZIP 320ms 3498 bytes
Jan 05, 2019 21:37:28.966 [18044] DEBUG - Request: [127.0.0.1:49276 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:28.966 [18044] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:28.967 [18044] DEBUG - HTTP requesting GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:28.967 [16596] DEBUG - Request: [127.0.0.1:49277 (Loopback)] GET / (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:28.985 [18044] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jan 05, 2019 21:37:28.988 [18044] DEBUG - [com.plexapp.system] HTTP reply status 200, with 59982 bytes of content.
Jan 05, 2019 21:37:28.990 [17008] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:28.990 [16584] DEBUG - Completed: [127.0.0.1:49276] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (9 live) GZIP 24ms 19459 bytes
Jan 05, 2019 21:37:29.029 [15900] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:37:29.202 [16868] DEBUG - DVR:Device: Discovering and refreshing all devices.
Jan 05, 2019 21:37:29.237 [16868] DEBUG - DVR:Grabber: HDHomerun discovered 0 compatible devices.
Jan 05, 2019 21:37:29.282 [16868] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Jan 05, 2019 21:37:29.674 [17012] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 05, 2019 21:37:29.677 [17012] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 05, 2019 21:37:29.678 [17012] DEBUG - NAT: UPnP, mapped port 18609 to 192.168.0.62:32400.
Jan 05, 2019 21:37:29.820 [18052] DEBUG - Request: [127.0.0.1:49286 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:29.821 [18052] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 05, 2019 21:37:29.821 [18052] DEBUG - HTTP requesting GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 05, 2019 21:37:29.827 [18052] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jan 05, 2019 21:37:29.827 [18052] DEBUG - [com.plexapp.system] HTTP reply status 200, with 16 bytes of content.
Jan 05, 2019 21:37:29.827 [16584] DEBUG - Completed: [127.0.0.1:49286] 200 GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (9 live) GZIP 7ms 465 bytes
Jan 05, 2019 21:37:29.856 [18204] DEBUG - Reading 148 bytes in the body, code is 200
Jan 05, 2019 21:37:29.856 [18204] DEBUG - [com.plexapp.agents.imdb] Plug-in running on port 49288.
Jan 05, 2019 21:37:29.856 [18204] DEBUG - Read configuration for [com.plexapp.agents.imdb], had 0 prefixes
Jan 05, 2019 21:37:29.860 [18204] DEBUG - [com.plexapp.agents.imdb] Sending command over HTTP (GET): /:/plugins/com.plexapp.agents.imdb/prefs
Jan 05, 2019 21:37:29.860 [18204] DEBUG - HTTP requesting GET http://127.0.0.1:49288/:/plugins/com.plexapp.agents.imdb/prefs
Jan 05, 2019 21:37:29.902 [18204] DEBUG - HTTP 200 response from GET http://127.0.0.1:49288/:/plugins/com.plexapp.agents.imdb/prefs
Jan 05, 2019 21:37:29.902 [18204] DEBUG - [com.plexapp.agents.imdb] HTTP reply status 200, with 2224 bytes of content.
Jan 05, 2019 21:37:29.903 [18204] DEBUG - Caching document http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/prefs as d231c449cdb9805d47360720d7ee891a5d0610ad
Jan 05, 2019 21:37:29.903 [18204] DEBUG - IVA: Downloading trailer lists.
Jan 05, 2019 21:37:29.945 [17020] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 05, 2019 21:37:29.948 [17020] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 05, 2019 21:37:29.949 [17020] DEBUG - NAT: UPnP, public address is 5.29.101.237
Jan 05, 2019 21:37:29.951 [16584] DEBUG - Completed: [127.0.0.1:49225] 200 GET / (9 live) GZIP 3006ms 1675 bytes
Jan 05, 2019 21:37:29.957 [18052] DEBUG - Request: [127.0.0.1:49304 (Loopback)] GET / (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:29.972 [17012] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.0.62:18609.
Jan 05, 2019 21:37:29.972 [17984] DEBUG - MyPlex: Updating device connections (from timer: 0)
Jan 05, 2019 21:37:29.972 [18016] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 05, 2019 21:37:29.972 [17460] DEBUG - NAT: UPnP, attempting port mapping.
Jan 05, 2019 21:37:29.972 [17984] 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 05, 2019 21:37:29.972 [17464] DEBUG - PublicAddressManager: Obtaining public IP.
Jan 05, 2019 21:37:29.972 [16588] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jan 05, 2019 21:37:29.973 [16588] DEBUG - EventSource: Successfully connected to 178.79.132.174.
Jan 05, 2019 21:37:29.973 [17464] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Jan 05, 2019 21:37:29.974 [16584] DEBUG - Completed: [127.0.0.1:49221] 200 GET / (9 live) GZIP 3030ms 1675 bytes
Jan 05, 2019 21:37:29.974 [16588] DEBUG - Completed: [127.0.0.1:49240] 200 GET / (9 live) GZIP 2753ms 1675 bytes
Jan 05, 2019 21:37:29.975 [16588] DEBUG - Completed: [127.0.0.1:49277] 200 GET / (9 live) GZIP 1008ms 1675 bytes
Jan 05, 2019 21:37:29.984 [18044] DEBUG - Request: [127.0.0.1:49317 (Loopback)] GET /library/sections (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:29.988 [16584] DEBUG - Completed: [127.0.0.1:49304] 200 GET / (9 live) GZIP 31ms 1675 bytes
Jan 05, 2019 21:37:30.025 [18384] DEBUG - MyPlex: Sending Server Info to myPlex (user=dvir@gassner.co.il, ip=, port=0)
Jan 05, 2019 21:37:30.027 [18384] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=7dd5e047-a157-486f-85f8-267a587207ef
Jan 05, 2019 21:37:30.075 [16584] DEBUG - Completed: [127.0.0.1:49317] 200 GET /library/sections (9 live) GZIP 91ms 903 bytes
Jan 05, 2019 21:37:30.093 [18056] DEBUG - Request: [127.0.0.1:49348 (Loopback)] GET /channels/all (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:30.094 [16588] DEBUG - Completed: [127.0.0.1:49348] 200 GET /channels/all (9 live) GZIP 1ms 387 bytes
Jan 05, 2019 21:37:30.107 [18044] DEBUG - Request: [127.0.0.1:49349 (Loopback)] GET /library/sections (10 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:30.109 [16584] DEBUG - Completed: [127.0.0.1:49349] 200 GET /library/sections (10 live) GZIP 2ms 903 bytes
Jan 05, 2019 21:37:30.121 [18144] DEBUG - Request: [127.0.0.1:49350 (Loopback)] GET /channels/all (10 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:30.122 [16584] DEBUG - Completed: [127.0.0.1:49350] 200 GET /channels/all (10 live) GZIP 1ms 387 bytes
Jan 05, 2019 21:37:30.145 [17464] DEBUG - HTTP 200 response from GET http://plex.tv/pms/:/ip
Jan 05, 2019 21:37:30.145 [17464] DEBUG - PublicAddressManager: Got public IP from http://plex.tv: 5.29.101.237
Jan 05, 2019 21:37:30.312 [18204] DEBUG - IVA: Downloaded 13 trailers for source 1.
Jan 05, 2019 21:37:30.387 [17984] 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 05, 2019 21:37:30.389 [17012] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 05, 2019 21:37:30.393 [17984] DEBUG - CERT: Certificate will not expire soon.
Jan 05, 2019 21:37:30.629 [18204] DEBUG - IVA: Downloaded 26 trailers for source 2.
Jan 05, 2019 21:37:30.747 [16584] DEBUG - EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="7dd5e047-a157-486f-85f8-267a587207ef" connectivity="0" command="notifyConnectivity"/>'
Jan 05, 2019 21:37:30.758 [18384] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=7dd5e047-a157-486f-85f8-267a587207ef
Jan 05, 2019 21:37:30.758 [18384] DEBUG - MyPlex: Published Mapping State response was 201
Jan 05, 2019 21:37:30.758 [18384] DEBUG - MyPlex: Got response for bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea ~ registered :0
Jan 05, 2019 21:37:31.676 [18000] DEBUG - NetworkServiceBrowser: SERVER arrived: 192.168.0.62 (Fox)
Jan 05, 2019 21:37:31.677 [18000] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.0.62
Jan 05, 2019 21:37:31.677 [18000] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.0.62
Jan 05, 2019 21:37:31.677 [18000] DEBUG - HTTP requesting GET http://127.0.0.1:49227/system/notify/serverUpdated?host=192.168.0.62
Jan 05, 2019 21:37:31.683 [18052] DEBUG - Request: [127.0.0.1:49446 (Loopback)] GET /servers (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:31.683 [16588] DEBUG - Completed: [127.0.0.1:49446] 200 GET /servers (9 live) GZIP 1ms 485 bytes
Jan 05, 2019 21:37:31.688 [16596] DEBUG - Request: [127.0.0.1:49447 (Loopback)] GET /library/sections (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:31.690 [16588] DEBUG - Completed: [127.0.0.1:49447] 200 GET /library/sections (9 live) GZIP 1ms 903 bytes
Jan 05, 2019 21:37:31.694 [18044] DEBUG - Request: [127.0.0.1:49448 (Loopback)] GET /channels/all (9 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:31.694 [16588] DEBUG - Completed: [127.0.0.1:49448] 200 GET /channels/all (9 live) GZIP 0ms 387 bytes
Jan 05, 2019 21:37:31.697 [18000] DEBUG - HTTP 200 response from GET http://127.0.0.1:49227/system/notify/serverUpdated?host=192.168.0.62
Jan 05, 2019 21:37:31.697 [18000] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.
Jan 05, 2019 21:37:31.725 [16868] DEBUG - HTTP 200 response from POST http://127.0.0.1:32600/devices/discover
Jan 05, 2019 21:37:32.983 [17460] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 05, 2019 21:37:32.986 [17460] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 05, 2019 21:37:33.037 [17460] DEBUG - NAT: UPnP, mapped port 18609 to 192.168.0.62:32400.
Jan 05, 2019 21:37:33.151 [17464] DEBUG - NAT: UPnP, found device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>
Jan 05, 2019 21:37:33.154 [17464] DEBUG - NAT: UPnP, usable device <http://192.168.0.1:59891/rootDesc.xml> with private address <192.168.0.62>.
Jan 05, 2019 21:37:33.155 [17464] DEBUG - NAT: UPnP, public address is 5.29.101.237
Jan 05, 2019 21:37:33.164 [17460] DEBUG - PublicAddressManager: Mapping succeeded for 192.168.0.62:18609.
Jan 05, 2019 21:37:33.190 [17020] DEBUG - MyPlex: Sending Server Info to myPlex (user=dvir@gassner.co.il, ip=, port=18609)
Jan 05, 2019 21:37:33.191 [17020] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=08f310e3-12bb-47d9-b95f-b2f694785275
Jan 05, 2019 21:37:33.200 [17464] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 05, 2019 21:37:33.205 [17460] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 05, 2019 21:37:33.484 [13500] DEBUG - HTTP requesting GET https://plex.tv/services/pubsub/servers
Jan 05, 2019 21:37:33.787 [17020] DEBUG - HTTP 201 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=08f310e3-12bb-47d9-b95f-b2f694785275
Jan 05, 2019 21:37:33.788 [17020] DEBUG - MyPlex: Published Mapping State response was 201
Jan 05, 2019 21:37:33.788 [17020] DEBUG - MyPlex: Got response for bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea ~ registered :0
Jan 05, 2019 21:37:33.788 [16584] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (7dd5e047-a157-486f-85f8-267a587207ef, expected 08f310e3-12bb-47d9-b95f-b2f694785275)
Jan 05, 2019 21:37:33.806 [17020] DEBUG - MyPlex: Last published value didn't change, we're done.
Jan 05, 2019 21:37:34.017 [13500] DEBUG - HTTP 200 response from GET https://plex.tv/services/pubsub/servers
Jan 05, 2019 21:37:34.215 [18048] DEBUG - Request: [54.171.49.143:54232 (WAN)] GET /identity (8 live) TLS Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:34.216 [16588] DEBUG - Completed: [54.171.49.143:54232] 200 GET /identity (8 live) TLS 1ms 386 bytes (pipelined: 1)
Jan 05, 2019 21:37:34.487 [16588] DEBUG - EventSource: Got event [data] '<Message address="5.29.101.237" port="18609" asyncIdentifier="08f310e3-12bb-47d9-b95f-b2f694785275" connectivity="1" command="notifyConnectivity"/>'
Jan 05, 2019 21:37:34.487 [16588] DEBUG - PubSub: Got notified of reachability: 1 for 5.29.101.237:18609
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 139.162.195.42 was 146 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 178.79.132.174 was 152 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 82.94.168.55 was 165 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 172.104.252.87 was 269 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 172.104.216.125 was 293 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 45.79.211.86 was 320 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 45.79.10.123 was 471 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 184.105.148.101 was 472 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 139.162.117.249 was 692 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Time to connect to 172.104.59.31 was 982 ms.
Jan 05, 2019 21:37:35.495 [13500] DEBUG - PubSubManager: Updating best ping time for 178.79.132.174 to 152 ms.
Jan 05, 2019 21:37:36.677 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.1:59891/rootDesc.xml
Jan 05, 2019 21:37:36.677 [18000] DEBUG - HTTP requesting GET http://192.168.0.1:59891/rootDesc.xml
Jan 05, 2019 21:37:36.679 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.1:59891/rootDesc.xml
Jan 05, 2019 21:37:36.680 [18000] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.1:59891/rootDesc.xml
Jan 05, 2019 21:37:36.680 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (DIR-878)
Jan 05, 2019 21:37:36.680 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANDevice)
Jan 05, 2019 21:37:36.680 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.1 (WANConnectionDevice)
Jan 05, 2019 21:37:36.680 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.2:49152/description.xml
Jan 05, 2019 21:37:36.680 [18000] DEBUG - HTTP requesting GET http://192.168.0.2:49152/description.xml
Jan 05, 2019 21:37:36.685 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.2:49152/description.xml
Jan 05, 2019 21:37:36.685 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.2:49152/description.xml
Jan 05, 2019 21:37:36.685 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.2 (eufy Genie-16E8)
Jan 05, 2019 21:37:36.707 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.4:14114/Device.xml
Jan 05, 2019 21:37:36.707 [18000] DEBUG - HTTP requesting GET http://192.168.0.4:14114/Device.xml
Jan 05, 2019 21:37:37.577 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.4:14114/Device.xml
Jan 05, 2019 21:37:37.577 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.4:14114/Device.xml
Jan 05, 2019 21:37:37.577 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.4 (Konnected)
Jan 05, 2019 21:37:37.577 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.37:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.577 [18000] DEBUG - HTTP requesting GET http://192.168.0.37:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.587 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.37:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.588 [18000] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.37:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.588 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (192.168.0.37 - Sonos One)
Jan 05, 2019 21:37:37.588 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (192.168.0.37 - Sonos One Media Server)
Jan 05, 2019 21:37:37.588 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.37 (Kitchen - Sonos One Media Renderer)
Jan 05, 2019 21:37:37.589 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.63:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.590 [18000] DEBUG - HTTP requesting GET http://192.168.0.63:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.608 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.63:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.610 [18000] DEBUG - NetworkServiceBrowser: found 3 SSDP devices via http://192.168.0.63:1400/xml/device_description.xml
Jan 05, 2019 21:37:37.610 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (192.168.0.63 - Sonos Connect:Amp)
Jan 05, 2019 21:37:37.610 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (192.168.0.63 - Sonos Connect:Amp Media Server)
Jan 05, 2019 21:37:37.610 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.63 (Living Room - Sonos Connect:Amp Media Renderer)
Jan 05, 2019 21:37:37.986 [17016] DEBUG - Activity: Ended activity bba30512-6596-453e-b2e8-ef55668c0ca0.
Jan 05, 2019 21:37:37.986 [17016] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 21:37:38.690 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.62:32469/DeviceDescription.xml
Jan 05, 2019 21:37:38.690 [18000] DEBUG - HTTP requesting GET http://192.168.0.62:32469/DeviceDescription.xml
Jan 05, 2019 21:37:38.702 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.62:32469/DeviceDescription.xml
Jan 05, 2019 21:37:38.703 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.62:32469/DeviceDescription.xml
Jan 05, 2019 21:37:38.703 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.62 (Plex Media Server: Fox)
Jan 05, 2019 21:37:39.473 [18056] DEBUG - Request: [127.0.0.1:49728 (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 (8 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.531 [18056] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.545 [18056] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\a1\a145abbb1d17c835232a38a0929328259b7df774.png is 32624.
Jan 05, 2019 21:37:39.546 [16588] DEBUG - Completed: [127.0.0.1:49728] 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 (8 live) 73ms 32624 bytes (pipelined: 1)
Jan 05, 2019 21:37:39.549 [18144] DEBUG - Request: [127.0.0.1:49728 (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 (7 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.549 [18144] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.550 [18144] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\d2\d20a8c1601a1a8facc7b25cb2f3735827a5ba0cc.png is 10683.
Jan 05, 2019 21:37:39.552 [16584] DEBUG - Completed: [127.0.0.1:49728] 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 (7 live) 3ms 10683 bytes (pipelined: 2)
Jan 05, 2019 21:37:39.554 [18052] DEBUG - Request: [127.0.0.1:49728 (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 (6 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.555 [18052] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.png] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.556 [18052] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\ab\ab96e8964b94322a84d0f7fd2490bb16259396ac.png is 3097.
Jan 05, 2019 21:37:39.557 [16588] DEBUG - Completed: [127.0.0.1:49728] 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 (6 live) 2ms 3097 bytes (pipelined: 3)
Jan 05, 2019 21:37:39.559 [16596] DEBUG - Request: [127.0.0.1:49728 (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 (5 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.560 [16596] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.561 [16596] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\f9\f97ac7d1835a4f3a37b19ab76cfeea8e4e4fe733.jpg is 7852.
Jan 05, 2019 21:37:39.562 [16584] DEBUG - Completed: [127.0.0.1:49728] 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 (5 live) 2ms 7852 bytes (pipelined: 4)
Jan 05, 2019 21:37:39.568 [18044] DEBUG - Request: [127.0.0.1:49728 (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 (4 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.568 [18044] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.569 [18044] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\dd\dd48254469847a37e81af334403590fb6b815270.jpg is 3078.
Jan 05, 2019 21:37:39.570 [16588] DEBUG - Completed: [127.0.0.1:49728] 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 (4 live) 2ms 3078 bytes (pipelined: 5)
Jan 05, 2019 21:37:39.575 [18048] DEBUG - Request: [127.0.0.1:49728 (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 (4 live) Signed-in Token (dvirgassner)
Jan 05, 2019 21:37:39.575 [18048] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/:/resources/dlna-icon-260.jpg] (is local: 1 upscaled: 0)
Jan 05, 2019 21:37:39.576 [18048] DEBUG - Content-Length of C:\Users\Administrator\AppData\Local\Plex Media Server\Cache\PhotoTranscoder\c8\c8901481ac00cfedfc4a23133e930a37c2df8459.jpg is 1137.
Jan 05, 2019 21:37:39.577 [16584] DEBUG - Completed: [127.0.0.1:49728] 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 (4 live) 2ms 1137 bytes (pipelined: 6)
Jan 05, 2019 21:39:07.791 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:39:07.791 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:39:07.825 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:39:07.826 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:39:07.826 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:39:17.175 [5336] DEBUG - BPQ: [Starting] -> [Processing]
Jan 05, 2019 21:39:17.176 [5336] DEBUG - BPQ: generating queue items from 0 generator(s)
Jan 05, 2019 21:39:17.176 [5336] DEBUG - BPQ: generated 0 item(s) for queue
Jan 05, 2019 21:39:17.177 [5336] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 05, 2019 21:39:17.177 [5336] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.
Jan 05, 2019 21:39:17.179 [5336] DEBUG - BPQ: [Processing] -> [Idle]
Jan 05, 2019 21:39:26.485 [7236] DEBUG - Request: [127.0.0.1:62751 (Loopback)] GET /servers (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:39:26.486 [16588] DEBUG - Completed: [127.0.0.1:62751] 200 GET /servers (3 live) GZIP 1ms 485 bytes
Jan 05, 2019 21:39:26.491 [7236] DEBUG - Request: [127.0.0.1:62752 (Loopback)] GET /library/sections (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:39:26.492 [16588] DEBUG - Completed: [127.0.0.1:62752] 200 GET /library/sections (3 live) GZIP 1ms 903 bytes
Jan 05, 2019 21:39:26.495 [13760] DEBUG - Request: [127.0.0.1:62753 (Loopback)] GET /channels/all (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:39:26.496 [16588] DEBUG - Completed: [127.0.0.1:62753] 200 GET /channels/all (3 live) GZIP 0ms 387 bytes
Jan 05, 2019 21:39:26.501 [7236] DEBUG - Request: [127.0.0.1:62754 (Loopback)] GET /library/sections (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:39:26.502 [16588] DEBUG - Completed: [127.0.0.1:62754] 200 GET /library/sections (3 live) GZIP 1ms 903 bytes
Jan 05, 2019 21:39:26.505 [13760] DEBUG - Request: [127.0.0.1:62755 (Loopback)] GET /channels/all (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:39:26.506 [16588] DEBUG - Completed: [127.0.0.1:62755] 200 GET /channels/all (3 live) GZIP 0ms 387 bytes
Jan 05, 2019 21:43:48.721 [17020] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 28.150148 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:47:07.148 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:47:07.148 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:47:07.184 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:47:07.185 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:47:07.185 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:49:38.739 [13500] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 28.070721 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:52:27.587 [1560] DEBUG - Checking if time for scheduled update
Jan 05, 2019 21:52:27.588 [1560] INFO - It's been 1085 seconds, so we're starting scheduled library update for section 3 (Home Videos)
Jan 05, 2019 21:52:27.588 [1560] DEBUG - Library Updater: Requested that section 3 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 21:52:27.588 [1560] DEBUG - Library Updater: Queueing section 3
Jan 05, 2019 21:52:27.588 [1560] INFO - It's been 1084 seconds, so we're starting scheduled library update for section 1 (Movies)
Jan 05, 2019 21:52:27.588 [1560] DEBUG - Library Updater: Requested that section 1 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 21:52:27.588 [1560] DEBUG - Library Updater: Queueing section 1
Jan 05, 2019 21:52:27.589 [1560] INFO - It's been 1084 seconds, so we're starting scheduled library update for section 2 (TV Shows)
Jan 05, 2019 21:52:27.589 [4732] INFO - Notification: Scanning the "Home Videos" section - 
Jan 05, 2019 21:52:27.589 [1560] DEBUG - Library Updater: Requested that section 2 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 21:52:27.589 [1560] DEBUG - Library Updater: Queueing section 2
Jan 05, 2019 21:52:27.589 [4732] DEBUG - Scanning section 3
Jan 05, 2019 21:52:28.158 [4732] 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\Plex Media Scanner.exe --scan --refresh --section 3 
Jan 05, 2019 21:52:28.279 [4732] DEBUG - Jobs: Starting child process with pid 16296
Jan 05, 2019 21:52:29.638 [15900] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 05, 2019 21:52:29.639 [15900] DEBUG - HTTP requesting GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:52:29.639 [7236] DEBUG - Request: [127.0.0.1:50309 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=3&sinceTime=1546716749 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:29.714 [7236] DEBUG - Asked to cull library timeline before time 1546716749, we're starting with 0 items.
Jan 05, 2019 21:52:29.714 [7236] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 21:52:29.714 [16588] DEBUG - Completed: [127.0.0.1:50309] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=3&sinceTime=1546716749 (3 live) GZIP 75ms 166 bytes
Jan 05, 2019 21:52:30.096 [10624] DEBUG - Request: [127.0.0.1:50319 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%9B%D7%A4%D7%95%D7%9C%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:30.096 [16588] DEBUG - Completed: [127.0.0.1:50319] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%9B%D7%A4%D7%95%D7%9C%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:30.110 [7236] DEBUG - Request: [127.0.0.1:50320 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%91%D7%A9%D7%91%D7%99%D7%9C%D7%94%20%D7%92%D7%99%D7%91%D7%95%D7%A8%D7%99%D7%9D%20%D7%A2%D7%A4%D7%99%D7%9D (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:30.111 [16588] DEBUG - Completed: [127.0.0.1:50320] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%91%D7%A9%D7%91%D7%99%D7%9C%D7%94%20%D7%92%D7%99%D7%91%D7%95%D7%A8%D7%99%D7%9D%20%D7%A2%D7%A4%D7%99%D7%9D (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:30.145 [10624] DEBUG - Request: [127.0.0.1:50321 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%94%D7%9E%D7%93%D7%A8%D7%A9%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:30.145 [16588] DEBUG - Completed: [127.0.0.1:50321] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%94%D7%9E%D7%93%D7%A8%D7%A9%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:30.148 [15900] DEBUG - HTTP 200 response from GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 21:52:30.158 [8692] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 16296 is 0 (success)
Jan 05, 2019 21:52:30.159 [8692] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 05, 2019 21:52:30.159 [8692] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 21:52:30.159 [8692] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 05, 2019 21:52:30.160 [8692] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 05, 2019 21:52:30.160 [8692] DEBUG - Collection: Refreshed visibility in section 3 in 0ms.
Jan 05, 2019 21:52:30.171 [15900] DEBUG - MyPlex: updating with 3 access tokens
Jan 05, 2019 21:52:30.171 [10812] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:52:30.176 [4732] DEBUG - Activity: registered new activity ec1bd6e3-2995-41ed-adf0-acbc4c900473 - Processing subscriptions
Jan 05, 2019 21:52:30.176 [8692] DEBUG - Activity: Ended activity ec1bd6e3-2995-41ed-adf0-acbc4c900473.
Jan 05, 2019 21:52:30.176 [8692] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 21:52:30.176 [4732] INFO - Notification: Scanning the "Movies" section - 
Jan 05, 2019 21:52:30.176 [4732] DEBUG - Scanning section 1
Jan 05, 2019 21:52:30.176 [4732] DEBUG - Killing job.
Jan 05, 2019 21:52:30.177 [4732] 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\Plex Media Scanner.exe --scan --refresh --section 1 
Jan 05, 2019 21:52:30.179 [4732] DEBUG - Jobs: Starting child process with pid 4188
Jan 05, 2019 21:52:30.344 [7236] DEBUG - Request: [127.0.0.1:50324 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=1&sinceTime=1546716750 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:30.344 [7236] DEBUG - Asked to cull library timeline before time 1546716750, we're starting with 0 items.
Jan 05, 2019 21:52:30.345 [7236] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 21:52:30.345 [16588] DEBUG - Completed: [127.0.0.1:50324] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=1&sinceTime=1546716750 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:30.590 [10812] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 21:52:31.113 [10624] DEBUG - Request: [127.0.0.1:50325 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Scared%20Shrekless%20(2010) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.114 [16588] DEBUG - Completed: [127.0.0.1:50325] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Scared%20Shrekless%20(2010) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.123 [7236] DEBUG - Request: [127.0.0.1:50326 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(2014) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.124 [16588] DEBUG - Completed: [127.0.0.1:50326] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(2014) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.151 [10624] DEBUG - Request: [127.0.0.1:50327 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(1982) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.151 [16588] DEBUG - Completed: [127.0.0.1:50327] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(1982) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.167 [7236] DEBUG - Request: [127.0.0.1:50328 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Diaries%20(2001) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.168 [16588] DEBUG - Completed: [127.0.0.1:50328] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Diaries%20(2001) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.177 [10624] DEBUG - Request: [127.0.0.1:50329 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Despicable%20Me%20(2010) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.177 [16588] DEBUG - Completed: [127.0.0.1:50329] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Despicable%20Me%20(2010) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.196 [7236] DEBUG - Request: [127.0.0.1:50330 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Sound%20of%20Music%20(1965) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.196 [16588] DEBUG - Completed: [127.0.0.1:50330] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Sound%20of%20Music%20(1965) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.213 [10624] DEBUG - Request: [127.0.0.1:50331 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Bride%20(1987) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.214 [16588] DEBUG - Completed: [127.0.0.1:50331] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Bride%20(1987) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.252 [7236] DEBUG - Request: [127.0.0.1:50332 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.253 [16588] DEBUG - Completed: [127.0.0.1:50332] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.288 [10624] DEBUG - Request: [127.0.0.1:50333 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG%5CSubs (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.289 [16588] DEBUG - Completed: [127.0.0.1:50333] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG%5CSubs (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.291 [7236] DEBUG - Request: [127.0.0.1:50334 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Lion%20King%20(1994) (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.292 [16588] DEBUG - Completed: [127.0.0.1:50334] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Lion%20King%20(1994) (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.345 [10624] DEBUG - Request: [127.0.0.1:50335 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Ratatouille%20(2007)%20[1080p] (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.346 [16588] DEBUG - Completed: [127.0.0.1:50335] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Ratatouille%20(2007)%20[1080p] (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.372 [7236] DEBUG - Request: [127.0.0.1:50336 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Hotel%20Transylvania%20(2012)%20[1080p] (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.373 [16588] DEBUG - Completed: [127.0.0.1:50336] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Hotel%20Transylvania%20(2012)%20[1080p] (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.386 [8692] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 4188 is 0 (success)
Jan 05, 2019 21:52:31.386 [8692] DEBUG - Collections: Found 1 collections tags in section 1.
Jan 05, 2019 21:52:31.386 [8692] DEBUG - Collections: We had 1 collections in the library already.
Jan 05, 2019 21:52:31.386 [8692] DEBUG - Collections: Synced tags in section 1 in 0ms.
Jan 05, 2019 21:52:31.388 [8692] DEBUG - Collections: Refreshed attributes for 1 collections in section 1 in 1ms.
Jan 05, 2019 21:52:31.388 [8692] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Jan 05, 2019 21:52:31.389 [4732] DEBUG - Activity: registered new activity ab8d3ab8-9f52-4025-9483-d1efd0a54bd7 - Processing subscriptions
Jan 05, 2019 21:52:31.389 [1560] DEBUG - Activity: Ended activity ab8d3ab8-9f52-4025-9483-d1efd0a54bd7.
Jan 05, 2019 21:52:31.389 [4732] INFO - Notification: Scanning the "TV Shows" section - 
Jan 05, 2019 21:52:31.389 [1560] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 21:52:31.389 [4732] DEBUG - Scanning section 2
Jan 05, 2019 21:52:31.389 [4732] DEBUG - Killing job.
Jan 05, 2019 21:52:31.389 [4732] 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\Plex Media Scanner.exe --scan --refresh --section 2 
Jan 05, 2019 21:52:31.392 [4732] DEBUG - Jobs: Starting child process with pid 10856
Jan 05, 2019 21:52:31.535 [10624] DEBUG - Request: [127.0.0.1:50337 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1546716751 (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.536 [10624] DEBUG - Asked to cull library timeline before time 1546716751, we're starting with 0 items.
Jan 05, 2019 21:52:31.536 [10624] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 21:52:31.536 [16588] DEBUG - Completed: [127.0.0.1:50337] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1546716751 (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.722 [7236] DEBUG - Request: [127.0.0.1:50338 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Westworld%20season%202 (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.722 [16588] DEBUG - Completed: [127.0.0.1:50338] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Westworld%20season%202 (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.736 [10624] DEBUG - Request: [127.0.0.1:50339 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Billions%20season%202 (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.737 [16588] DEBUG - Completed: [127.0.0.1:50339] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Billions%20season%202 (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.763 [7236] DEBUG - Request: [127.0.0.1:50340 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Fauda%20Season%202 (4 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 21:52:31.764 [16584] DEBUG - Completed: [127.0.0.1:50340] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Fauda%20Season%202 (4 live) GZIP 0ms 166 bytes
Jan 05, 2019 21:52:31.786 [8692] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 10856 is 0 (success)
Jan 05, 2019 21:52:31.787 [8692] DEBUG - Collections: Found 0 collections tags in section 2.
Jan 05, 2019 21:52:31.787 [8692] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 21:52:31.787 [8692] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 05, 2019 21:52:31.788 [8692] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Jan 05, 2019 21:52:31.788 [8692] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Jan 05, 2019 21:52:31.789 [4732] DEBUG - Activity: registered new activity 74113a23-dc6c-45b5-9c8b-f8cbb6a25c52 - Processing subscriptions
Jan 05, 2019 21:52:31.789 [10812] DEBUG - Activity: Ended activity 74113a23-dc6c-45b5-9c8b-f8cbb6a25c52.
Jan 05, 2019 21:52:31.789 [4732] INFO - Notification: Library scan complete - Extra information may still be downloading from the Internet
Jan 05, 2019 21:52:31.789 [10812] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 21:55:17.035 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:55:17.035 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:55:17.082 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:55:17.082 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:55:17.082 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:57:27.590 [8692] DEBUG - Checking if time for scheduled update
Jan 05, 2019 21:57:38.763 [10500] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 20.790283 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 21:59:07.286 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:59:07.286 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:59:07.317 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:59:07.317 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 21:59:07.317 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:01:38.776 [13644] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 27.930738 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:02:27.590 [13644] DEBUG - Checking if time for scheduled update
Jan 05, 2019 22:03:07.304 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:03:07.304 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:03:07.341 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:03:07.342 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:03:07.342 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:05:38.786 [13644] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 29.076596 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:07:07.668 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:07:07.668 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:07:07.705 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:07:07.705 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:07:07.706 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:07:17.673 [13644] DEBUG - Sync: uploadStatus
Jan 05, 2019 22:07:27.590 [13644] DEBUG - Checking if time for scheduled update
Jan 05, 2019 22:07:37.219 [18208] DEBUG - Putting plug-in com.plexapp.agents.imdb to sleep.
Jan 05, 2019 22:07:37.219 [18208] DEBUG - Stopping [com.plexapp.agents.imdb].
Jan 05, 2019 22:07:37.219 [18208] DEBUG - [com.plexapp.agents.imdb] Killing.
Jan 05, 2019 22:07:47.222 [18208] DEBUG - Stopping file watcher for com.plexapp.agents.imdb
Jan 05, 2019 22:09:38.799 [8692] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 28.489328 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:12:27.591 [10500] DEBUG - Checking if time for scheduled update
Jan 05, 2019 22:12:27.591 [10500] INFO - It's been 1197 seconds, so we're starting scheduled library update for section 3 (Home Videos)
Jan 05, 2019 22:12:27.591 [10500] DEBUG - Library Updater: Requested that section 3 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 22:12:27.591 [10500] DEBUG - Library Updater: Queueing section 3
Jan 05, 2019 22:12:27.591 [10500] INFO - It's been 1196 seconds, so we're starting scheduled library update for section 1 (Movies)
Jan 05, 2019 22:12:27.591 [10500] DEBUG - Library Updater: Requested that section 1 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 22:12:27.591 [10500] DEBUG - Library Updater: Queueing section 1
Jan 05, 2019 22:12:27.592 [10500] INFO - It's been 1196 seconds, so we're starting scheduled library update for section 2 (TV Shows)
Jan 05, 2019 22:12:27.592 [14952] INFO - Notification: Scanning the "Home Videos" section - 
Jan 05, 2019 22:12:27.592 [10500] DEBUG - Library Updater: Requested that section 2 be updated, force=0, deep=0, subdir= iTunes=0
Jan 05, 2019 22:12:27.592 [10500] DEBUG - Library Updater: Queueing section 2
Jan 05, 2019 22:12:27.592 [14952] DEBUG - Scanning section 3
Jan 05, 2019 22:12:27.592 [14952] DEBUG - Killing job.
Jan 05, 2019 22:12:27.592 [14952] 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\Plex Media Scanner.exe --scan --refresh --section 3 
Jan 05, 2019 22:12:27.597 [14952] DEBUG - Jobs: Starting child process with pid 14152
Jan 05, 2019 22:12:27.750 [8692] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 05, 2019 22:12:27.750 [8692] DEBUG - HTTP requesting GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 22:12:27.750 [10624] DEBUG - Request: [127.0.0.1:55810 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=3&sinceTime=1546717947 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:27.751 [10624] DEBUG - Asked to cull library timeline before time 1546717947, we're starting with 0 items.
Jan 05, 2019 22:12:27.751 [10624] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 22:12:27.751 [16588] DEBUG - Completed: [127.0.0.1:55810] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=3&sinceTime=1546717947 (3 live) GZIP 1ms 166 bytes
Jan 05, 2019 22:12:27.823 [3648] DEBUG - Request: [127.0.0.1:55816 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%9B%D7%A4%D7%95%D7%9C%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:27.823 [16588] DEBUG - Completed: [127.0.0.1:55816] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%9B%D7%A4%D7%95%D7%9C%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:27.827 [10624] DEBUG - Request: [127.0.0.1:55817 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%91%D7%A9%D7%91%D7%99%D7%9C%D7%94%20%D7%92%D7%99%D7%91%D7%95%D7%A8%D7%99%D7%9D%20%D7%A2%D7%A4%D7%99%D7%9D (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:27.827 [16588] DEBUG - Completed: [127.0.0.1:55817] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%91%D7%A9%D7%91%D7%99%D7%9C%D7%94%20%D7%92%D7%99%D7%91%D7%95%D7%A8%D7%99%D7%9D%20%D7%A2%D7%A4%D7%99%D7%9D (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:27.832 [3648] DEBUG - Request: [127.0.0.1:55818 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%94%D7%9E%D7%93%D7%A8%D7%A9%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:27.832 [16588] DEBUG - Completed: [127.0.0.1:55818] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20%D7%94%D7%9E%D7%93%D7%A8%D7%A9%D7%94%20%D7%A2%D7%95%D7%A0%D7%94%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:27.843 [16184] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 14152 is 0 (success)
Jan 05, 2019 22:12:27.844 [16184] DEBUG - Collections: Found 0 collections tags in section 3.
Jan 05, 2019 22:12:27.844 [16184] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 22:12:27.844 [16184] DEBUG - Collections: Synced tags in section 3 in 0ms.
Jan 05, 2019 22:12:27.845 [16184] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 0ms.
Jan 05, 2019 22:12:27.845 [16184] DEBUG - Collection: Refreshed visibility in section 3 in 0ms.
Jan 05, 2019 22:12:27.846 [14952] DEBUG - Activity: registered new activity 8e2fe3e5-b41d-4353-b12c-b33fd011c4f3 - Processing subscriptions
Jan 05, 2019 22:12:27.846 [10500] DEBUG - Activity: Ended activity 8e2fe3e5-b41d-4353-b12c-b33fd011c4f3.
Jan 05, 2019 22:12:27.846 [10500] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 22:12:27.846 [14952] INFO - Notification: Scanning the "Movies" section - 
Jan 05, 2019 22:12:27.846 [14952] DEBUG - Scanning section 1
Jan 05, 2019 22:12:27.846 [14952] DEBUG - Killing job.
Jan 05, 2019 22:12:27.847 [14952] 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\Plex Media Scanner.exe --scan --refresh --section 1 
Jan 05, 2019 22:12:27.849 [14952] DEBUG - Jobs: Starting child process with pid 1856
Jan 05, 2019 22:12:27.992 [10624] DEBUG - Request: [127.0.0.1:55819 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=1&sinceTime=1546717947 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:27.992 [10624] DEBUG - Asked to cull library timeline before time 1546717947, we're starting with 0 items.
Jan 05, 2019 22:12:27.992 [10624] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 22:12:27.993 [16588] DEBUG - Completed: [127.0.0.1:55819] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=1&sinceTime=1546717947 (3 live) GZIP 1ms 166 bytes
Jan 05, 2019 22:12:28.069 [3648] DEBUG - Request: [127.0.0.1:55820 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Scared%20Shrekless%20(2010) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.069 [16588] DEBUG - Completed: [127.0.0.1:55820] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Scared%20Shrekless%20(2010) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.072 [10624] DEBUG - Request: [127.0.0.1:55821 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(2014) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.072 [16588] DEBUG - Completed: [127.0.0.1:55821] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(2014) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.074 [3648] DEBUG - Request: [127.0.0.1:55822 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(1982) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.074 [16588] DEBUG - Completed: [127.0.0.1:55822] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Annie%20(1982) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.077 [10624] DEBUG - Request: [127.0.0.1:55823 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Diaries%20(2001) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.077 [16588] DEBUG - Completed: [127.0.0.1:55823] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Diaries%20(2001) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.079 [3648] DEBUG - Request: [127.0.0.1:55824 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Despicable%20Me%20(2010) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.080 [16588] DEBUG - Completed: [127.0.0.1:55824] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Despicable%20Me%20(2010) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.082 [10624] DEBUG - Request: [127.0.0.1:55825 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Sound%20of%20Music%20(1965) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.082 [16588] DEBUG - Completed: [127.0.0.1:55825] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Sound%20of%20Music%20(1965) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.085 [3648] DEBUG - Request: [127.0.0.1:55826 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Bride%20(1987) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.085 [16588] DEBUG - Completed: [127.0.0.1:55826] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Princess%20Bride%20(1987) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.089 [10624] DEBUG - Request: [127.0.0.1:55827 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.089 [16588] DEBUG - Completed: [127.0.0.1:55827] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.094 [3648] DEBUG - Request: [127.0.0.1:55828 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG%5CSubs (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.094 [16588] DEBUG - Completed: [127.0.0.1:55828] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%2EJungle%2EBook%2E1967%2E720p%2EBluRay%2EH264%2EAAC-RARBG%5CSubs (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.104 [10624] DEBUG - Request: [127.0.0.1:55829 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Lion%20King%20(1994) (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.104 [16588] DEBUG - Completed: [127.0.0.1:55829] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20The%20Lion%20King%20(1994) (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.106 [3648] DEBUG - Request: [127.0.0.1:55830 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Ratatouille%20(2007)%20[1080p] (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.106 [16588] DEBUG - Completed: [127.0.0.1:55830] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Ratatouille%20(2007)%20[1080p] (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.108 [10624] DEBUG - Request: [127.0.0.1:55831 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Hotel%20Transylvania%20(2012)%20[1080p] (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.108 [16588] DEBUG - Completed: [127.0.0.1:55831] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Hotel%20Transylvania%20(2012)%20[1080p] (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.118 [8836] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 1856 is 0 (success)
Jan 05, 2019 22:12:28.118 [8836] DEBUG - Collections: Found 1 collections tags in section 1.
Jan 05, 2019 22:12:28.119 [8836] DEBUG - Collections: We had 1 collections in the library already.
Jan 05, 2019 22:12:28.119 [8836] DEBUG - Collections: Synced tags in section 1 in 0ms.
Jan 05, 2019 22:12:28.119 [8836] DEBUG - Collections: Refreshed attributes for 1 collections in section 1 in 0ms.
Jan 05, 2019 22:12:28.120 [8836] DEBUG - Collection: Refreshed visibility in section 1 in 0ms.
Jan 05, 2019 22:12:28.121 [14952] DEBUG - Activity: registered new activity e12cd230-574a-45cd-b6cb-99b259aa5db6 - Processing subscriptions
Jan 05, 2019 22:12:28.121 [16184] DEBUG - Activity: Ended activity e12cd230-574a-45cd-b6cb-99b259aa5db6.
Jan 05, 2019 22:12:28.121 [14952] INFO - Notification: Scanning the "TV Shows" section - 
Jan 05, 2019 22:12:28.121 [16184] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 22:12:28.121 [14952] DEBUG - Scanning section 2
Jan 05, 2019 22:12:28.121 [14952] DEBUG - Killing job.
Jan 05, 2019 22:12:28.121 [14952] 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\Plex Media Scanner.exe --scan --refresh --section 2 
Jan 05, 2019 22:12:28.123 [14952] DEBUG - Jobs: Starting child process with pid 2896
Jan 05, 2019 22:12:28.269 [3648] DEBUG - Request: [127.0.0.1:55832 (Loopback)] GET /:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1546717948 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.269 [3648] DEBUG - Asked to cull library timeline before time 1546717948, we're starting with 0 items.
Jan 05, 2019 22:12:28.269 [3648] DEBUG - Library timeline cull complete with 0 items left.
Jan 05, 2019 22:12:28.269 [16588] DEBUG - Completed: [127.0.0.1:55832] 200 GET /:/metadata/notify/cullTimeline?librarySectionID=2&sinceTime=1546717948 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.284 [8692] DEBUG - HTTP 200 response from GET https://plex.tv/servers/bb7b1b3d8dd2f1546869f50ba0c70e4e620374ea/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1
Jan 05, 2019 22:12:28.290 [8692] DEBUG - MyPlex: updating with 3 access tokens
Jan 05, 2019 22:12:28.291 [8692] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 22:12:28.334 [10624] DEBUG - Request: [127.0.0.1:55834 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Westworld%20season%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.334 [16588] DEBUG - Completed: [127.0.0.1:55834] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Westworld%20season%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.348 [3648] DEBUG - Request: [127.0.0.1:55835 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Billions%20season%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.349 [16588] DEBUG - Completed: [127.0.0.1:55835] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Billions%20season%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.350 [10624] DEBUG - Request: [127.0.0.1:55836 (Loopback)] GET /:/metadata/updateProgressMessage?message=Scanning%20Fauda%20Season%202 (3 live) GZIP Signed-in Token (dvirgassner)
Jan 05, 2019 22:12:28.351 [16588] DEBUG - Completed: [127.0.0.1:55836] 200 GET /:/metadata/updateProgressMessage?message=Scanning%20Fauda%20Season%202 (3 live) GZIP 0ms 166 bytes
Jan 05, 2019 22:12:28.361 [13644] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Media Scanner.exe' exit code for process 2896 is 0 (success)
Jan 05, 2019 22:12:28.361 [13644] DEBUG - Collections: Found 0 collections tags in section 2.
Jan 05, 2019 22:12:28.361 [13644] DEBUG - Collections: We had 0 collections in the library already.
Jan 05, 2019 22:12:28.361 [13644] DEBUG - Collections: Synced tags in section 2 in 0ms.
Jan 05, 2019 22:12:28.362 [13644] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 0ms.
Jan 05, 2019 22:12:28.362 [13644] DEBUG - Collection: Refreshed visibility in section 2 in 0ms.
Jan 05, 2019 22:12:28.363 [14952] DEBUG - Activity: registered new activity d91a9ea9-2b45-42d4-b15d-172b3477d36b - Processing subscriptions
Jan 05, 2019 22:12:28.363 [14884] DEBUG - Activity: Ended activity d91a9ea9-2b45-42d4-b15d-172b3477d36b.
Jan 05, 2019 22:12:28.363 [14952] INFO - Notification: Library scan complete - Extra information may still be downloading from the Internet
Jan 05, 2019 22:12:28.363 [14884] DEBUG - DVR:Subscription: Refreshed all content in 0 ms.
Jan 05, 2019 22:12:28.671 [8692] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 05, 2019 22:15:17.688 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:15:17.688 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:15:17.725 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:15:17.726 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:15:17.726 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:17:27.592 [13644] DEBUG - Checking if time for scheduled update
Jan 05, 2019 22:17:48.822 [13644] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 28.689650 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:19:09.531 [18000] DEBUG - NetworkServiceBrowser: Parsing SSDP schema for http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:19:09.531 [18000] DEBUG - HTTP requesting GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:19:09.576 [18000] DEBUG - HTTP 200 response from GET http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:19:09.577 [18000] DEBUG - NetworkServiceBrowser: found 1 SSDP devices via http://192.168.0.88:2869/upnphost/udhisapi.dll?content=uuid:98ec6ff8-f9cd-43f2-978a-7b0416041633
Jan 05, 2019 22:19:09.577 [18000] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:21:38.835 [16184] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 29.938718 seconds: 192.168.0.88 (XboxOne)
Jan 05, 2019 22:21:41.610 [8692] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Tuner Service.exe' exit code for process 18060 is 1073807364 (failure)
Jan 05, 2019 22:21:41.610 [18012] DEBUG - Plex Tuner Service.exe exited.
Jan 05, 2019 22:21:41.659 [16184] DEBUG - Jobs: 'D:\Program files\Plex\Plex Media Server\Plex Dlna Server.exe' exit code for process 18024 is 1073807364 (failure)
Jan 05, 2019 22:21:41.660 [18008] DEBUG - Plex Dlna Server.exe exited.
Jan 05, 2019 22:21:41.713 [18012] 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.13.9.5456-ecd600442 32600 /waitmutex 
