2018-06-24 20:14:41,803 (7f20aa54f700) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: fd05be3 (Fri Jun 08 17:33:50 UTC 2018)
2018-06-24 20:14:41,804 (7f20aa54f700) :  DEBUG (core:361) - Using the elevated policy
2018-06-24 20:14:41,804 (7f20aa54f700) :  DEBUG (core:450) - Starting runtime component.
2018-06-24 20:14:41,811 (7f20aa54f700) :  DEBUG (core:450) - Starting caching component.
2018-06-24 20:14:41,811 (7f20aa54f700) :  DEBUG (core:450) - Starting data component.
2018-06-24 20:14:41,812 (7f20aa54f700) :  DEBUG (core:450) - Starting networking component.
2018-06-24 20:14:41,813 (7f20aa54f700) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-06-24 20:14:41,816 (7f20aa54f700) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-06-24 20:14:41,818 (7f20aa54f700) :  DEBUG (core:450) - Starting localization component.
2018-06-24 20:14:41,819 (7f20aa54f700) :  INFO (localization:409) - Setting the default locale to en-us
2018-06-24 20:14:41,819 (7f20aa54f700) :  DEBUG (core:450) - Starting messaging component.
2018-06-24 20:14:41,820 (7f20aa54f700) :  DEBUG (core:450) - Starting debugging component.
2018-06-24 20:14:41,821 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-06-24 20:14:41,823 (7f20aa54f700) :  DEBUG (core:450) - Starting services component.
2018-06-24 20:14:41,828 (7f20aa54f700) :  DEBUG (core:450) - Starting myplex component.
2018-06-24 20:14:41,828 (7f20aa54f700) :  DEBUG (core:450) - Starting notifications component.
2018-06-24 20:14:42,205 (7f20aa54f700) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-06-24 20:14:42,217 (7f20aa54f700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-06-24 20:14:42,328 (7f20a09e1700) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-06-24 20:14:42,328 (7f20aa54f700) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-06-24 20:14:42,330 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-06-24 20:14:42,333 (7f20aa54f700) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-06-24 20:14:42,335 (7f20aa54f700) :  DEBUG (core:150) - Finished starting framework core
2018-06-24 20:14:42,336 (7f20aa54f700) :  DEBUG (core:560) - Loading plug-in code
2018-06-24 20:14:42,337 (7f207bfff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-06-24 20:14:42,348 (7f207bfff700) :  DEBUG (core:538) - Machine identifier is 6aa2959cccfe8ea185a5501c6c5d15c2a6a61cde
2018-06-24 20:14:42,348 (7f207bfff700) :  DEBUG (core:539) - Server version is 1.13.2.5154-fd05be322
2018-06-24 20:14:43,083 (7f20a09e1700) :  DEBUG (services:362) - Loaded services
2018-06-24 20:14:43,098 (7f20a0573700) :  DEBUG (services:438) - No shared code to load
2018-06-24 20:14:44,020 (7f20aa54f700) :  DEBUG (core:566) - Finished loading plug-in code
2018-06-24 20:14:44,424 (7f20aa54f700) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-06-24 20:14:44,427 (7f20aa54f700) :  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-06-24 20:14:44,429 (7f20aa54f700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-06-24 20:14:44,451 (7f20aa54f700) :  INFO (core:611) - Started plug-in
2018-06-24 20:14:44,451 (7f20aa54f700) :  DEBUG (socketinterface:160) - Starting socket server
2018-06-24 20:14:44,453 (7f20aa54f700) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-06-24 20:14:44,454 (7f20aa54f700) :  INFO (socketinterface:184) - Socket server started on port 34128
2018-06-24 20:14:44,454 (7f20aa54f700) :  INFO (pipeinterface:25) - Entering run loop
2018-06-24 20:14:44,455 (7f20aa54f700) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-06-24 20:14:44,459 (7f20aa54f700) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-06-24 20:14:44,461 (7f20aa54f700) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-06-24 20:14:45,292 (7f20a09e1700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/prefs
2018-06-24 20:14:45,340 (7f20a09e1700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/prefs
2018-06-24 20:14:45,341 (7f20a09e1700) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-06-24 20:14:45,344 (7f20a09e1700) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-06-24 20:14:45,361 (7f20a09e1700) :  DEBUG (runtime:88) - Sending packed state data (104 bytes)
2018-06-24 20:14:45,362 (7f20a09e1700) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 2345 bytes
2018-06-24 20:15:05,205 (7f20a09e1700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgoxNDI4ODJzNApkYmlkaTAKczcKdmVyc2lvbm5zMTAKcGFyZW50R1VJRG5zOApwYXJlbnRJRHM1Ck1vdmllczEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGI6Ly90dDE0MTY4MDE%40bGFuZz1lbnM0Cmd1aWRzOQp0dDE0MTY4MDFzMgppZHIwCg__
2018-06-24 20:15:05,209 (7f20a09e1700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgoxNDI4ODJzNApkYmlkaTAKczcKdmVyc2lvbm5zMTAKcGFyZW50R1VJRG5zOApwYXJlbnRJRHM1Ck1vdmllczEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGI6Ly90dDE0MTY4MDE@bGFuZz1lbnM0Cmd1aWRzOQp0dDE0MTY4MDFzMgppZHIwCg__
2018-06-24 20:15:05,210 (7f20a09e1700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt1416801?lang=en
2018-06-24 20:15:05,229 (7f20a09e1700) :  ERROR (model:205) - Cannot read model from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Movies/0/1f2d1f66cdaee338fff701b831b097a2d8d5235.bundle/Contents/com.plexapp.agents.imdb
2018-06-24 20:15:05,230 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/142882/tree'
2018-06-24 20:15:05,265 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration'
2018-06-24 20:15:07,469 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1416801%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2018-06-24 20:15:09,572 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1416801%2Fimages'
2018-06-24 20:15:11,684 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F51209%2Frecommendations'
2018-06-24 20:15:13,820 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/4d9Cj7I3hZTQAvxK0rebrDGKTb5.jpg'
2018-06-24 20:15:33,861 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/acO0ADPwk5tkCtt6MKn8lq8srKC.jpg'
2018-06-24 20:15:58,904 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/a6TzpWI2SxntWUHKiiOVpJJ7a1d.jpg'
2018-06-24 20:16:18,942 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/seEBJVKqGrU3ghfk9wMJBiUCQrc.jpg'
2018-06-24 20:16:38,995 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/j6UF7h8mIUBb6MLQSMCWneHis7r.jpg'
2018-06-24 20:16:59,063 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/jq5hQ4omRbBkKQ6SWX4cpPi80Dt.jpg'
2018-06-24 20:17:19,107 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/msPnBkmsdB08q2p69fuPgzv35Gc.jpg'
2018-06-24 20:17:39,172 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pFnWN7L6ZZqDFUueLgj2NFpdIa7.jpg'
2018-06-24 20:17:59,230 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/oVgvsabRDSUhqeWUkfNbXkr2gQ.jpg'
2018-06-24 20:18:19,289 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/cEaQJmrfYhspnm1YbyAQEOqElYb.jpg'
2018-06-24 20:18:39,348 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vKDiYBA3ozJdha2K8FICLJwUrJp.jpg'
2018-06-24 20:18:59,398 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pF9cWejzfaezG2XvARbp3Dla3Z1.jpg'
2018-06-24 20:19:19,450 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/edaCxF6x23anULAc11siNurId3d.jpg'
2018-06-24 20:19:39,508 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/DABwGsiPHWQ8AvSm5TMlUPU9Zz.jpg'
2018-06-24 20:19:59,562 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/k3G70hmxzESuyrmTu30lVvmjD5U.jpg'
2018-06-24 20:20:19,598 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/stwIjts5428V8v45mYmUmD9XhHd.jpg'
2018-06-24 20:20:39,635 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/z6G8uopvi0VLy3pSIE8xsi6Mn9x.jpg'
2018-06-24 20:20:59,693 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/krX5YBT1drEhkXzlOawSedQ4QjY.jpg'
2018-06-24 20:21:19,735 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/ak5cdzsWSHKIwodmVl9nfxqNuPu.jpg'
2018-06-24 20:21:39,789 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/cgEDxhCZXjD5IWInqrR306lckQP.jpg'
2018-06-24 20:21:59,827 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/4J5k53qOym5g1fNAXimuNCjgEQg.jpg'
2018-06-24 20:22:19,896 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/1416801?lang=en&ratings=1&reviews=1&extras=1'
2018-06-24 20:22:19,980 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1416801%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-06-24 20:22:22,300 (7f20a09e1700) :  INFO (__init__:830) - Added 1 of 2 extras.
2018-06-24 20:22:22,311 (7f20a09e1700) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=Kill%20the%20Irishman'
2018-06-24 20:22:22,430 (7f20a09e1700) :  DEBUG (chapterdb:197) - Added 20 chapters.
2018-06-24 20:22:22,462 (7f20a09e1700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(5699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=123&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-06-24 20:22:22,463 (7f20a09e1700) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2018-06-24 20:22:22,543 (7f20a09e1700) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2018-06-24 20:22:22,874 (7f20a09e1700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(5699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=123&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-06-24 20:22:22,879 (7f20a09e1700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(5699)?lang=en&bitrates=80,212,450,750,1500,8000&duration=123&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-06-24 20:22:22,888 (7f20a09e1700) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Movies/0/1f2d1f66cdaee338fff701b831b097a2d8d5235.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-06-24 20:22:22,891 (7f20a09e1700) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-06-24 20:22:22,892 (7f20a09e1700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
