2018-08-07 11:43:01,384 (1a9c) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 6fa5e50 (Mon Jul 23 22:35:14 UTC 2018)
2018-08-07 11:43:01,384 (1a9c) :  DEBUG (core:361) - Using the elevated policy
2018-08-07 11:43:01,384 (1a9c) :  DEBUG (core:450) - Starting runtime component.
2018-08-07 11:43:01,391 (1a9c) :  DEBUG (core:450) - Starting caching component.
2018-08-07 11:43:01,391 (1a9c) :  DEBUG (core:450) - Starting data component.
2018-08-07 11:43:01,391 (1a9c) :  DEBUG (core:450) - Starting networking component.
2018-08-07 11:43:01,392 (1a9c) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-08-07 11:43:01,433 (1a9c) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-08-07 11:43:01,434 (1a9c) :  DEBUG (core:450) - Starting localization component.
2018-08-07 11:43:01,434 (1a9c) :  INFO (localization:409) - Setting the default locale to en-us
2018-08-07 11:43:01,434 (1a9c) :  DEBUG (core:450) - Starting messaging component.
2018-08-07 11:43:01,437 (1a9c) :  DEBUG (core:450) - Starting debugging component.
2018-08-07 11:43:01,437 (1a9c) :  DEBUG (core:450) - Starting services component.
2018-08-07 11:43:01,437 (1a9c) :  DEBUG (core:450) - Starting myplex component.
2018-08-07 11:43:01,437 (1a9c) :  DEBUG (core:450) - Starting notifications component.
2018-08-07 11:43:01,440 (1a28) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-08-07 11:43:01,604 (1a9c) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-08-07 11:43:01,611 (1a9c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-08-07 11:43:01,744 (ac) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-08-07 11:43:01,744 (1a9c) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-08-07 11:43:01,749 (1a9c) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-08-07 11:43:01,749 (1a9c) :  DEBUG (core:150) - Finished starting framework core
2018-08-07 11:43:01,749 (1a9c) :  DEBUG (core:560) - Loading plug-in code
2018-08-07 11:43:01,750 (ac) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-08-07 11:43:01,752 (b20) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-08-07 11:43:02,678 (ac) :  DEBUG (services:362) - Loaded services
2018-08-07 11:43:02,710 (1f9c) :  DEBUG (services:438) - No shared code to load
2018-08-07 11:43:03,045 (1a9c) :  DEBUG (core:566) - Finished loading plug-in code
2018-08-07 11:43:03,211 (1a9c) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-08-07 11:43:03,213 (1a9c) :  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-08-07 11:43:03,216 (1a9c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-08-07 11:43:03,240 (1a9c) :  INFO (core:611) - Started plug-in
2018-08-07 11:43:03,240 (1a9c) :  DEBUG (socketinterface:160) - Starting socket server
2018-08-07 11:43:03,243 (1a9c) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-08-07 11:43:03,244 (1a9c) :  INFO (socketinterface:184) - Socket server started on port 51416
2018-08-07 11:43:03,244 (1a9c) :  INFO (pipeinterface:25) - Entering run loop
2018-08-07 11:43:03,244 (1a9c) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-08-07 11:43:03,246 (1a9c) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-08-07 11:43:03,246 (1a9c) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-08-07 11:43:03,276 (2064) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/prefs
2018-08-07 11:43:03,292 (2064) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/prefs
2018-08-07 11:43:03,295 (2064) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-08-07 11:43:03,296 (2064) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-08-07 11:43:03,313 (2064) :  DEBUG (runtime:88) - Sending packed state data (104 bytes)
2018-08-07 11:43:03,313 (2064) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 2224 bytes
2018-08-07 11:43:03,460 (b20) :  DEBUG (core:538) - Machine identifier is db873f057cbdcf6079e2492fc609a14815f4fd7e
2018-08-07 11:43:03,460 (b20) :  DEBUG (core:539) - Server version is 1.13.5.5291-6fa5e50a8
2018-08-07 11:55:58,436 (1f1c) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKRGVhZHBvb2wgMiBTdXBlciBEdXBlciBDdXRzNApuYW1lbnM4CnBsZXhIYXNoczE0MApaJTNBJTVDVmlkZW8lNUNNb3ZpZXMlNUNEVkQlNUNEZWFkcG9vbCUyRTIlMkVTdXBlciUyRUR1cGVyJTJFQ3V0JTJFVW5yYXRlZCUyRTIwMTglMkUxMDgwcCUyRUFNWk4lMkVXRUItREwlMkVERCUyQjUlMkUxJTJFSCUyRTI2NC1TaUdNQSUyRW1rdnM4CmZpbGVuYW1lczQKMjAxOHM0CnllYXJzMgotMXM4CmR1cmF0aW9uczYKMTcxMTU1czIKaWRyMAo_
2018-08-07 11:55:58,438 (1f1c) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKRGVhZHBvb2wgMiBTdXBlciBEdXBlciBDdXRzNApuYW1lbnM4CnBsZXhIYXNoczE0MApaJTNBJTVDVmlkZW8lNUNNb3ZpZXMlNUNEVkQlNUNEZWFkcG9vbCUyRTIlMkVTdXBlciUyRUR1cGVyJTJFQ3V0JTJFVW5yYXRlZCUyRTIwMTglMkUxMDgwcCUyRUFNWk4lMkVXRUItREwlMkVERCUyQjUlMkUxJTJFSCUyRTI2NC1TaUdNQSUyRW1rdnM4CmZpbGVuYW1lczQKMjAxOHM0CnllYXJzMgotMXM4CmR1cmF0aW9uczYKMTcxMTU1czIKaWRyMAo_
2018-08-07 11:55:58,440 (1f1c) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Deadpool 2 Super Duper Cut', 'plexHash': None, 'filename': 'Z%3A%5CVideo%5CMovies%5CDVD%5CDeadpool%2E2%2ESuper%2EDuper%2ECut%2EUnrated%2E2018%2E1080p%2EAMZN%2EWEB-DL%2EDD%2B5%2E1%2EH%2E264-SiGMA%2Emkv', 'year': '2018', 'duration': '-1', 'id': '171155'}
2018-08-07 11:55:58,443 (1f1c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/171155/tree'
2018-08-07 11:55:58,451 (1f1c) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [Z:\Video\Movies\DVD\Deadpool.2.Super.Duper.Cut.Unrated.2018.1080p.AMZN.WEB-DL.DD+5.1.H.264-SiGMA.mkv]
2018-08-07 11:55:58,451 (1f1c) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:55:58,451 (1f1c) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-08-07 11:55:58,453 (1f1c) :  INFO (logkit:16) - {}
2018-08-07 11:55:58,453 (1f1c) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml
2018-08-07 11:55:58,471 (1f1c) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml'
2018-08-07 11:55:58,943 (1f1c) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:55:58,943 (1f1c) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-08-07 11:55:58,943 (1f1c) :  INFO (logkit:16) - {}
2018-08-07 11:55:58,944 (1f1c) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-08-07 11:55:58,963 (1f1c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DDeadpool%2B2%2BSuper%2BDuper%2BCut%26year%3D2018%26language%3Den%26include_adult%3Dfalse'
2018-08-07 11:56:01,209 (1f1c) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-08-07 11:56:01,233 (1f1c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Deadpool%202%20Super%20Duper%20Cut&year=2018&plexHash=&lang=en&manual=0'
2018-08-07 11:56:01,506 (1f1c) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-08-07 11:56:01,506 (1f1c) :  DEBUG (runtime:924) - Response: [200] str, 320 bytes
2018-08-07 11:56:04,529 (1490) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKRGVhZHBvb2wgMiBTdXBlciBEdXBlciBDdXRzNApuYW1lbnM4CnBsZXhIYXNoczE0MApaJTNBJTVDVmlkZW8lNUNNb3ZpZXMlNUNEVkQlNUNEZWFkcG9vbCUyRTIlMkVTdXBlciUyRUR1cGVyJTJFQ3V0JTJFVW5yYXRlZCUyRTIwMTglMkUxMDgwcCUyRUFNWk4lMkVXRUItREwlMkVERCUyQjUlMkUxJTJFSCUyRTI2NC1TaUdNQSUyRW1rdnM4CmZpbGVuYW1lczQKMjAxOHM0CnllYXJzMgotMXM4CmR1cmF0aW9uczYKMTcxMTU1czIKaWRyMAo_
2018-08-07 11:56:04,530 (1490) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-08-07 11:56:04,532 (1490) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3Cm5zMTcKb3BlblN1YnRpdGxlc0hhc2hzMjYKRGVhZHBvb2wgMiBTdXBlciBEdXBlciBDdXRzNApuYW1lbnM4CnBsZXhIYXNoczE0MApaJTNBJTVDVmlkZW8lNUNNb3ZpZXMlNUNEVkQlNUNEZWFkcG9vbCUyRTIlMkVTdXBlciUyRUR1cGVyJTJFQ3V0JTJFVW5yYXRlZCUyRTIwMTglMkUxMDgwcCUyRUFNWk4lMkVXRUItREwlMkVERCUyQjUlMkUxJTJFSCUyRTI2NC1TaUdNQSUyRW1rdnM4CmZpbGVuYW1lczQKMjAxOHM0CnllYXJzMgotMXM4CmR1cmF0aW9uczYKMTcxMTU1czIKaWRyMAo_
2018-08-07 11:56:04,532 (1490) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': None, 'name': 'Deadpool 2 Super Duper Cut', 'plexHash': None, 'filename': 'Z%3A%5CVideo%5CMovies%5CDVD%5CDeadpool%2E2%2ESuper%2EDuper%2ECut%2EUnrated%2E2018%2E1080p%2EAMZN%2EWEB-DL%2EDD%2B5%2E1%2EH%2E264-SiGMA%2Emkv', 'year': '2018', 'duration': '-1', 'id': '171155'}
2018-08-07 11:56:04,532 (1490) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/171155/tree'
2018-08-07 11:56:04,538 (1490) :  ERROR (agentkit:462) - We seem to be missing the hash for media item [Z:\Video\Movies\DVD\Deadpool.2.Super.Duper.Cut.Unrated.2018.1080p.AMZN.WEB-DL.DD+5.1.H.264-SiGMA.mkv]
2018-08-07 11:56:04,538 (1490) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:56:04,539 (1490) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-08-07 11:56:04,539 (1490) :  INFO (logkit:16) - {}
2018-08-07 11:56:04,539 (1490) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml
2018-08-07 11:56:04,578 (1490) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml' from the HTTP cache
2018-08-07 11:56:04,618 (1490) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:56:04,618 (1490) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-08-07 11:56:04,618 (1490) :  INFO (logkit:16) - {}
2018-08-07 11:56:04,618 (1490) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-08-07 11:56:04,671 (1490) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DDeadpool%2B2%2BSuper%2BDuper%2BCut%26year%3D2018%26language%3Den%26include_adult%3Dfalse' from the HTTP cache
2018-08-07 11:56:04,714 (1490) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-08-07 11:56:04,753 (1490) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Deadpool%202%20Super%20Duper%20Cut&year=2018&plexHash=&lang=en&manual=0' from the HTTP cache
2018-08-07 11:56:04,805 (1490) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-08-07 11:56:04,805 (1490) :  DEBUG (runtime:924) - Response: [200] str, 320 bytes
2018-08-07 11:58:51,374 (324) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgphNWE5Y2I3N2I3ZjZkY2FmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczMxCkRlYWRwb29sIDIgU3VwZXIgRHVwZXIgQ3V0IDIwMThzNApuYW1lczg2ClolM0ElNUNWaWRlbyU1Q01vdmllcyU1Q0hEJTVDRGVhZHBvb2wlMkUyJTJFU3VwZXIlMkVEdXBlciUyRUN1dCUyRVVucmF0ZWQlMkUyMDE4JTJFbWt2czgKZmlsZW5hbWVzNDAKNjEwNmQyOGQ3OGIxZWYxZDc5YWRlMDZlYmY2ZGQ3MjMyYWY1ZTllMHM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM2CjE3MTE1NnMyCmlkcjAK
2018-08-07 11:58:51,377 (324) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-08-07 11:58:51,378 (324) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgphNWE5Y2I3N2I3ZjZkY2FmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczMxCkRlYWRwb29sIDIgU3VwZXIgRHVwZXIgQ3V0IDIwMThzNApuYW1lczg2ClolM0ElNUNWaWRlbyU1Q01vdmllcyU1Q0hEJTVDRGVhZHBvb2wlMkUyJTJFU3VwZXIlMkVEdXBlciUyRUN1dCUyRVVucmF0ZWQlMkUyMDE4JTJFbWt2czgKZmlsZW5hbWVzNDAKNjEwNmQyOGQ3OGIxZWYxZDc5YWRlMDZlYmY2ZGQ3MjMyYWY1ZTllMHM4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM2CjE3MTE1NnMyCmlkcjAK
2018-08-07 11:58:51,378 (324) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'a5a9cb77b7f6dcaf', 'name': 'Deadpool 2 Super Duper Cut 2018', 'filename': 'Z%3A%5CVideo%5CMovies%5CHD%5CDeadpool%2E2%2ESuper%2EDuper%2ECut%2EUnrated%2E2018%2Emkv', 'plexHash': '6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0', 'duration': '-1', 'id': '171156'}
2018-08-07 11:58:51,381 (324) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/171156/tree'
2018-08-07 11:58:51,391 (324) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml
2018-08-07 11:58:51,411 (324) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml'
2018-08-07 11:58:51,552 (324) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml'
2018-08-07 11:58:51,552 (324) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-08-07 11:58:51,552 (324) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:58:51,552 (324) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-08-07 11:58:51,552 (324) :  INFO (logkit:16) - {}
2018-08-07 11:58:51,553 (324) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml
2018-08-07 11:58:51,585 (324) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml' from the HTTP cache
2018-08-07 11:58:51,618 (324) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:58:51,618 (324) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-08-07 11:58:51,618 (324) :  INFO (logkit:16) - {}
2018-08-07 11:58:51,618 (324) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-08-07 11:58:51,634 (324) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DDeadpool%2B2%2BSuper%2BDuper%2BCut%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse'
2018-08-07 11:58:53,766 (324) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-08-07 11:58:53,783 (324) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Deadpool%202%20Super%20Duper%20Cut%202018&year=&plexHash=6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0&lang=en&manual=0'
2018-08-07 11:58:54,121 (324) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-08-07 11:58:54,121 (324) :  DEBUG (runtime:924) - Response: [200] str, 320 bytes
2018-08-07 11:59:35,164 (1050) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgphNWE5Y2I3N2I3ZjZkY2FmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczMxCkRlYWRwb29sIDIgU3VwZXIgRHVwZXIgQ3V0IDIwMThzNApuYW1lczg2ClolM0ElNUNWaWRlbyU1Q01vdmllcyU1Q0hEJTVDRGVhZHBvb2wlMkUyJTJFU3VwZXIlMkVEdXBlciUyRUN1dCUyRVVucmF0ZWQlMkUyMDE4JTJFbWt2czgKZmlsZW5hbWVzNDAKNjEwNmQyOGQ3OGIxZWYxZDc5YWRlMDZlYmY2ZGQ3MjMyYWY1ZTllMHM4CnBsZXhIYXNoczgKMTUyMDE3OTJzOApkdXJhdGlvbnM2CjE3MTE1NnMyCmlkcjAK
2018-08-07 11:59:35,165 (1050) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2018-08-07 11:59:35,167 (1050) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU2CnMxNgphNWE5Y2I3N2I3ZjZkY2FmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczMxCkRlYWRwb29sIDIgU3VwZXIgRHVwZXIgQ3V0IDIwMThzNApuYW1lczg2ClolM0ElNUNWaWRlbyU1Q01vdmllcyU1Q0hEJTVDRGVhZHBvb2wlMkUyJTJFU3VwZXIlMkVEdXBlciUyRUN1dCUyRVVucmF0ZWQlMkUyMDE4JTJFbWt2czgKZmlsZW5hbWVzNDAKNjEwNmQyOGQ3OGIxZWYxZDc5YWRlMDZlYmY2ZGQ3MjMyYWY1ZTllMHM4CnBsZXhIYXNoczgKMTUyMDE3OTJzOApkdXJhdGlvbnM2CjE3MTE1NnMyCmlkcjAK
2018-08-07 11:59:35,167 (1050) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'a5a9cb77b7f6dcaf', 'name': 'Deadpool 2 Super Duper Cut 2018', 'filename': 'Z%3A%5CVideo%5CMovies%5CHD%5CDeadpool%2E2%2ESuper%2EDuper%2ECut%2EUnrated%2E2018%2Emkv', 'plexHash': '6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0', 'duration': '15201792', 'id': '171156'}
2018-08-07 11:59:35,167 (1050) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/171156/tree'
2018-08-07 11:59:35,174 (1050) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml
2018-08-07 11:59:35,190 (1050) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml'
2018-08-07 11:59:35,259 (1050) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/61/6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0.xml'
2018-08-07 11:59:35,259 (1050) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-08-07 11:59:35,259 (1050) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:59:35,259 (1050) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-08-07 11:59:35,259 (1050) :  INFO (logkit:16) - {}
2018-08-07 11:59:35,259 (1050) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml
2018-08-07 11:59:35,292 (1050) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/ed/ed96ac631864a89a650c5277cd4d29fe12e02366.xml' from the HTTP cache
2018-08-07 11:59:35,325 (1050) :  INFO (logkit:16) - Scoring {}
2018-08-07 11:59:35,326 (1050) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2018-08-07 11:59:35,326 (1050) :  INFO (logkit:16) - {}
2018-08-07 11:59:35,326 (1050) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2018-08-07 11:59:35,361 (1050) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DDeadpool%2B2%2BSuper%2BDuper%2BCut%2B2018%26year%3D%26language%3Den%26include_adult%3Dfalse' from the HTTP cache
2018-08-07 11:59:35,392 (1050) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2018-08-07 11:59:35,408 (1050) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Deadpool%202%20Super%20Duper%20Cut%202018&year=&plexHash=6106d28d78b1ef1d79ade06ebf6dd7232af5e9e0&lang=en&manual=1'
2018-08-07 11:59:35,743 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/uDbyCsI9zXVOjfXuZp3qwHA8ZOe.jpg', score=67, year=2008, id='tt1345734', name='Rough Cut')
2018-08-07 11:59:35,743 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/aUEyTgIHjZbX1YPQwtVU5OUEDMW.jpg', score=67, year=2009, id='tt1245735', name='Smash Cut')
2018-08-07 11:59:35,743 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/313Zm4MuEqmd7kwNulajZgrRDIL.jpg', score=67, year=2017, id='tt6612630', name='Deadpool: No Good Deed')
2018-08-07 11:59:35,743 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/zDZ1FilPeuX5arBSdPC6iLF9IXM.jpg', score=67, year=2015, id='tt2574576', name='Cut Snake')
2018-08-07 11:59:35,744 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/fY9z85jIk0He2TMXq0GeMszpyCX.jpg', score=67, year=2014, id='tt3228830', name='Super Duper Alice Cooper')
2018-08-07 11:59:35,744 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/qDV8XuPQ3ZJgA7TvgrolhlRdb3i.jpg', score=67, year=1981, id='tt0082675', name='Super Fool!')
2018-08-07 11:59:35,744 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/4pHweXitREXKXMIeikzsPM2XvU7.jpg', score=67, year=1975, id='tt0247744', name='Super Spook')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=67, year=None, id='tt7682996', name='Deadpool 2')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=67, year=None, id='tt6263850', name='Deadpool 3')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/1uqsrDndIey3aQuf4ez8DhGU3TW.jpg', score=67, year=2018, id='tt5321570', name='Super Heady')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/dnBtrFsmrOthPUjXrt1bcHxyJAB.jpg', score=67, year=2017, id='tt6744200', name='Superperson')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/inVq3FRqcYIRl2la8iZikYYxFNR.jpg', score=65, year=2016, id='tt1431045', name='Deadpool')
2018-08-07 11:59:35,746 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/imps263dHNe3SuoaAJORZyNTdDT.jpg', score=65, year=1999, id='tt0167404', name='The Sixth Sense')
2018-08-07 11:59:35,747 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/to0spRl1CMDvyUbOnbb4fTk3VAd.jpg', score=65, year=2018, id='tt5463162', name='Deadpool 2')
2018-08-07 11:59:35,747 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/705cX3NvfegQUCPNhSqeIZanYDj.jpg', score=65, year=2011, id='tt1716772', name='The Inbetweeners Movie')
2018-08-07 11:59:35,747 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/m7gGJnVTusmcaO1cl32xr7ZpRF9.jpg', score=65, year=2004, id='tt0420251', name=u'Three\u2026 Extremes')
2018-08-07 11:59:35,749 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/shUBvMDtquz8zbzDrBGs9le24Xv.jpg', score=65, year=2014, id='tt2245171', name='The Cut')
2018-08-07 11:59:35,749 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/6R8NePHOSL2n62R3b3mJPqyn5Bx.jpg', score=65, year=2000, id='tt0189456', name='Cut')
2018-08-07 11:59:35,749 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/48BILFoefjopuBbl16yI9p3UsD.jpg', score=65, year=2008, id='tt0469913', name='Good Time Max')
2018-08-07 11:59:35,749 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/50gNhP3IGf1vxDmQc8LDHpQl0nh.jpg', score=65, year=1980, id='tt0081439', name='Rough Cut')
2018-08-07 11:59:35,750 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/rE2B7axPvqKLtMTPrODATXoiMRf.jpg', score=65, year=2014, id='tt2078549', name='BlackJacks')
2018-08-07 11:59:35,750 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/57SZgdugVClIy22rfnANeBJ5KsN.jpg', score=62, year=2018, id='tt0859635', name='Super Troopers 2')
2018-08-07 11:59:35,750 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/53Jh8cMCfDOI87XnPBCIiTCwN6U.jpg', score=54, year=2017, id='tt5653514', name='Bad Day for the Cut')
2018-08-07 11:59:35,750 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/53aexl6AywjRaxmkplXIfb4xubv.jpg', score=54, year=2017, id='tt5891348', name='Super Singh')
2018-08-07 11:59:35,750 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/7y8fhgq4bZZVCfRagzxyPNZ7sxn.jpg', score=54, year=2017, id='tt7666582', name='Super vacanze di Natale')
2018-08-07 11:59:35,752 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/yuXD27NCaYhom6b9Cw9liFzRutn.jpg', score=52, year=2017, id='tt5657028', name='Alibi.com')
2018-08-07 11:59:35,752 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/xDJAZWq7kS1G6eps4oJCKOgutHU.jpg', score=52, year=2017, id='tt5667696', name='Cut Shoot Kill')
2018-08-07 11:59:35,752 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/pbJ1vNwK9X6ZQm2vxarxcLJ0aGn.jpg', score=47, year=2016, id='tt3799694', name='The Nice Guys')
2018-08-07 11:59:35,753 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/vfzE3pjE5G7G7kcZWrA3fnbZo7V.jpg', score=42, year=1982, id='tt0083658', name='Blade Runner')
2018-08-07 11:59:35,753 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/4ucLGcXVVSVnsfkGtbLY4XAius8.jpg', score=42, year=1973, id='tt0070047', name='The Exorcist')
2018-08-07 11:59:35,753 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/tgXOraQmKatqrVs2KMgnNn6QpO.jpg', score=42, year=2000, id='tt0187393', name='The Patriot')
2018-08-07 11:59:35,753 (1050) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/60ZBfceE3EeTdNOkjxk5xl6CJGi.jpg', score=42, year=2014, id='tt2179239', name='The Last Showing')
2018-08-07 11:59:35,776 (1050) :  DEBUG (networking:166) - Requesting 'https://api.cinematerial.com/1/request.json?imdb_id=tt6744200&key=plex&secret=ra4O5as9BZNBU&width=720&thumb_width=100.'
2018-08-07 11:59:37,240 (1050) :  DEBUG (networking:166) - Requesting 'https://api.cinematerial.com/1/request.json?imdb_id=tt5321570&key=plex&secret=ral8LXYix.7Iw&width=720&thumb_width=100.'
2018-08-07 11:59:38,733 (1050) :  DEBUG (networking:166) - Requesting 'https://api.cinematerial.com/1/request.json?imdb_id=tt6263850&key=plex&secret=ra9VDyuIfnY62&width=720&thumb_width=100.'
2018-08-07 11:59:40,171 (1050) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-08-07 11:59:40,171 (1050) :  DEBUG (runtime:924) - Response: [200] str, 13244 bytes
