2018-11-18 21:00:39,030 (7f821522c700) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 17:17:43 UTC 2018)
2018-11-18 21:00:39,031 (7f821522c700) :  DEBUG (core:361) - Using the elevated policy
2018-11-18 21:00:39,033 (7f821522c700) :  DEBUG (core:450) - Starting runtime component.
2018-11-18 21:00:39,053 (7f821522c700) :  DEBUG (core:450) - Starting caching component.
2018-11-18 21:00:39,054 (7f821522c700) :  DEBUG (core:450) - Starting data component.
2018-11-18 21:00:39,056 (7f821522c700) :  DEBUG (core:450) - Starting networking component.
2018-11-18 21:00:39,091 (7f821522c700) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-18 21:00:39,169 (7f821522c700) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-18 21:00:39,178 (7f821522c700) :  DEBUG (core:450) - Starting localization component.
2018-11-18 21:00:39,181 (7f821522c700) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-18 21:00:39,182 (7f821522c700) :  DEBUG (core:450) - Starting messaging component.
2018-11-18 21:00:39,184 (7f821522c700) :  DEBUG (core:450) - Starting debugging component.
2018-11-18 21:00:39,186 (7f821522c700) :  DEBUG (core:450) - Starting services component.
2018-11-18 21:00:39,187 (7f821522c700) :  DEBUG (core:450) - Starting myplex component.
2018-11-18 21:00:39,188 (7f821522c700) :  DEBUG (core:450) - Starting notifications component.
2018-11-18 21:00:39,230 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.lyricfind'
2018-11-18 21:00:40,487 (7f821522c700) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.lyricfind in namespace 'metadata'
2018-11-18 21:00:40,570 (7f821522c700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-18 21:00:40,707 (7f81efd11710) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-18 21:00:40,707 (7f821522c700) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-18 21:00:40,712 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-18 21:00:40,716 (7f821522c700) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-18 21:00:40,717 (7f821522c700) :  DEBUG (core:150) - Finished starting framework core
2018-11-18 21:00:40,721 (7f821522c700) :  DEBUG (core:560) - Loading plug-in code
2018-11-18 21:00:40,771 (7f81ef6b5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-18 21:00:40,809 (7f81ef6b5710) :  DEBUG (core:538) - Machine identifier is 529f39113a7abe3875fddeb73975c5a77e6746d5
2018-11-18 21:00:40,810 (7f81ef6b5710) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-18 21:00:41,110 (7f821522c700) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-18 21:00:41,113 (7f821522c700) :  DEBUG (agentkit:1115) - Creating new agent class called LyricFindAlbumAgent
2018-11-18 21:00:41,119 (7f821522c700) :  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-18 21:00:41,131 (7f821522c700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjgKY29tLnBsZXhhcHAuYWdlbnRzLnBsZXhtdXNpY3MyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtMQpzMgp4bnIwCg__'
2018-11-18 21:00:41,210 (7f821522c700) :  INFO (core:611) - Started plug-in
2018-11-18 21:00:41,211 (7f821522c700) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-18 21:00:41,238 (7f821522c700) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-18 21:00:41,239 (7f821522c700) :  INFO (socketinterface:184) - Socket server started on port 33592
2018-11-18 21:00:41,241 (7f821522c700) :  INFO (pipeinterface:25) - Entering run loop
2018-11-18 21:00:41,243 (7f821522c700) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-18 21:00:41,272 (7f821522c700) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-18 21:00:41,281 (7f821522c700) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 153 bytes
2018-11-18 21:00:41,328 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvU2hvd3Jvb20lMjBvZiUyMENvbXBhc3Npb24%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTA3czIKaWRyMAo_
2018-11-18 21:00:41,333 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:41,586 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvU2hvd3Jvb20lMjBvZiUyMENvbXBhc3Npb24@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTA3czIKaWRyMAo_
2018-11-18 21:00:41,622 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54507', 'guid': 'com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:00:41,719 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54507/tree'
2018-11-18 21:00:41,804 (7f81ef387710) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.lastfm in namespace 'metadata'
2018-11-18 21:00:41,883 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en
2018-11-18 21:00:41,945 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:00:42,120 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:00:42,138 (7f81efd11710) :  DEBUG (services:362) - Loaded services
2018-11-18 21:00:42,182 (7f81ef9a3710) :  DEBUG (services:438) - No shared code to load
2018-11-18 21:00:42,378 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:42,380 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:00:42,481 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUwN3M0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Nob3dyb29tJTIwb2YlMjBDb21wYXNzaW9uP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:00:42,487 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:42,492 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUwN3M0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Nob3dyb29tJTIwb2YlMjBDb21wYXNzaW9uP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:00:42,495 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en
2018-11-18 21:00:42,498 (7f81ef387710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-18 21:00:42,502 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54507/tree'
2018-11-18 21:00:42,626 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Showroom%20of%20Compassion'
2018-11-18 21:00:42,935 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:00:42,971 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric What's Now Is Now to track What's Now Is Now
2018-11-18 21:00:43,000 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mustache%20Man%20%28Wasted%29'
2018-11-18 21:00:43,016 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Long%20Time'
2018-11-18 21:00:43,024 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Federal%20Funding'
2018-11-18 21:00:43,035 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Got%20to%20Move'
2018-11-18 21:00:43,316 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Teenage%20Pregnancy'
2018-11-18 21:00:43,412 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Sick%20of%20You'
2018-11-18 21:00:43,450 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Easy%20to%20Crash'
2018-11-18 21:00:43,614 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Bound%20Away'
2018-11-18 21:00:43,703 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=The%20Winter'
2018-11-18 21:00:43,722 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Italian Guy to track Italian Guy
2018-11-18 21:00:43,953 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:00:44,077 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:00:44,084 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:44,086 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:00:51,199 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQi1TaWRlcyUyMGFuZCUyMFJhcml0aWVzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDUxOXMyCmlkcjAK
2018-11-18 21:00:51,204 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:51,209 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQi1TaWRlcyUyMGFuZCUyMFJhcml0aWVzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDUxOXMyCmlkcjAK
2018-11-18 21:00:51,212 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54519', 'guid': 'com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:00:51,313 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54519/tree'
2018-11-18 21:00:51,349 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en
2018-11-18 21:00:51,351 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:00:51,391 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:00:51,534 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:51,536 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:00:51,565 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUxOXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0ItU2lkZXMlMjBhbmQlMjBSYXJpdGllcz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:00:51,570 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:51,576 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUxOXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0ItU2lkZXMlMjBhbmQlMjBSYXJpdGllcz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:00:51,579 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en
2018-11-18 21:00:51,582 (7f81ef387710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-18 21:00:51,584 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54519/tree'
2018-11-18 21:00:51,623 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=B-Sides%20and%20Rarities'
2018-11-18 21:00:51,886 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 12 tasks
2018-11-18 21:00:51,890 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric War Pigs to track War Pigs
2018-11-18 21:00:51,965 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Excuse Me, I Think I've Got a Heartache to track Excuse Me, I Think I've Got a Heartache
2018-11-18 21:00:51,969 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mahna%20Mahna'
2018-11-18 21:00:51,986 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Strangers in the Night to track Strangers in the Night
2018-11-18 21:00:51,992 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Ruby%2C%20Don%27t%20Take%20Your%20Love%20to%20Town'
2018-11-18 21:00:52,050 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Subtract One Love (Multiply the Heartaches) to track Subtract One Love (Multiply the Heartaches)
2018-11-18 21:00:52,052 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Conroy'
2018-11-18 21:00:52,109 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Never, Never Gonna Give You Up to track Never Never Gonna Give You Up
2018-11-18 21:00:52,185 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Thrills'
2018-11-18 21:00:52,531 (7f81efd11710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Short%20Skirt%2C%20Long%20Jacket%20%5BLive%5D'
2018-11-18 21:00:52,557 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=It%27s%20Coming%20Down%20%5BLive%5D'
2018-11-18 21:00:52,995 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=%5BUntitled%5D'
2018-11-18 21:00:53,024 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Ruby Don't Take Your Love to Town to track Ruby, Don't Take Your Love to Town
2018-11-18 21:00:53,630 (7f81eea3d710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJlc3N1cmUlMjBDaGllZj9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNTQ1MzJzMgppZHIwCg__
2018-11-18 21:00:53,635 (7f81eea3d710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:53,641 (7f81eea3d710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJlc3N1cmUlMjBDaGllZj9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNTQ1MzJzMgppZHIwCg__
2018-11-18 21:00:53,643 (7f81eea3d710) :  INFO (agentkit:957) - Searching for matches for {'id': '54532', 'guid': 'com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:00:53,646 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54532/tree'
2018-11-18 21:00:53,685 (7f81eea3d710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en
2018-11-18 21:00:53,687 (7f81eea3d710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:00:53,719 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:00:53,741 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:00:53,836 (7f81eea3d710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:53,837 (7f81eea3d710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:00:53,908 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:00:53,918 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:53,920 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:00:53,925 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUzMnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1ByZXNzdXJlJTIwQ2hpZWY%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:00:53,929 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:00:53,935 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUzMnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1ByZXNzdXJlJTIwQ2hpZWY@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:00:53,939 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en
2018-11-18 21:00:53,942 (7f81ef387710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-18 21:00:53,945 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54532/tree'
2018-11-18 21:00:53,996 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Pressure%20Chief'
2018-11-18 21:00:54,329 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:00:54,360 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Wheels'
2018-11-18 21:00:54,361 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Take%20It%20All%20Away'
2018-11-18 21:00:54,378 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Dime'
2018-11-18 21:00:54,452 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=No%20Phone'
2018-11-18 21:00:54,603 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Carbon%20Monoxide'
2018-11-18 21:00:54,667 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Waiting'
2018-11-18 21:00:54,683 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=The%20Guitar%20Man'
2018-11-18 21:00:54,727 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=She%27ll%20Hang%20the%20Baskets'
2018-11-18 21:00:54,926 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=End%20of%20the%20Movie'
2018-11-18 21:00:54,962 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Palm%20of%20Your%20Hand'
2018-11-18 21:00:54,982 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Tougher%20Than%20It%20Is'
2018-11-18 21:00:55,226 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:00:55,290 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:00:55,298 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:00:55,300 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:01:03,184 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTYKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQ29tZm9ydCUyMEVhZ2xlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU0NHMyCmlkcjAK
2018-11-18 21:01:03,190 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:03,197 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTYKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQ29tZm9ydCUyMEVhZ2xlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU0NHMyCmlkcjAK
2018-11-18 21:01:03,201 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54544', 'guid': 'com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:01:03,216 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54544/tree'
2018-11-18 21:01:03,264 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en
2018-11-18 21:01:03,266 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:01:03,305 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:01:03,411 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:03,413 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:01:03,463 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU0NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU2CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0NvbWZvcnQlMjBFYWdsZT9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:03,466 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:03,471 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU0NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU2CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0NvbWZvcnQlMjBFYWdsZT9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:03,474 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en
2018-11-18 21:01:03,476 (7f81ef387710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-18 21:01:03,478 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54544/tree'
2018-11-18 21:01:03,529 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Comfort%20Eagle'
2018-11-18 21:01:03,742 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:01:03,772 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Short Skirt/Long Jacket to track Short Skirt/Long Jacket
2018-11-18 21:01:03,791 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Opera%20Singer'
2018-11-18 21:01:03,806 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Meanwhile%2C%20Rick%20James...'
2018-11-18 21:01:03,817 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Commissioning%20a%20Symphony%20in%20C'
2018-11-18 21:01:03,823 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Shadow%20Stabbing'
2018-11-18 21:01:04,074 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Comfort%20Eagle'
2018-11-18 21:01:04,106 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Arco%20Arena'
2018-11-18 21:01:04,127 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Love You Madly to track Love You Madly
2018-11-18 21:01:04,145 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Pretty%20Pink%20Ribbon'
2018-11-18 21:01:04,202 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Long%20Line%20of%20Cars'
2018-11-18 21:01:04,363 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=World%20of%20Two'
2018-11-18 21:01:04,567 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:01:04,665 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:04,670 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:04,671 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:01:12,902 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJvbG9uZ2luZyUyMHRoZSUyME1hZ2ljP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo0ODM5NnMyCmlkcjAK
2018-11-18 21:01:12,906 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:12,911 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJvbG9uZ2luZyUyMHRoZSUyME1hZ2ljP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo0ODM5NnMyCmlkcjAK
2018-11-18 21:01:12,913 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '48396', 'guid': 'com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:01:12,947 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/48396/tree'
2018-11-18 21:01:13,023 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en
2018-11-18 21:01:13,026 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:01:13,097 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:01:13,307 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:13,310 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:01:13,369 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0ODM5NnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Byb2xvbmdpbmclMjB0aGUlMjBNYWdpYz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:13,375 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:13,384 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0ODM5NnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Byb2xvbmdpbmclMjB0aGUlMjBNYWdpYz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:13,388 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en
2018-11-18 21:01:13,392 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:13,456 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/48396/tree'
2018-11-18 21:01:13,528 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Prolonging%20the%20Magic'
2018-11-18 21:01:13,760 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 13 tasks
2018-11-18 21:01:13,777 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Never There to track Never There
2018-11-18 21:01:13,786 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Guitar'
2018-11-18 21:01:13,803 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Satan%20Is%20My%20Motor'
2018-11-18 21:01:13,805 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mexico'
2018-11-18 21:01:13,820 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=You%20Turn%20the%20Screws'
2018-11-18 21:01:14,119 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Sheep%20Go%20to%20Heaven'
2018-11-18 21:01:14,130 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Walk%20on%20By'
2018-11-18 21:01:14,188 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=When%20You%20Sleep'
2018-11-18 21:01:14,204 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Hem%20of%20Your%20Garment'
2018-11-18 21:01:14,471 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Let%20Me%20Go'
2018-11-18 21:01:14,483 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Alpha%20Beta%20Parking%20Lot'
2018-11-18 21:01:14,497 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Cool%20Blue%20Reason'
2018-11-18 21:01:14,516 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Where%20Would%20I%20Be%3F'
2018-11-18 21:01:14,851 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:01:15,087 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:15,096 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:15,098 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:01:25,359 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTYxczIKaWRyMAo_
2018-11-18 21:01:25,364 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:25,369 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTYxczIKaWRyMAo_
2018-11-18 21:01:25,371 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54561', 'guid': 'com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:01:25,373 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54561/tree'
2018-11-18 21:01:25,427 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:01:25,430 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:01:25,476 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:01:25,688 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:25,689 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:01:25,765 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU2MXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:01:25,769 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:25,774 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU2MXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:01:25,777 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:01:25,779 (7f81ef387710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-18 21:01:25,781 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54561/tree'
2018-11-18 21:01:25,876 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Motorcade%20of%20Generosity%20%5BEnhanced%5D'
2018-11-18 21:01:26,652 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 13 tasks
2018-11-18 21:01:26,657 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Comanche to track Comanche
2018-11-18 21:01:26,662 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Ruby Sees All to track Ruby Sees All
2018-11-18 21:01:26,672 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Up So Close to track Up So Close
2018-11-18 21:01:26,681 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Pentagram to track Pentagram
2018-11-18 21:01:26,691 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Jolene to track Jolene
2018-11-18 21:01:26,725 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Haze of Love to track Haze of Love
2018-11-18 21:01:26,781 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Rock'n'Roll Lifestyle to track Rock'n'roll Lifestyle
2018-11-18 21:01:26,828 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Is%20This%20Love%3F'
2018-11-18 21:01:26,831 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=You%20Part%20the%20Waters'
2018-11-18 21:01:26,844 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric I Bombed Korea to track I Bombed Korea
2018-11-18 21:01:26,850 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Jesus Wrote a Blank Check to track Jesus Wrote a Blank Check
2018-11-18 21:01:26,901 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Ain't No Good to track Ain't No Good
2018-11-18 21:01:26,931 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mr.%20Mastodon%20Farm'
2018-11-18 21:01:27,237 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:01:27,372 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:27,379 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:27,380 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:01:50,454 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTYKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQ29tZm9ydCUyMEVhZ2xlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU0NHMyCmlkcjAK
2018-11-18 21:01:50,458 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:50,463 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTYKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQ29tZm9ydCUyMEVhZ2xlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU0NHMyCmlkcjAK
2018-11-18 21:01:50,466 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54544', 'guid': 'com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:01:50,469 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54544/tree'
2018-11-18 21:01:50,507 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en
2018-11-18 21:01:50,509 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:01:50,536 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:01:50,682 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:50,684 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:01:50,710 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU0NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU2CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0NvbWZvcnQlMjBFYWdsZT9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:50,714 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:01:50,719 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU0NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU2CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0NvbWZvcnQlMjBFYWdsZT9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:01:50,722 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Comfort%20Eagle?lang=en
2018-11-18 21:01:50,724 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:50,751 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54544/tree'
2018-11-18 21:01:50,788 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Comfort%20Eagle'
2018-11-18 21:01:51,057 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:01:51,094 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Short Skirt/Long Jacket to track Short Skirt/Long Jacket
2018-11-18 21:01:51,095 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Opera%20Singer'
2018-11-18 21:01:51,096 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Shadow%20Stabbing'
2018-11-18 21:01:51,097 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Meanwhile%2C%20Rick%20James...'
2018-11-18 21:01:51,114 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Commissioning%20a%20Symphony%20in%20C'
2018-11-18 21:01:51,391 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Arco%20Arena'
2018-11-18 21:01:51,415 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Comfort%20Eagle'
2018-11-18 21:01:51,449 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Long%20Line%20of%20Cars'
2018-11-18 21:01:51,478 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Love You Madly to track Love You Madly
2018-11-18 21:01:51,490 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Pretty%20Pink%20Ribbon'
2018-11-18 21:01:51,743 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=World%20of%20Two'
2018-11-18 21:01:51,945 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:01:51,995 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/bf5cd0c83f901987ed7140b4cd58afd9159dd1f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:01:52,000 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:01:52,001 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:02,875 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQi1TaWRlcyUyMGFuZCUyMFJhcml0aWVzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDUxOXMyCmlkcjAK
2018-11-18 21:02:02,880 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:02,885 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvQi1TaWRlcyUyMGFuZCUyMFJhcml0aWVzP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDUxOXMyCmlkcjAK
2018-11-18 21:02:02,888 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54519', 'guid': 'com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:02,894 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54519/tree'
2018-11-18 21:02:02,939 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en
2018-11-18 21:02:02,941 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:02,981 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:03,142 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:03,144 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:03,179 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUxOXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0ItU2lkZXMlMjBhbmQlMjBSYXJpdGllcz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:02:03,184 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:03,192 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUxOXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0ItU2lkZXMlMjBhbmQlMjBSYXJpdGllcz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:02:03,196 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/B-Sides%20and%20Rarities?lang=en
2018-11-18 21:02:03,198 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:03,279 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54519/tree'
2018-11-18 21:02:03,322 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=B-Sides%20and%20Rarities'
2018-11-18 21:02:03,606 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 12 tasks
2018-11-18 21:02:03,612 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric War Pigs to track War Pigs
2018-11-18 21:02:03,684 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Excuse Me, I Think I've Got a Heartache to track Excuse Me, I Think I've Got a Heartache
2018-11-18 21:02:03,702 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mahna%20Mahna'
2018-11-18 21:02:03,705 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Strangers in the Night to track Strangers in the Night
2018-11-18 21:02:03,709 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Ruby%2C%20Don%27t%20Take%20Your%20Love%20to%20Town'
2018-11-18 21:02:03,712 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Conroy'
2018-11-18 21:02:03,747 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Subtract One Love (Multiply the Heartaches) to track Subtract One Love (Multiply the Heartaches)
2018-11-18 21:02:03,779 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Never, Never Gonna Give You Up to track Never Never Gonna Give You Up
2018-11-18 21:02:03,796 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Thrills'
2018-11-18 21:02:04,026 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Ruby Don't Take Your Love to Town to track Ruby, Don't Take Your Love to Town
2018-11-18 21:02:04,105 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Short%20Skirt%2C%20Long%20Jacket%20%5BLive%5D'
2018-11-18 21:02:04,106 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=%5BUntitled%5D'
2018-11-18 21:02:04,112 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=It%27s%20Coming%20Down%20%5BLive%5D'
2018-11-18 21:02:04,524 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:04,622 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/76d6fdddbcfec096779f99102b640327e7ba9c2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:04,628 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:04,630 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:09,579 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvRmFzaGlvbiUyME51Z2dldD9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKMTMyNDJzMgppZHIwCg__
2018-11-18 21:02:09,582 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:09,587 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvRmFzaGlvbiUyME51Z2dldD9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKMTMyNDJzMgppZHIwCg__
2018-11-18 21:02:09,589 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '13242', 'guid': 'com.plexapp.agents.lastfm://Cake/Fashion%20Nugget?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:09,674 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13242/tree'
2018-11-18 21:02:09,736 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Fashion%20Nugget?lang=en
2018-11-18 21:02:09,739 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/6/8b3c56d89c8aa4291174ff0143ef9d9ef001f16.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:09,817 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:10,029 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:10,031 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:10,089 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMzI0MnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0Zhc2hpb24lMjBOdWdnZXQ%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:02:10,093 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:10,099 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMzI0MnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL0Zhc2hpb24lMjBOdWdnZXQ@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:02:10,103 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Fashion%20Nugget?lang=en
2018-11-18 21:02:10,105 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/6/8b3c56d89c8aa4291174ff0143ef9d9ef001f16.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:10,220 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13242/tree'
2018-11-18 21:02:10,355 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Fashion%20Nugget'
2018-11-18 21:02:10,606 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 14 tasks
2018-11-18 21:02:10,610 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric The Distance to track The Distance
2018-11-18 21:02:10,671 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Friend%20Is%20a%20Four%20Letter%20Word'
2018-11-18 21:02:10,683 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Frank%20Sinatra'
2018-11-18 21:02:10,698 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Open%20Book'
2018-11-18 21:02:10,745 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Daria'
2018-11-18 21:02:11,069 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric I Will Survive to track I Will Survive
2018-11-18 21:02:11,104 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Perhaps, Perhaps, Perhaps to track Perhaps, Perhaps, Perhaps
2018-11-18 21:02:11,138 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Stickshifts%20and%20Safetybelts'
2018-11-18 21:02:11,142 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Race%20Car%20Ya-Yas'
2018-11-18 21:02:11,143 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=It%27s%20Coming%20Down'
2018-11-18 21:02:11,154 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Nugget'
2018-11-18 21:02:11,417 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=She%27ll%20Come%20Back%20to%20Me'
2018-11-18 21:02:11,453 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Italian%20Leather%20Sofa'
2018-11-18 21:02:11,490 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Sad Songs and Waltzes to track Sad Songs and Waltzes
2018-11-18 21:02:11,689 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:12,020 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/6/8b3c56d89c8aa4291174ff0143ef9d9ef001f16.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:12,026 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:12,027 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:17,245 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTYxczIKaWRyMAo_
2018-11-18 21:02:17,250 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:17,256 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTYxczIKaWRyMAo_
2018-11-18 21:02:17,259 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54561', 'guid': 'com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:17,261 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54561/tree'
2018-11-18 21:02:17,305 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:02:17,307 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:17,337 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:17,480 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:17,482 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:17,512 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU2MXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:02:17,518 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:17,526 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU2MXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:02:17,530 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:02:17,533 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:17,594 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54561/tree'
2018-11-18 21:02:17,646 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Motorcade%20of%20Generosity%20%5BEnhanced%5D'
2018-11-18 21:02:17,861 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 13 tasks
2018-11-18 21:02:17,865 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Comanche to track Comanche
2018-11-18 21:02:17,872 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Ruby Sees All to track Ruby Sees All
2018-11-18 21:02:17,879 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Up So Close to track Up So Close
2018-11-18 21:02:17,886 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Pentagram to track Pentagram
2018-11-18 21:02:17,892 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Jolene to track Jolene
2018-11-18 21:02:17,902 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Haze of Love to track Haze of Love
2018-11-18 21:02:17,965 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Jesus Wrote a Blank Check to track Jesus Wrote a Blank Check
2018-11-18 21:02:17,967 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=You%20Part%20the%20Waters'
2018-11-18 21:02:17,975 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Rock'n'Roll Lifestyle to track Rock'n'roll Lifestyle
2018-11-18 21:02:17,976 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Is%20This%20Love%3F'
2018-11-18 21:02:17,992 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric I Bombed Korea to track I Bombed Korea
2018-11-18 21:02:18,036 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Ain't No Good to track Ain't No Good
2018-11-18 21:02:18,040 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mr.%20Mastodon%20Farm'
2018-11-18 21:02:18,313 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:18,401 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:18,449 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:18,450 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:20,858 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJlc3N1cmUlMjBDaGllZj9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNTQ1MzJzMgppZHIwCg__
2018-11-18 21:02:20,862 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:20,867 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNTcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJlc3N1cmUlMjBDaGllZj9sYW5nPWVuczQKZ3VpZGIxczUKZm9yY2VzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMxMwpwcmltYXJ5X2FnZW50czUKNTQ1MzJzMgppZHIwCg__
2018-11-18 21:02:20,869 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54532', 'guid': 'com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:20,871 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54532/tree'
2018-11-18 21:02:20,909 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en
2018-11-18 21:02:20,912 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:20,947 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:21,088 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:21,089 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:21,116 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUzMnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1ByZXNzdXJlJTIwQ2hpZWY%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:02:21,120 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:21,125 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUzMnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczU3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1ByZXNzdXJlJTIwQ2hpZWY@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-18 21:02:21,128 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Pressure%20Chief?lang=en
2018-11-18 21:02:21,129 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:21,156 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54532/tree'
2018-11-18 21:02:21,195 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Pressure%20Chief'
2018-11-18 21:02:21,459 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:02:21,467 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Wheels'
2018-11-18 21:02:21,468 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=No%20Phone'
2018-11-18 21:02:21,469 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Take%20It%20All%20Away'
2018-11-18 21:02:21,477 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Dime'
2018-11-18 21:02:21,778 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Carbon%20Monoxide'
2018-11-18 21:02:21,781 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Waiting'
2018-11-18 21:02:21,787 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=The%20Guitar%20Man'
2018-11-18 21:02:21,792 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=She%27ll%20Hang%20the%20Baskets'
2018-11-18 21:02:22,031 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=End%20of%20the%20Movie'
2018-11-18 21:02:22,066 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Palm%20of%20Your%20Hand'
2018-11-18 21:02:22,101 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Tougher%20Than%20It%20Is'
2018-11-18 21:02:22,316 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:22,355 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/65098d9251d144d9c8977ea11ff0047f9f1f436.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:22,360 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:22,361 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:32,141 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJvbG9uZ2luZyUyMHRoZSUyME1hZ2ljP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo0ODM5NnMyCmlkcjAK
2018-11-18 21:02:32,147 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:32,156 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvUHJvbG9uZ2luZyUyMHRoZSUyME1hZ2ljP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo0ODM5NnMyCmlkcjAK
2018-11-18 21:02:32,159 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '48396', 'guid': 'com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:32,163 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/48396/tree'
2018-11-18 21:02:32,221 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en
2018-11-18 21:02:32,223 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:32,295 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:32,569 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:32,570 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:32,610 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0ODM5NnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Byb2xvbmdpbmclMjB0aGUlMjBNYWdpYz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:02:32,616 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:32,624 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo0ODM5NnM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Byb2xvbmdpbmclMjB0aGUlMjBNYWdpYz9sYW5nPWVuczQKZ3VpZHM0Cm51bGxzMgppZHIwCg__
2018-11-18 21:02:32,628 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Prolonging%20the%20Magic?lang=en
2018-11-18 21:02:32,631 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:32,706 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/48396/tree'
2018-11-18 21:02:32,778 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Prolonging%20the%20Magic'
2018-11-18 21:02:33,045 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 13 tasks
2018-11-18 21:02:33,062 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Never There to track Never There
2018-11-18 21:02:33,064 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Satan%20Is%20My%20Motor'
2018-11-18 21:02:33,077 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mexico'
2018-11-18 21:02:33,090 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Guitar'
2018-11-18 21:02:33,096 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=You%20Turn%20the%20Screws'
2018-11-18 21:02:33,382 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Walk%20on%20By'
2018-11-18 21:02:33,383 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Sheep%20Go%20to%20Heaven'
2018-11-18 21:02:33,443 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Hem%20of%20Your%20Garment'
2018-11-18 21:02:33,449 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=When%20You%20Sleep'
2018-11-18 21:02:33,713 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Cool%20Blue%20Reason'
2018-11-18 21:02:33,721 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Let%20Me%20Go'
2018-11-18 21:02:33,726 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Alpha%20Beta%20Parking%20Lot'
2018-11-18 21:02:33,774 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Where%20Would%20I%20Be%3F'
2018-11-18 21:02:34,103 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:34,242 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/b/ea730163de85899510dfe4c4eee0f680d6614ca.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:34,251 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:34,253 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:02:36,570 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvU2hvd3Jvb20lMjBvZiUyMENvbXBhc3Npb24%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTA3czIKaWRyMAo_
2018-11-18 21:02:36,574 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:36,579 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzNjcKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvU2hvd3Jvb20lMjBvZiUyMENvbXBhc3Npb24@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTA3czIKaWRyMAo_
2018-11-18 21:02:36,581 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54507', 'guid': 'com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:02:36,583 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54507/tree'
2018-11-18 21:02:36,623 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en
2018-11-18 21:02:36,625 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:02:36,653 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:02:36,780 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:36,781 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:02:36,812 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUwN3M0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Nob3dyb29tJTIwb2YlMjBDb21wYXNzaW9uP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:02:36,816 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:02:36,822 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDUwN3M0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczY3CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL1Nob3dyb29tJTIwb2YlMjBDb21wYXNzaW9uP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:02:36,824 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Showroom%20of%20Compassion?lang=en
2018-11-18 21:02:36,826 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:36,865 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54507/tree'
2018-11-18 21:02:36,925 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Showroom%20of%20Compassion'
2018-11-18 21:02:37,215 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-18 21:02:37,240 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric What's Now Is Now to track What's Now Is Now
2018-11-18 21:02:37,241 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Got%20to%20Move'
2018-11-18 21:02:37,244 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Long%20Time'
2018-11-18 21:02:37,245 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Federal%20Funding'
2018-11-18 21:02:37,260 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mustache%20Man%20%28Wasted%29'
2018-11-18 21:02:37,487 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Teenage%20Pregnancy'
2018-11-18 21:02:37,532 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Sick%20of%20You'
2018-11-18 21:02:37,562 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Easy%20to%20Crash'
2018-11-18 21:02:37,585 (7f81ef9a3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Bound%20Away'
2018-11-18 21:02:37,715 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=The%20Winter'
2018-11-18 21:02:37,774 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Italian Guy to track Italian Guy
2018-11-18 21:02:37,928 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:02:37,975 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/e/9535d9016d316682df331811b9e23c822c4d4f2.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:02:37,980 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:02:37,981 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-18 21:04:18,854 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ%40bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTc1czIKaWRyMAo_
2018-11-18 21:04:18,859 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:04:18,869 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjEzMjQxczgKcGFyZW50SURzODUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0Nha2UvTW90b3JjYWRlJTIwb2YlMjBHZW5lcm9zaXR5JTIwJTVCRW5oYW5jZWQlNUQ@bGFuZz1lbnM0Cmd1aWRiMXM1CmZvcmNlczI1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm1zMTMKcHJpbWFyeV9hZ2VudHM1CjU0NTc1czIKaWRyMAo_
2018-11-18 21:04:18,873 (7f81ef387710) :  INFO (agentkit:957) - Searching for matches for {'id': '54575', 'guid': 'com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '13241'}
2018-11-18 21:04:18,885 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54575/tree'
2018-11-18 21:04:18,939 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:04:18,943 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-18 21:04:19,040 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/13241/tree'
2018-11-18 21:04:19,303 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:04:19,305 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-18 21:04:19,346 (7f81ef387710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU3NXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:04:19,349 (7f81ef387710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-18 21:04:19,354 (7f81ef387710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU3NXM0CmRiaWRpMApzNwp2ZXJzaW9uczQwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlP2xhbmc9ZW5zMTAKcGFyZW50R1VJRHM1CjEzMjQxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczg1CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9DYWtlL01vdG9yY2FkZSUyMG9mJTIwR2VuZXJvc2l0eSUyMCU1QkVuaGFuY2VkJTVEP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-18 21:04:19,357 (7f81ef387710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Cake/Motorcade%20of%20Generosity%20%5BEnhanced%5D?lang=en
2018-11-18 21:04:19,359 (7f81ef387710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:04:19,402 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54575/tree'
2018-11-18 21:04:19,435 (7f81ef387710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&album=Motorcade%20of%20Generosity%20%5BEnhanced%5D'
2018-11-18 21:04:19,651 (7f81ef387710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 13 tasks
2018-11-18 21:04:19,655 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Comanche to track Comanche
2018-11-18 21:04:19,658 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Ruby Sees All to track Ruby Sees All
2018-11-18 21:04:19,671 (7f81eed2b710) :  INFO (__init__:32) - Adding Lyric Up So Close to track Up So Close
2018-11-18 21:04:19,677 (7f81ef019710) :  INFO (__init__:32) - Adding Lyric Pentagram to track Pentagram
2018-11-18 21:04:19,682 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Jolene to track Jolene
2018-11-18 21:04:19,700 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Haze of Love to track Haze of Love
2018-11-18 21:04:19,780 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Rock'n'Roll Lifestyle to track Rock'n'roll Lifestyle
2018-11-18 21:04:19,781 (7f81eed2b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=You%20Part%20the%20Waters'
2018-11-18 21:04:19,787 (7f81ef019710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Is%20This%20Love%3F'
2018-11-18 21:04:19,800 (7f81eea3d710) :  INFO (__init__:32) - Adding Lyric Jesus Wrote a Blank Check to track Jesus Wrote a Blank Check
2018-11-18 21:04:19,820 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric I Bombed Korea to track I Bombed Korea
2018-11-18 21:04:19,874 (7f81ef9a3710) :  INFO (__init__:32) - Adding Lyric Ain't No Good to track Ain't No Good
2018-11-18 21:04:19,875 (7f81eea3d710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Cake&track=Mr.%20Mastodon%20Farm'
2018-11-18 21:04:20,140 (7f81ef387710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-18 21:04:20,357 (7f81ef387710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/1/33b05071efcd6cbf37984ae485a5746f88e6c7b.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-18 21:04:20,363 (7f81ef387710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-18 21:04:20,364 (7f81ef387710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
