2018-07-30 06:03:07,184 (7f6e9a961700) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 6fa5e50 (Tue Jul 24 06:22:23 UTC 2018)
2018-07-30 06:03:07,185 (7f6e9a961700) :  DEBUG (core:361) - Using the elevated policy
2018-07-30 06:03:07,185 (7f6e9a961700) :  DEBUG (core:450) - Starting runtime component.
2018-07-30 06:03:07,187 (7f6e9a961700) :  DEBUG (core:450) - Starting caching component.
2018-07-30 06:03:07,188 (7f6e9a961700) :  DEBUG (core:450) - Starting data component.
2018-07-30 06:03:07,188 (7f6e9a961700) :  DEBUG (core:450) - Starting networking component.
2018-07-30 06:03:07,197 (7f6e9a961700) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-07-30 06:03:07,209 (7f6e9a961700) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-07-30 06:03:07,210 (7f6e9a961700) :  DEBUG (core:450) - Starting localization component.
2018-07-30 06:03:07,210 (7f6e9a961700) :  INFO (localization:409) - Setting the default locale to en-us
2018-07-30 06:03:07,211 (7f6e9a961700) :  DEBUG (core:450) - Starting messaging component.
2018-07-30 06:03:07,211 (7f6e9a961700) :  DEBUG (core:450) - Starting debugging component.
2018-07-30 06:03:07,211 (7f6e9a961700) :  DEBUG (core:450) - Starting services component.
2018-07-30 06:03:07,212 (7f6e9a961700) :  DEBUG (core:450) - Starting myplex component.
2018-07-30 06:03:07,212 (7f6e9a961700) :  DEBUG (core:450) - Starting notifications component.
2018-07-30 06:03:07,218 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-07-30 06:03:07,307 (7f6e9a961700) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-07-30 06:03:07,310 (7f6e9a961700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-07-30 06:03:07,320 (7f6e7a7fc700) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-07-30 06:03:07,320 (7f6e9a961700) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-07-30 06:03:07,321 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-07-30 06:03:07,321 (7f6e9a961700) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-07-30 06:03:07,322 (7f6e9a961700) :  DEBUG (core:150) - Finished starting framework core
2018-07-30 06:03:07,322 (7f6e9a961700) :  DEBUG (core:560) - Loading plug-in code
2018-07-30 06:03:07,322 (7f6e797fa700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-07-30 06:03:07,324 (7f6e797fa700) :  DEBUG (core:538) - Machine identifier is 0bcca5d21d6dbfbe4fef7536b55c7830f80b744e
2018-07-30 06:03:07,325 (7f6e797fa700) :  DEBUG (core:539) - Server version is 1.13.5.5291-6fa5e50a8
2018-07-30 06:03:07,667 (7f6e7a7fc700) :  DEBUG (services:362) - Loaded services
2018-07-30 06:03:07,734 (7f6e79ffb700) :  DEBUG (services:438) - No shared code to load
2018-07-30 06:03:07,777 (7f6e9a961700) :  DEBUG (core:566) - Finished loading plug-in code
2018-07-30 06:03:07,901 (7f6e9a961700) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-07-30 06:03:07,902 (7f6e9a961700) :  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-07-30 06:03:07,902 (7f6e9a961700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-07-30 06:03:07,908 (7f6e9a961700) :  INFO (core:611) - Started plug-in
2018-07-30 06:03:07,908 (7f6e9a961700) :  DEBUG (socketinterface:160) - Starting socket server
2018-07-30 06:03:07,911 (7f6e9a961700) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-07-30 06:03:07,911 (7f6e9a961700) :  INFO (socketinterface:184) - Socket server started on port 38557
2018-07-30 06:03:07,912 (7f6e9a961700) :  INFO (pipeinterface:25) - Entering run loop
2018-07-30 06:03:07,912 (7f6e9a961700) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-07-30 06:03:07,913 (7f6e9a961700) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-07-30 06:03:07,913 (7f6e9a961700) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-07-30 06:03:07,937 (7f6e7a7fc700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgowZWFhZWE5ODgwNjkyMThmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczcKQ2hhcHBpZXM0Cm5hbWVzNAoyMDE0czQKeWVhcnM4NQolMkZob21lJTJGa2V2aW4lMkZWaWRlb3MlMkZTY2llbmNlJTIwRmljdGlvbiUyMGFuZCUyMEZhbnRhc3klMkZDSEFQUGlFJTIwKDIwMTQpJTJFbTR2czgKZmlsZW5hbWVzNDAKYzQwZDQyOTMzZjEwZmY4OTAwMTRlYWEwYTJlY2YwODAyYjJkMGYwY3M4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjYwMTYwczIKaWRyMAo_
2018-07-30 06:03:07,952 (7f6e7a7fc700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgowZWFhZWE5ODgwNjkyMThmczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczcKQ2hhcHBpZXM0Cm5hbWVzNAoyMDE0czQKeWVhcnM4NQolMkZob21lJTJGa2V2aW4lMkZWaWRlb3MlMkZTY2llbmNlJTIwRmljdGlvbiUyMGFuZCUyMEZhbnRhc3klMkZDSEFQUGlFJTIwKDIwMTQpJTJFbTR2czgKZmlsZW5hbWVzNDAKYzQwZDQyOTMzZjEwZmY4OTAwMTRlYWEwYTJlY2YwODAyYjJkMGYwY3M4CnBsZXhIYXNoczIKLTFzOApkdXJhdGlvbnM1CjYwMTYwczIKaWRyMAo_
2018-07-30 06:03:07,952 (7f6e7a7fc700) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '0eaaea988069218f', 'name': 'Chappie', 'year': '2014', 'filename': '%2Fhome%2Fkevin%2FVideos%2FScience%20Fiction%20and%20Fantasy%2FCHAPPiE%20(2014)%2Em4v', 'plexHash': 'c40d42933f10ff890014eaa0a2ecf0802b2d0f0c', 'duration': '-1', 'id': '60160'}
2018-07-30 06:03:07,972 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/60160/tree'
2018-07-30 06:03:08,004 (7f6e7a7fc700) :  INFO (__init__:138) - checking hash search vector: https://meta.plex.tv/movie/hash/c4/c40d42933f10ff890014eaa0a2ecf0802b2d0f0c.xml
2018-07-30 06:03:08,026 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/c4/c40d42933f10ff890014eaa0a2ecf0802b2d0f0c.xml'
2018-07-30 06:03:08,412 (7f6e7a7fc700) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/c4/c40d42933f10ff890014eaa0a2ecf0802b2d0f0c.xml'
2018-07-30 06:03:08,412 (7f6e7a7fc700) :  INFO (__init__:165) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-07-30 06:03:08,412 (7f6e7a7fc700) :  INFO (__init__:169) - Scoring {}
2018-07-30 06:03:08,412 (7f6e7a7fc700) :  INFO (__init__:438) - ---- HASH RESULTS MAP ----
2018-07-30 06:03:08,412 (7f6e7a7fc700) :  INFO (__init__:439) - {}
2018-07-30 06:03:08,413 (7f6e7a7fc700) :  INFO (__init__:138) - checking title/year search vector: https://meta.plex.tv/movie/guid/cb/cb60c05443243e6158e94154b4a648b263985cf8.xml
2018-07-30 06:03:08,433 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/cb/cb60c05443243e6158e94154b4a648b263985cf8.xml'
2018-07-30 06:03:08,785 (7f6e7a7fc700) :  INFO (__init__:169) - Scoring {'tt1823672': [0, u'Chappie', u'2014', 100, 285, 0]}
2018-07-30 06:03:08,785 (7f6e7a7fc700) :  INFO (__init__:464) - ---- TITLE_YEAR RESULTS MAP ----
2018-07-30 06:03:08,785 (7f6e7a7fc700) :  INFO (__init__:465) - {'tt1823672': [0, u'Chappie', u'2014', 100, 285, 95]}
2018-07-30 06:03:08,816 (7f6e7a7fc700) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-07-30 06:03:08,851 (7f6e7a7fc700) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-07-30 06:03:08,855 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1823672%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-07-30 06:03:11,227 (7f6e7a7fc700) :  INFO (__init__:473) - Adding title_year match: Chappie (2015) score=95, key=tt1823672
2018-07-30 06:03:11,227 (7f6e7a7fc700) :  INFO (__init__:487) - ---- TMDb RESULTS MAP ----
2018-07-30 06:03:11,229 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fsearch%2Fmovie%3Fquery%3DChappie%26year%3D2014%26language%3Den%26include_adult%3Dfalse'
2018-07-30 06:03:13,593 (7f6e7a7fc700) :  INFO (__init__:491) - ---- UMP RESULTS MAP ----
2018-07-30 06:03:13,609 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/ump/matches?type=1&title=Chappie&year=2014&plexHash=c40d42933f10ff890014eaa0a2ecf0802b2d0f0c&lang=en&manual=0'
2018-07-30 06:03:14,133 (7f6e7a7fc700) :  INFO (__init__:325) - MetadataSearchResult(lang='en', thumb='https://image.tmdb.org/t/p/w154/saF3HtAduvrP9ytXDxSnQJP3oqx.jpg', score=98, year=2015, id='tt1823672', name='Chappie')
2018-07-30 06:03:14,134 (7f6e7a7fc700) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-07-30 06:03:14,134 (7f6e7a7fc700) :  DEBUG (runtime:924) - Response: [200] str, 720 bytes
2018-07-30 06:03:14,269 (7f6e7a7fc700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo2MDE2MHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTgyMzY3Mj9sYW5nPWVuczQKZ3VpZHM5CnR0MTgyMzY3MnMyCmlkcjAK
2018-07-30 06:03:14,270 (7f6e7a7fc700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo2MDE2MHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTgyMzY3Mj9sYW5nPWVuczQKZ3VpZHM5CnR0MTgyMzY3MnMyCmlkcjAK
2018-07-30 06:03:14,271 (7f6e7a7fc700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt1823672?lang=en
2018-07-30 06:03:14,273 (7f6e7a7fc700) :  ERROR (model:205) - Cannot read model from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/e/1823280344cc8b08a6d057edaa2228970c9191b.bundle/Contents/com.plexapp.agents.imdb
2018-07-30 06:03:14,273 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/60160/tree'
2018-07-30 06:03:14,333 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration'
2018-07-30 06:03:16,494 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1823672%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-07-30 06:03:19,384 (7f6e7a7fc700) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 4107 KB (1024 items)
2018-07-30 06:03:19,389 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1823672%2Fimages'
2018-07-30 06:03:21,565 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F198184%2Frecommendations'
2018-07-30 06:03:23,777 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/vZuWg4aPa3VCnJsyJOKzFQT6Mal.jpg'
2018-07-30 06:03:23,891 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/vZuWg4aPa3VCnJsyJOKzFQT6Mal.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:23,915 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/oOdAFmO6lpMbDRoas1T2VNSot04.jpg'
2018-07-30 06:03:23,994 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/oOdAFmO6lpMbDRoas1T2VNSot04.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,010 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/hM61GOzPapkUNSMxbKvt890XW5j.jpg'
2018-07-30 06:03:24,087 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/hM61GOzPapkUNSMxbKvt890XW5j.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,089 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/nfFvCAhLDqwtyfEaQ8uJ6HcN403.jpg'
2018-07-30 06:03:24,200 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/nfFvCAhLDqwtyfEaQ8uJ6HcN403.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,202 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/vMHfDQc27s9ORDD9P4auQBvCjC5.jpg'
2018-07-30 06:03:24,281 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/vMHfDQc27s9ORDD9P4auQBvCjC5.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,283 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/1ayXr01s01MAR3BNLiTRSawXQwR.jpg'
2018-07-30 06:03:24,366 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/1ayXr01s01MAR3BNLiTRSawXQwR.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,368 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/uo9ySb9gICM8pfqeuXQUlH6GXs7.jpg'
2018-07-30 06:03:24,481 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/uo9ySb9gICM8pfqeuXQUlH6GXs7.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,482 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/fcWtYLAmQjksKUzWouFdIMKUYgK.jpg'
2018-07-30 06:03:24,559 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/fcWtYLAmQjksKUzWouFdIMKUYgK.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,561 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/8ObhSKM5D15w7xUMOJalAZO5q22.jpg'
2018-07-30 06:03:24,673 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/8ObhSKM5D15w7xUMOJalAZO5q22.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,677 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/y5lG7TBpeOMG0jxAaTK0ghZSzBJ.jpg'
2018-07-30 06:03:24,755 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/y5lG7TBpeOMG0jxAaTK0ghZSzBJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,756 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/bJElxnra5BwL9eer1RvkH65YIcA.jpg'
2018-07-30 06:03:24,834 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/bJElxnra5BwL9eer1RvkH65YIcA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,836 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/bAsIY6OActXU6RsVgv8vuInbq4S.jpg'
2018-07-30 06:03:24,913 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/bAsIY6OActXU6RsVgv8vuInbq4S.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:24,942 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/qNd37ugLwkuZXfpTB8ehvkpdfwt.jpg'
2018-07-30 06:03:25,021 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/qNd37ugLwkuZXfpTB8ehvkpdfwt.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,023 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/p8IdsvAxa0C2hV73YY0s5yupNB.jpg'
2018-07-30 06:03:25,134 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/p8IdsvAxa0C2hV73YY0s5yupNB.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,136 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/in93ue2CPzLbCn0VlnV9KuhfDGg.jpg'
2018-07-30 06:03:25,216 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/in93ue2CPzLbCn0VlnV9KuhfDGg.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,217 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/c1W07oecm1LfwFaFkaBGbG6IdpY.jpg'
2018-07-30 06:03:25,298 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/c1W07oecm1LfwFaFkaBGbG6IdpY.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,300 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/6zmKkfSvo4tBtvGSjn5PofWIvP9.jpg'
2018-07-30 06:03:25,378 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/6zmKkfSvo4tBtvGSjn5PofWIvP9.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,380 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/1GdyyibR7otSZ46UVP0s8lWJeok.jpg'
2018-07-30 06:03:25,472 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/1GdyyibR7otSZ46UVP0s8lWJeok.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,473 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vPQ5iJGV2pfeZ7Noks7tqPCQ8fJ.jpg'
2018-07-30 06:03:25,552 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/vPQ5iJGV2pfeZ7Noks7tqPCQ8fJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,554 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/rpMOs9AAF5wai6VLtXQJVP2FC7w.jpg'
2018-07-30 06:03:25,633 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/rpMOs9AAF5wai6VLtXQJVP2FC7w.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,661 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qXirDZHqgCINGO1WW4FxrtmgCYJ.jpg'
2018-07-30 06:03:25,738 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qXirDZHqgCINGO1WW4FxrtmgCYJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,740 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/rf368ra6nTXLFtFJkmczzzukIX6.jpg'
2018-07-30 06:03:25,816 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/rf368ra6nTXLFtFJkmczzzukIX6.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,818 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/4EjuO91Lc6CX9kXDCzNxzmaJOSV.jpg'
2018-07-30 06:03:25,895 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/4EjuO91Lc6CX9kXDCzNxzmaJOSV.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:25,897 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/zxOIrv4kaa5HNssYWZcggQI4ocm.jpg'
2018-07-30 06:03:25,976 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/zxOIrv4kaa5HNssYWZcggQI4ocm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,001 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3nSdElmE3sY3YjMMYc8UPZqIQOU.jpg'
2018-07-30 06:03:26,078 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3nSdElmE3sY3YjMMYc8UPZqIQOU.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,080 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lPzTy1kSV4FfMH6Po2OO1aHpleO.jpg'
2018-07-30 06:03:26,158 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lPzTy1kSV4FfMH6Po2OO1aHpleO.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,160 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/fjqdYod47TSDUZdKCJAyNw85pRA.jpg'
2018-07-30 06:03:26,247 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/fjqdYod47TSDUZdKCJAyNw85pRA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,250 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/xhef8CC9w8UL8k08scLMZZXtdvG.jpg'
2018-07-30 06:03:26,327 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/xhef8CC9w8UL8k08scLMZZXtdvG.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,329 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/9cx0j3kiYHSAAcuQ8A1vVSS8nNf.jpg'
2018-07-30 06:03:26,406 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/9cx0j3kiYHSAAcuQ8A1vVSS8nNf.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,443 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/5ZvApgilBYM6u2m7wfFlYHsAmFH.jpg'
2018-07-30 06:03:26,520 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/5ZvApgilBYM6u2m7wfFlYHsAmFH.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,522 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/gsK6BmtR7OVGA0kUH76xCW7QneI.jpg'
2018-07-30 06:03:26,602 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/gsK6BmtR7OVGA0kUH76xCW7QneI.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,603 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/saF3HtAduvrP9ytXDxSnQJP3oqx.jpg'
2018-07-30 06:03:26,682 (7f6e7a7fc700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/saF3HtAduvrP9ytXDxSnQJP3oqx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-07-30 06:03:26,688 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/1823672?lang=en&ratings=1&reviews=1&extras=1'
2018-07-30 06:03:26,834 (7f6e7a7fc700) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1823672%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2018-07-30 06:03:26,854 (7f6e7a7fc700) :  INFO (__init__:805) - Added 9 of 51 extras.
2018-07-30 06:03:26,864 (7f6e7a7fc700) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Chappie'
2018-07-30 06:03:27,080 (7f6e7a7fc700) :  DEBUG (chapterdb:197) - Added 16 chapters.
2018-07-30 06:03:27,091 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(140316)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=113&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,091 (7f6e7a7fc700) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2018-07-30 06:03:27,193 (7f6e7a7fc700) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2018-07-30 06:03:27,281 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(140316)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=113&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,282 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(140316)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=113&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,283 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(836274)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=131&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,285 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(836274)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=131&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,286 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(836274)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=131&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,291 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(348729)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=97&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,293 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(348729)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=97&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,294 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(348729)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=97&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,295 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(948965)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=125&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,298 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(948965)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=125&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,300 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(948965)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=125&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,301 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(702260)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=22&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,304 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(702260)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=22&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,305 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(702260)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=22&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,308 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(809087)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=46&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,310 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(809087)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=46&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,311 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(809087)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=46&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,313 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(955151)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=37&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,314 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(955151)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=37&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,316 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(955151)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=37&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,317 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(486447)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=71&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,319 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(486447)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=71&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,320 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(486447)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=71&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,321 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(658056)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=49&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,323 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(658056)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=49&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,324 (7f6e7a7fc700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(658056)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=49&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-07-30 06:03:27,326 (7f6e7a7fc700) :  DEBUG (model:229) - Serializing to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/e/1823280344cc8b08a6d057edaa2228970c9191b.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-07-30 06:03:27,327 (7f6e7a7fc700) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-07-30 06:03:27,327 (7f6e7a7fc700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
