2018-11-09 10:21:15,277 (76faf000) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 16:44:57 UTC 2018)
2018-11-09 10:21:15,278 (76faf000) :  DEBUG (core:361) - Using the elevated policy
2018-11-09 10:21:15,279 (76faf000) :  DEBUG (core:450) - Starting runtime component.
2018-11-09 10:21:15,286 (76faf000) :  DEBUG (core:450) - Starting caching component.
2018-11-09 10:21:15,287 (76faf000) :  DEBUG (core:450) - Starting data component.
2018-11-09 10:21:15,288 (76faf000) :  DEBUG (core:450) - Starting networking component.
2018-11-09 10:21:15,306 (76faf000) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-09 10:21:15,333 (76faf000) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-09 10:21:15,339 (76faf000) :  DEBUG (core:450) - Starting localization component.
2018-11-09 10:21:15,340 (76faf000) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-09 10:21:15,341 (76faf000) :  DEBUG (core:450) - Starting messaging component.
2018-11-09 10:21:15,343 (76faf000) :  DEBUG (core:450) - Starting debugging component.
2018-11-09 10:21:15,343 (76faf000) :  DEBUG (core:450) - Starting services component.
2018-11-09 10:21:15,344 (76faf000) :  DEBUG (core:450) - Starting myplex component.
2018-11-09 10:21:15,345 (76faf000) :  DEBUG (core:450) - Starting notifications component.
2018-11-09 10:21:15,413 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.lyricfind'
2018-11-09 10:21:16,172 (76faf000) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.lyricfind in namespace 'metadata'
2018-11-09 10:21:16,224 (76faf000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-09 10:21:16,261 (76faf000) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-09 10:21:16,266 (76faf000) :  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 10:21:16,306 (76faf000) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-09 10:21:16,308 (73a59460) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-09 10:21:16,311 (76faf000) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-09 10:21:16,312 (76faf000) :  DEBUG (core:150) - Finished starting framework core
2018-11-09 10:21:16,313 (76faf000) :  DEBUG (core:560) - Loading plug-in code
2018-11-09 10:21:16,314 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-09 10:21:16,321 (733fd460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-09 10:21:16,375 (733fd460) :  DEBUG (core:538) - Machine identifier is 72b893ab7afe747e17b52d11accfccf67907a6fc
2018-11-09 10:21:16,376 (733fd460) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-09 10:21:16,475 (76faf000) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-09 10:21:16,488 (76faf000) :  DEBUG (agentkit:1115) - Creating new agent class called LyricFindAlbumAgent
2018-11-09 10:21:16,490 (76faf000) :  DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Album'], 'accepts_from': None, 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.plexmusic', 'com.plexapp.agents.localmedia', 'com.plexapp.agents.lastfm'], 'languages': ['xn'], 'persist_stored_files': True, 'version': 0, 'primary_provider': False, 'prefs': False, 'name': 'LyricFind'}]
2018-11-09 10:21:16,493 (76faf000) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjgKY29tLnBsZXhhcHAuYWdlbnRzLnBsZXhtdXNpY3MyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtMQpzMgp4bnIwCg__'
2018-11-09 10:21:16,903 (76faf000) :  INFO (core:611) - Started plug-in
2018-11-09 10:21:16,904 (76faf000) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-09 10:21:16,906 (76faf000) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-09 10:21:16,912 (76faf000) :  INFO (socketinterface:184) - Socket server started on port 51499
2018-11-09 10:21:16,913 (76faf000) :  INFO (pipeinterface:25) - Entering run loop
2018-11-09 10:21:16,914 (76faf000) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-09 10:21:16,951 (76faf000) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-09 10:21:16,958 (76faf000) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 153 bytes
2018-11-09 10:21:17,009 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM1Nwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVGhlJTIwQmx1ZXByaW50P2xhbmc9ZnJzNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjE2czIKaWRyMAo_
2018-11-09 10:21:17,113 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM1Nwpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVGhlJTIwQmx1ZXByaW50P2xhbmc9ZnJzNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjE2czIKaWRyMAo_
2018-11-09 10:21:17,124 (730cf460) :  INFO (agentkit:957) - Searching for matches for {'id': '7216', 'guid': 'com.plexapp.agents.lastfm://Jay-Z/The%20Blueprint?lang=fr', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '7215'}
2018-11-09 10:21:17,127 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7216/tree'
2018-11-09 10:21:17,481 (730cf460) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.lastfm in namespace 'metadata'
2018-11-09 10:21:17,524 (730cf460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/The%20Blueprint?lang=fr
2018-11-09 10:21:17,541 (730cf460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/8/6a3a857f525c3a9944d2939587a3a1babf25215.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-09 10:21:17,679 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7215/tree'
2018-11-09 10:21:17,848 (73a59460) :  DEBUG (services:362) - Loaded services
2018-11-09 10:21:17,868 (736eb460) :  DEBUG (services:438) - No shared code to load
2018-11-09 10:21:17,984 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:21:17,985 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 10:21:18,148 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjE2czQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1RoZSUyMEJsdWVwcmludD9sYW5nPWZyczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:21:18,153 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjE2czQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1RoZSUyMEJsdWVwcmludD9sYW5nPWZyczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:21:18,156 (730cf460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/The%20Blueprint?lang=fr
2018-11-09 10:21:18,219 (730cf460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/8/6a3a857f525c3a9944d2939587a3a1babf25215.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:21:18,300 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7216/tree'
2018-11-09 10:21:18,405 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&album=The%20Blueprint'
2018-11-09 10:21:38,432 (730cf460) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 15 tasks
2018-11-09 10:21:38,445 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=The%20Ruler%27s%20Back'
2018-11-09 10:21:38,454 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Takeover'
2018-11-09 10:21:38,463 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Girls%2C%20Girls%2C%20Girls'
2018-11-09 10:21:38,463 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Izzo%20%28H.O.V.A.%29'
2018-11-09 10:21:58,466 (736eb460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=The%20Ruler%27s%20Back'
2018-11-09 10:21:58,479 (72d37460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Takeover'
2018-11-09 10:21:58,497 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=U%20Don%27t%20Know'
2018-11-09 10:21:58,507 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Jigga%20That%20N%2A%2A%2Aa'
2018-11-09 10:21:58,514 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Heart%20of%20the%20City%20%28Ain%27t%20No%20Love%29'
2018-11-09 10:21:58,519 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Hola%27%20Hovito'
2018-11-09 10:22:18,525 (72d37460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=U%20Don%27t%20Know'
2018-11-09 10:22:18,579 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Song%20Cry'
2018-11-09 10:22:18,583 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Never%20Change'
2018-11-09 10:22:18,584 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=All%20I%20Need'
2018-11-09 10:22:18,598 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Renegade'
2018-11-09 10:22:38,649 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Blueprint%20%28Momma%20Loves%20Me%29'
2018-11-09 10:22:38,721 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Girls%2C%20Girls%2C%20Girls%2C%20Pt.%202'
2018-11-09 10:22:38,730 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Breathe%20Easy%20%28Lyrical%20Exercise%29'
2018-11-09 10:22:58,753 (730cf460) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-09 10:22:58,935 (730cf460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/8/6a3a857f525c3a9944d2939587a3a1babf25215.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:22:59,085 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:22:59,086 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-09 10:23:04,109 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjQwNTM4czgKcGFyZW50SURzMTMKbG9jYWw6Ly80MDUzOXM0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MDUzOXMyCmlkcjAK
2018-11-09 10:23:04,113 (730cf460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:23:04,117 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCnhuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjQwNTM4czgKcGFyZW50SURzMTMKbG9jYWw6Ly80MDUzOXM0Cmd1aWRiMXM1CmZvcmNlczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhczEzCnByaW1hcnlfYWdlbnRzNQo0MDUzOXMyCmlkcjAK
2018-11-09 10:23:04,118 (730cf460) :  INFO (agentkit:957) - Searching for matches for {'id': '40539', 'guid': 'local://40539', 'force': True, 'primary_agent': 'com.plexapp.agents.localmedia', 'parentID': '40538'}
2018-11-09 10:23:04,190 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/40539/tree'
2018-11-09 10:23:04,378 (730cf460) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.localmedia in namespace 'metadata'
2018-11-09 10:23:04,400 (730cf460) :  DEBUG (model:32) - Loading model with GUID local://40539
2018-11-09 10:23:04,401 (730cf460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/7/99ba096379156f3572e6734709c7d611b698c57.bundle/Contents/com.plexapp.agents.localmedia/Info.xml
2018-11-09 10:23:04,482 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/40538/tree'
2018-11-09 10:23:04,523 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:23:04,524 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 10:23:04,574 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MDUzOXM0CmRiaWRpMApzNwp2ZXJzaW9uczEzCmxvY2FsOi8vNDA1MzhzMTAKcGFyZW50R1VJRHM1CjQwNTM4czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczEzCmxvY2FsOi8vNDA1MzlzNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-09 10:23:04,576 (730cf460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:23:04,580 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKeG5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MDUzOXM0CmRiaWRpMApzNwp2ZXJzaW9uczEzCmxvY2FsOi8vNDA1MzhzMTAKcGFyZW50R1VJRHM1CjQwNTM4czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczEzCmxvY2FsOi8vNDA1MzlzNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-09 10:23:04,582 (730cf460) :  DEBUG (model:32) - Loading model with GUID local://40539
2018-11-09 10:23:04,584 (730cf460) :  ERROR (model:205) - Cannot read model from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/7/99ba096379156f3572e6734709c7d611b698c57.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-09 10:23:04,585 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/40539/tree'
2018-11-09 10:23:04,647 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&album=It%27s%20About%20Time'
2018-11-09 10:23:24,684 (730cf460) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 15 tasks
2018-11-09 10:23:24,691 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Anything'
2018-11-09 10:23:24,696 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=I%27m%20So%20Into%20You'
2018-11-09 10:23:24,704 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Right%20Here'
2018-11-09 10:23:24,761 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Weak'
2018-11-09 10:23:44,732 (736eb460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Right%20Here'
2018-11-09 10:23:44,786 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=You%27re%20Always%20on%20My%20Mind'
2018-11-09 10:23:44,794 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Coming%20Home'
2018-11-09 10:23:44,800 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Downtown'
2018-11-09 10:23:44,805 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Give%20It%20to%20Me'
2018-11-09 10:24:04,820 (736eb460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Coming%20Home'
2018-11-09 10:24:04,847 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Blak%20Pudd%27n'
2018-11-09 10:24:04,866 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=It%27s%20About%20Time'
2018-11-09 10:24:04,895 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=That%27s%20What%20I%20Need'
2018-11-09 10:24:04,919 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Think%20You%27re%20Gonna%20Like%20It'
2018-11-09 10:24:24,870 (73a59460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Blak%20Pudd%27n'
2018-11-09 10:24:24,874 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=SWV%20%28In%20the%20House%29'
2018-11-09 10:24:24,898 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Weak%20%28A%20Cappella%29'
2018-11-09 10:24:24,964 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=Right%20Here%20%5BVibe%20Mix%5D'
2018-11-09 10:24:44,900 (73a59460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=SWV&track=SWV%20%28In%20the%20House%29'
2018-11-09 10:24:44,987 (730cf460) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-09 10:24:45,056 (730cf460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/7/99ba096379156f3572e6734709c7d611b698c57.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:24:45,061 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:24:45,062 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-09 10:24:48,268 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM1NQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVGhlJTIwRHluYXN0eT9sYW5nPWZyczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNDAxNzNzMgppZHIwCg__
2018-11-09 10:24:48,271 (730cf460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:24:48,274 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM1NQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVGhlJTIwRHluYXN0eT9sYW5nPWZyczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNDAxNzNzMgppZHIwCg__
2018-11-09 10:24:48,276 (730cf460) :  INFO (agentkit:957) - Searching for matches for {'id': '40173', 'guid': 'com.plexapp.agents.lastfm://Jay-Z/The%20Dynasty?lang=fr', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '7215'}
2018-11-09 10:24:48,277 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/40173/tree'
2018-11-09 10:24:48,331 (730cf460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/The%20Dynasty?lang=fr
2018-11-09 10:24:48,333 (730cf460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/f/6db85b27b8efc1ce539ce6404f4b0fd9164db86.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-09 10:24:48,383 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7215/tree'
2018-11-09 10:24:48,454 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:24:48,455 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 10:24:48,476 (730cf460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MDE3M3M0CmRiaWRpMApzNwp2ZXJzaW9uczQxCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9KYXktWj9sYW5nPWZyczEwCnBhcmVudEdVSURzNAo3MjE1czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9KYXktWi9UaGUlMjBEeW5hc3R5P2xhbmc9ZnJzNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-09 10:24:48,478 (730cf460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:24:48,482 (730cf460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0MDE3M3M0CmRiaWRpMApzNwp2ZXJzaW9uczQxCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9KYXktWj9sYW5nPWZyczEwCnBhcmVudEdVSURzNAo3MjE1czgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9KYXktWi9UaGUlMjBEeW5hc3R5P2xhbmc9ZnJzNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-09 10:24:48,484 (730cf460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/The%20Dynasty?lang=fr
2018-11-09 10:24:48,485 (730cf460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/f/6db85b27b8efc1ce539ce6404f4b0fd9164db86.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:24:48,535 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/40173/tree'
2018-11-09 10:24:48,598 (730cf460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&album=The%20Dynasty%20Roc%3A%20La%20Familia'
2018-11-09 10:24:58,723 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM2MApjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovUmVhc29uYWJsZSUyMERvdWJ0P2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjMyczIKaWRyMAo_
2018-11-09 10:24:58,726 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:24:58,729 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM2MApjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovUmVhc29uYWJsZSUyMERvdWJ0P2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjMyczIKaWRyMAo_
2018-11-09 10:24:58,731 (73a59460) :  INFO (agentkit:957) - Searching for matches for {'id': '7232', 'guid': 'com.plexapp.agents.lastfm://Jay-Z/Reasonable%20Doubt?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '7215'}
2018-11-09 10:24:58,752 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7232/tree'
2018-11-09 10:24:58,793 (73a59460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/Reasonable%20Doubt?lang=en
2018-11-09 10:24:58,795 (73a59460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/b/ca37ff145962b626c050ffe7e4b2cb53cf07b26.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-09 10:24:58,798 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7215/tree'
2018-11-09 10:24:58,868 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:24:58,869 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 10:24:58,918 (73a59460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjMyczQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNjAKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1JlYXNvbmFibGUlMjBEb3VidD9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:24:58,921 (73a59460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:24:58,925 (73a59460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjMyczQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNjAKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1JlYXNvbmFibGUlMjBEb3VidD9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:24:58,927 (73a59460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/Reasonable%20Doubt?lang=en
2018-11-09 10:24:58,928 (73a59460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/b/ca37ff145962b626c050ffe7e4b2cb53cf07b26.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:24:58,980 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7232/tree'
2018-11-09 10:24:59,030 (73a59460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&album=Reasonable%20Doubt'
2018-11-09 10:25:08,625 (730cf460) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 16 tasks
2018-11-09 10:25:08,641 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Change%20the%20Game'
2018-11-09 10:25:08,648 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Intro'
2018-11-09 10:25:08,653 (7275b460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Streets%20Is%20Talking'
2018-11-09 10:25:08,667 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=I%20Just%20Wanna%20Love%20U%20%28Give%20It%202%20Me%29'
2018-11-09 10:25:15,269 (7242d460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM3Mgpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVm9sLiUyMDIlMjBIYXJkJTIwS25vY2slMjBMaWZlP2xhbmc9ZnJzNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjQ4czIKaWRyMAo_
2018-11-09 10:25:15,272 (7242d460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:25:15,275 (7242d460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmZyczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM0CjcyMTVzOApwYXJlbnRJRHM3Mgpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vSmF5LVovVm9sLiUyMDIlMjBIYXJkJTIwS25vY2slMjBMaWZlP2xhbmc9ZnJzNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNAo3MjQ4czIKaWRyMAo_
2018-11-09 10:25:15,277 (7242d460) :  INFO (agentkit:957) - Searching for matches for {'id': '7248', 'guid': 'com.plexapp.agents.lastfm://Jay-Z/Vol.%202%20Hard%20Knock%20Life?lang=fr', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '7215'}
2018-11-09 10:25:15,300 (7242d460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7248/tree'
2018-11-09 10:25:15,321 (7242d460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/Vol.%202%20Hard%20Knock%20Life?lang=fr
2018-11-09 10:25:15,322 (7242d460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/0/539272a6c6818fc768d2a8edeeebdd9deab359f.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-09 10:25:15,328 (7242d460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7215/tree'
2018-11-09 10:25:15,421 (7242d460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:25:15,422 (7242d460) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-09 10:25:15,466 (7242d460) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjQ4czQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNzIKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1ZvbC4lMjAyJTIwSGFyZCUyMEtub2NrJTIwTGlmZT9sYW5nPWZyczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:25:15,469 (7242d460) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-09 10:25:15,473 (7242d460) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNAo3MjQ4czQKZGJpZGkwCnM3CnZlcnNpb25zNDEKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aP2xhbmc9ZnJzMTAKcGFyZW50R1VJRHM0CjcyMTVzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNzIKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0pheS1aL1ZvbC4lMjAyJTIwSGFyZCUyMEtub2NrJTIwTGlmZT9sYW5nPWZyczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-09 10:25:15,475 (7242d460) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Jay-Z/Vol.%202%20Hard%20Knock%20Life?lang=fr
2018-11-09 10:25:15,476 (7242d460) :  DEBUG (model:234) - Deserializing from /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/0/539272a6c6818fc768d2a8edeeebdd9deab359f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:25:15,507 (7242d460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/7248/tree'
2018-11-09 10:25:15,528 (7242d460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&album=Vol.%202%20Hard%20Knock%20Life'
2018-11-09 10:25:19,054 (73a59460) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 15 tasks
2018-11-09 10:25:19,060 (71e51460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Can%27t%20Knock%20the%20Hustle'
2018-11-09 10:25:19,063 (7213f460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Politics%20as%20Usual'
2018-11-09 10:25:19,066 (71875460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Dead%20Presidents%20II'
2018-11-09 10:25:19,068 (71b63460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Brooklyn%27s%20Finest'
2018-11-09 10:25:28,670 (736eb460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Intro'
2018-11-09 10:25:28,673 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Get%20Your%20Mind%20Right%20Mami'
2018-11-09 10:25:28,678 (7275b460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Streets%20Is%20Talking'
2018-11-09 10:25:28,681 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=This%20Can%27t%20Be%20Life'
2018-11-09 10:25:28,702 (7275b460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Stick%202%20the%20Script'
2018-11-09 10:25:28,732 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=You%2C%20Me%2C%20Him%20and%20Her'
2018-11-09 10:25:35,551 (7242d460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&album=Vol.%202%20Hard%20Knock%20Life'
2018-11-09 10:25:35,554 (7242d460) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 1 tasks
2018-11-09 10:25:35,577 (71587460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Hard%20Knock%20Life%20%28Ghetto%20Anthem%29'
2018-11-09 10:25:39,097 (71e51460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Can%27t%20Knock%20the%20Hustle'
2018-11-09 10:25:39,099 (71b63460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=D%27evils'
2018-11-09 10:25:39,102 (7213f460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Feelin%27%20It'
2018-11-09 10:25:39,113 (71875460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=22%20Two%27s'
2018-11-09 10:25:39,121 (71e51460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Can%20I%20Live'
2018-11-09 10:25:48,697 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Guilty%20Until%20Proven%20Innocent'
2018-11-09 10:25:48,703 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Parking%20Lot%20Pimpin%27'
2018-11-09 10:25:48,731 (7275b460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Holla'
2018-11-09 10:25:48,753 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=1-900-Hustler'
2018-11-09 10:25:55,598 (7242d460) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-09 10:25:55,606 (7242d460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/0/539272a6c6818fc768d2a8edeeebdd9deab359f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:25:55,627 (7242d460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:25:55,628 (7242d460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-09 10:25:59,126 (7213f460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Feelin%27%20It'
2018-11-09 10:25:59,128 (71b63460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Ain%27t%20No%20Nigga'
2018-11-09 10:25:59,144 (71e51460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Cashmere%20Thoughts'
2018-11-09 10:25:59,151 (71875460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Friend%20or%20Foe'
2018-11-09 10:25:59,156 (7213f460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Coming%20of%20Age'
2018-11-09 10:26:08,755 (72d37460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Parking%20Lot%20Pimpin%27'
2018-11-09 10:26:08,759 (7275b460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Holla'
2018-11-09 10:26:08,760 (736eb460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=The%20R.O.C.'
2018-11-09 10:26:08,783 (72a49460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=1-900-Hustler'
2018-11-09 10:26:08,786 (72d37460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Soon%20You%27ll%20Understand'
2018-11-09 10:26:08,808 (72a49460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Where%20Have%20You%20Been'
2018-11-09 10:26:08,823 (7275b460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Squeeze%201st'
2018-11-09 10:26:19,155 (71b63460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Ain%27t%20No%20Nigga'
2018-11-09 10:26:19,162 (71b63460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Bring%20It%20On'
2018-11-09 10:26:19,185 (71e51460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Regrets'
2018-11-09 10:26:19,189 (71875460) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Can%27t%20Knock%20the%20Hustle%20%5BFool%27s%20Paradise%20Remix%5D'
2018-11-09 10:26:28,844 (7275b460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Squeeze%201st'
2018-11-09 10:26:28,847 (730cf460) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-09 10:26:28,904 (730cf460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/f/6db85b27b8efc1ce539ce6404f4b0fd9164db86.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:26:28,924 (730cf460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:26:28,925 (730cf460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-09 10:26:39,269 (71e51460) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Jay-Z&track=Regrets'
2018-11-09 10:26:39,272 (73a59460) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-09 10:26:39,382 (73a59460) :  DEBUG (model:229) - Serializing to /volume1/Plex/Library/Application Support/Plex Media Server/Metadata/Albums/b/ca37ff145962b626c050ffe7e4b2cb53cf07b26.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-09 10:26:39,387 (73a59460) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-09 10:26:39,388 (73a59460) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
