2018-11-09 18:40:22,816 (76fc1000) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 16:44:57 UTC 2018)
2018-11-09 18:40:22,817 (76fc1000) :  DEBUG (core:361) - Using the elevated policy
2018-11-09 18:40:22,818 (76fc1000) :  DEBUG (core:450) - Starting runtime component.
2018-11-09 18:40:22,824 (76fc1000) :  DEBUG (core:450) - Starting caching component.
2018-11-09 18:40:22,825 (76fc1000) :  DEBUG (core:450) - Starting data component.
2018-11-09 18:40:22,826 (76fc1000) :  DEBUG (core:450) - Starting networking component.
2018-11-09 18:40:22,840 (76fc1000) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-09 18:40:22,882 (76fc1000) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-09 18:40:22,884 (76fc1000) :  DEBUG (core:450) - Starting localization component.
2018-11-09 18:40:22,885 (76fc1000) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-09 18:40:22,886 (76fc1000) :  DEBUG (core:450) - Starting messaging component.
2018-11-09 18:40:22,888 (76fc1000) :  DEBUG (core:450) - Starting debugging component.
2018-11-09 18:40:22,888 (76fc1000) :  DEBUG (core:450) - Starting services component.
2018-11-09 18:40:22,889 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.plexmusic'
2018-11-09 18:40:22,890 (76fc1000) :  DEBUG (core:450) - Starting myplex component.
2018-11-09 18:40:22,894 (76fc1000) :  DEBUG (core:450) - Starting notifications component.
2018-11-09 18:40:23,619 (76fc1000) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.plexmusic in namespace 'metadata'
2018-11-09 18:40:23,645 (76fc1000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-09 18:40:23,667 (76fc1000) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-09 18:40:23,671 (76fc1000) :  CRITICAL (runtime:1299) - Exception getting hosted resource hashes (most recent call last):
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/runtime.py", line 1293, in get_resource_hashes
    json = self._core.networking.http_request("http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes", timeout=10).content
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 265, in content
    return self.__str__()
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 243, in __str__
    self.load()
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 181, in load
    f = self._opener.open(req, timeout=self._timeout)
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py", line 444, in open
    response = meth(req, response)
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py", line 557, in http_response
    'http', request, response, code, msg, hdrs)
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py", line 482, in error
    return self._call_chain(*args)
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py", line 416, in _call_chain
    result = func(*args)
  File "/volume1/@appstore/Plex Media Server/Resources/Plug-ins-ecd600442/Framework.bundle/Contents/Resources/Platforms/Shared/Libraries/urllib2_new.py", line 565, in http_error_default
    raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 404: Not Found

