2018-07-14 20:37:36,760 (1560) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 31d3c0c (Fri May 04 04:32:44 UTC 2018)
2018-07-14 20:37:36,762 (1560) :  DEBUG (core:361) - Using the elevated policy
2018-07-14 20:37:36,762 (1560) :  DEBUG (core:450) - Starting runtime component.
2018-07-14 20:37:36,765 (1560) :  DEBUG (core:450) - Starting caching component.
2018-07-14 20:37:36,765 (1560) :  DEBUG (core:450) - Starting data component.
2018-07-14 20:37:36,765 (1560) :  DEBUG (core:450) - Starting networking component.
2018-07-14 20:37:36,766 (1560) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-07-14 20:37:36,769 (1560) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-07-14 20:37:36,769 (1560) :  DEBUG (core:450) - Starting localization component.
2018-07-14 20:37:36,769 (1560) :  INFO (localization:409) - Setting the default locale to en-us
2018-07-14 20:37:36,769 (1560) :  DEBUG (core:450) - Starting messaging component.
2018-07-14 20:37:36,770 (1560) :  DEBUG (core:450) - Starting debugging component.
2018-07-14 20:37:36,770 (1560) :  DEBUG (core:450) - Starting services component.
2018-07-14 20:37:36,772 (20ec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.thetvdb'
2018-07-14 20:37:36,772 (1560) :  DEBUG (core:450) - Starting myplex component.
2018-07-14 20:37:36,773 (1560) :  DEBUG (core:450) - Starting notifications component.
2018-07-14 20:37:36,881 (1560) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.thetvdb in namespace 'metadata'
2018-07-14 20:37:36,885 (1560) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-07-14 20:37:36,904 (1560) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-07-14 20:37:36,904 (760) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-07-14 20:37:36,907 (1560) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-07-14 20:37:36,907 (760) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-07-14 20:37:36,907 (22c0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-07-14 20:37:36,907 (1560) :  DEBUG (core:150) - Finished starting framework core
2018-07-14 20:37:36,910 (1560) :  DEBUG (core:560) - Loading plug-in code
2018-07-14 20:37:36,951 (22c0) :  DEBUG (core:538) - Machine identifier is 93e1db4da1e422763fc5df78e5a3a61e9318ff7d
2018-07-14 20:37:36,953 (22c0) :  DEBUG (core:539) - Server version is 1.13.0.5023-31d3c0c65
2018-07-14 20:37:37,266 (760) :  DEBUG (services:362) - Loaded services
2018-07-14 20:37:37,282 (15f0) :  DEBUG (services:438) - No shared code to load
2018-07-14 20:37:37,503 (1560) :  DEBUG (core:566) - Finished loading plug-in code
2018-07-14 20:37:37,505 (1560) :  DEBUG (agentkit:1115) - Creating new agent class called TVDBAgent
2018-07-14 20:37:37,506 (1560) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['TV_Show'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': None, 'languages': ['en', 'fr', 'zh', 'sv', 'no', 'da', 'fi', 'nl', 'de', 'it', 'es', 'pl', 'hu', 'el', 'tr', 'ru', 'he', 'ja', 'pt', 'cs', 'ko', 'sl', 'hr'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'TheTVDB'}]
2018-07-14 20:37:37,506 (1560) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMAppZGVudGlmaWVycjEKczEwCmFnZW50X2luZm8xCnIyCjEwCnIzCnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNApzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3M3ClRoZVRWREJzNApuYW1lMQpzNwpUVl9TaG93MjMKczIKZW5zMgpmcnMyCnpoczIKc3ZzMgpub3MyCmRhczIKZmlzMgpubHMyCmRlczIKaXRzMgplc3MyCnBsczIKaHVzMgplbHMyCnRyczIKcnVzMgpoZXMyCmphczIKcHRzMgpjc3MyCmtvczIKc2xzMgpocnIwCg__'
2018-07-14 20:37:37,516 (1560) :  INFO (core:611) - Started plug-in
2018-07-14 20:37:37,517 (1560) :  DEBUG (socketinterface:160) - Starting socket server
2018-07-14 20:37:37,519 (1560) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-07-14 20:37:37,519 (1560) :  INFO (socketinterface:184) - Socket server started on port 65409
2018-07-14 20:37:37,519 (1560) :  INFO (pipeinterface:25) - Entering run loop
2018-07-14 20:37:37,519 (1560) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-07-14 20:37:37,520 (1560) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-07-14 20:37:37,522 (1560) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 151 bytes
2018-07-14 20:37:37,551 (a2c) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.thetvdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAoxMTI0czQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNwpUVl9TaG93czEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8zMjEyMzk%40bGFuZz1lbnM0Cmd1aWRzNgozMjEyMzlzMgppZHIwCg__
2018-07-14 20:37:37,552 (a2c) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-07-14 20:37:37,566 (a2c) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.thetvdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAoxMTI0czQKZGJpZGkwCnM3CnZlcnNpb25uczEwCnBhcmVudEdVSURuczgKcGFyZW50SURzNwpUVl9TaG93czEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLnRoZXR2ZGI6Ly8zMjEyMzk@bGFuZz1lbnM0Cmd1aWRzNgozMjEyMzlzMgppZHIwCg__
2018-07-14 20:37:37,566 (a2c) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.thetvdb://321239?lang=en
2018-07-14 20:37:37,569 (a2c) :  DEBUG (model:234) - Deserializing from C:\Users\Notandi\AppData\Local\Plex Media Server\Metadata\TV Shows\0\9243bbeecd4ac93bf3dca9722366b940279ec1f.bundle\Contents\com.plexapp.agents.thetvdb\Info.xml
2018-07-14 20:37:37,599 (a2c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/1124/tree'
2018-07-14 20:37:37,612 (a2c) :  INFO (logkit:16) - def update()
2018-07-14 20:37:37,614 (a2c) :  DEBUG (networking:166) - Requesting 'https://tvdb2.plex.tv/login'
2018-07-14 20:37:38,740 (a2c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/series/321239?lang=en' from the HTTP cache
2018-07-14 20:37:38,773 (a2c) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Ffind%2F321239%3Fexternal_source%3Dtvdb_id' from the HTTP cache
2018-07-14 20:37:38,809 (a2c) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Ftv%2F69478%2Frecommendations' from the HTTP cache
2018-07-14 20:37:38,849 (a2c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/series/321239/actors' from the HTTP cache
2018-07-14 20:37:38,868 (a2c) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-07-14 20:37:38,868 (a2c) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.thetvdb
2018-07-14 20:37:38,880 (a2c) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/tv_e/321239/handmaid+s+tale/2017'
2018-07-14 20:37:39,789 (a2c) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/tv_e/321239/handmaid+s+tale/2017'
2018-07-14 20:37:39,812 (a2c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/series/321239/episodes?page=1' from the HTTP cache
2018-07-14 20:37:39,834 (a2c) :  INFO (logkit:16) - Show ordering is: aired
2018-07-14 20:37:39,835 (a2c) :  INFO (logkit:16) - No media for season 2 episode 1 - skipping population of episode data
2018-07-14 20:37:39,835 (a2c) :  INFO (logkit:16) - No media for season 0 episode 1 - skipping population of episode data
2018-07-14 20:37:39,835 (a2c) :  INFO (logkit:16) - No media for season 2 episode 2 - skipping population of episode data
2018-07-14 20:37:39,835 (a2c) :  INFO (logkit:16) - No media for season 0 episode 2 - skipping population of episode data
2018-07-14 20:37:39,835 (a2c) :  INFO (logkit:16) - No media for season 2 episode 3 - skipping population of episode data
2018-07-14 20:37:39,836 (a2c) :  INFO (logkit:16) - No media for season 0 episode 3 - skipping population of episode data
2018-07-14 20:37:39,836 (a2c) :  INFO (logkit:16) - No media for season 2 episode 4 - skipping population of episode data
2018-07-14 20:37:39,838 (a2c) :  INFO (logkit:16) - No media for season 0 episode 4 - skipping population of episode data
2018-07-14 20:37:39,838 (a2c) :  INFO (logkit:16) - No media for season 2 episode 5 - skipping population of episode data
2018-07-14 20:37:39,838 (a2c) :  INFO (logkit:16) - No media for season 0 episode 5 - skipping population of episode data
2018-07-14 20:37:39,838 (a2c) :  INFO (logkit:16) - No media for season 2 episode 6 - skipping population of episode data
2018-07-14 20:37:39,839 (a2c) :  INFO (logkit:16) - No media for season 0 episode 6 - skipping population of episode data
2018-07-14 20:37:39,841 (a2c) :  INFO (logkit:16) - No media for season 2 episode 7 - skipping population of episode data
2018-07-14 20:37:39,841 (a2c) :  INFO (logkit:16) - No media for season 0 episode 7 - skipping population of episode data
2018-07-14 20:37:39,841 (a2c) :  INFO (logkit:16) - No media for season 1 episode 8 - skipping population of episode data
2018-07-14 20:37:39,842 (a2c) :  INFO (logkit:16) - No media for season 2 episode 8 - skipping population of episode data
2018-07-14 20:37:39,842 (a2c) :  INFO (logkit:16) - No media for season 0 episode 8 - skipping population of episode data
2018-07-14 20:37:39,842 (a2c) :  INFO (logkit:16) - No media for season 1 episode 9 - skipping population of episode data
2018-07-14 20:37:39,842 (a2c) :  INFO (logkit:16) - No media for season 2 episode 9 - skipping population of episode data
2018-07-14 20:37:39,842 (a2c) :  INFO (logkit:16) - No media for season 0 episode 9 - skipping population of episode data
2018-07-14 20:37:39,844 (a2c) :  INFO (logkit:16) - No media for season 1 episode 10 - skipping population of episode data
2018-07-14 20:37:39,844 (a2c) :  INFO (logkit:16) - No media for season 2 episode 10 - skipping population of episode data
2018-07-14 20:37:39,845 (a2c) :  INFO (logkit:16) - No media for season 0 episode 10 - skipping population of episode data
2018-07-14 20:37:39,845 (a2c) :  INFO (logkit:16) - No media for season 2 episode 11 - skipping population of episode data
2018-07-14 20:37:39,845 (a2c) :  INFO (logkit:16) - No media for season 2 episode 12 - skipping population of episode data
2018-07-14 20:37:39,845 (a2c) :  INFO (logkit:16) - No media for season 2 episode 13 - skipping population of episode data
2018-07-14 20:37:39,845 (a2c) :  DEBUG (threadkit:234) - Starting a parallel task set named UpdateEpisodes with 7 tasks
2018-07-14 20:37:39,907 (2288) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/5883022?lang=en' from the HTTP cache
2018-07-14 20:37:39,911 (560) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/6051446?lang=en' from the HTTP cache
2018-07-14 20:37:39,917 (20cc) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/6051447?lang=en' from the HTTP cache
2018-07-14 20:37:39,923 (1f1c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/6051448?lang=en' from the HTTP cache
2018-07-14 20:37:40,000 (2288) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,002 (560) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,003 (20cc) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,005 (1f1c) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,029 (20cc) :  DEBUG (networking:166) - Requesting 'https://tvdb2.plex.tv/episodes/6051451?lang=en'
2018-07-14 20:37:40,051 (2288) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/6051449?lang=en' from the HTTP cache
2018-07-14 20:37:40,056 (560) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/episodes/6051450?lang=en' from the HTTP cache
2018-07-14 20:37:40,098 (2288) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,098 (560) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:40,607 (20cc) :  DEBUG (networking:166) - Requesting 'https://thetvdb.com/banners/episodes/321239/6051451.jpg'
2018-07-14 20:37:43,210 (20cc) :  DEBUG (networking:198) - Not caching 'https://thetvdb.com/banners/episodes/321239/6051451.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-14 20:37:43,211 (20cc) :  INFO (logkit:16) - Season Extra XML is empty - therefore, no episode XML
2018-07-14 20:37:43,213 (a2c) :  DEBUG (threadkit:244) - Parallel task set UpdateEpisodes ended
2018-07-14 20:37:43,236 (a2c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/series/321239/images?lang=en' from the HTTP cache
2018-07-14 20:37:43,269 (a2c) :  CRITICAL (core:574) - Exception writing to C:\Users\Notandi\AppData\Local\Plex Media Server\Plug-in Support\Caches\com.plexapp.agents.thetvdb\HTTP.system\CacheInfo (most recent call last):
  File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-31d3c0c65\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\storage.py", line 86, in save
    shutil.move(tempfile, filename)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 302, in move
    copy2(src, real_dst)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 130, in copy2
    copyfile(src, dst)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 83, in copyfile
    with open(dst, 'wb') as fdst:
  File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-31d3c0c65\Framework.bundle\Contents\Resources\Versions\2\Python\subsystem\ospathfix.py", line 70, in builtins_open
    return __builtins__['_open'](longpathify(uni(filename)), mode, buffering)
IOError: [Errno 13] Permission denied: u'\\\\?\\C:\\Users\\Notandi\\AppData\\Local\\Plex Media Server\\Plug-in Support\\Caches\\com.plexapp.agents.thetvdb\\HTTP.system\\CacheInfo'

2018-07-14 20:37:43,270 (a2c) :  INFO (logkit:16) - Problem with the request: 
2018-07-14 20:37:43,270 (a2c) :  INFO (logkit:16) - Bad image type query data for TVDB id: 321239 (image_type: fanart)
2018-07-14 20:37:43,289 (a2c) :  CRITICAL (core:574) - Exception writing to C:\Users\Notandi\AppData\Local\Plex Media Server\Plug-in Support\Caches\com.plexapp.agents.thetvdb\HTTP.system\CacheInfo (most recent call last):
  File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-31d3c0c65\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\storage.py", line 86, in save
    shutil.move(tempfile, filename)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 302, in move
    copy2(src, real_dst)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 130, in copy2
    copyfile(src, dst)
  File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\shutil.py", line 83, in copyfile
    with open(dst, 'wb') as fdst:
  File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-31d3c0c65\Framework.bundle\Contents\Resources\Versions\2\Python\subsystem\ospathfix.py", line 70, in builtins_open
    return __builtins__['_open'](longpathify(uni(filename)), mode, buffering)
IOError: [Errno 13] Permission denied: u'\\\\?\\C:\\Users\\Notandi\\AppData\\Local\\Plex Media Server\\Plug-in Support\\Caches\\com.plexapp.agents.thetvdb\\HTTP.system\\CacheInfo'

2018-07-14 20:37:43,292 (a2c) :  INFO (logkit:16) - Problem with the request: 
2018-07-14 20:37:43,292 (a2c) :  INFO (logkit:16) - Bad image type query data for TVDB id: 321239 (image_type: poster)
2018-07-14 20:37:43,312 (a2c) :  DEBUG (networking:161) - Fetching 'https://tvdb2.plex.tv/series/321239/images/query?keyType=seasonwide&lang=en' from the HTTP cache
2018-07-14 20:37:43,338 (a2c) :  INFO (logkit:16) - Problem with the request: 'NoneType' object has no attribute 'get'
2018-07-14 20:37:43,338 (a2c) :  INFO (logkit:16) - Bad image type query data for TVDB id: 321239 (image_type: series)
2018-07-14 20:37:43,346 (a2c) :  INFO (logkit:16) - Problem with the request: 'NoneType' object has no attribute 'get'
2018-07-14 20:37:43,346 (a2c) :  INFO (logkit:16) - Bad image type query data for TVDB id: 321239 (image_type: season)
2018-07-14 20:37:43,348 (a2c) :  DEBUG (threadkit:234) - Starting a parallel task set named DownloadImages with 1 tasks
2018-07-14 20:37:43,349 (a2c) :  DEBUG (threadkit:244) - Parallel task set DownloadImages ended
2018-07-14 20:37:43,351 (a2c) :  INFO (logkit:16) - Series Extra XML is empty - therefore, no season XML
2018-07-14 20:37:43,474 (a2c) :  DEBUG (model:229) - Serializing to C:\Users\Notandi\AppData\Local\Plex Media Server\Metadata\TV Shows\0\9243bbeecd4ac93bf3dca9722366b940279ec1f.bundle\Contents\com.plexapp.agents.thetvdb\Info.xml
2018-07-14 20:37:43,479 (a2c) :  DEBUG (runtime:88) - Sending packed state data (583 bytes)
2018-07-14 20:37:43,479 (a2c) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
