2019-03-07 02:02:26,835 (84c) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cc260c4 (Fri Dec 14 11:21:01 UTC 2018)
2019-03-07 02:02:26,835 (84c) :  DEBUG (core:361) - Using the elevated policy
2019-03-07 02:02:26,835 (84c) :  DEBUG (core:450) - Starting runtime component.
2019-03-07 02:02:26,838 (84c) :  DEBUG (core:450) - Starting caching component.
2019-03-07 02:02:26,838 (84c) :  DEBUG (core:450) - Starting data component.
2019-03-07 02:02:26,838 (84c) :  DEBUG (core:450) - Starting networking component.
2019-03-07 02:02:26,842 (84c) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-03-07 02:02:26,864 (84c) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-03-07 02:02:26,865 (84c) :  DEBUG (core:450) - Starting localization component.
2019-03-07 02:02:26,865 (84c) :  INFO (localization:409) - Setting the default locale to en-us
2019-03-07 02:02:26,865 (84c) :  DEBUG (core:450) - Starting messaging component.
2019-03-07 02:02:26,865 (84c) :  DEBUG (core:450) - Starting debugging component.
2019-03-07 02:02:26,865 (84c) :  DEBUG (core:450) - Starting services component.
2019-03-07 02:02:26,865 (84c) :  DEBUG (core:450) - Starting myplex component.
2019-03-07 02:02:26,867 (84c) :  DEBUG (core:450) - Starting notifications component.
2019-03-07 02:02:26,867 (534) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2019-03-07 02:02:27,009 (84c) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2019-03-07 02:02:27,022 (84c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-03-07 02:02:27,193 (84c) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-03-07 02:02:27,193 (1698) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-03-07 02:02:27,196 (84c) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-03-07 02:02:27,196 (84c) :  DEBUG (core:150) - Finished starting framework core
2019-03-07 02:02:27,197 (84c) :  DEBUG (core:560) - Loading plug-in code
2019-03-07 02:02:27,200 (1698) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-03-07 02:02:27,200 (524) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-03-07 02:02:27,233 (524) :  DEBUG (core:538) - Machine identifier is 4fc6fcbb720dfeaf43f7e287a162df51bc136a6e
2019-03-07 02:02:27,233 (524) :  DEBUG (core:539) - Server version is 1.14.1.5488-cc260c476
2019-03-07 02:02:27,444 (1698) :  DEBUG (services:362) - Loaded services
2019-03-07 02:02:27,451 (1b00) :  DEBUG (services:438) - No shared code to load
2019-03-07 02:02:27,763 (84c) :  DEBUG (core:566) - Finished loading plug-in code
2019-03-07 02:02:27,898 (84c) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2019-03-07 02:02:27,901 (84c) :  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-07 02:02:27,905 (84c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2019-03-07 02:02:27,917 (84c) :  INFO (core:611) - Started plug-in
2019-03-07 02:02:27,917 (84c) :  DEBUG (socketinterface:160) - Starting socket server
2019-03-07 02:02:27,917 (84c) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-03-07 02:02:27,918 (84c) :  INFO (socketinterface:184) - Socket server started on port 50147
2019-03-07 02:02:27,918 (84c) :  INFO (pipeinterface:25) - Entering run loop
2019-03-07 02:02:27,918 (84c) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-03-07 02:02:27,920 (84c) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-03-07 02:02:27,921 (84c) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2019-03-07 02:02:27,953 (25b4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgpkYzFkMzMxN2ExMzZiNjJiczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE3ClRoYXQgVGhpbmcgWW91IERvczQKbmFtZXM0MAozZWZhOTEwMTRkOGRiYTBlOWU5YzQwMjQ2ZGFiOWJjMjcxOTM4ZjNlczgKcGxleEhhc2hzOTkKViUzQSU1Q1JlY29yZGVkJTIwTW92aWVzJTVDVGhhdCUyMFRoaW5nJTIwWW91JTIwRG8lMjAoMTk5NiklNUNUaGF0JTIwVGhpbmclMjBZb3UlMjBEbyUyMCgxOTk2KSUyRXRzczgKZmlsZW5hbWVzOAp0ZWxlc3luY3M2CnNvdXJjZXM0CjE5OTZzNAp5ZWFyczIKLTFzOApkdXJhdGlvbnM1CjI4NDI0czIKaWRyMAo_
2019-03-07 02:02:27,953 (25b4) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-03-07 02:02:27,963 (25b4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgpkYzFkMzMxN2ExMzZiNjJiczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE3ClRoYXQgVGhpbmcgWW91IERvczQKbmFtZXM0MAozZWZhOTEwMTRkOGRiYTBlOWU5YzQwMjQ2ZGFiOWJjMjcxOTM4ZjNlczgKcGxleEhhc2hzOTkKViUzQSU1Q1JlY29yZGVkJTIwTW92aWVzJTVDVGhhdCUyMFRoaW5nJTIwWW91JTIwRG8lMjAoMTk5NiklNUNUaGF0JTIwVGhpbmclMjBZb3UlMjBEbyUyMCgxOTk2KSUyRXRzczgKZmlsZW5hbWVzOAp0ZWxlc3luY3M2CnNvdXJjZXM0CjE5OTZzNAp5ZWFyczIKLTFzOApkdXJhdGlvbnM1CjI4NDI0czIKaWRyMAo_
2019-03-07 02:02:27,964 (25b4) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'dc1d3317a136b62b', 'name': 'That Thing You Do', 'plexHash': '3efa91014d8dba0e9e9c40246dab9bc271938f3e', 'filename': 'V%3A%5CRecorded%20Movies%5CThat%20Thing%20You%20Do%20(1996)%5CThat%20Thing%20You%20Do%20(1996)%2Ets', 'source': 'telesync', 'year': '1996', 'duration': '-1', 'id': '28424'}
2019-03-07 02:02:27,966 (25b4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28424/tree'
2019-03-07 02:02:27,971 (25b4) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/3e/3efa91014d8dba0e9e9c40246dab9bc271938f3e.xml
2019-03-07 02:02:27,993 (25b4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/3e/3efa91014d8dba0e9e9c40246dab9bc271938f3e.xml'
2019-03-07 02:02:28,358 (25b4) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/3e/3efa91014d8dba0e9e9c40246dab9bc271938f3e.xml'
2019-03-07 02:02:28,358 (25b4) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-03-07 02:02:28,358 (25b4) :  INFO (logkit:16) - Scoring {}
2019-03-07 02:02:28,358 (25b4) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-03-07 02:02:28,358 (25b4) :  INFO (logkit:16) - {}
2019-03-07 02:02:28,358 (25b4) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/e8/e8027dd8836de84ef82225529c1eda9f56d65d1f.xml
2019-03-07 02:02:28,384 (25b4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/e8/e8027dd8836de84ef82225529c1eda9f56d65d1f.xml'
2019-03-07 02:02:28,525 (25b4) :  INFO (logkit:16) - Scoring {'tt0117887': [5, u'That Thing You Do 1996', u'0', 99, 27711, 0]}
2019-03-07 02:02:28,525 (25b4) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-03-07 02:02:28,525 (25b4) :  INFO (logkit:16) - {'tt0117887': [5, u'That Thing You Do 1996', u'0', 99, 27711, 84]}
2019-03-07 02:02:28,536 (25b4) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2019-03-07 02:02:28,538 (25b4) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2019-03-07 02:02:28,561 (25b4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0117887%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2019-03-07 02:02:30,711 (25b4) :  INFO (logkit:16) - Adding title_year match: That Thing You Do! (1996) score=84, key=tt0117887
2019-03-07 02:02:30,713 (25b4) :  INFO (logkit:16) - ---- TMDb RESULTS MAP ----
2019-03-07 02:02:30,739 (25b4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DThat%2BThing%2BYou%2BDo%26year%3D1996%26language%3Den%26include_adult%3Dtrue'
2019-03-07 02:02:32,897 (25b4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb/messaging/function/TWVzc2FnZUtpdDpHZXRJbWRiSWQ_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzNAo5NTkxczcKdG1kYl9pZHIwCg__'
2019-03-07 02:02:36,496 (25b4) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb=None, score=90, year=1996, id=u'tt0117887', name=u'That Thing You Do!')
2019-03-07 02:02:36,497 (25b4) :  INFO (logkit:16) - ---- UMP RESULTS MAP ----
2019-03-07 02:02:36,535 (25b4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=That%20Thing%20You%20Do&year=1996&plexHash=3efa91014d8dba0e9e9c40246dab9bc271938f3e&lang=en&manual=0'
2019-03-07 02:02:36,803 (25b4) :  INFO (logkit:16) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/4Nwp0qmcpTbbdaZLLpxVAI5jPPF.jpg', score=98, year=1996, id='tt0117887', name='That Thing You Do!')
2019-03-07 02:02:36,805 (25b4) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-03-07 02:02:36,805 (25b4) :  DEBUG (runtime:924) - Response: [200] str, 736 bytes
2019-03-07 02:02:36,864 (4a0) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODQyNHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDExNzg4Nz9sYW5nPWVuczQKZ3VpZHM5CnR0MDExNzg4N3MyCmlkcjAK
2019-03-07 02:02:36,864 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:36,865 (4a0) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODQyNHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDExNzg4Nz9sYW5nPWVuczQKZ3VpZHM5CnR0MDExNzg4N3MyCmlkcjAK
2019-03-07 02:02:36,865 (4a0) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0117887?lang=en
2019-03-07 02:02:36,867 (4a0) :  ERROR (model:205) - Cannot read model from C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\f\7e82850814d47ab8fd58b13cc08eac15788a9b6.bundle\Contents\com.plexapp.agents.imdb
2019-03-07 02:02:36,868 (4a0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28424/tree'
2019-03-07 02:02:36,884 (1aec) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgo5Njk5OTQ1OGI5MWY1MDc3czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE3Ck5vcnRod2VzdCBQYXNzYWdlczQKbmFtZXM0MApjODA0MGQ3Njg2MWJkZjVhZDExYmRkYTQyOGE3MWU2NjkxZjVkYWY2czgKcGxleEhhc2hzOTEKViUzQSU1Q1JlY29yZGVkJTIwTW92aWVzJTVDTm9ydGh3ZXN0JTIwUGFzc2FnZSUyMCgxOTQwKSU1Q05vcnRod2VzdCUyMFBhc3NhZ2UlMjAoMTk0MCklMkV0c3M4CmZpbGVuYW1lczgKdGVsZXN5bmNzNgpzb3VyY2VzNAoxOTQwczQKeWVhcnMyCi0xczgKZHVyYXRpb25zNQoyODQyNXMyCmlkcjAK
2019-03-07 02:02:36,887 (1aec) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-03-07 02:02:36,888 (1aec) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgo5Njk5OTQ1OGI5MWY1MDc3czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczE3Ck5vcnRod2VzdCBQYXNzYWdlczQKbmFtZXM0MApjODA0MGQ3Njg2MWJkZjVhZDExYmRkYTQyOGE3MWU2NjkxZjVkYWY2czgKcGxleEhhc2hzOTEKViUzQSU1Q1JlY29yZGVkJTIwTW92aWVzJTVDTm9ydGh3ZXN0JTIwUGFzc2FnZSUyMCgxOTQwKSU1Q05vcnRod2VzdCUyMFBhc3NhZ2UlMjAoMTk0MCklMkV0c3M4CmZpbGVuYW1lczgKdGVsZXN5bmNzNgpzb3VyY2VzNAoxOTQwczQKeWVhcnMyCi0xczgKZHVyYXRpb25zNQoyODQyNXMyCmlkcjAK
2019-03-07 02:02:36,888 (1aec) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '96999458b91f5077', 'name': 'Northwest Passage', 'plexHash': 'c8040d76861bdf5ad11bdda428a71e6691f5daf6', 'filename': 'V%3A%5CRecorded%20Movies%5CNorthwest%20Passage%20(1940)%5CNorthwest%20Passage%20(1940)%2Ets', 'source': 'telesync', 'year': '1940', 'duration': '-1', 'id': '28425'}
2019-03-07 02:02:36,891 (1aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28425/tree'
2019-03-07 02:02:36,897 (1aec) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/c8/c8040d76861bdf5ad11bdda428a71e6691f5daf6.xml
2019-03-07 02:02:36,921 (4a0) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2019-03-07 02:02:36,940 (1aec) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/c8/c8040d76861bdf5ad11bdda428a71e6691f5daf6.xml'
2019-03-07 02:02:37,009 (4a0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0117887%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2019-03-07 02:02:37,085 (1aec) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/c8/c8040d76861bdf5ad11bdda428a71e6691f5daf6.xml'
2019-03-07 02:02:37,085 (1aec) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-03-07 02:02:37,085 (1aec) :  INFO (logkit:16) - Scoring {}
2019-03-07 02:02:37,086 (1aec) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-03-07 02:02:37,086 (1aec) :  INFO (logkit:16) - {}
2019-03-07 02:02:37,086 (1aec) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/46/4601600dd8ce60f47fd2da0bcdfdb45cae852b7d.xml
2019-03-07 02:02:37,108 (1aec) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/46/4601600dd8ce60f47fd2da0bcdfdb45cae852b7d.xml'
2019-03-07 02:02:37,221 (1aec) :  INFO (logkit:16) - Scoring {'tt0032851': [0, u'Northwest Passage', u'1940', 100, 1027, 0]}
2019-03-07 02:02:37,223 (1aec) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-03-07 02:02:37,223 (1aec) :  INFO (logkit:16) - {'tt0032851': [0, u'Northwest Passage', u'1940', 100, 1027, 100]}
2019-03-07 02:02:37,247 (1aec) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0032851%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2019-03-07 02:02:39,184 (4a0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0117887%2Fimages'
2019-03-07 02:02:39,375 (1aec) :  INFO (logkit:16) - Adding title_year match: Northwest Passage (1940) score=100, key=tt0032851
2019-03-07 02:02:39,375 (1aec) :  INFO (logkit:16) - Found perfect match with title/year query.
2019-03-07 02:02:39,375 (1aec) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-03-07 02:02:39,377 (1aec) :  DEBUG (runtime:924) - Response: [200] str, 648 bytes
2019-03-07 02:02:39,453 (216c) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODQyNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDAzMjg1MT9sYW5nPWVuczQKZ3VpZHM5CnR0MDAzMjg1MXMyCmlkcjAK
2019-03-07 02:02:39,453 (216c) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:39,454 (216c) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODQyNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDAzMjg1MT9sYW5nPWVuczQKZ3VpZHM5CnR0MDAzMjg1MXMyCmlkcjAK
2019-03-07 02:02:39,456 (216c) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0032851?lang=en
2019-03-07 02:02:39,456 (216c) :  ERROR (model:205) - Cannot read model from C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\4\ec123e6f6665c803784c7b088cc3b119c40d21c.bundle\Contents\com.plexapp.agents.imdb
2019-03-07 02:02:39,457 (216c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28425/tree'
2019-03-07 02:02:39,503 (216c) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2019-03-07 02:02:39,569 (216c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0032851%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2019-03-07 02:02:41,365 (4a0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F9591%2Frecommendations'
2019-03-07 02:02:41,750 (216c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0032851%2Fimages'
2019-03-07 02:02:43,552 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/2DKVJbAKtAB0IXUzRg49XIcmelN.jpg'
2019-03-07 02:02:43,619 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/2DKVJbAKtAB0IXUzRg49XIcmelN.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:43,661 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/3dW8wSKA3S0v5pM5tuJGW2DqLBF.jpg'
2019-03-07 02:02:43,703 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/3dW8wSKA3S0v5pM5tuJGW2DqLBF.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:43,736 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/xuXWwRquydnIJMmM7bBGiaeqWqh.jpg'
2019-03-07 02:02:43,782 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/xuXWwRquydnIJMmM7bBGiaeqWqh.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:43,808 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/6uIGc25rahyKeG99h6JP7c1e6ai.jpg'
2019-03-07 02:02:43,861 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/6uIGc25rahyKeG99h6JP7c1e6ai.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:43,900 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/8N4KWF6dOGvDm2jFfqSr2wFh9mH.jpg'
2019-03-07 02:02:43,924 (216c) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F43805%2Frecommendations'
2019-03-07 02:02:43,941 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/8N4KWF6dOGvDm2jFfqSr2wFh9mH.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:43,967 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/2KhvxQGsuf19rcO18Wk6yGYCWtV.jpg'
2019-03-07 02:02:44,009 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/2KhvxQGsuf19rcO18Wk6yGYCWtV.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,030 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/htSrh2MwlHKYgF2nX0bgoFkP0YD.jpg'
2019-03-07 02:02:44,076 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/htSrh2MwlHKYgF2nX0bgoFkP0YD.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,104 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/payQ1DNvsXZLKoeTd3pq73ao7BM.jpg'
2019-03-07 02:02:44,144 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/payQ1DNvsXZLKoeTd3pq73ao7BM.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,165 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/9RmZu33qHdyZFGLfhEOmkTjdNEu.jpg'
2019-03-07 02:02:44,209 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/9RmZu33qHdyZFGLfhEOmkTjdNEu.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,229 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/nwP7YgAftOC7DMZKtGlHbTtIvXm.jpg'
2019-03-07 02:02:44,266 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/nwP7YgAftOC7DMZKtGlHbTtIvXm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,286 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/luSHsQYyX0aukrHPL5joYOxcsO9.jpg'
2019-03-07 02:02:44,325 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/luSHsQYyX0aukrHPL5joYOxcsO9.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,345 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pStdavdGUj2aLUpN8vHC9AbxgnU.jpg'
2019-03-07 02:02:44,392 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pStdavdGUj2aLUpN8vHC9AbxgnU.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,417 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/heMnws4SvjxS6L5H7C5ENjuWwUC.jpg'
2019-03-07 02:02:44,453 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/heMnws4SvjxS6L5H7C5ENjuWwUC.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,477 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lCPs42mujsvvtqKqIUkvwGlsPSA.jpg'
2019-03-07 02:02:44,523 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lCPs42mujsvvtqKqIUkvwGlsPSA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,548 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/bHNjMlsCV4HjIXxk7MmGJvh77zt.jpg'
2019-03-07 02:02:44,582 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/bHNjMlsCV4HjIXxk7MmGJvh77zt.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,608 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/tL7OTWYHWcno5G4oNVrgTwSoP3U.jpg'
2019-03-07 02:02:44,641 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/tL7OTWYHWcno5G4oNVrgTwSoP3U.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,664 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/4Nwp0qmcpTbbdaZLLpxVAI5jPPF.jpg'
2019-03-07 02:02:44,698 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/4Nwp0qmcpTbbdaZLLpxVAI5jPPF.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,720 (4a0) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/9RM0KN8CaRggEwY52b9fVhXiFXH.jpg'
2019-03-07 02:02:44,770 (4a0) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/9RM0KN8CaRggEwY52b9fVhXiFXH.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:44,782 (4a0) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0117887?lang=en&ratings=1&reviews=1&extras=1'
2019-03-07 02:02:44,908 (4a0) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0117887%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2019-03-07 02:02:44,959 (4a0) :  INFO (logkit:16) - Added 1 of 2 extras.
2019-03-07 02:02:44,980 (4a0) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=That%20Thing%20You%20Do%21'
2019-03-07 02:02:45,134 (4a0) :  DEBUG (logkit:13) - Added 0 chapters.
2019-03-07 02:02:45,247 (4a0) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(6699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=100&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-07 02:02:45,247 (4a0) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2019-03-07 02:02:45,276 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,278 (4a0) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2019-03-07 02:02:45,378 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,380 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,380 (4a0) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(6699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=100&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-07 02:02:45,381 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,381 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,382 (4a0) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(6699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=100&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-07 02:02:45,384 (4a0) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-07 02:02:45,388 (4a0) :  DEBUG (model:229) - Serializing to C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\f\7e82850814d47ab8fd58b13cc08eac15788a9b6.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2019-03-07 02:02:45,391 (4a0) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2019-03-07 02:02:45,391 (4a0) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2019-03-07 02:02:46,101 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/lbhgk1XSb2oJoFJwwTxrY2yEB8N.jpg'
2019-03-07 02:02:46,141 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/lbhgk1XSb2oJoFJwwTxrY2yEB8N.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,161 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qJWKrCLEklL6twhjyNufzWEx85x.jpg'
2019-03-07 02:02:46,204 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qJWKrCLEklL6twhjyNufzWEx85x.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,226 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/7lPeBrX5b8fnoRkdNrcqdWAkvC0.jpg'
2019-03-07 02:02:46,278 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/7lPeBrX5b8fnoRkdNrcqdWAkvC0.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,298 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/h0HmqY3TACtgV7iNPvWtpJ1zmyF.jpg'
2019-03-07 02:02:46,345 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/h0HmqY3TACtgV7iNPvWtpJ1zmyF.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,367 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/1mKHndOYo6qGnNamXvcrlVzgfsQ.jpg'
2019-03-07 02:02:46,414 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/1mKHndOYo6qGnNamXvcrlVzgfsQ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,437 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/e8taATXq18V0Ftnkv26lGFA8Q9i.jpg'
2019-03-07 02:02:46,470 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/e8taATXq18V0Ftnkv26lGFA8Q9i.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,490 (216c) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/acw5eA6Empyu2Vkqnvx89Q7Mh33.jpg'
2019-03-07 02:02:46,523 (216c) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/acw5eA6Empyu2Vkqnvx89Q7Mh33.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-07 02:02:46,533 (216c) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0032851?lang=en&ratings=1&reviews=1&extras=1'
2019-03-07 02:02:46,687 (216c) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0032851%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2019-03-07 02:02:46,733 (216c) :  INFO (logkit:16) - Added 0 of 0 extras.
2019-03-07 02:02:46,756 (216c) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Northwest%20Passage'
2019-03-07 02:02:46,875 (216c) :  DEBUG (logkit:13) - Added 0 chapters.
2019-03-07 02:02:46,928 (216c) :  DEBUG (model:229) - Serializing to C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\4\ec123e6f6665c803784c7b088cc3b119c40d21c.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2019-03-07 02:02:46,931 (216c) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2019-03-07 02:02:46,931 (216c) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
