2018-10-28 00:12:05,645 (1189fe5c0) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 10d48da (Fri Sep 21 03:30:28 UTC 2018)
2018-10-28 00:12:05,645 (1189fe5c0) :  DEBUG (core:361) - Using the elevated policy
2018-10-28 00:12:05,646 (1189fe5c0) :  DEBUG (core:450) - Starting runtime component.
2018-10-28 00:12:05,647 (1189fe5c0) :  DEBUG (core:450) - Starting caching component.
2018-10-28 00:12:05,648 (1189fe5c0) :  DEBUG (core:450) - Starting data component.
2018-10-28 00:12:05,648 (1189fe5c0) :  DEBUG (core:450) - Starting networking component.
2018-10-28 00:12:05,648 (1189fe5c0) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-10-28 00:12:05,653 (1189fe5c0) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-10-28 00:12:05,659 (1189fe5c0) :  DEBUG (core:450) - Starting localization component.
2018-10-28 00:12:05,659 (1189fe5c0) :  INFO (localization:409) - Setting the default locale to en-us
2018-10-28 00:12:05,659 (1189fe5c0) :  DEBUG (core:450) - Starting messaging component.
2018-10-28 00:12:05,660 (1189fe5c0) :  DEBUG (core:450) - Starting debugging component.
2018-10-28 00:12:05,660 (1189fe5c0) :  DEBUG (core:450) - Starting services component.
2018-10-28 00:12:05,660 (1189fe5c0) :  DEBUG (core:450) - Starting myplex component.
2018-10-28 00:12:05,660 (1189fe5c0) :  DEBUG (core:450) - Starting notifications component.
2018-10-28 00:12:05,661 (700012275000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-10-28 00:12:05,839 (1189fe5c0) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-10-28 00:12:05,845 (1189fe5c0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-10-28 00:12:05,937 (700012275000) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-10-28 00:12:05,937 (1189fe5c0) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-10-28 00:12:05,938 (700012275000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-10-28 00:12:05,938 (1189fe5c0) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-10-28 00:12:05,939 (1189fe5c0) :  DEBUG (core:150) - Finished starting framework core
2018-10-28 00:12:05,940 (700012a7b000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-10-28 00:12:05,940 (1189fe5c0) :  DEBUG (core:560) - Loading plug-in code
2018-10-28 00:12:06,676 (1189fe5c0) :  DEBUG (core:566) - Finished loading plug-in code
2018-10-28 00:12:06,962 (700012275000) :  DEBUG (services:362) - Loaded services
2018-10-28 00:12:06,985 (1189fe5c0) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-10-28 00:12:06,990 (700012678000) :  DEBUG (services:438) - No shared code to load
2018-10-28 00:12:06,991 (1189fe5c0) :  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-10-28 00:12:06,993 (1189fe5c0) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-10-28 00:12:07,002 (1189fe5c0) :  INFO (core:611) - Started plug-in
2018-10-28 00:12:07,002 (1189fe5c0) :  DEBUG (socketinterface:160) - Starting socket server
2018-10-28 00:12:07,003 (1189fe5c0) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-10-28 00:12:07,004 (1189fe5c0) :  INFO (socketinterface:184) - Socket server started on port 59114
2018-10-28 00:12:07,004 (1189fe5c0) :  INFO (pipeinterface:25) - Entering run loop
2018-10-28 00:12:07,004 (1189fe5c0) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-10-28 00:12:07,005 (1189fe5c0) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-10-28 00:12:07,006 (1189fe5c0) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-10-28 00:12:07,008 (700012678000) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/prefs
2018-10-28 00:12:07,026 (700012678000) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/prefs
2018-10-28 00:12:07,027 (700012678000) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-10-28 00:12:07,028 (700012678000) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-10-28 00:12:07,033 (700012678000) :  DEBUG (runtime:88) - Sending packed state data (104 bytes)
2018-10-28 00:12:07,033 (700012678000) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 2221 bytes
2018-10-28 00:12:08,029 (700012a7b000) :  DEBUG (core:538) - Machine identifier is e84f306669aa686495422001454649faf7af45da
2018-10-28 00:12:08,029 (700012a7b000) :  DEBUG (core:539) - Server version is 1.13.8.5395-10d48da0d
2018-10-28 00:12:24,991 (700012678000) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgo1Njc3MzE2YzA2ZWY0ODcyczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxClRoZSBGYXJtZXIncyBEYXVnaHRlcnM0Cm5hbWVzNAoxOTQ3czQKeWVhcnMxMTYKJTJGVm9sdW1lcyUyRlNjcmF0Y2glMkZQbGV4JTJGTW92aWVzJTJGVGhlJTIwRmFybWVyJ3MlMjBEYXVnaHRlciUyMCgxOTQ3KSUyRlRoZSUyMEZhcm1lcidzJTIwRGF1Z2h0ZXIlMjAoMTk0NyklMkVta3ZzOApmaWxlbmFtZXM0MAo5NjcyMWM1ZTgyMjE3Y2U2Y2U2ZmZjYmExZWQ5MmUzOWExYzc2NTg3czgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczYKNTQwNTgxczIKaWRyMAo_
2018-10-28 00:12:24,994 (700012678000) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgo1Njc3MzE2YzA2ZWY0ODcyczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxClRoZSBGYXJtZXIncyBEYXVnaHRlcnM0Cm5hbWVzNAoxOTQ3czQKeWVhcnMxMTYKJTJGVm9sdW1lcyUyRlNjcmF0Y2glMkZQbGV4JTJGTW92aWVzJTJGVGhlJTIwRmFybWVyJ3MlMjBEYXVnaHRlciUyMCgxOTQ3KSUyRlRoZSUyMEZhcm1lcidzJTIwRGF1Z2h0ZXIlMjAoMTk0NyklMkVta3ZzOApmaWxlbmFtZXM0MAo5NjcyMWM1ZTgyMjE3Y2U2Y2U2ZmZjYmExZWQ5MmUzOWExYzc2NTg3czgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczYKNTQwNTgxczIKaWRyMAo_
2018-10-28 00:12:24,994 (700012678000) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '5677316c06ef4872', 'name': "The Farmer's Daughter", 'year': '1947', 'filename': "%2FVolumes%2FScratch%2FPlex%2FMovies%2FThe%20Farmer's%20Daughter%20(1947)%2FThe%20Farmer's%20Daughter%20(1947)%2Emkv", 'plexHash': '96721c5e82217ce6ce6ffcba1ed92e39a1c76587', 'duration': '-1', 'id': '540581'}
2018-10-28 00:12:24,995 (700012678000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/540581/tree'
2018-10-28 00:12:25,004 (700012678000) :  INFO (__init__:138) - checking hash search vector: https://meta.plex.tv/movie/hash/96/96721c5e82217ce6ce6ffcba1ed92e39a1c76587.xml
2018-10-28 00:12:25,011 (700012678000) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/96/96721c5e82217ce6ce6ffcba1ed92e39a1c76587.xml'
2018-10-28 00:12:25,059 (700012678000) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/96/96721c5e82217ce6ce6ffcba1ed92e39a1c76587.xml'
2018-10-28 00:12:25,059 (700012678000) :  INFO (__init__:165) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2018-10-28 00:12:25,060 (700012678000) :  INFO (__init__:169) - Scoring {}
2018-10-28 00:12:25,060 (700012678000) :  INFO (__init__:438) - ---- HASH RESULTS MAP ----
2018-10-28 00:12:25,060 (700012678000) :  INFO (__init__:439) - {}
2018-10-28 00:12:25,061 (700012678000) :  INFO (__init__:138) - checking title/year search vector: https://meta.plex.tv/movie/guid/1f/1fe8c9a1ec1468f4fa775a20588ba4bb83bb927e.xml
2018-10-28 00:12:25,073 (700012678000) :  DEBUG (networking:161) - Fetching 'https://meta.plex.tv/movie/guid/1f/1fe8c9a1ec1468f4fa775a20588ba4bb83bb927e.xml' from the HTTP cache
2018-10-28 00:12:25,087 (700012678000) :  INFO (__init__:169) - Scoring {'tt0039370': [0, u'The Farmers Daughter', u'1947', 100, 406, 0]}
2018-10-28 00:12:25,087 (700012678000) :  INFO (__init__:464) - ---- TITLE_YEAR RESULTS MAP ----
2018-10-28 00:12:25,088 (700012678000) :  INFO (__init__:465) - {'tt0039370': [0, u'The Farmers Daughter', u'1947', 100, 406, 98]}
2018-10-28 00:12:25,101 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0039370%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2018-10-28 00:12:25,113 (700012678000) :  INFO (__init__:473) - Adding title_year match: The Farmer's Daughter (1947) score=98, key=tt0039370
2018-10-28 00:12:25,114 (700012678000) :  INFO (__init__:481) - Found perfect match with title/year query.
2018-10-28 00:12:25,115 (700012678000) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2018-10-28 00:12:25,116 (700012678000) :  DEBUG (runtime:924) - Response: [200] str, 652 bytes
2018-10-28 00:12:25,166 (700012678000) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgo1NDA1ODFzNApkYmlkaTAKczcKdmVyc2lvbm5zMTAKcGFyZW50R1VJRG5zOApwYXJlbnRJRHM1Ck1vdmllczEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGI6Ly90dDAwMzkzNzA%40bGFuZz1lbnM0Cmd1aWRzOQp0dDAwMzkzNzBzMgppZHIwCg__
2018-10-28 00:12:25,168 (700012678000) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNgo1NDA1ODFzNApkYmlkaTAKczcKdmVyc2lvbm5zMTAKcGFyZW50R1VJRG5zOApwYXJlbnRJRHM1Ck1vdmllczEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGI6Ly90dDAwMzkzNzA@bGFuZz1lbnM0Cmd1aWRzOQp0dDAwMzkzNzBzMgppZHIwCg__
2018-10-28 00:12:25,168 (700012678000) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0039370?lang=en
2018-10-28 00:12:25,170 (700012678000) :  DEBUG (model:234) - Deserializing from /Users/rrg/Library/Application Support/Plex Media Server/Metadata/Movies/0/1dc496bb2f9da9a8c4144bc40b6f0a745b76e1b.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-10-28 00:12:25,186 (700012678000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/540581/tree'
2018-10-28 00:12:25,203 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-10-28 00:12:25,226 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0039370%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den' from the HTTP cache
2018-10-28 00:12:25,252 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0039370%2Fimages' from the HTTP cache
2018-10-28 00:12:25,275 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F43466%2Frecommendations' from the HTTP cache
2018-10-28 00:12:25,295 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/l6rpdQ4E1Sb2rxHvHkJpenWh9Tm.jpg'
2018-10-28 00:12:25,326 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/l6rpdQ4E1Sb2rxHvHkJpenWh9Tm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,331 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/lPxLcA8qvenKUCEbTQI8wmX2sW8.jpg'
2018-10-28 00:12:25,352 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/lPxLcA8qvenKUCEbTQI8wmX2sW8.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,357 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/xldNI6iMerDxqun5KGqmZ3OfVEe.jpg'
2018-10-28 00:12:25,376 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/xldNI6iMerDxqun5KGqmZ3OfVEe.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,380 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/2n1gPPZ0kANDTU2DcGksTqMYUbm.jpg'
2018-10-28 00:12:25,406 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/2n1gPPZ0kANDTU2DcGksTqMYUbm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,416 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/teIOPX3DR2s1nrcgOp4lqMyUsPS.jpg'
2018-10-28 00:12:25,441 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/teIOPX3DR2s1nrcgOp4lqMyUsPS.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,446 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/nCILOFcfOzSkwX2dd5OVGLu6nSO.jpg'
2018-10-28 00:12:25,476 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/nCILOFcfOzSkwX2dd5OVGLu6nSO.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,481 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/4wFXHSKOfeNFIaVeoiXusbTJYLz.jpg'
2018-10-28 00:12:25,500 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/4wFXHSKOfeNFIaVeoiXusbTJYLz.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,504 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/l0kaE6nPvsHS3eziVpE1BG9gOUw.jpg'
2018-10-28 00:12:25,524 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/l0kaE6nPvsHS3eziVpE1BG9gOUw.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,528 (700012678000) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/cfOt3oPJBrYXNaK0IMEsuruIth6.jpg'
2018-10-28 00:12:25,551 (700012678000) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/cfOt3oPJBrYXNaK0IMEsuruIth6.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2018-10-28 00:12:25,557 (700012678000) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0039370?lang=en&ratings=1&reviews=1&extras=1'
2018-10-28 00:12:25,596 (700012678000) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0039370%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2018-10-28 00:12:25,608 (700012678000) :  INFO (__init__:805) - Added 1 of 1 extras.
2018-10-28 00:12:25,624 (700012678000) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Farmer%27s%20Daughter'
2018-10-28 00:12:26,104 (700012678000) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Farmer%27s%20Daughter'
2018-10-28 00:12:26,104 (700012678000) :  INFO (__init__:876) - Error obtaining Plex movie Chapter data for 0039370: HTTP Error 401: Unauthorized
2018-10-28 00:12:26,127 (700012678000) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(79427)?lang=en&bitrates=80,212,450,750,1500,8000&duration=106&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-10-28 00:12:26,128 (700012678000) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2018-10-28 00:12:26,153 (700012678000) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2018-10-28 00:12:26,272 (700012678000) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(79427)?lang=en&bitrates=80,212,450,750,1500,8000&duration=106&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-10-28 00:12:26,274 (700012678000) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(79427)?lang=en&bitrates=80,212,450,750,1500,8000&duration=106&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2018-10-28 00:12:26,278 (700012678000) :  DEBUG (model:229) - Serializing to /Users/rrg/Library/Application Support/Plex Media Server/Metadata/Movies/0/1dc496bb2f9da9a8c4144bc40b6f0a745b76e1b.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-10-28 00:12:26,279 (700012678000) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-10-28 00:12:26,279 (700012678000) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
