2018-11-28 02:51:37,698 (27c0) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 09:36:00 UTC 2018)
2018-11-28 02:51:37,698 (27c0) :  DEBUG (core:361) - Using the elevated policy
2018-11-28 02:51:37,700 (27c0) :  DEBUG (core:450) - Starting runtime component.
2018-11-28 02:51:37,701 (27c0) :  DEBUG (core:450) - Starting caching component.
2018-11-28 02:51:37,701 (27c0) :  DEBUG (core:450) - Starting data component.
2018-11-28 02:51:37,701 (27c0) :  DEBUG (core:450) - Starting networking component.
2018-11-28 02:51:37,704 (27c0) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-28 02:51:37,720 (27c0) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-28 02:51:37,720 (27c0) :  DEBUG (core:450) - Starting localization component.
2018-11-28 02:51:37,720 (27c0) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-28 02:51:37,720 (27c0) :  DEBUG (core:450) - Starting messaging component.
2018-11-28 02:51:37,721 (27c0) :  DEBUG (core:450) - Starting debugging component.
2018-11-28 02:51:37,721 (27c0) :  DEBUG (core:450) - Starting services component.
2018-11-28 02:51:37,721 (27c0) :  DEBUG (core:450) - Starting myplex component.
2018-11-28 02:51:37,721 (27c0) :  DEBUG (core:450) - Starting notifications component.
2018-11-28 02:51:37,723 (1258) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-11-28 02:51:37,822 (27c0) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-11-28 02:51:37,828 (27c0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-28 02:51:37,844 (27c0) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-28 02:51:37,845 (1aec) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-28 02:51:37,845 (27c0) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-28 02:51:37,846 (27c0) :  DEBUG (core:150) - Finished starting framework core
2018-11-28 02:51:37,846 (27c0) :  DEBUG (core:560) - Loading plug-in code
2018-11-28 02:51:37,848 (1aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-28 02:51:37,848 (28c4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-28 02:51:38,072 (1aec) :  DEBUG (services:362) - Loaded services
2018-11-28 02:51:38,082 (80c) :  DEBUG (services:438) - No shared code to load
2018-11-28 02:51:38,270 (27c0) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-28 02:51:38,792 (27c0) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-11-28 02:51:38,792 (27c0) :  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-28 02:51:38,793 (27c0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-11-28 02:51:38,806 (27c0) :  INFO (core:611) - Started plug-in
2018-11-28 02:51:38,806 (27c0) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-28 02:51:38,808 (27c0) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-28 02:51:38,809 (27c0) :  INFO (socketinterface:184) - Socket server started on port 62108
2018-11-28 02:51:38,809 (27c0) :  INFO (pipeinterface:25) - Entering run loop
2018-11-28 02:51:38,809 (27c0) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-28 02:51:38,809 (27c0) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-28 02:51:38,809 (27c0) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-11-28 02:51:38,832 (2928) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgpjMjZmM2EyYzYxOTE4YmM1czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczI0ClRoZSBDaHJpc3RtYXMgQ2hyb25pY2xlc3M0Cm5hbWVzNzMKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDVGhlJTIwQ2hyaXN0bWFzJTIwQ2hyb25pY2xlcyUyRW1wNHM4CmZpbGVuYW1lczQwCjcwODM0NGJkYzAyMDBlYTcwNGVlNjUxMTQ3YThkNGYyZTBkM2E5MTlzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQozNzQyOHMyCmlkcjAK
2018-11-28 02:51:38,839 (2928) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgpjMjZmM2EyYzYxOTE4YmM1czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczI0ClRoZSBDaHJpc3RtYXMgQ2hyb25pY2xlc3M0Cm5hbWVzNzMKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDVGhlJTIwQ2hyaXN0bWFzJTIwQ2hyb25pY2xlcyUyRW1wNHM4CmZpbGVuYW1lczQwCjcwODM0NGJkYzAyMDBlYTcwNGVlNjUxMTQ3YThkNGYyZTBkM2E5MTlzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQozNzQyOHMyCmlkcjAK
2018-11-28 02:51:38,841 (2928) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'c26f3a2c61918bc5', 'name': 'The Christmas Chronicles', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CThe%20Christmas%20Chronicles%2Emp4', 'plexHash': '708344bdc0200ea704ee651147a8d4f2e0d3a919', 'duration': '-1', 'id': '37428'}
2018-11-28 02:51:38,842 (2928) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37428/tree'
2018-11-28 02:51:38,846 (2928) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/70/708344bdc0200ea704ee651147a8d4f2e0d3a919.xml
2018-11-28 02:51:38,862 (2928) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/70/708344bdc0200ea704ee651147a8d4f2e0d3a919.xml'
2018-11-28 02:51:39,480 (2928) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/70/708344bdc0200ea704ee651147a8d4f2e0d3a919.xml'
2018-11-28 02:51:39,480 (2928) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-11-28 02:51:39,480 (2928) :  INFO (logkit:16) - Scoring {}
2018-11-28 02:51:39,480 (2928) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-28 02:51:39,482 (2928) :  INFO (logkit:16) - {}
2018-11-28 02:51:39,482 (2928) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/fe/fe87818b555c654e1e1680619d6b262dcf5f2af7.xml
2018-11-28 02:51:39,759 (2928) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/fe/fe87818b555c654e1e1680619d6b262dcf5f2af7.xml'
2018-11-28 02:51:39,900 (2928) :  INFO (logkit:16) - Scoring {}
2018-11-28 02:51:39,900 (2928) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-28 02:51:39,900 (2928) :  INFO (logkit:16) - {}
2018-11-28 02:51:39,901 (2928) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-28 02:51:39,905 (2928) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-11-28 02:51:39,907 (2928) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-11-28 02:51:39,921 (2928) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DThe%2BChristmas%2BChronicles%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-28 02:51:42,286 (2928) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNgo1Mjc0MzVzNwp0bWRiX2lkcjAK'
2018-11-28 02:51:46,257 (28c4) :  DEBUG (core:538) - Machine identifier is 6d991ea58d477b0ed9af8a2e324fd8474eb0a67a
2018-11-28 02:51:46,259 (28c4) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-28 02:51:46,696 (2928) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=90, year=2018, id=u'tt2990140', name=u'The Christmas Chronicles')
2018-11-28 02:51:46,696 (2928) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-28 02:51:46,710 (2928) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=The%20Christmas%20Chronicles&year=&plexHash=708344bdc0200ea704ee651147a8d4f2e0d3a919&lang=en&manual=0'
2018-11-28 02:51:47,309 (2928) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/3xGoeE32Dk3KlDADbb8DkYDx1Y7.jpg', score=101, year=2018, id='tt2990140', name='The Christmas Chronicles')
2018-11-28 02:51:47,309 (2928) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-28 02:51:47,309 (2928) :  DEBUG (runtime:924) - Response: [200] str, 744 bytes
2018-11-28 02:51:47,392 (22e8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyOHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0Mjk5MDE0MD9sYW5nPWVuczQKZ3VpZHM5CnR0Mjk5MDE0MHMyCmlkcjAK
2018-11-28 02:51:47,394 (22e8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyOHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0Mjk5MDE0MD9sYW5nPWVuczQKZ3VpZHM5CnR0Mjk5MDE0MHMyCmlkcjAK
2018-11-28 02:51:47,394 (22e8) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt2990140?lang=en
2018-11-28 02:51:47,395 (22e8) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\3\9e9ba172b4f1c5b29eb8da809ffea363169462a.bundle\Contents\com.plexapp.agents.imdb
2018-11-28 02:51:47,397 (22e8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37428/tree'
2018-11-28 02:51:47,426 (2a68) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgowNmFiNmQ5NGIzOTkyZDc4czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxCkNocmlzdG1hcyBJbmhlcml0YW5jZXM0Cm5hbWVzNjgKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwSW5oZXJpdGFuY2UlMkVtcDRzOApmaWxlbmFtZXM0MAoyMzIwYTRiM2UwMzhmMTI0YmYwNzVkMGUzOGFmNzdjOGNmOTg0MzI5czgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjdzMgppZHIwCg__
2018-11-28 02:51:47,427 (2a68) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-28 02:51:47,428 (2a68) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgowNmFiNmQ5NGIzOTkyZDc4czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxCkNocmlzdG1hcyBJbmhlcml0YW5jZXM0Cm5hbWVzNjgKRiUzQSU1Q1BsYXlvbiU1Q0hhbGxtYXJrJTVDQ2hyaXN0bWFzJTVDQ2hyaXN0bWFzJTIwSW5oZXJpdGFuY2UlMkVtcDRzOApmaWxlbmFtZXM0MAoyMzIwYTRiM2UwMzhmMTI0YmYwNzVkMGUzOGFmNzdjOGNmOTg0MzI5czgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMzc0MjdzMgppZHIwCg__
2018-11-28 02:51:47,430 (2a68) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '06ab6d94b3992d78', 'name': 'Christmas Inheritance', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CChristmas%20Inheritance%2Emp4', 'plexHash': '2320a4b3e038f124bf075d0e38af77c8cf984329', 'duration': '-1', 'id': '37427'}
2018-11-28 02:51:47,430 (2a68) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37427/tree'
2018-11-28 02:51:47,436 (2a68) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/23/2320a4b3e038f124bf075d0e38af77c8cf984329.xml
2018-11-28 02:51:47,440 (22e8) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-28 02:51:47,470 (2a68) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/23/2320a4b3e038f124bf075d0e38af77c8cf984329.xml'
2018-11-28 02:51:47,503 (22e8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt2990140%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-28 02:51:47,957 (2a68) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/23/2320a4b3e038f124bf075d0e38af77c8cf984329.xml'
2018-11-28 02:51:47,957 (2a68) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-11-28 02:51:47,957 (2a68) :  INFO (logkit:16) - Scoring {}
2018-11-28 02:51:47,957 (2a68) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-28 02:51:47,957 (2a68) :  INFO (logkit:16) - {}
2018-11-28 02:51:47,957 (2a68) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/b6/b6524b0637340095df06897ff2eb7f95a0c77e83.xml
2018-11-28 02:51:48,210 (2a68) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/b6/b6524b0637340095df06897ff2eb7f95a0c77e83.xml' from the HTTP cache
2018-11-28 02:51:48,240 (2a68) :  INFO (logkit:16) - Scoring {}
2018-11-28 02:51:48,240 (2a68) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-28 02:51:48,240 (2a68) :  INFO (logkit:16) - {}
2018-11-28 02:51:48,240 (2a68) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-28 02:51:48,272 (2a68) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DChristmas%2BInheritance%26year%3D%26language%3Den%26include_adult%3Dfalse' from the HTTP cache
2018-11-28 02:51:48,301 (2a68) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNgo0NDk3NTVzNwp0bWRiX2lkcjAK'
2018-11-28 02:51:50,257 (22e8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt2990140%2Fimages'
2018-11-28 02:51:50,520 (2a68) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=90, year=2017, id=u'tt7608534', name=u'Christmas Inheritance')
2018-11-28 02:51:50,520 (2a68) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-28 02:51:50,536 (2a68) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Christmas%20Inheritance&year=&plexHash=2320a4b3e038f124bf075d0e38af77c8cf984329&lang=en&manual=0'
2018-11-28 02:51:51,321 (2a68) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/sqdbF40nBzS2IZVAGPxJT7loxoM.jpg', score=101, year=2017, id='tt7608534', name='Christmas Inheritance')
2018-11-28 02:51:51,321 (2a68) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-28 02:51:51,321 (2a68) :  DEBUG (runtime:924) - Response: [200] str, 740 bytes
2018-11-28 02:51:51,375 (1aa8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyN3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0NzYwODUzND9sYW5nPWVuczQKZ3VpZHM5CnR0NzYwODUzNHMyCmlkcjAK
2018-11-28 02:51:51,377 (1aa8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyN3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0NzYwODUzND9sYW5nPWVuczQKZ3VpZHM5CnR0NzYwODUzNHMyCmlkcjAK
2018-11-28 02:51:51,378 (1aa8) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt7608534?lang=en
2018-11-28 02:51:51,378 (1aa8) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\d\5698fdc217f00cedebe3974dcec205fbc24cb86.bundle\Contents\com.plexapp.agents.imdb
2018-11-28 02:51:51,378 (1aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37427/tree'
2018-11-28 02:51:51,392 (2cf8) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgo5MDJhNGNjMzgzMWM5YWNhczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE5ClRoZSBQcmluY2VzcyBTd2l0Y2hzNApuYW1lczY4CkYlM0ElNUNQbGF5b24lNUNIYWxsbWFyayU1Q0NocmlzdG1hcyU1Q1RoZSUyMFByaW5jZXNzJTIwU3dpdGNoJTJFbXA0czgKZmlsZW5hbWVzNDAKMDZlZGNmODZhYTI2ODIxNzhmMmY4YjU0ZDE1ODhlOGM2ZTA0ZjVlNnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDI5czIKaWRyMAo_
2018-11-28 02:51:51,394 (2cf8) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-11-28 02:51:51,394 (2cf8) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgo5MDJhNGNjMzgzMWM5YWNhczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE5ClRoZSBQcmluY2VzcyBTd2l0Y2hzNApuYW1lczY4CkYlM0ElNUNQbGF5b24lNUNIYWxsbWFyayU1Q0NocmlzdG1hcyU1Q1RoZSUyMFByaW5jZXNzJTIwU3dpdGNoJTJFbXA0czgKZmlsZW5hbWVzNDAKMDZlZGNmODZhYTI2ODIxNzhmMmY4YjU0ZDE1ODhlOGM2ZTA0ZjVlNnM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjM3NDI5czIKaWRyMAo_
2018-11-28 02:51:51,394 (2cf8) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '902a4cc3831c9aca', 'name': 'The Princess Switch', 'filename': 'F%3A%5CPlayon%5CHallmark%5CChristmas%5CThe%20Princess%20Switch%2Emp4', 'plexHash': '06edcf86aa2682178f2f8b54d1588e8c6e04f5e6', 'duration': '-1', 'id': '37429'}
2018-11-28 02:51:51,394 (2cf8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37429/tree'
2018-11-28 02:51:51,401 (2cf8) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/06/06edcf86aa2682178f2f8b54d1588e8c6e04f5e6.xml
2018-11-28 02:51:51,414 (1aa8) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-28 02:51:51,431 (2cf8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/06/06edcf86aa2682178f2f8b54d1588e8c6e04f5e6.xml'
2018-11-28 02:51:51,479 (1aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt7608534%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-28 02:51:51,881 (2cf8) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/06/06edcf86aa2682178f2f8b54d1588e8c6e04f5e6.xml'
2018-11-28 02:51:51,881 (2cf8) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-11-28 02:51:51,881 (2cf8) :  INFO (logkit:16) - Scoring {}
2018-11-28 02:51:51,881 (2cf8) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-11-28 02:51:51,881 (2cf8) :  INFO (logkit:16) - {}
2018-11-28 02:51:51,881 (2cf8) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/08/087f10c6598167a41e0391a26d18d4723312ae45.xml
2018-11-28 02:51:52,349 (2cf8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/08/087f10c6598167a41e0391a26d18d4723312ae45.xml'
2018-11-28 02:51:52,424 (22e8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F527435%2Frecommendations'
2018-11-28 02:51:52,506 (2cf8) :  INFO (logkit:16) - Scoring {'tt8954732': [0, u'The Princess Switch', None, 100, 71, 0]}
2018-11-28 02:51:52,506 (2cf8) :  INFO (logkit:16) - Exception applying year penalty/bonus
2018-11-28 02:51:52,506 (2cf8) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-11-28 02:51:52,506 (2cf8) :  INFO (logkit:16) - {'tt8954732': [0, u'The Princess Switch', None, 100, 71, 91]}
2018-11-28 02:51:52,522 (2cf8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8954732%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-28 02:51:53,658 (1aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt7608534%2Fimages'
2018-11-28 02:51:54,619 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/vUbWBVHngojXOu1kBgH6xFkZZTA.jpg'
2018-11-28 02:51:54,674 (2cf8) :  INFO (logkit:16) - Adding title_year match: The Princess Switch (2018) score=91, key=tt8954732
2018-11-28 02:51:54,674 (2cf8) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-11-28 02:51:54,690 (2cf8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DThe%2BPrincess%2BSwitch%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-11-28 02:51:54,700 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/vUbWBVHngojXOu1kBgH6xFkZZTA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:54,717 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/pTyvOrKfvSEzrZ5ZoIoy3HpoRVy.jpg'
2018-11-28 02:51:54,776 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/pTyvOrKfvSEzrZ5ZoIoy3HpoRVy.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:54,792 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/vBOiZj6QVkybUEKmcCHYu2vcQPv.jpg'
2018-11-28 02:51:54,851 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/vBOiZj6QVkybUEKmcCHYu2vcQPv.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:54,868 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/tk7C13iul0iwbLGhST1PoYMT3xx.jpg'
2018-11-28 02:51:54,933 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/tk7C13iul0iwbLGhST1PoYMT3xx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:54,950 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/mkgt0E6SmdY2srx6M1jaBb403Z9.jpg'
2018-11-28 02:51:55,009 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/mkgt0E6SmdY2srx6M1jaBb403Z9.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,506 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/3Lk4cObYl9iu6I5dVDciODQ45Ks.jpg'
2018-11-28 02:51:55,562 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/3Lk4cObYl9iu6I5dVDciODQ45Ks.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,578 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/nNXx1OiBEprTj3uS5t3PUYhvBuH.jpg'
2018-11-28 02:51:55,648 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/nNXx1OiBEprTj3uS5t3PUYhvBuH.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,663 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/qY7ARp5zxbCrUpzdJrYsqyA4KC3.jpg'
2018-11-28 02:51:55,717 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/qY7ARp5zxbCrUpzdJrYsqyA4KC3.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,733 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/otRkTvLPQdzQIw6Tj6aUPaMVCCS.jpg'
2018-11-28 02:51:55,786 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/otRkTvLPQdzQIw6Tj6aUPaMVCCS.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,802 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qz2dTKSQ9VSAswb1O9jWS15jqL1.jpg'
2018-11-28 02:51:55,861 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qz2dTKSQ9VSAswb1O9jWS15jqL1.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,875 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3xGoeE32Dk3KlDADbb8DkYDx1Y7.jpg'
2018-11-28 02:51:55,938 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3xGoeE32Dk3KlDADbb8DkYDx1Y7.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:55,953 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pH4hDqlrWIzTNBaBkxG4gJxh4Sh.jpg'
2018-11-28 02:51:56,017 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pH4hDqlrWIzTNBaBkxG4gJxh4Sh.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:56,032 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/ojowwITvzQAk624fxuVfSYtB3vo.jpg'
2018-11-28 02:51:56,092 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/ojowwITvzQAk624fxuVfSYtB3vo.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:56,107 (22e8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/5Il2EMSF2KecrUKZPuen6BZmaCP.jpg'
2018-11-28 02:51:56,158 (22e8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/5Il2EMSF2KecrUKZPuen6BZmaCP.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:56,164 (22e8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/2990140?lang=en&ratings=1&reviews=1&extras=1'
2018-11-28 02:51:56,303 (22e8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt2990140%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-28 02:51:56,484 (1aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F449755%2Frecommendations'
2018-11-28 02:51:56,861 (2cf8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNgo1NTY4MDNzNwp0bWRiX2lkcjAK'
2018-11-28 02:51:58,460 (22e8) :  INFO (logkit:16) - Added 0 of 0 extras.
2018-11-28 02:51:58,493 (22e8) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Christmas%20Chronicles'
2018-11-28 02:51:58,927 (22e8) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Christmas%20Chronicles'
2018-11-28 02:51:58,927 (22e8) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 2990140: HTTP Error 401: Unauthorized
2018-11-28 02:51:59,006 (22e8) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\3\9e9ba172b4f1c5b29eb8da809ffea363169462a.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-28 02:51:59,009 (22e8) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-28 02:51:59,009 (22e8) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-28 02:51:59,099 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/n1daj6hS4yf858HgPfe7kGyjISp.jpg'
2018-11-28 02:51:59,165 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/n1daj6hS4yf858HgPfe7kGyjISp.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:59,181 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/w1JmwP9Xpr8XzdaGLXZnkRSaWAo.jpg'
2018-11-28 02:51:59,236 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/w1JmwP9Xpr8XzdaGLXZnkRSaWAo.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:59,253 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/dR0ynNHyD8mv2OeUqWG05wRcbUg.jpg'
2018-11-28 02:51:59,318 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/dR0ynNHyD8mv2OeUqWG05wRcbUg.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:59,334 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qOl3l8cYBsM29APM2k20UhmDTKG.jpg'
2018-11-28 02:51:59,404 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qOl3l8cYBsM29APM2k20UhmDTKG.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:59,420 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/q9pXHlvk1MxcvVPoRtIaQoj8xYx.jpg'
2018-11-28 02:51:59,474 (2cf8) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=90, year=2018, id=u'tt8954732', name=u'The Princess Switch')
2018-11-28 02:51:59,474 (2cf8) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-11-28 02:51:59,477 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/q9pXHlvk1MxcvVPoRtIaQoj8xYx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:51:59,492 (2cf8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=The%20Princess%20Switch&year=&plexHash=06edcf86aa2682178f2f8b54d1588e8c6e04f5e6&lang=en&manual=0'
2018-11-28 02:51:59,509 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3tI7yXrVPbdg2GNsDXpdJ77VysX.jpg'
2018-11-28 02:51:59,569 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3tI7yXrVPbdg2GNsDXpdJ77VysX.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:00,069 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/mq3E3RQaGRr9Fid4kCQuUFUltGG.jpg'
2018-11-28 02:52:00,101 (2cf8) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/5FssOF42lrsVbJiMe4XNW5IF5hA.jpg', score=101, year=2018, id='tt8954732', name='The Princess Switch')
2018-11-28 02:52:00,101 (2cf8) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-11-28 02:52:00,102 (2cf8) :  DEBUG (runtime:924) - Response: [200] str, 736 bytes
2018-11-28 02:52:00,128 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/mq3E3RQaGRr9Fid4kCQuUFUltGG.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:00,148 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/xvJMNDSizLT7cEyeJmnzKvMCRvJ.jpg'
2018-11-28 02:52:00,157 (2c84) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyOXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODk1NDczMj9sYW5nPWVuczQKZ3VpZHM5CnR0ODk1NDczMnMyCmlkcjAK
2018-11-28 02:52:00,157 (2c84) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-11-28 02:52:00,158 (2c84) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQozNzQyOXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0ODk1NDczMj9sYW5nPWVuczQKZ3VpZHM5CnR0ODk1NDczMnMyCmlkcjAK
2018-11-28 02:52:00,158 (2c84) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt8954732?lang=en
2018-11-28 02:52:00,160 (2c84) :  ERROR (model:205) - Cannot read model from C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\53116184ab4dff6ac0faa7a2f6c115c80277ee7.bundle\Contents\com.plexapp.agents.imdb
2018-11-28 02:52:00,160 (2c84) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/37429/tree'
2018-11-28 02:52:00,196 (2c84) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-11-28 02:52:00,211 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/xvJMNDSizLT7cEyeJmnzKvMCRvJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:00,243 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/7xbbdYGD5G7xt9YU8upRDC5u6m7.jpg'
2018-11-28 02:52:00,259 (2c84) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8954732%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-11-28 02:52:00,302 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/7xbbdYGD5G7xt9YU8upRDC5u6m7.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:00,318 (1aa8) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/sqdbF40nBzS2IZVAGPxJT7loxoM.jpg'
2018-11-28 02:52:00,375 (1aa8) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/sqdbF40nBzS2IZVAGPxJT7loxoM.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:00,378 (1aa8) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/7608534?lang=en&ratings=1&reviews=1&extras=1'
2018-11-28 02:52:00,517 (1aa8) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt7608534%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-11-28 02:52:02,470 (2c84) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8954732%2Fimages'
2018-11-28 02:52:03,160 (1aa8) :  INFO (logkit:16) - Added 0 of 0 extras.
2018-11-28 02:52:03,240 (1aa8) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20Inheritance'
2018-11-28 02:52:03,569 (1aa8) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=Christmas%20Inheritance'
2018-11-28 02:52:03,569 (1aa8) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 7608534: HTTP Error 401: Unauthorized
2018-11-28 02:52:03,622 (1aa8) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\d\5698fdc217f00cedebe3974dcec205fbc24cb86.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-28 02:52:03,625 (1aa8) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-28 02:52:03,625 (1aa8) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-28 02:52:04,673 (2c84) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F556803%2Frecommendations'
2018-11-28 02:52:06,846 (2c84) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/iHbHTn6awwTTu9kcfBe2hkTuWXS.jpg'
2018-11-28 02:52:06,901 (2c84) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/iHbHTn6awwTTu9kcfBe2hkTuWXS.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:06,917 (2c84) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/eZeKHAqNsDAG6r8hi4sMzwqHn5n.jpg'
2018-11-28 02:52:06,973 (2c84) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/eZeKHAqNsDAG6r8hi4sMzwqHn5n.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:06,990 (2c84) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/biRUM4CBgfJtEQDCttf3zca4epp.jpg'
2018-11-28 02:52:07,043 (2c84) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/biRUM4CBgfJtEQDCttf3zca4epp.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:07,552 (2c84) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/5FssOF42lrsVbJiMe4XNW5IF5hA.jpg'
2018-11-28 02:52:07,608 (2c84) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/5FssOF42lrsVbJiMe4XNW5IF5hA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:07,743 (2c84) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 1676 KB (1024 items)
2018-11-28 02:52:07,770 (2c84) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/8cEOKJHhYV4NSb2MHdt9RmzEPUf.jpg'
2018-11-28 02:52:07,835 (2c84) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/8cEOKJHhYV4NSb2MHdt9RmzEPUf.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-11-28 02:52:07,838 (2c84) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/8954732?lang=en&ratings=1&reviews=1&extras=1'
2018-11-28 02:52:07,986 (2c84) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt8954732%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2018-11-28 02:52:08,016 (2c84) :  INFO (logkit:16) - Added 0 of 0 extras.
2018-11-28 02:52:08,030 (2c84) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Princess%20Switch'
2018-11-28 02:52:08,355 (2c84) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Princess%20Switch'
2018-11-28 02:52:08,355 (2c84) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 8954732: HTTP Error 401: Unauthorized
2018-11-28 02:52:08,604 (2c84) :  DEBUG (model:229) - Serializing to C:\Users\Media\AppData\Local\Plex Media Server\Metadata\Movies\7\53116184ab4dff6ac0faa7a2f6c115c80277ee7.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2018-11-28 02:52:08,605 (2c84) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-11-28 02:52:08,605 (2c84) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
