2018-11-27 11:55:07,056 (2dac) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 09:36:00 UTC 2018)
2018-11-27 11:55:07,056 (2dac) :  DEBUG (core:361) - Using the elevated policy
2018-11-27 11:55:07,056 (2dac) :  DEBUG (core:450) - Starting runtime component.
2018-11-27 11:55:07,059 (2dac) :  DEBUG (core:450) - Starting caching component.
2018-11-27 11:55:07,059 (2dac) :  DEBUG (core:450) - Starting data component.
2018-11-27 11:55:07,059 (2dac) :  DEBUG (core:450) - Starting networking component.
2018-11-27 11:55:07,061 (2dac) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-27 11:55:07,075 (2dac) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-27 11:55:07,076 (2dac) :  DEBUG (core:450) - Starting localization component.
2018-11-27 11:55:07,076 (2dac) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-27 11:55:07,076 (2dac) :  DEBUG (core:450) - Starting messaging component.
2018-11-27 11:55:07,076 (2dac) :  DEBUG (core:450) - Starting debugging component.
2018-11-27 11:55:07,078 (2dac) :  DEBUG (core:450) - Starting services component.
2018-11-27 11:55:07,078 (1330) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-11-27 11:55:07,078 (2dac) :  DEBUG (core:450) - Starting myplex component.
2018-11-27 11:55:07,078 (2dac) :  DEBUG (core:450) - Starting notifications component.
2018-11-27 11:55:07,147 (2dac) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-11-27 11:55:07,150 (2dac) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-27 11:55:07,170 (2dac) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-27 11:55:07,170 (a14) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-27 11:55:07,171 (2dac) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-27 11:55:07,171 (2dac) :  DEBUG (core:150) - Finished starting framework core
2018-11-27 11:55:07,171 (2a50) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-27 11:55:07,171 (a14) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-27 11:55:07,173 (2dac) :  DEBUG (core:560) - Loading plug-in code
2018-11-27 11:55:07,177 (2a50) :  DEBUG (core:538) - Machine identifier is 6d991ea58d477b0ed9af8a2e324fd8474eb0a67a
2018-11-27 11:55:07,177 (2a50) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-27 11:55:07,397 (a14) :  DEBUG (services:362) - Loaded services
2018-11-27 11:55:07,404 (e14) :  DEBUG (services:438) - No shared code to load
2018-11-27 11:55:07,598 (2dac) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-27 11:55:07,677 (2dac) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-11-27 11:55:07,678 (2dac) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': ['com.plexapp.agents.localmedia'], 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.themoviedb'], 'languages': ['en', 'sv', 'fr', 'es', 'nl', 'de', 'it', 'da', 'ar', 'ca', 'zh', 'cs', 'et', 'fi', 'el', 'he', 'hi', 'hu', 'id', 'ja', 'ko', 'lv', 'no', 'fa', 'pl', 'pt', 'ro', 'ru', 'sk', 'th', 'tr', 'uk', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Movie'}]
2018-11-27 11:55:07,680 (2dac) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-11-27 11:55:07,696 (2dac) :  INFO (core:611) - Started plug-in
2018-11-27 11:55:07,696 (2dac) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-27 11:55:07,697 (2dac) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-27 11:55:07,697 (2dac) :  INFO (socketinterface:184) - Socket server started on port 57360
2018-11-27 11:55:07,697 (2dac) :  INFO (pipeinterface:25) - Entering run loop
2018-11-27 11:55:07,697 (2dac) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-27 11:55:07,697 (2dac) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-27 11:55:07,697 (2dac) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-11-27 11:55:07,721 (aa8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzQKUHJpZGUgUHJlanVkaWNlIGFuZCBNaXN0bGV0b2UgMjAxOHM0Cm5hbWVzODcKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDUHJpZGUlMjBQcmVqdWRpY2UlMjBhbmQlMjBNaXN0bGV0b2UlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MTlzMgppZHIwCg__
2018-11-27 11:55:07,729 (aa8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzQKUHJpZGUgUHJlanVkaWNlIGFuZCBNaXN0bGV0b2UgMjAxOHM0Cm5hbWVzODcKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDUHJpZGUlMjBQcmVqdWRpY2UlMjBhbmQlMjBNaXN0bGV0b2UlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MTlzMgppZHIwCg__
2018-11-27 11:55:07,729 (aa8) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Pride Prejudice and Mistletoe 2018', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CPride%20Prejudice%20and%20Mistletoe%202018%2Emp4', 'plexHash': None, 'duration': '-1', 'id': '37419'}
2018-11-27 11:55:07,730 (aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37419/tree'
2018-11-27 11:55:07,733 (aa8) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Pride Prejudice and Mistletoe 2018.mp4]
2018-11-27 11:55:07,733 (aa8) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:55:07,733 (aa8) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 11:55:07,733 (aa8) :  INFO (logkit:16) - {}
2018-11-27 11:55:07,733 (aa8) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/5b/5b1e02026938b0ca995c6018a7bdd9df8528e7dc.xml
2018-11-27 11:55:07,749 (aa8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/5b/5b1e02026938b0ca995c6018a7bdd9df8528e7dc.xml'
2018-11-27 11:55:08,068 (aa8) :  INFO (logkit:16) - Scoring {'tt8634192': [5, u'Pride, Prejudice and Mistletoe', u'2018', 100, 260, 0]}
2018-11-27 11:55:08,068 (aa8) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 11:55:08,069 (aa8) :  INFO (logkit:16) - {'tt8634192': [5, u'Pride, Prejudice and Mistletoe', u'2018', 100, 260, 90]}
2018-11-27 11:55:08,072 (aa8) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-11-27 11:55:08,073 (aa8) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-11-27 11:55:08,088 (aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634192%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-27 11:55:10,674 (aa8) :  INFO (logkit:16) - Adding title_year match: Pride, Prejudice and Mistletoe (2018) score=90, key=tt8634192
2018-11-27 11:55:10,676 (aa8) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 11:55:10,690 (aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DPride%2BPrejudice%2Band%2BMistletoe%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 11:55:13,127 (aa8) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 11:55:13,141 (aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Pride%20Prejudice%20and%20Mistletoe%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 11:55:13,667 (aa8) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/7WorfTMdTRgKczyCCM3nMJvzgyt.jpg', score=97, year=2018, id='tt8634192', name='Pride, Prejudice, and Mistletoe')
2018-11-27 11:55:13,667 (aa8) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 11:55:13,667 (aa8) :  DEBUG (runtime:924) - Response: [200] str, 752 bytes
2018-11-27 11:55:13,752 (2ad4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQxOXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODYzNDE5Mj9sYW5nPWVuczQKZ3VpZHM5CnR0ODYzNDE5MnMyCmlkcjAK
2018-11-27 11:55:13,753 (2ad4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQxOXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODYzNDE5Mj9sYW5nPWVuczQKZ3VpZHM5CnR0ODYzNDE5MnMyCmlkcjAK
2018-11-27 11:55:13,753 (2ad4) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt8634192?lang=en
2018-11-27 11:55:13,755 (2ad4) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\e4b2b0a340b31c3ac6b83c4a3435df9b7fe090a.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 11:55:13,755 (2ad4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37419/tree'
2018-11-27 11:55:13,759 (2ad4) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Pride Prejudice and Mistletoe 2018.mp4]
2018-11-27 11:55:13,790 (2ad4) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 11:55:13,834 (2ad4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634192%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 11:55:16,282 (2ad4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634192%2Fimages'
2018-11-27 11:55:18,546 (2ad4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F535810%2Frecommendations'
2018-11-27 11:55:20,989 (2ad4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/8wpUjpALsGmPM58j95CXJ15tNtS.jpg'
2018-11-27 11:55:21,085 (2ad4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/8wpUjpALsGmPM58j95CXJ15tNtS.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:55:21,101 (2ad4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/7WorfTMdTRgKczyCCM3nMJvzgyt.jpg'
2018-11-27 11:55:21,165 (2ad4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/7WorfTMdTRgKczyCCM3nMJvzgyt.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:55:21,180 (2ad4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/brYEwaSk7ftFlo0PWquD2JFCDRg.jpg'
2018-11-27 11:55:21,243 (2ad4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/brYEwaSk7ftFlo0PWquD2JFCDRg.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:55:21,246 (2ad4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/8634192?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:55:21,743 (2ad4) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/8634192?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:55:21,743 (2ad4) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 8634192: HTTP Error 404: Not Found
2018-11-27 11:55:21,743 (2ad4) :  INFO (logkit:16) - Error obtaining extras for 8634192: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:55:21,743 (2ad4) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 8634192: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:55:21,759 (2ad4) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Pride%2C%20Prejudice%2C%20and%20Mistletoe'
2018-11-27 11:55:22,401 (2ad4) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Pride%2C%20Prejudice%2C%20and%20Mistletoe'
2018-11-27 11:55:22,401 (2ad4) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 8634192: HTTP Error 401: Unauthorized
2018-11-27 11:55:22,421 (2ad4) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\e4b2b0a340b31c3ac6b83c4a3435df9b7fe090a.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 11:55:22,423 (2ad4) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 11:55:22,423 (2ad4) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-27 11:56:38,542 (25c8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzAKQSBTaG9lIEFkZGljdCdzIENocmlzdG1hcyAyMDE4czQKbmFtZXM4MwpGJTNBJTVDUGxheW9uJTVDSGFsbG1hcmslNUNDaHJpc3RtYXMlNUNBJTIwU2hvZSUyMEFkZGljdCdzJTIwQ2hyaXN0bWFzJTIwMjAxOCUyRW1wNHM4CmZpbGVuYW1lbnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDIwczIKaWRyMAo_
2018-11-27 11:56:38,542 (25c8) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-27 11:56:38,543 (25c8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzAKQSBTaG9lIEFkZGljdCdzIENocmlzdG1hcyAyMDE4czQKbmFtZXM4MwpGJTNBJTVDUGxheW9uJTVDSGFsbG1hcmslNUNDaHJpc3RtYXMlNUNBJTIwU2hvZSUyMEFkZGljdCdzJTIwQ2hyaXN0bWFzJTIwMjAxOCUyRW1wNHM4CmZpbGVuYW1lbnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDIwczIKaWRyMAo_
2018-11-27 11:56:38,543 (25c8) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': "A Shoe Addict's Christmas 2018", 'filename': "F%3A%5CPlayon%5CHallmark%5CChristmas%5CA%20Shoe%20Addict's%20Christmas%202018%2Emp4", 'plexHash': None, 'duration': '-1', 'id': '37420'}
2018-11-27 11:56:38,545 (25c8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37420/tree'
2018-11-27 11:56:38,549 (25c8) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\A Shoe Addict's Christmas 2018.mp4]
2018-11-27 11:56:38,551 (25c8) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:56:38,551 (25c8) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 11:56:38,551 (25c8) :  INFO (logkit:16) - {}
2018-11-27 11:56:38,552 (25c8) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/07/070dcce83b68a9fe6a8618da59b321e030b348bb.xml
2018-11-27 11:56:38,575 (25c8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/07/070dcce83b68a9fe6a8618da59b321e030b348bb.xml'
2018-11-27 11:56:38,788 (25c8) :  INFO (logkit:16) - Scoring {'tt8634202': [5, u"A Shoe Addict's Christmas", u'2018', 100, 20, 0]}
2018-11-27 11:56:38,789 (25c8) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 11:56:38,789 (25c8) :  INFO (logkit:16) - {'tt8634202': [5, u"A Shoe Addict's Christmas", u'2018', 100, 20, 85]}
2018-11-27 11:56:38,812 (25c8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634202%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-27 11:56:40,997 (25c8) :  INFO (logkit:16) - Adding title_year match: A Shoe Addict's Christmas (2018) score=85, key=tt8634202
2018-11-27 11:56:40,997 (25c8) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 11:56:41,028 (25c8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DA%2BShoe%2BAddict%2527s%2BChristmas%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 11:56:43,323 (25c8) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 11:56:43,339 (25c8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=A%20Shoe%20Addict%27s%20Christmas%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 11:56:43,890 (25c8) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/dkuTJ6fBV8Wz7EfqIkfbHskIOyc.jpg', score=98, year=2018, id='tt8634202', name="A Shoe Addict's Christmas")
2018-11-27 11:56:43,891 (25c8) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 11:56:43,891 (25c8) :  DEBUG (runtime:924) - Response: [200] str, 744 bytes
2018-11-27 11:56:43,947 (1ff4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODYzNDIwMj9sYW5nPWVuczQKZ3VpZHM5CnR0ODYzNDIwMnMyCmlkcjAK
2018-11-27 11:56:43,947 (1ff4) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-27 11:56:43,948 (1ff4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODYzNDIwMj9sYW5nPWVuczQKZ3VpZHM5CnR0ODYzNDIwMnMyCmlkcjAK
2018-11-27 11:56:43,948 (1ff4) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt8634202?lang=en
2018-11-27 11:56:43,950 (1ff4) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\8\55c6372015c6667592e2c9b9e5d7051cdb6f076.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 11:56:43,950 (1ff4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37420/tree'
2018-11-27 11:56:43,953 (1ff4) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\A Shoe Addict's Christmas 2018.mp4]
2018-11-27 11:56:43,986 (1ff4) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 11:56:44,032 (1ff4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634202%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 11:56:46,230 (1ff4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8634202%2Fimages'
2018-11-27 11:56:48,417 (1ff4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F535809%2Frecommendations'
2018-11-27 11:56:50,621 (1ff4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/39CTfPdi6eynvbYvx4trlha096E.jpg'
2018-11-27 11:56:50,693 (1ff4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/39CTfPdi6eynvbYvx4trlha096E.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:56:50,707 (1ff4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/8UpsPqGJppvidFUSD2OByDBeg3e.jpg'
2018-11-27 11:56:50,770 (1ff4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/8UpsPqGJppvidFUSD2OByDBeg3e.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:56:50,786 (1ff4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/6uQi0Oejmc6LLKg6UQYaDZFpX8n.jpg'
2018-11-27 11:56:50,842 (1ff4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/6uQi0Oejmc6LLKg6UQYaDZFpX8n.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:56:50,857 (1ff4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/nayJNnLwYayJYQ1garqPjQnne0g.jpg'
2018-11-27 11:56:50,937 (1ff4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/nayJNnLwYayJYQ1garqPjQnne0g.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:56:50,953 (1ff4) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/dXy55JISYOfUKZdpKG1A8yegHzw.jpg'
2018-11-27 11:56:51,013 (1ff4) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/dXy55JISYOfUKZdpKG1A8yegHzw.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:56:51,015 (1ff4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/8634202?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:56:51,506 (1ff4) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/8634202?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:56:51,506 (1ff4) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 8634202: HTTP Error 404: Not Found
2018-11-27 11:56:51,506 (1ff4) :  INFO (logkit:16) - Error obtaining extras for 8634202: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:56:51,506 (1ff4) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 8634202: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:56:51,644 (1ff4) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 1739 KB (1024 items)
2018-11-27 11:56:51,671 (1ff4) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=A%20Shoe%20Addict%27s%20Christmas'
2018-11-27 11:56:52,016 (1ff4) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=A%20Shoe%20Addict%27s%20Christmas'
2018-11-27 11:56:52,016 (1ff4) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 8634202: HTTP Error 401: Unauthorized
2018-11-27 11:56:52,042 (1ff4) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\8\55c6372015c6667592e2c9b9e5d7051cdb6f076.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 11:56:52,043 (1ff4) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 11:56:52,043 (1ff4) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-27 11:57:52,808 (17a8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzQKQ2hyaXN0bWFzIG9uIEhvbmV5c3Vja2xlIExhbmUgMjAxOHM0Cm5hbWVzODcKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwb24lMjBIb25leXN1Y2tsZSUyMExhbmUlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjFzMgppZHIwCg__
2018-11-27 11:57:52,809 (17a8) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-27 11:57:52,809 (17a8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMzQKQ2hyaXN0bWFzIG9uIEhvbmV5c3Vja2xlIExhbmUgMjAxOHM0Cm5hbWVzODcKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwb24lMjBIb25leXN1Y2tsZSUyMExhbmUlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjFzMgppZHIwCg__
2018-11-27 11:57:52,809 (17a8) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Christmas on Honeysuckle Lane 2018', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CChristmas%20on%20Honeysuckle%20Lane%202018%2Emp4', 'plexHash': None, 'duration': '-1', 'id': '37421'}
2018-11-27 11:57:52,811 (17a8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37421/tree'
2018-11-27 11:57:52,813 (17a8) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas on Honeysuckle Lane 2018.mp4]
2018-11-27 11:57:52,813 (17a8) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:57:52,815 (17a8) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 11:57:52,815 (17a8) :  INFO (logkit:16) - {}
2018-11-27 11:57:52,815 (17a8) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/0f/0f5476d04b5d7f4839ebc4a40a4a6563a6019b43.xml
2018-11-27 11:57:52,828 (17a8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/0f/0f5476d04b5d7f4839ebc4a40a4a6563a6019b43.xml'
2018-11-27 11:57:53,028 (17a8) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:57:53,028 (17a8) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 11:57:53,028 (17a8) :  INFO (logkit:16) - {}
2018-11-27 11:57:53,029 (17a8) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 11:57:53,042 (17a8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DChristmas%2Bon%2BHoneysuckle%2BLane%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 11:57:55,494 (17a8) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 11:57:55,509 (17a8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Christmas%20on%20Honeysuckle%20Lane%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 11:57:56,055 (17a8) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/i5h7cU9pHhMR28DniNCMUM8LoBX.jpg', score=98, year=2018, id='tt9083646', name='Christmas on Honeysuckle Lane')
2018-11-27 11:57:56,055 (17a8) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 11:57:56,055 (17a8) :  DEBUG (runtime:924) - Response: [200] str, 748 bytes
2018-11-27 11:57:56,107 (2a78) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTA4MzY0Nj9sYW5nPWVuczQKZ3VpZHM5CnR0OTA4MzY0NnMyCmlkcjAK
2018-11-27 11:57:56,108 (2a78) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-27 11:57:56,108 (2a78) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTA4MzY0Nj9sYW5nPWVuczQKZ3VpZHM5CnR0OTA4MzY0NnMyCmlkcjAK
2018-11-27 11:57:56,108 (2a78) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt9083646?lang=en
2018-11-27 11:57:56,109 (2a78) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\b\b8bed55630f7695c294dd78c6c3d3e13a4bba33.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 11:57:56,111 (2a78) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37421/tree'
2018-11-27 11:57:56,114 (2a78) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas on Honeysuckle Lane 2018.mp4]
2018-11-27 11:57:56,142 (2a78) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 11:57:56,184 (2a78) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9083646%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 11:57:58,371 (2a78) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9083646%2Fimages'
2018-11-27 11:58:00,555 (2a78) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F553798%2Frecommendations'
2018-11-27 11:58:02,756 (2a78) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/wHqyVR3C2NNr8xfIC3mI1yvBPdn.jpg'
2018-11-27 11:58:02,818 (2a78) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/wHqyVR3C2NNr8xfIC3mI1yvBPdn.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:58:02,839 (2a78) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/nkF9RAZGzyXQwt8hzegKDIA3Gnx.jpg'
2018-11-27 11:58:02,913 (2a78) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/nkF9RAZGzyXQwt8hzegKDIA3Gnx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:58:02,940 (2a78) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/848Emiim0Q7XQRg534rglQVR0K8.jpg'
2018-11-27 11:58:02,997 (2a78) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/848Emiim0Q7XQRg534rglQVR0K8.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:58:03,019 (2a78) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/i5h7cU9pHhMR28DniNCMUM8LoBX.jpg'
2018-11-27 11:58:03,082 (2a78) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/i5h7cU9pHhMR28DniNCMUM8LoBX.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:58:03,085 (2a78) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/9083646?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:58:03,549 (2a78) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/9083646?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:58:03,549 (2a78) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 9083646: HTTP Error 404: Not Found
2018-11-27 11:58:03,549 (2a78) :  INFO (logkit:16) - Error obtaining extras for 9083646: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:58:03,549 (2a78) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 9083646: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:58:03,572 (2a78) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20on%20Honeysuckle%20Lane'
2018-11-27 11:58:03,907 (2a78) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20on%20Honeysuckle%20Lane'
2018-11-27 11:58:03,907 (2a78) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 9083646: HTTP Error 401: Unauthorized
2018-11-27 11:58:03,940 (2a78) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\b\b8bed55630f7695c294dd78c6c3d3e13a4bba33.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 11:58:03,944 (2a78) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 11:58:03,944 (2a78) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-27 11:59:28,036 (1f0) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjgKQ2hyaXN0bWFzIGF0IHRoZSBQYWxhY2UgMjAxOHM0Cm5hbWVzODEKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwYXQlMjB0aGUlMjBQYWxhY2UlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjJzMgppZHIwCg__
2018-11-27 11:59:28,038 (1f0) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-27 11:59:28,038 (1f0) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjgKQ2hyaXN0bWFzIGF0IHRoZSBQYWxhY2UgMjAxOHM0Cm5hbWVzODEKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwYXQlMjB0aGUlMjBQYWxhY2UlMjAyMDE4JTJFbXA0czgKZmlsZW5hbWVuczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjJzMgppZHIwCg__
2018-11-27 11:59:28,038 (1f0) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Christmas at the Palace 2018', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CChristmas%20at%20the%20Palace%202018%2Emp4', 'plexHash': None, 'duration': '-1', 'id': '37422'}
2018-11-27 11:59:28,039 (1f0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37422/tree'
2018-11-27 11:59:28,046 (1f0) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas at the Palace 2018.mp4]
2018-11-27 11:59:28,046 (1f0) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:59:28,046 (1f0) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 11:59:28,046 (1f0) :  INFO (logkit:16) - {}
2018-11-27 11:59:28,046 (1f0) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/9f/9f0abbffd2bc58e1dedf31d6c55118afcba4e6d1.xml
2018-11-27 11:59:28,062 (1f0) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/9f/9f0abbffd2bc58e1dedf31d6c55118afcba4e6d1.xml'
2018-11-27 11:59:28,232 (1f0) :  INFO (logkit:16) - Scoring {}
2018-11-27 11:59:28,232 (1f0) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 11:59:28,233 (1f0) :  INFO (logkit:16) - {}
2018-11-27 11:59:28,233 (1f0) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 11:59:28,249 (1f0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DChristmas%2Bat%2Bthe%2BPalace%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 11:59:30,424 (1f0) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 11:59:30,440 (1f0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Christmas%20at%20the%20Palace%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 11:59:31,038 (1f0) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/yZScfCQI6ZX8IRYqAuG13tAgt28.jpg', score=98, year=2018, id='tt9033632', name='Christmas at the Palace')
2018-11-27 11:59:31,039 (1f0) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 11:59:31,039 (1f0) :  DEBUG (runtime:924) - Response: [200] str, 740 bytes
2018-11-27 11:59:31,092 (2424) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTAzMzYzMj9sYW5nPWVuczQKZ3VpZHM5CnR0OTAzMzYzMnMyCmlkcjAK
2018-11-27 11:59:31,092 (2424) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-27 11:59:31,092 (2424) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyMnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTAzMzYzMj9sYW5nPWVuczQKZ3VpZHM5CnR0OTAzMzYzMnMyCmlkcjAK
2018-11-27 11:59:31,094 (2424) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt9033632?lang=en
2018-11-27 11:59:31,095 (2424) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\c\fb559c021f69511d6e32e8914c5e2586c8d45c0.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 11:59:31,095 (2424) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37422/tree'
2018-11-27 11:59:31,098 (2424) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas at the Palace 2018.mp4]
2018-11-27 11:59:31,130 (2424) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 11:59:31,177 (2424) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9033632%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 11:59:33,361 (2424) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9033632%2Fimages'
2018-11-27 11:59:35,543 (2424) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F550023%2Frecommendations'
2018-11-27 11:59:37,740 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/qM6N7TRvSxuIVrEc2w0tMSjNQ3Z.jpg'
2018-11-27 11:59:37,815 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/qM6N7TRvSxuIVrEc2w0tMSjNQ3Z.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:37,831 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/aviwpfNlrmagGg2KDwykTPfHXLU.jpg'
2018-11-27 11:59:37,894 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/aviwpfNlrmagGg2KDwykTPfHXLU.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:37,911 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/yZScfCQI6ZX8IRYqAuG13tAgt28.jpg'
2018-11-27 11:59:37,977 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/yZScfCQI6ZX8IRYqAuG13tAgt28.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:37,994 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/1aqpMQWHJjbKpU7cyrnwnu5k1me.jpg'
2018-11-27 11:59:38,299 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/1aqpMQWHJjbKpU7cyrnwnu5k1me.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:38,315 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/sxVCu9fzQTxRg5vTHYpJUWmK7ic.jpg'
2018-11-27 11:59:38,404 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/sxVCu9fzQTxRg5vTHYpJUWmK7ic.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:38,420 (2424) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lD6xv3dz6AStcYsrDk6sqHKMgxD.jpg'
2018-11-27 11:59:38,480 (2424) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lD6xv3dz6AStcYsrDk6sqHKMgxD.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 11:59:38,483 (2424) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/9033632?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:59:38,970 (2424) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/9033632?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 11:59:38,971 (2424) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 9033632: HTTP Error 404: Not Found
2018-11-27 11:59:38,971 (2424) :  INFO (logkit:16) - Error obtaining extras for 9033632: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:59:38,973 (2424) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 9033632: 'NoneType' object has no attribute 'xpath'
2018-11-27 11:59:38,987 (2424) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20at%20the%20Palace'
2018-11-27 11:59:39,479 (2424) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20at%20the%20Palace'
2018-11-27 11:59:39,479 (2424) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 9033632: HTTP Error 401: Unauthorized
2018-11-27 11:59:39,516 (2424) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\c\fb559c021f69511d6e32e8914c5e2586c8d45c0.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 11:59:39,519 (2424) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 11:59:39,519 (2424) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-27 12:01:54,627 (2dc4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKQ2hyaXN0bWFzIEV2ZXJsYXN0aW5nIDIwMThzNApuYW1lczc1CkYlM0ElNUNQbGF5b24lNUNIYWxsbWFyayU1Q0NocmlzdG1hcyU1Q0NocmlzdG1hcyUyMEV2ZXJsYXN0aW5nJTIwMjAxOCUyRW1wNHM4CmZpbGVuYW1lbnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDIzczIKaWRyMAo_
2018-11-27 12:01:54,628 (2dc4) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-27 12:01:54,628 (2dc4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKQ2hyaXN0bWFzIEV2ZXJsYXN0aW5nIDIwMThzNApuYW1lczc1CkYlM0ElNUNQbGF5b24lNUNIYWxsbWFyayU1Q0NocmlzdG1hcyU1Q0NocmlzdG1hcyUyMEV2ZXJsYXN0aW5nJTIwMjAxOCUyRW1wNHM4CmZpbGVuYW1lbnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDIzczIKaWRyMAo_
2018-11-27 12:01:54,630 (2dc4) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Christmas Everlasting 2018', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CChristmas%20Everlasting%202018%2Emp4', 'plexHash': None, 'duration': '-1', 'id': '37423'}
2018-11-27 12:01:54,631 (2dc4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37423/tree'
2018-11-27 12:01:54,638 (2dc4) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas Everlasting 2018.mp4]
2018-11-27 12:01:54,638 (2dc4) :  INFO (logkit:16) - Scoring {}
2018-11-27 12:01:54,638 (2dc4) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 12:01:54,638 (2dc4) :  INFO (logkit:16) - {}
2018-11-27 12:01:54,638 (2dc4) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/dd/dd9b7ba3b9c4a92f730adf5beb2e02703c44e29f.xml
2018-11-27 12:01:54,661 (2dc4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/dd/dd9b7ba3b9c4a92f730adf5beb2e02703c44e29f.xml'
2018-11-27 12:01:54,865 (2dc4) :  INFO (logkit:16) - Scoring {'tt9028802': [5, u'Christmas Everlasting', u'2018', 100, 200, 0]}
2018-11-27 12:01:54,867 (2dc4) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 12:01:54,867 (2dc4) :  INFO (logkit:16) - {'tt9028802': [5, u'Christmas Everlasting', u'2018', 100, 200, 89]}
2018-11-27 12:01:54,891 (2dc4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9028802%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-27 12:01:57,382 (2dc4) :  INFO (logkit:16) - Adding title_year match: Christmas Everlasting (2018) score=89, key=tt9028802
2018-11-27 12:01:57,382 (2dc4) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 12:01:57,414 (2dc4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DChristmas%2BEverlasting%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 12:01:59,878 (2dc4) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 12:01:59,904 (2dc4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Christmas%20Everlasting%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 12:02:00,469 (2dc4) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/zMKd2QgO7OCQn25bqUbGmBupBOG.jpg', score=98, year=2018, id='tt9028802', name='Christmas Everlasting')
2018-11-27 12:02:00,470 (2dc4) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 12:02:00,470 (2dc4) :  DEBUG (runtime:924) - Response: [200] str, 740 bytes
2018-11-27 12:02:00,519 (e44) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyM3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTAyODgwMj9sYW5nPWVuczQKZ3VpZHM5CnR0OTAyODgwMnMyCmlkcjAK
2018-11-27 12:02:00,520 (e44) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-27 12:02:00,522 (e44) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyM3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0OTAyODgwMj9sYW5nPWVuczQKZ3VpZHM5CnR0OTAyODgwMnMyCmlkcjAK
2018-11-27 12:02:00,523 (e44) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt9028802?lang=en
2018-11-27 12:02:00,523 (e44) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\e0966bb31b66da8cb8cd52ba86faec3547fa0d8.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 12:02:00,523 (e44) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37423/tree'
2018-11-27 12:02:00,529 (e44) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Christmas Everlasting 2018.mp4]
2018-11-27 12:02:00,592 (e44) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 12:02:00,667 (e44) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9028802%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 12:02:02,880 (e44) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt9028802%2Fimages'
2018-11-27 12:02:05,095 (e44) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F549319%2Frecommendations'
2018-11-27 12:02:07,313 (e44) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/k8SdUjTNfQmtzZB1JYQODNlTtmK.jpg'
2018-11-27 12:02:07,372 (e44) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/k8SdUjTNfQmtzZB1JYQODNlTtmK.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:02:07,400 (e44) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/mnyC3pSz1e0YWrKIzGmMROI4Msk.jpg'
2018-11-27 12:02:07,469 (e44) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/mnyC3pSz1e0YWrKIzGmMROI4Msk.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:02:07,492 (e44) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pt00qo06Z8qPZFEfa1cnHMtngcV.jpg'
2018-11-27 12:02:07,561 (e44) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pt00qo06Z8qPZFEfa1cnHMtngcV.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:02:07,588 (e44) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/zMKd2QgO7OCQn25bqUbGmBupBOG.jpg'
2018-11-27 12:02:07,650 (e44) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/zMKd2QgO7OCQn25bqUbGmBupBOG.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:02:07,657 (e44) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/9028802?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 12:02:07,891 (e44) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/9028802?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 12:02:07,891 (e44) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 9028802: HTTP Error 404: Not Found
2018-11-27 12:02:07,891 (e44) :  INFO (logkit:16) - Error obtaining extras for 9028802: 'NoneType' object has no attribute 'xpath'
2018-11-27 12:02:07,892 (e44) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 9028802: 'NoneType' object has no attribute 'xpath'
2018-11-27 12:02:07,917 (e44) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20Everlasting'
2018-11-27 12:02:08,430 (e44) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20Everlasting'
2018-11-27 12:02:08,430 (e44) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 9028802: HTTP Error 401: Unauthorized
2018-11-27 12:02:08,469 (e44) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\e0966bb31b66da8cb8cd52ba86faec3547fa0d8.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 12:02:08,470 (e44) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 12:02:08,470 (e44) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-27 12:03:12,069 (22c4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjcKTGFzdCBWZXJtb250IENocmlzdG1hcyAyMDE4czQKbmFtZXM3OApGJTNBJTVDUGxheW9uJTVDSGFsbG1hcmslNUNDaHJpc3RtYXMlNUNMYXN0JTIwVmVybW9udCUyMENocmlzdG1hcyUyMDIwMTglMkVtcDRzOApmaWxlbmFtZW5zOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQozNzQyNHMyCmlkcjAK
2018-11-27 12:03:12,071 (22c4) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-27 12:03:12,071 (22c4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjcKTGFzdCBWZXJtb250IENocmlzdG1hcyAyMDE4czQKbmFtZXM3OApGJTNBJTVDUGxheW9uJTVDSGFsbG1hcmslNUNDaHJpc3RtYXMlNUNMYXN0JTIwVmVybW9udCUyMENocmlzdG1hcyUyMDIwMTglMkVtcDRzOApmaWxlbmFtZW5zOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQozNzQyNHMyCmlkcjAK
2018-11-27 12:03:12,071 (22c4) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Last Vermont Christmas 2018', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CLast%20Vermont%20Christmas%202018%2Emp4', 'plexHash': None, 'duration': '-1', 'id': '37424'}
2018-11-27 12:03:12,072 (22c4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37424/tree'
2018-11-27 12:03:12,082 (22c4) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Last Vermont Christmas 2018.mp4]
2018-11-27 12:03:12,082 (22c4) :  INFO (logkit:16) - Scoring {}
2018-11-27 12:03:12,082 (22c4) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-27 12:03:12,082 (22c4) :  INFO (logkit:16) - {}
2018-11-27 12:03:12,082 (22c4) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/72/721995e374695c27faa1f8feaa662a8f48087115.xml
2018-11-27 12:03:12,112 (22c4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/72/721995e374695c27faa1f8feaa662a8f48087115.xml'
2018-11-27 12:03:12,345 (22c4) :  INFO (logkit:16) - Scoring {}
2018-11-27 12:03:12,345 (22c4) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-27 12:03:12,346 (22c4) :  INFO (logkit:16) - {}
2018-11-27 12:03:12,346 (22c4) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-27 12:03:12,371 (22c4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DLast%2BVermont%2BChristmas%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-27 12:03:14,858 (22c4) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-27 12:03:14,885 (22c4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Last%20Vermont%20Christmas%202018&year=&plexHash=&lang=en&manual=0'
2018-11-27 12:03:15,591 (22c4) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/3MEE0NKgHfL5iyxU1LDl240EYzz.jpg', score=98, year=2018, id='tt8420670', name='Last Vermont Christmas')
2018-11-27 12:03:15,592 (22c4) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-27 12:03:15,592 (22c4) :  DEBUG (runtime:924) - Response: [200] str, 740 bytes
2018-11-27 12:03:15,634 (2e00) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyNHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODQyMDY3MD9sYW5nPWVuczQKZ3VpZHM5CnR0ODQyMDY3MHMyCmlkcjAK
2018-11-27 12:03:15,634 (2e00) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-27 12:03:15,635 (2e00) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyNHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODQyMDY3MD9sYW5nPWVuczQKZ3VpZHM5CnR0ODQyMDY3MHMyCmlkcjAK
2018-11-27 12:03:15,635 (2e00) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt8420670?lang=en
2018-11-27 12:03:15,637 (2e00) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\4\8122dff1ea9973bbf911ce7d1779c9eeb165f80.bundle\Contents\com.plexapp.agents.imdb
2018-11-27 12:03:15,638 (2e00) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37424/tree'
2018-11-27 12:03:15,642 (2e00) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [F:\Playon\Hallmark\Christmas\Last Vermont Christmas 2018.mp4]
2018-11-27 12:03:15,694 (2e00) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-27 12:03:15,770 (2e00) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8420670%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-27 12:03:18,253 (2e00) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8420670%2Fimages'
2018-11-27 12:03:20,773 (2e00) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F555183%2Frecommendations'
2018-11-27 12:03:23,256 (2e00) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/5rruEy31coHUwRJP4JEyXmmQCe4.jpg'
2018-11-27 12:03:23,325 (2e00) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/5rruEy31coHUwRJP4JEyXmmQCe4.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:03:23,348 (2e00) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3MEE0NKgHfL5iyxU1LDl240EYzz.jpg'
2018-11-27 12:03:23,440 (2e00) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3MEE0NKgHfL5iyxU1LDl240EYzz.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:03:23,467 (2e00) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3XstZ4BDVGcYCHoLI2irVfVhTkK.jpg'
2018-11-27 12:03:23,545 (2e00) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3XstZ4BDVGcYCHoLI2irVfVhTkK.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-27 12:03:23,548 (2e00) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/8420670?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 12:03:24,042 (2e00) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/8420670?lang=en&ratings=1&reviews=1&extras=1'
2018-11-27 12:03:24,042 (2e00) :  INFO (logkit:16) - Plex_Movie_FAILED - Error obtaining Plex movie data for 8420670: HTTP Error 404: Not Found
2018-11-27 12:03:24,042 (2e00) :  INFO (logkit:16) - Error obtaining extras for 8420670: 'NoneType' object has no attribute 'xpath'
2018-11-27 12:03:24,043 (2e00) :  INFO (logkit:16) - Error obtaining Rotten tomato data for 8420670: 'NoneType' object has no attribute 'xpath'
2018-11-27 12:03:24,066 (2e00) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Last%20Vermont%20Christmas'
2018-11-27 12:03:24,427 (2e00) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Last%20Vermont%20Christmas'
2018-11-27 12:03:24,427 (2e00) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 8420670: HTTP Error 401: Unauthorized
2018-11-27 12:03:24,454 (2e00) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\4\8122dff1ea9973bbf911ce7d1779c9eeb165f80.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-27 12:03:24,457 (2e00) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-27 12:03:24,457 (2e00) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