2018-11-09 18:40:23,708 (73a59460) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-09 18:40:23,709 (76fc1000) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-09 18:40:23,712 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-09 18:40:23,715 (76fc1000) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-09 18:40:23,716 (76fc1000) :  DEBUG (core:150) - Finished starting framework core
2018-11-09 18:40:23,717 (76fc1000) :  DEBUG (core:560) - Loading plug-in code
2018-11-09 18:40:23,722 (733fd460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-09 18:40:23,799 (733fd460) :  DEBUG (core:538) - Machine identifier is 72b893ab7afe747e17b52d11accfccf67907a6fc
2018-11-09 18:40:23,800 (733fd460) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-09 18:40:24,614 (73a59460) :  DEBUG (services:362) - Loaded services
2018-11-09 18:40:24,672 (736eb460) :  DEBUG (services:438) - No shared code to load
2018-11-09 18:40:24,992 (76fc1000) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-09 18:40:25,331 (76fc1000) :  DEBUG (messaging:69) - Exposing function NormalizeArtist for remote access
2018-11-09 18:40:25,332 (76fc1000) :  DEBUG (agentkit:1115) - Creating new agent class called GracenoteArtistAgent
2018-11-09 18:40:25,338 (76fc1000) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Artist'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.localmedia'], 'languages': ['en', 'ar', 'bg', 'zh', 'hr', 'cs', 'da', 'nl', 'fi', 'fr', 'de', 'el', 'hu', 'id', 'it', 'ja', 'ko', 'nn', 'pl', 'pt', 'ro', 'ru', 'sr', 'sk', 'es', 'sv', 'th', 'tr', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Premium Music'}]
2018-11-09 18:40:25,342 (76fc1000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWNzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3MxOApQbGV4IFByZW1pdW0gTXVzaWNzNApuYW1lMQpzNgpBcnRpc3QxCnMyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYTI5CnMyCmVuczIKYXJzMgpiZ3MyCnpoczIKaHJzMgpjc3MyCmRhczIKbmxzMgpmaXMyCmZyczIKZGVzMgplbHMyCmh1czIKaWRzMgppdHMyCmphczIKa29zMgpubnMyCnBsczIKcHRzMgpyb3MyCnJ1czIKc3JzMgpza3MyCmVzczIKc3ZzMgp0aHMyCnRyczIKdmlyMAo_'
2018-11-09 18:40:25,408 (76fc1000) :  DEBUG (agentkit:1115) - Creating new agent class called GracenoteAlbumAgent
2018-11-09 18:40:25,423 (76fc1000) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Artist'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.localmedia'], 'languages': ['en', 'ar', 'bg', 'zh', 'hr', 'cs', 'da', 'nl', 'fi', 'fr', 'de', 'el', 'hu', 'id', 'it', 'ja', 'ko', 'nn', 'pl', 'pt', 'ro', 'ru', 'sr', 'sk', 'es', 'sv', 'th', 'tr', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Premium Music'}, {'media_types': ['Album'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.localmedia'], 'languages': ['en', 'ar', 'bg', 'zh', 'hr', 'cs', 'da', 'nl', 'fi', 'fr', 'de', 'el', 'hu', 'id', 'it', 'ja', 'ko', 'nn', 'pl', 'pt', 'ro', 'ru', 'sr', 'sk', 'es', 'sv', 'th', 'tr', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Premium Music'}]
2018-11-09 18:40:25,427 (76fc1000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxMApkaWN0Cmxpc3QKZGljdApsaXN0Cmxpc3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5wbGV4bXVzaWNzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMgpyMgpyNgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3MxOApQbGV4IFByZW1pdW0gTXVzaWNzNApuYW1lMQpzNgpBcnRpc3QxCnMyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYTI5CnMyCmVuczIKYXJzMgpiZ3MyCnpoczIKaHJzMgpjc3MyCmRhczIKbmxzMgpmaXMyCmZyczIKZGVzMgplbHMyCmh1czIKaWRzMgppdHMyCmphczIKa29zMgpubnMyCnBsczIKcHRzMgpyb3MyCnJ1czIKc3JzMgpza3MyCmVzczIKc3ZzMgp0aHMyCnRyczIKdmkxMApyNwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyOApzMTQKY29udHJpYnV0ZXNfdG9yOQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3MxOApQbGV4IFByZW1pdW0gTXVzaWNzNApuYW1lMQpzNQpBbGJ1bTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMjkKczIKZW5zMgphcnMyCmJnczIKemhzMgpocnMyCmNzczIKZGFzMgpubHMyCmZpczIKZnJzMgpkZXMyCmVsczIKaHVzMgppZHMyCml0czIKamFzMgprb3MyCm5uczIKcGxzMgpwdHMyCnJvczIKcnVzMgpzcnMyCnNrczIKZXNzMgpzdnMyCnRoczIKdHJzMgp2aXIwCg__'
2018-11-09 18:40:25,460 (76fc1000) :  INFO (core:611) - Started plug-in
2018-11-09 18:40:25,461 (76fc1000) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-09 18:40:25,463 (76fc1000) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-09 18:40:25,464 (76fc1000) :  INFO (socketinterface:184) - Socket server started on port 38600
2018-11-09 18:40:25,465 (76fc1000) :  INFO (pipeinterface:25) - Entering run loop
2018-11-09 18:40:25,466 (76fc1000) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-09 18:40:25,472 (76fc1000) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-09 18:40:25,475 (76fc1000) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 153 bytes
2018-11-09 18:40:25,481 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/prefs
2018-11-09 18:40:25,544 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/prefs
2018-11-09 18:40:25,547 (73a59460) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-11-09 18:40:25,566 (73a59460) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.plexmusic
2018-11-09 18:40:25,572 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:40:25,573 (73a59460) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 731 bytes
2018-11-09 18:42:13,192 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzCjE5ODkgKERlbHV4ZSlzNQphbGJ1bXMyCjE2czUKaW5kZXhzMTYKNmU5N2Q4YTk1ZjU4OWU3OHMxNwpvcGVuU3VidGl0bGVzSGFzaHMxMwpOZXcgUm9tYW50aWNzczQKbmFtZXMxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3RuczUKdGl0bGVzMTEzCiUyRnZvbHVtZTElMkZtdXNpYyUyRm11c2lxdWUlMkZUYXlsb3IlMjBTd2lmdCUyRlRheWxvciUyMFN3aWZ0JTIwLSUyMDE5ODklMjAoRGVsdXhlKSUyRjE2JTIwTmV3JTIwUm9tYW50aWNzJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3M0MAo1ZGY4ZGQ5MzM3ZGI3MDU2MDBjMDljZmE4ZjZjNjA2NjA0NjFlODBkczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:42:13,198 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzCjE5ODkgKERlbHV4ZSlzNQphbGJ1bXMyCjE2czUKaW5kZXhzMTYKNmU5N2Q4YTk1ZjU4OWU3OHMxNwpvcGVuU3VidGl0bGVzSGFzaHMxMwpOZXcgUm9tYW50aWNzczQKbmFtZXMxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3RuczUKdGl0bGVzMTEzCiUyRnZvbHVtZTElMkZtdXNpYyUyRm11c2lxdWUlMkZUYXlsb3IlMjBTd2lmdCUyRlRheWxvciUyMFN3aWZ0JTIwLSUyMDE5ODklMjAoRGVsdXhlKSUyRjE2JTIwTmV3JTIwUm9tYW50aWNzJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3M0MAo1ZGY4ZGQ5MzM3ZGI3MDU2MDBjMDljZmE4ZjZjNjA2NjA0NjFlODBkczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:42:13,200 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'album': '1989 (Deluxe)', 'index': '16', 'openSubtitlesHash': '6e97d8a95f589e78', 'name': 'New Romantics', 'title': None, 'artist': 'Taylor Swift', 'filename': '%2Fvolume1%2Fmusic%2Fmusique%2FTaylor%20Swift%2FTaylor%20Swift%20-%201989%20(Deluxe)%2F16%20New%20Romantics%2Em4a', 'disc': '1', 'plexHash': '5df8dd9337db705600c09cfa8f6c60660461e80d', 'duration': '-1', 'id': '41852'}
2018-11-09 18:42:13,206 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:13,235 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:13,265 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:42:13,266 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 18:42:14,181 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzCjE5ODkgKERlbHV4ZSlzNQphbGJ1bXMyCjE2czUKaW5kZXhzMTYKNmU5N2Q4YTk1ZjU4OWU3OHMxNwpvcGVuU3VidGl0bGVzSGFzaHMxMwpOZXcgUm9tYW50aWNzczQKbmFtZXMxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3RuczUKdGl0bGVzMTEzCiUyRnZvbHVtZTElMkZtdXNpYyUyRm11c2lxdWUlMkZUYXlsb3IlMjBTd2lmdCUyRlRheWxvciUyMFN3aWZ0JTIwLSUyMDE5ODklMjAoRGVsdXhlKSUyRjE2JTIwTmV3JTIwUm9tYW50aWNzJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3M0MAo1ZGY4ZGQ5MzM3ZGI3MDU2MDBjMDljZmE4ZjZjNjA2NjA0NjFlODBkczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:42:14,184 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 18:42:14,188 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlMTEKczEzCjE5ODkgKERlbHV4ZSlzNQphbGJ1bXMyCjE2czUKaW5kZXhzMTYKNmU5N2Q4YTk1ZjU4OWU3OHMxNwpvcGVuU3VidGl0bGVzSGFzaHMxMwpOZXcgUm9tYW50aWNzczQKbmFtZXMxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3RuczUKdGl0bGVzMTEzCiUyRnZvbHVtZTElMkZtdXNpYyUyRm11c2lxdWUlMkZUYXlsb3IlMjBTd2lmdCUyRlRheWxvciUyMFN3aWZ0JTIwLSUyMDE5ODklMjAoRGVsdXhlKSUyRjE2JTIwTmV3JTIwUm9tYW50aWNzJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3M0MAo1ZGY4ZGQ5MzM3ZGI3MDU2MDBjMDljZmE4ZjZjNjA2NjA0NjFlODBkczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:42:14,189 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'album': '1989 (Deluxe)', 'index': '16', 'openSubtitlesHash': '6e97d8a95f589e78', 'name': 'New Romantics', 'title': None, 'artist': 'Taylor Swift', 'filename': '%2Fvolume1%2Fmusic%2Fmusique%2FTaylor%20Swift%2FTaylor%20Swift%20-%201989%20(Deluxe)%2F16%20New%20Romantics%2Em4a', 'disc': '1', 'plexHash': '5df8dd9337db705600c09cfa8f6c60660461e80d', 'duration': '-1', 'id': '41852'}
2018-11-09 18:42:14,191 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:14,220 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:14,255 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:42:14,256 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 18:42:24,347 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlNQpuczgKcGFyZW50SURzMTMKbG9jYWw6Ly80MTg1MnM0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MTg1MnMyCmlkcjAK
2018-11-09 18:42:24,349 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 18:42:24,353 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M2CkFydGlzdHMxMAptZWRpYV90eXBlNQpuczgKcGFyZW50SURzMTMKbG9jYWw6Ly80MTg1MnM0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MTg1MnMyCmlkcjAK
2018-11-09 18:42:24,354 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'id': '41852', 'guid': 'local://41852', 'force': True, 'primary_agent': 'com.plexapp.agents.localmedia', 'parentID': None}
2018-11-09 18:42:24,356 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:24,388 (73a59460) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.localmedia in namespace 'metadata'
2018-11-09 18:42:24,408 (73a59460) :  DEBUG (model:32) - Loading model with GUID local://41852
2018-11-09 18:42:24,414 (73a59460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Artists/f/8f08504a10fa2f57556f6508943351650e02436.bundle/Contents/com.plexapp.agents.localmedia/Info.xml
2018-11-09 18:42:24,417 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:24,454 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:42:24,455 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 384 bytes
2018-11-09 18:42:24,474 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MTg1MnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczYKQXJ0aXN0czEwCm1lZGlhX3R5cGVzMTMKbG9jYWw6Ly80MTg1MnM0Cmd1aWRzNQo0MTg1MnMyCmlkcjAK
2018-11-09 18:42:24,479 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MTg1MnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczYKQXJ0aXN0czEwCm1lZGlhX3R5cGVzMTMKbG9jYWw6Ly80MTg1MnM0Cmd1aWRzNQo0MTg1MnMyCmlkcjAK
2018-11-09 18:42:24,481 (73a59460) :  DEBUG (model:32) - Loading model with GUID local://41852
2018-11-09 18:42:24,483 (73a59460) :  ERROR (model:205) - Cannot read model from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Artists/f/8f08504a10fa2f57556f6508943351650e02436.bundle/Contents/com.plexapp.agents.plexmusic
2018-11-09 18:42:24,484 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:42:24,517 (73a59460) :  INFO (__init__:230) - Updating: Taylor Swift (GUID: local://41852)
2018-11-09 18:42:24,519 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.lastfm/messaging/function/TWVzc2FnZUtpdDpBcnRpc3RTZWFyY2g_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKbGlzdAozCnMyCnhuczQKbGFuZ3IxCnM2CmFsYnVtc3MxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3QxCnMxMwoxOTg5IChEZWx1eGUpcjAK'
2018-11-09 18:42:50,557 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.lastfm/messaging/function/TWVzc2FnZUtpdDpBcnRpc3RNYmlkTG9va3Vw/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpuczEzCmxhc3RmbV9hcnRpc3RyMAo_'
2018-11-09 18:42:50,606 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.htbackdrops/messaging/function/TWVzc2FnZUtpdDpBcnRpc3RTZWFyY2g_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMTIKVGF5bG9yIFN3aWZ0czExCmFydGlzdF9uYW1lcjAK'
2018-11-09 18:43:15,286 (73a59460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.htbackdrops/messaging/function/TWVzc2FnZUtpdDpBcnRpc3RTZWFyY2g_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMQpzMTIKVGF5bG9yIFN3aWZ0czExCmFydGlzdF9uYW1lcjAK'
2018-11-09 18:43:15,287 (73a59460) :  INFO (Artist:76) - Couldn't fetch HTBackdrops artist.
2018-11-09 18:43:15,368 (73a59460) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/a/taylor_swift'
2018-11-09 18:43:35,382 (73a59460) :  INFO (Artist:116) - No artist result from Discogs cache
2018-11-09 18:43:35,386 (73a59460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Artists/f/8f08504a10fa2f57556f6508943351650e02436.bundle/Contents/com.plexapp.agents.plexmusic/Info.xml
2018-11-09 18:43:35,390 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:43:35,391 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-09 18:43:35,931 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGUxMwpzMTMKMTk4OSAoRGVsdXhlKXM1CmFsYnVtczEKMXM1CmluZGV4czE2CjJiMWJjODljMWNkNGNjMGZzMTcKb3BlblN1YnRpdGxlc0hhc2hzMTkKV2VsY29tZSBUbyBOZXcgWW9ya3M0Cm5hbWVzMTIKVGF5bG9yIFN3aWZ0czYKYXJ0aXN0czQwCmFiY2RkOTZjMzVjOWM1ZjZlMTQ0N2E4OTU3ZDcxNDY0YjMwNmNlMDFzOApwbGV4SGFzaHMxMjMKJTJGdm9sdW1lMSUyRm11c2ljJTJGbXVzaXF1ZSUyRlRheWxvciUyMFN3aWZ0JTJGVGF5bG9yJTIwU3dpZnQlMjAtJTIwMTk4OSUyMChEZWx1eGUpJTJGMDElMjBXZWxjb21lJTIwVG8lMjBOZXclMjBZb3JrJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3MxMwpsb2NhbDovLzQxODUyczEwCnBhcmVudEdVSURzNQo0MTg1MnM4CnBhcmVudElEczYKMjEyNjAwczgKZHVyYXRpb25uczUKdGl0bGVzNQo0MTg1M3MyCmlkcjAK
2018-11-09 18:43:35,934 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 18:43:35,938 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGUxMwpzMTMKMTk4OSAoRGVsdXhlKXM1CmFsYnVtczEKMXM1CmluZGV4czE2CjJiMWJjODljMWNkNGNjMGZzMTcKb3BlblN1YnRpdGxlc0hhc2hzMTkKV2VsY29tZSBUbyBOZXcgWW9ya3M0Cm5hbWVzMTIKVGF5bG9yIFN3aWZ0czYKYXJ0aXN0czQwCmFiY2RkOTZjMzVjOWM1ZjZlMTQ0N2E4OTU3ZDcxNDY0YjMwNmNlMDFzOApwbGV4SGFzaHMxMjMKJTJGdm9sdW1lMSUyRm11c2ljJTJGbXVzaXF1ZSUyRlRheWxvciUyMFN3aWZ0JTJGVGF5bG9yJTIwU3dpZnQlMjAtJTIwMTk4OSUyMChEZWx1eGUpJTJGMDElMjBXZWxjb21lJTIwVG8lMjBOZXclMjBZb3JrJTJFbTRhczgKZmlsZW5hbWVzMQoxczQKZGlzY3MxMwpsb2NhbDovLzQxODUyczEwCnBhcmVudEdVSURzNQo0MTg1MnM4CnBhcmVudElEczYKMjEyNjAwczgKZHVyYXRpb25uczUKdGl0bGVzNQo0MTg1M3MyCmlkcjAK
2018-11-09 18:43:35,940 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'album': '1989 (Deluxe)', 'index': '1', 'openSubtitlesHash': '2b1bc89c1cd4cc0f', 'name': 'Welcome To New York', 'artist': 'Taylor Swift', 'parentID': '41852', 'filename': '%2Fvolume1%2Fmusic%2Fmusique%2FTaylor%20Swift%2FTaylor%20Swift%20-%201989%20(Deluxe)%2F01%20Welcome%20To%20New%20York%2Em4a', 'disc': '1', 'parentGUID': 'local://41852', 'plexHash': 'abcdd96c35c9c5f6e1447a8957d71464b306ce01', 'duration': '212600', 'title': None, 'id': '41853'}
2018-11-09 18:43:35,974 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41853/tree'
2018-11-09 18:43:36,007 (73a59460) :  DEBUG (model:32) - Loading model with GUID local://41852
2018-11-09 18:43:36,009 (73a59460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Artists/f/8f08504a10fa2f57556f6508943351650e02436.bundle/Contents/com.plexapp.agents.plexmusic/Info.xml
2018-11-09 18:43:36,013 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:43:36,048 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:43:36,049 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 18:43:36,789 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjQxODUyczgKcGFyZW50SURzMTMKbG9jYWw6Ly80MTg1M3M0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MTg1M3MyCmlkcjAK
2018-11-09 18:43:36,792 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 18:43:36,795 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjQxODUyczgKcGFyZW50SURzMTMKbG9jYWw6Ly80MTg1M3M0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MTg1M3MyCmlkcjAK
2018-11-09 18:43:36,797 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'id': '41853', 'guid': 'local://41853', 'force': True, 'primary_agent': 'com.plexapp.agents.localmedia', 'parentID': '41852'}
2018-11-09 18:43:36,798 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41853/tree'
2018-11-09 18:43:36,831 (73a59460) :  DEBUG (model:32) - Loading model with GUID local://41853
2018-11-09 18:43:36,833 (73a59460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/1/20bc4350818991d89f11b41e1c6f723d8c39c93.bundle/Contents/com.plexapp.agents.localmedia/Info.xml
2018-11-09 18:43:36,855 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41852/tree'
2018-11-09 18:43:36,891 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:43:36,892 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 384 bytes
2018-11-09 18:43:36,928 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MTg1M3M0CmRiaWRpMApzNwp2ZXJzaW9uczEzCmxvY2FsOi8vNDE4NTJzMTAKcGFyZW50R1VJRHM1CjQxODUyczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczEzCmxvY2FsOi8vNDE4NTNzNApndWlkczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:43:36,931 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 18:43:36,935 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.plexmusic/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MTg1M3M0CmRiaWRpMApzNwp2ZXJzaW9uczEzCmxvY2FsOi8vNDE4NTJzMTAKcGFyZW50R1VJRHM1CjQxODUyczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczEzCmxvY2FsOi8vNDE4NTNzNApndWlkczUKNDE4NTJzMgppZHIwCg__
2018-11-09 18:43:36,937 (73a59460) :  DEBUG (model:32) - Loading model with GUID local://41853
2018-11-09 18:43:36,938 (73a59460) :  ERROR (model:205) - Cannot read model from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/1/20bc4350818991d89f11b41e1c6f723d8c39c93.bundle/Contents/com.plexapp.agents.plexmusic
2018-11-09 18:43:36,939 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/41853/tree'
2018-11-09 18:43:36,972 (73a59460) :  INFO (__init__:369) - Updating album: 1989 (Deluxe)
2018-11-09 18:43:36,973 (73a59460) :  INFO (__init__:370) - With guid: local://41853
2018-11-09 18:43:36,975 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.lastfm/messaging/function/TWVzc2FnZUtpdDpBcnRpc3RTZWFyY2g_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKbGlzdAozCnMyCnhuczQKbGFuZ3IxCnM2CmFsYnVtc3MxMgpUYXlsb3IgU3dpZnRzNgphcnRpc3QxCnMxMwoxOTg5IChEZWx1eGUpcjAK'
2018-11-09 18:43:57,075 (73a59460) :  INFO (__init__:392) - Didn't find any tracks from Gracenote albums, finish by searching for most popular tracks only
2018-11-09 18:43:57,130 (73a59460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/1/20bc4350818991d89f11b41e1c6f723d8c39c93.bundle/Contents/com.plexapp.agents.plexmusic/Info.xml
2018-11-09 18:43:57,134 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 18:43:57,135 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
