2019-03-03 15:40:24,947 (2750) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cc260c4 (Fri Dec 14 11:21:01 UTC 2018)
2019-03-03 15:40:24,947 (2750) :  DEBUG (core:361) - Using the elevated policy
2019-03-03 15:40:24,947 (2750) :  DEBUG (core:450) - Starting runtime component.
2019-03-03 15:40:24,950 (2750) :  DEBUG (core:450) - Starting caching component.
2019-03-03 15:40:24,950 (2750) :  DEBUG (core:450) - Starting data component.
2019-03-03 15:40:24,950 (2750) :  DEBUG (core:450) - Starting networking component.
2019-03-03 15:40:24,950 (2750) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-03-03 15:40:24,966 (2750) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-03-03 15:40:24,967 (2750) :  DEBUG (core:450) - Starting localization component.
2019-03-03 15:40:24,967 (2750) :  INFO (localization:409) - Setting the default locale to en-us
2019-03-03 15:40:24,967 (2750) :  DEBUG (core:450) - Starting messaging component.
2019-03-03 15:40:24,967 (2750) :  DEBUG (core:450) - Starting debugging component.
2019-03-03 15:40:24,967 (2750) :  DEBUG (core:450) - Starting services component.
2019-03-03 15:40:24,969 (2b98) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2019-03-03 15:40:24,969 (2750) :  DEBUG (core:450) - Starting myplex component.
2019-03-03 15:40:24,969 (2750) :  DEBUG (core:450) - Starting notifications component.
2019-03-03 15:40:25,072 (2750) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2019-03-03 15:40:25,076 (2750) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-03-03 15:40:25,095 (978) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-03-03 15:40:25,095 (2750) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-03-03 15:40:25,098 (978) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-03-03 15:40:25,098 (2750) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-03-03 15:40:25,098 (1644) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-03-03 15:40:25,098 (2750) :  DEBUG (core:150) - Finished starting framework core
2019-03-03 15:40:25,099 (2750) :  DEBUG (core:560) - Loading plug-in code
2019-03-03 15:40:25,127 (1644) :  DEBUG (core:538) - Machine identifier is 4fc6fcbb720dfeaf43f7e287a162df51bc136a6e
2019-03-03 15:40:25,127 (1644) :  DEBUG (core:539) - Server version is 1.14.1.5488-cc260c476
2019-03-03 15:40:25,328 (978) :  DEBUG (services:362) - Loaded services
2019-03-03 15:40:25,336 (2d5c) :  DEBUG (services:438) - No shared code to load
2019-03-03 15:40:25,634 (2750) :  DEBUG (core:566) - Finished loading plug-in code
2019-03-03 15:40:25,753 (2750) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2019-03-03 15:40:25,753 (2750) :  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'}]
2019-03-03 15:40:25,755 (2750) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2019-03-03 15:40:25,765 (2750) :  INFO (core:611) - Started plug-in
2019-03-03 15:40:25,765 (2750) :  DEBUG (socketinterface:160) - Starting socket server
2019-03-03 15:40:25,766 (2750) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-03-03 15:40:25,766 (2750) :  INFO (socketinterface:184) - Socket server started on port 55650
2019-03-03 15:40:25,766 (2750) :  INFO (pipeinterface:25) - Entering run loop
2019-03-03 15:40:25,766 (2750) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-03-03 15:40:25,767 (2750) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-03-03 15:40:25,767 (2750) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2019-03-03 15:40:25,806 (2aec) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiNWNhYjA0YmEzMTU1YzMwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIzCldpbmdzIE92ZXIgR3JhbmQgQ2FueW9uczQKbmFtZXM5MwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNXaW5ncyUyME92ZXIlMjBHcmFuZCUyMENhbnlvbiU1Q1dpbmdzJTIwT3ZlciUyMEdyYW5kJTIwQ2FueW9uJTJFdHNzOApmaWxlbmFtZXM4CnRlbGVzeW5jczYKc291cmNlczQwCjI5OTY0ZDA0NWYwYjJjYTE2M2UyOTQ3YTY1N2FkOGEyN2QyZjUwMzRzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoyODM3NnMyCmlkcjAK
2019-03-03 15:40:25,809 (2aec) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-03-03 15:40:25,825 (2aec) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiNWNhYjA0YmEzMTU1YzMwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIzCldpbmdzIE92ZXIgR3JhbmQgQ2FueW9uczQKbmFtZXM5MwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNXaW5ncyUyME92ZXIlMjBHcmFuZCUyMENhbnlvbiU1Q1dpbmdzJTIwT3ZlciUyMEdyYW5kJTIwQ2FueW9uJTJFdHNzOApmaWxlbmFtZXM4CnRlbGVzeW5jczYKc291cmNlczQwCjI5OTY0ZDA0NWYwYjJjYTE2M2UyOTQ3YTY1N2FkOGEyN2QyZjUwMzRzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoyODM3NnMyCmlkcjAK
2019-03-03 15:40:25,825 (2aec) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'b5cab04ba3155c30', 'name': 'Wings Over Grand Canyon', 'filename': 'V%3A%5CRecorded%20Movies%5CWings%20Over%20Grand%20Canyon%5CWings%20Over%20Grand%20Canyon%2Ets', 'source': 'telesync', 'plexHash': '29964d045f0b2ca163e2947a657ad8a27d2f5034', 'duration': '-1', 'id': '28376'}
2019-03-03 15:40:25,826 (2aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28376/tree'
2019-03-03 15:40:25,835 (2aec) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml
2019-03-03 15:40:25,862 (2aec) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml'
2019-03-03 15:40:26,234 (2aec) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml'
2019-03-03 15:40:26,236 (2aec) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-03-03 15:40:26,236 (2aec) :  INFO (logkit:16) - Scoring {}
2019-03-03 15:40:26,236 (2aec) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-03-03 15:40:26,236 (2aec) :  INFO (logkit:16) - {}
2019-03-03 15:40:26,237 (2aec) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/f1/f167e712e4c51a48c6d83beafd37117a5187f67e.xml
2019-03-03 15:40:26,273 (2aec) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/f1/f167e712e4c51a48c6d83beafd37117a5187f67e.xml'
2019-03-03 15:40:26,418 (2aec) :  INFO (logkit:16) - Scoring {}
2019-03-03 15:40:26,418 (2aec) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-03-03 15:40:26,418 (2aec) :  INFO (logkit:16) - {}
2019-03-03 15:40:26,420 (2aec) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2019-03-03 15:40:26,421 (2aec) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2019-03-03 15:40:26,423 (2aec) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2019-03-03 15:40:26,444 (2aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DWings%2BOver%2BGrand%2BCanyon%26year%3D%26language%3Den%26include_adult%3Dtrue'
2019-03-03 15:40:28,660 (2aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNgozNzMyNDZzNwp0bWRiX2lkcjAK'
2019-03-03 15:40:32,252 (2aec) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2019-03-03 15:40:32,290 (2aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Wings%20Over%20Grand%20Canyon&year=&plexHash=29964d045f0b2ca163e2947a657ad8a27d2f5034&lang=en&manual=0'
2019-03-03 15:40:32,628 (2aec) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-03-03 15:40:32,628 (2aec) :  DEBUG (runtime:924) - Response: [200] str, 320 bytes
2019-03-03 15:40:32,709 (16ec) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiNWNhYjA0YmEzMTU1YzMwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIzCldpbmdzIE92ZXIgR3JhbmQgQ2FueW9uczQKbmFtZXM5MwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNXaW5ncyUyME92ZXIlMjBHcmFuZCUyMENhbnlvbiU1Q1dpbmdzJTIwT3ZlciUyMEdyYW5kJTIwQ2FueW9uJTJFdHNzOApmaWxlbmFtZXM4CnRlbGVzeW5jczYKc291cmNlczQwCjI5OTY0ZDA0NWYwYjJjYTE2M2UyOTQ3YTY1N2FkOGEyN2QyZjUwMzRzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoyODM3NnMyCmlkcjAK
2019-03-03 15:40:32,710 (16ec) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-03-03 15:40:32,710 (16ec) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiNWNhYjA0YmEzMTU1YzMwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIzCldpbmdzIE92ZXIgR3JhbmQgQ2FueW9uczQKbmFtZXM5MwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNXaW5ncyUyME92ZXIlMjBHcmFuZCUyMENhbnlvbiU1Q1dpbmdzJTIwT3ZlciUyMEdyYW5kJTIwQ2FueW9uJTJFdHNzOApmaWxlbmFtZXM4CnRlbGVzeW5jczYKc291cmNlczQwCjI5OTY0ZDA0NWYwYjJjYTE2M2UyOTQ3YTY1N2FkOGEyN2QyZjUwMzRzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoyODM3NnMyCmlkcjAK
2019-03-03 15:40:32,710 (16ec) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'b5cab04ba3155c30', 'name': 'Wings Over Grand Canyon', 'filename': 'V%3A%5CRecorded%20Movies%5CWings%20Over%20Grand%20Canyon%5CWings%20Over%20Grand%20Canyon%2Ets', 'source': 'telesync', 'plexHash': '29964d045f0b2ca163e2947a657ad8a27d2f5034', 'duration': '-1', 'id': '28376'}
2019-03-03 15:40:32,711 (16ec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28376/tree'
2019-03-03 15:40:32,717 (16ec) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml
2019-03-03 15:40:32,736 (16ec) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml'
2019-03-03 15:40:32,828 (16ec) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/29/29964d045f0b2ca163e2947a657ad8a27d2f5034.xml'
2019-03-03 15:40:32,828 (16ec) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-03-03 15:40:32,828 (16ec) :  INFO (logkit:16) - Scoring {}
2019-03-03 15:40:32,828 (16ec) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-03-03 15:40:32,828 (16ec) :  INFO (logkit:16) - {}
2019-03-03 15:40:32,828 (16ec) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/f1/f167e712e4c51a48c6d83beafd37117a5187f67e.xml
2019-03-03 15:40:32,871 (16ec) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/f1/f167e712e4c51a48c6d83beafd37117a5187f67e.xml' from the HTTP cache
2019-03-03 15:40:32,911 (16ec) :  INFO (logkit:16) - Scoring {}
2019-03-03 15:40:32,911 (16ec) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-03-03 15:40:32,911 (16ec) :  INFO (logkit:16) - {}
2019-03-03 15:40:32,911 (16ec) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2019-03-03 15:40:32,951 (16ec) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DWings%2BOver%2BGrand%2BCanyon%26year%3D%26language%3Den%26include_adult%3Dtrue' from the HTTP cache
2019-03-03 15:40:32,993 (16ec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNgozNzMyNDZzNwp0bWRiX2lkcjAK'
2019-03-03 15:40:33,091 (16ec) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2019-03-03 15:40:33,131 (16ec) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Wings%20Over%20Grand%20Canyon&year=&plexHash=29964d045f0b2ca163e2947a657ad8a27d2f5034&lang=en&manual=0' from the HTTP cache
2019-03-03 15:40:33,171 (16ec) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-03-03 15:40:33,171 (16ec) :  DEBUG (runtime:924) - Response: [200] str, 320 bytes
