2018-11-19 23:27:04,744 (7ff9faa7f700) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: ecd6004 (Tue Oct 30 17:17:43 UTC 2018)
2018-11-19 23:27:04,746 (7ff9faa7f700) :  DEBUG (core:361) - Using the elevated policy
2018-11-19 23:27:04,748 (7ff9faa7f700) :  DEBUG (core:450) - Starting runtime component.
2018-11-19 23:27:04,758 (7ff9faa7f700) :  DEBUG (core:450) - Starting caching component.
2018-11-19 23:27:04,760 (7ff9faa7f700) :  DEBUG (core:450) - Starting data component.
2018-11-19 23:27:04,762 (7ff9faa7f700) :  DEBUG (core:450) - Starting networking component.
2018-11-19 23:27:04,781 (7ff9faa7f700) :  DEBUG (networking:284) - Loaded HTTP cookies
2018-11-19 23:27:04,874 (7ff9faa7f700) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-11-19 23:27:04,880 (7ff9faa7f700) :  DEBUG (core:450) - Starting localization component.
2018-11-19 23:27:04,881 (7ff9faa7f700) :  INFO (localization:409) - Setting the default locale to en-us
2018-11-19 23:27:04,883 (7ff9faa7f700) :  DEBUG (core:450) - Starting messaging component.
2018-11-19 23:27:04,887 (7ff9faa7f700) :  DEBUG (core:450) - Starting debugging component.
2018-11-19 23:27:04,889 (7ff9faa7f700) :  DEBUG (core:450) - Starting services component.
2018-11-19 23:27:04,892 (7ff9faa7f700) :  DEBUG (core:450) - Starting myplex component.
2018-11-19 23:27:04,894 (7ff9faa7f700) :  DEBUG (core:450) - Starting notifications component.
2018-11-19 23:27:04,896 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.lyricfind'
2018-11-19 23:27:05,946 (7ff9faa7f700) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.lyricfind in namespace 'metadata'
2018-11-19 23:27:06,006 (7ff9faa7f700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-11-19 23:27:06,122 (7ff9f161b710) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-11-19 23:27:06,123 (7ff9faa7f700) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-11-19 23:27:06,127 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-11-19 23:27:06,131 (7ff9faa7f700) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-11-19 23:27:06,132 (7ff9faa7f700) :  DEBUG (core:150) - Finished starting framework core
2018-11-19 23:27:06,133 (7ff9faa7f700) :  DEBUG (core:560) - Loading plug-in code
2018-11-19 23:27:06,162 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-11-19 23:27:06,204 (7ff9f0ebf710) :  DEBUG (core:538) - Machine identifier is 529f39113a7abe3875fddeb73975c5a77e6746d5
2018-11-19 23:27:06,206 (7ff9f0ebf710) :  DEBUG (core:539) - Server version is 1.13.9.5456-ecd600442
2018-11-19 23:27:06,446 (7ff9faa7f700) :  DEBUG (core:566) - Finished loading plug-in code
2018-11-19 23:27:06,448 (7ff9faa7f700) :  DEBUG (agentkit:1115) - Creating new agent class called LyricFindAlbumAgent
2018-11-19 23:27:06,450 (7ff9faa7f700) :  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-19 23:27:06,454 (7ff9faa7f700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjgKY29tLnBsZXhhcHAuYWdlbnRzLnBsZXhtdXNpY3MyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtMQpzMgp4bnIwCg__'
2018-11-19 23:27:07,007 (7ff9f161b710) :  DEBUG (services:362) - Loaded services
2018-11-19 23:27:07,049 (7ff9f11ad710) :  DEBUG (services:438) - No shared code to load
2018-11-19 23:27:07,078 (7ff9faa7f700) :  INFO (core:611) - Started plug-in
2018-11-19 23:27:07,079 (7ff9faa7f700) :  DEBUG (socketinterface:160) - Starting socket server
2018-11-19 23:27:07,082 (7ff9faa7f700) :  DEBUG (runtime:1117) - Created a thread named 'start'
2018-11-19 23:27:07,084 (7ff9faa7f700) :  INFO (socketinterface:184) - Socket server started on port 49986
2018-11-19 23:27:07,086 (7ff9faa7f700) :  INFO (pipeinterface:25) - Entering run loop
2018-11-19 23:27:07,087 (7ff9faa7f700) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-11-19 23:27:07,097 (7ff9faa7f700) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2018-11-19 23:27:07,102 (7ff9faa7f700) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 153 bytes
2018-11-19 23:27:07,110 (7ff9f161b710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnMzCjQ3MXM4CnBhcmVudElEczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9QYXVsJTI3cyUyMEJvdXRpcXVlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU4OXMyCmlkcjAK
2018-11-19 23:27:07,114 (7ff9f161b710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:07,210 (7ff9f161b710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnMzCjQ3MXM4CnBhcmVudElEczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9QYXVsJTI3cyUyMEJvdXRpcXVlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDU4OXMyCmlkcjAK
2018-11-19 23:27:07,213 (7ff9f161b710) :  INFO (agentkit:957) - Searching for matches for {'id': '54589', 'guid': 'com.plexapp.agents.lastfm://Beastie%20Boys/Paul%27s%20Boutique?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '471'}
2018-11-19 23:27:07,249 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54589/tree'
2018-11-19 23:27:07,388 (7ff9f161b710) :  DEBUG (accessor:68) - Creating a new read-only model access point for provider com.plexapp.agents.lastfm in namespace 'metadata'
2018-11-19 23:27:07,419 (7ff9f161b710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Beastie%20Boys/Paul%27s%20Boutique?lang=en
2018-11-19 23:27:07,426 (7ff9f161b710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/df6b18dbf10356f1520a83c4167696ef4a0274f.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-19 23:27:07,498 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/471/tree'
2018-11-19 23:27:07,581 (7ff9f161b710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:07,582 (7ff9f161b710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-19 23:27:07,654 (7ff9f161b710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU4OXM0CmRiaWRpMApzNwp2ZXJzaW9uczUwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cz9sYW5nPWVuczEwCnBhcmVudEdVSURzMwo0NzFzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNzAKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0JlYXN0aWUlMjBCb3lzL1BhdWwlMjdzJTIwQm91dGlxdWU%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:07,658 (7ff9f161b710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:07,663 (7ff9f161b710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDU4OXM0CmRiaWRpMApzNwp2ZXJzaW9uczUwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cz9sYW5nPWVuczEwCnBhcmVudEdVSURzMwo0NzFzOApwYXJlbnRJRHM1CkFsYnVtczEwCm1lZGlhX3R5cGVzNzAKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL0JlYXN0aWUlMjBCb3lzL1BhdWwlMjdzJTIwQm91dGlxdWU@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:07,666 (7ff9f161b710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Beastie%20Boys/Paul%27s%20Boutique?lang=en
2018-11-19 23:27:07,667 (7ff9f161b710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/df6b18dbf10356f1520a83c4167696ef4a0274f.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-19 23:27:07,669 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54589/tree'
2018-11-19 23:27:07,766 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&album=Paul%27s%20Boutique'
2018-11-19 23:27:08,050 (7ff9f161b710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 23 tasks
2018-11-19 23:27:08,078 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric To All the Girls to track To All The Girls
2018-11-19 23:27:08,130 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Johnny Ryall to track Johnny Ryall
2018-11-19 23:27:08,141 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Egg Man to track Egg Man
2018-11-19 23:27:08,156 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric High Plains Drifter to track High Plains Drifter
2018-11-19 23:27:08,164 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric The Sounds of Science to track The Sounds Of Science
2018-11-19 23:27:08,169 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&track=Shake%20Your%20Rump'
2018-11-19 23:27:08,179 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric 3-Minute Rule to track 3-Minute Rule
2018-11-19 23:27:08,220 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric 5-Piece Chicken Dinner to track 5-Piece Chicken Dinner
2018-11-19 23:27:08,307 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Looking Down the Barrel of a Gun to track Looking Down The Barrel Of A Gun
2018-11-19 23:27:08,308 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&track=Hey%20Ladies'
2018-11-19 23:27:08,318 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric What Comes Around to track What Comes Around
2018-11-19 23:27:08,334 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Shadrach to track Shadrach
2018-11-19 23:27:08,362 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Ask for Janice to track Ask For Janice
2018-11-19 23:27:08,403 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&track=Car%20Thief'
2018-11-19 23:27:08,420 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric 59 Chrystie Street to track 59 Chrystie Street
2018-11-19 23:27:08,492 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Shake Your Rump to track Shake Your Rump
2018-11-19 23:27:08,499 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&track=Get%20On%20The%20Mic'
2018-11-19 23:27:08,533 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Stop That Train to track Stop That Train
2018-11-19 23:27:08,564 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric A Year and a Day to track A Year and A Day
2018-11-19 23:27:08,607 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Hello Brooklyn to track Hello Brooklyn
2018-11-19 23:27:08,648 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Dropping Names to track Dropping Names
2018-11-19 23:27:08,686 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Lay It on Me to track Lay It On Me
2018-11-19 23:27:08,750 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Mike on the Mic to track Mike On The Mic
2018-11-19 23:27:08,771 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric A.W.O.L. to track A.W.O.L.
2018-11-19 23:27:08,801 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Car Thief to track Car Thief
2018-11-19 23:27:08,808 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Get on the Mic to track Get On The Mic
2018-11-19 23:27:08,810 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Hey Ladies to track Hey Ladies
2018-11-19 23:27:08,819 (7ff9f161b710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-19 23:27:09,146 (7ff9f161b710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/c/df6b18dbf10356f1520a83c4167696ef4a0274f.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:09,156 (7ff9f161b710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:09,158 (7ff9f161b710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-19 23:27:24,686 (7ff9f161b710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjE1NjczczgKcGFyZW50SURzOTUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1BhdmVtZW50L1NsYW50ZWQlMjBhbmQlMjBlbmNoYW50ZWQlM0ElMjBMdXhlJTIwJTI2JTIwcmVkdXhlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDYxM3MyCmlkcjAK
2018-11-19 23:27:24,692 (7ff9f161b710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:24,700 (7ff9f161b710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjE1NjczczgKcGFyZW50SURzOTUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1BhdmVtZW50L1NsYW50ZWQlMjBhbmQlMjBlbmNoYW50ZWQlM0ElMjBMdXhlJTIwJTI2JTIwcmVkdXhlP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQo1NDYxM3MyCmlkcjAK
2018-11-19 23:27:24,704 (7ff9f161b710) :  INFO (agentkit:957) - Searching for matches for {'id': '54613', 'guid': 'com.plexapp.agents.lastfm://Pavement/Slanted%20and%20enchanted%3A%20Luxe%20%26%20reduxe?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '15673'}
2018-11-19 23:27:24,733 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54613/tree'
2018-11-19 23:27:24,864 (7ff9f161b710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Pavement/Slanted%20and%20enchanted%3A%20Luxe%20%26%20reduxe?lang=en
2018-11-19 23:27:24,867 (7ff9f161b710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/d04bd912a70192551b6e8fa74dad37dae21d5a1.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-19 23:27:24,990 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/15673/tree'
2018-11-19 23:27:25,169 (7ff9f161b710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:25,171 (7ff9f161b710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-19 23:27:25,252 (7ff9f161b710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDYxM3M0CmRiaWRpMApzNwp2ZXJzaW9uczQ0CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9QYXZlbWVudD9sYW5nPWVuczEwCnBhcmVudEdVSURzNQoxNTY3M3M4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM5NQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vUGF2ZW1lbnQvU2xhbnRlZCUyMGFuZCUyMGVuY2hhbnRlZCUzQSUyMEx1eGUlMjAlMjYlMjByZWR1eGU%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:25,258 (7ff9f161b710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:25,265 (7ff9f161b710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDYxM3M0CmRiaWRpMApzNwp2ZXJzaW9uczQ0CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9QYXZlbWVudD9sYW5nPWVuczEwCnBhcmVudEdVSURzNQoxNTY3M3M4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM5NQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vUGF2ZW1lbnQvU2xhbnRlZCUyMGFuZCUyMGVuY2hhbnRlZCUzQSUyMEx1eGUlMjAlMjYlMjByZWR1eGU@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:25,269 (7ff9f161b710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Pavement/Slanted%20and%20enchanted%3A%20Luxe%20%26%20reduxe?lang=en
2018-11-19 23:27:25,272 (7ff9f161b710) :  ERROR (model:205) - Cannot read model from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/d04bd912a70192551b6e8fa74dad37dae21d5a1.bundle/Contents/com.plexapp.agents.lyricfind
2018-11-19 23:27:25,274 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54613/tree'
2018-11-19 23:27:25,431 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&album=Slanted%20%26%20Enchanted%3A%20Luxe%20%26%20Reduxe'
2018-11-19 23:27:26,191 (7ff9f161b710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 48 tasks
2018-11-19 23:27:26,220 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Frontwards to track Frontwards
2018-11-19 23:27:26,232 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Texas%20Never%20Whispers'
2018-11-19 23:27:26,247 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Summer%20Babe%20%5BWinter%20Version%5D'
2018-11-19 23:27:26,253 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Trigger%20Cut/Wounded-Kite%20at%20%3A17'
2018-11-19 23:27:26,266 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=No%20Life%20Singed%20Her'
2018-11-19 23:27:26,479 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Texas Never Whispers to track Texas Never Whispers
2018-11-19 23:27:26,531 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric No Life Singed Her to track No Life Singed Her
2018-11-19 23:27:26,541 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Lions%20%28Linden%29'
2018-11-19 23:27:26,563 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Summer Babe [Winter Version] to track Summer Babe [Winter Version]
2018-11-19 23:27:26,578 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=In%20the%20Mouth%20a%20Desert'
2018-11-19 23:27:26,596 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Shoot%20the%20Singer%20%281%20Sick%20Verse%29'
2018-11-19 23:27:26,794 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Conduit%20for%20Sale%21'
2018-11-19 23:27:26,944 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Trigger Cut/Wounded-Kite at :17 to track Trigger Cut/Wounded-Kite at :17
2018-11-19 23:27:27,016 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Sue%20Me%20Jack'
2018-11-19 23:27:27,329 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Z%FCrich%20Is%20Stained'
2018-11-19 23:27:27,379 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Shoot the Singer (1 Sick Verse) to track Shoot the Singer (1 Sick Verse)
2018-11-19 23:27:27,386 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric In the Mouth a Desert to track In the Mouth a Desert
2018-11-19 23:27:27,440 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=So%20Stark%20%28You%27re%20a%20Skyscraper%29'
2018-11-19 23:27:27,451 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Chesley%27s%20Little%20Wrists'
2018-11-19 23:27:27,481 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Conduit for Sale! to track Conduit for Sale!
2018-11-19 23:27:27,493 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Greenlander'
2018-11-19 23:27:27,764 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Greenlander to track Greenlander
2018-11-19 23:27:27,777 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Rain%20Ammunition'
2018-11-19 23:27:27,852 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Loretta%27s%20Scars'
2018-11-19 23:27:28,070 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Zurich Is Stained to track Zürich Is Stained
2018-11-19 23:27:28,079 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Here'
2018-11-19 23:27:28,095 (7ff9f04f5710) :  INFO (__init__:32) - Adding Lyric Loretta's Scars to track Loretta's Scars
2018-11-19 23:27:28,126 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Drunks%20With%20Guns'
2018-11-19 23:27:28,239 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Two%20States'
2018-11-19 23:27:28,298 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Here to track Here
2018-11-19 23:27:28,337 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Ed%20Ames'
2018-11-19 23:27:28,456 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Perfume-V'
2018-11-19 23:27:28,484 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Two States to track Two States
2018-11-19 23:27:28,555 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=The%20List%20of%20Dorms'
2018-11-19 23:27:28,682 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Perfume-V to track Perfume-V
2018-11-19 23:27:28,694 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Fame%20Throwa'
2018-11-19 23:27:28,896 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Conduit%20for%20Sale%20%5BLive%5D'
2018-11-19 23:27:28,961 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Fame Throwa to track Fame Throwa
2018-11-19 23:27:28,999 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Jackals%2C%20False%20Grails%3A%20The%20Lonesome%20Era'
2018-11-19 23:27:29,084 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Fame%20Throwa%20%5BLive%5D'
2018-11-19 23:27:29,303 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Our%20Singer'
2018-11-19 23:27:29,307 (7ff9f07e3710) :  INFO (__init__:32) - Adding Lyric Jackals, False Grails: The Lonesome Era to track Jackals, False Grails: The Lonesome Era
2018-11-19 23:27:29,333 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Home%20%5BLive%5D'
2018-11-19 23:27:29,552 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Our Singer to track Our Singer
2018-11-19 23:27:29,612 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Summer%20Baby%20%5B7%22%20Version%5D'
2018-11-19 23:27:29,635 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Perfume-V%20%5BLive%5D'
2018-11-19 23:27:29,745 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Mercy%20Snack%3A%20The%20Laundromat'
2018-11-19 23:27:29,792 (7ff9f0ebf710) :  INFO (__init__:32) - Adding Lyric Summer Babe [Live][*] to track Summer Babe [Live]
2018-11-19 23:27:29,851 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Baptist%20Blacktick'
2018-11-19 23:27:29,990 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Frontwards%20%5BLive%5D'
2018-11-19 23:27:30,032 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=My%20First%20Mine'
2018-11-19 23:27:30,195 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Angel%20Carver%20Blues/Mellow%20Jazz%20Docent%20%5BLive%5D'
2018-11-19 23:27:30,315 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Here%20%5BAlternative%20Mix%5D'
2018-11-19 23:27:30,408 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Two%20States%20%5BLive%5D'
2018-11-19 23:27:30,662 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Nothing%20Ever%20Happens'
2018-11-19 23:27:30,698 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=No%20Life%20Singed%20Her%20%5BLive%5D'
2018-11-19 23:27:30,878 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Circa%201762'
2018-11-19 23:27:30,983 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=So%20Stark%20%5BLive%5D'
2018-11-19 23:27:31,038 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Kentucky%20Cocktail'
2018-11-19 23:27:31,263 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Box%20Elder%20%5BLive%5D'
2018-11-19 23:27:31,575 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Secret%20Knowledge%20of%20Backroads'
2018-11-19 23:27:31,722 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Baby%20Yeah%20%5BLive%5D'
2018-11-19 23:27:31,749 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Here'
2018-11-19 23:27:31,869 (7ff9f07e3710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=In%20the%20Mouth%20a%20Desert%20%5BLive%5D'
2018-11-19 23:27:31,994 (7ff9f0ad1710) :  INFO (__init__:32) - Adding Lyric Here to track Here
2018-11-19 23:27:32,491 (7ff9bbfff710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnMzCjQ3MXM4CnBhcmVudElEczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9MaWNlbnNlZCUyMFRvJTIwSWxsP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzMwo0NzJzMgppZHIwCg__
2018-11-19 23:27:32,497 (7ff9bbfff710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:32,504 (7ff9bbfff710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnMzCjQ3MXM4CnBhcmVudElEczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9MaWNlbnNlZCUyMFRvJTIwSWxsP2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzMwo0NzJzMgppZHIwCg__
2018-11-19 23:27:32,508 (7ff9bbfff710) :  INFO (agentkit:957) - Searching for matches for {'id': '472', 'guid': 'com.plexapp.agents.lastfm://Beastie%20Boys/Licensed%20To%20Ill?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '471'}
2018-11-19 23:27:32,511 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/472/tree'
2018-11-19 23:27:32,570 (7ff9f161b710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-19 23:27:32,589 (7ff9bbfff710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Beastie%20Boys/Licensed%20To%20Ill?lang=en
2018-11-19 23:27:32,592 (7ff9bbfff710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/e72c1b12395dfc843d095a593b50c7102e4b76a.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-19 23:27:32,609 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/471/tree'
2018-11-19 23:27:32,755 (7ff9bbfff710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:32,759 (7ff9bbfff710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-19 23:27:32,842 (7ff9bbfff710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMwo0NzJzNApkYmlkaTAKczcKdmVyc2lvbnM1MApjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vQmVhc3RpZSUyMEJveXM%40bGFuZz1lbnMxMApwYXJlbnRHVUlEczMKNDcxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9MaWNlbnNlZCUyMFRvJTIwSWxsP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-19 23:27:32,850 (7ff9bbfff710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:32,858 (7ff9bbfff710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMwo0NzJzNApkYmlkaTAKczcKdmVyc2lvbnM1MApjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vQmVhc3RpZSUyMEJveXM@bGFuZz1lbnMxMApwYXJlbnRHVUlEczMKNDcxczgKcGFyZW50SURzNQpBbGJ1bXMxMAptZWRpYV90eXBlczcwCmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9CZWFzdGllJTIwQm95cy9MaWNlbnNlZCUyMFRvJTIwSWxsP2xhbmc9ZW5zNApndWlkczQKbnVsbHMyCmlkcjAK
2018-11-19 23:27:32,864 (7ff9bbfff710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Beastie%20Boys/Licensed%20To%20Ill?lang=en
2018-11-19 23:27:32,868 (7ff9bbfff710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/e72c1b12395dfc843d095a593b50c7102e4b76a.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:32,911 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/472/tree'
2018-11-19 23:27:32,962 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Beastie%20Boys&album=Licensed%20to%20Ill'
2018-11-19 23:27:33,124 (7ff9f161b710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/f/d04bd912a70192551b6e8fa74dad37dae21d5a1.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:33,134 (7ff9f161b710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:33,136 (7ff9f161b710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-19 23:27:33,214 (7ff9bbfff710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 1 tasks
2018-11-19 23:27:33,224 (7ff9f161b710) :  INFO (__init__:32) - Adding Lyric Brass Monkey to track Brass Monkey
2018-11-19 23:27:33,228 (7ff9bbfff710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-19 23:27:33,282 (7ff9bbfff710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/4/e72c1b12395dfc843d095a593b50c7102e4b76a.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:33,290 (7ff9bbfff710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:33,292 (7ff9bbfff710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2018-11-19 23:27:42,149 (7ff9bbfff710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjE1NjczczgKcGFyZW50SURzNjIKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1BhdmVtZW50L1RlcnJvciUyMFR3aWxpZ2h0P2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQoxNTY3NHMyCmlkcjAK
2018-11-19 23:27:42,152 (7ff9bbfff710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:42,157 (7ff9bbfff710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjBzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1CkFsYnVtczEwCm1lZGlhX3R5cGU1CnM1CjE1NjczczgKcGFyZW50SURzNjIKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbTovL1BhdmVtZW50L1RlcnJvciUyMFR3aWxpZ2h0P2xhbmc9ZW5zNApndWlkYjFzNQpmb3JjZXMyNQpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtczEzCnByaW1hcnlfYWdlbnRzNQoxNTY3NHMyCmlkcjAK
2018-11-19 23:27:42,159 (7ff9bbfff710) :  INFO (agentkit:957) - Searching for matches for {'id': '15674', 'guid': 'com.plexapp.agents.lastfm://Pavement/Terror%20Twilight?lang=en', 'force': True, 'primary_agent': 'com.plexapp.agents.lastfm', 'parentID': '15673'}
2018-11-19 23:27:42,161 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/15674/tree'
2018-11-19 23:27:42,231 (7ff9bbfff710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Pavement/Terror%20Twilight?lang=en
2018-11-19 23:27:42,234 (7ff9bbfff710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/0/87950ff4b6b55cdda4b75d806b295682daa94af.bundle/Contents/com.plexapp.agents.lastfm/Info.xml
2018-11-19 23:27:42,281 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/15673/tree'
2018-11-19 23:27:42,369 (7ff9bbfff710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:42,371 (7ff9bbfff710) :  DEBUG (runtime:924) - Response: [200] str, 300 bytes
2018-11-19 23:27:42,398 (7ff9bbfff710) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxNTY3NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQ0CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9QYXZlbWVudD9sYW5nPWVuczEwCnBhcmVudEdVSURzNQoxNTY3M3M4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM2Mgpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vUGF2ZW1lbnQvVGVycm9yJTIwVHdpbGlnaHQ%40bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:42,402 (7ff9bbfff710) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-11-19 23:27:42,407 (7ff9bbfff710) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.lyricfind/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxNTY3NHM0CmRiaWRpMApzNwp2ZXJzaW9uczQ0CmNvbS5wbGV4YXBwLmFnZW50cy5sYXN0Zm06Ly9QYXZlbWVudD9sYW5nPWVuczEwCnBhcmVudEdVSURzNQoxNTY3M3M4CnBhcmVudElEczUKQWxidW1zMTAKbWVkaWFfdHlwZXM2Mgpjb20ucGxleGFwcC5hZ2VudHMubGFzdGZtOi8vUGF2ZW1lbnQvVGVycm9yJTIwVHdpbGlnaHQ@bGFuZz1lbnM0Cmd1aWRzNApudWxsczIKaWRyMAo_
2018-11-19 23:27:42,410 (7ff9bbfff710) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.lastfm://Pavement/Terror%20Twilight?lang=en
2018-11-19 23:27:42,433 (7ff9bbfff710) :  DEBUG (model:234) - Deserializing from /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/0/87950ff4b6b55cdda4b75d806b295682daa94af.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:42,489 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/15674/tree'
2018-11-19 23:27:42,599 (7ff9bbfff710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&album=Terror%20Twilight'
2018-11-19 23:27:43,263 (7ff9bbfff710) :  DEBUG (threadkit:234) - Starting a parallel task set named MatchLyrics with 11 tasks
2018-11-19 23:27:43,268 (7ff9f161b710) :  INFO (__init__:32) - Adding Lyric Spit on a Stranger to track Spit on a Stranger
2018-11-19 23:27:43,331 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Folk%20Jam'
2018-11-19 23:27:43,332 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=You%20Are%20a%20Light'
2018-11-19 23:27:43,335 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Major%20Leagues'
2018-11-19 23:27:43,346 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Cream%20of%20Gold'
2018-11-19 23:27:43,651 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Ann%20Don%5Cu2019t%20Cry'
2018-11-19 23:27:43,689 (7ff9f04f5710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Billie'
2018-11-19 23:27:43,698 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Platform%20Blues'
2018-11-19 23:27:44,037 (7ff9f0ebf710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Speak%2C%20See%2C%20Remember'
2018-11-19 23:27:44,220 (7ff9f0ad1710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=The%20Hexx'
2018-11-19 23:27:44,443 (7ff9f161b710) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/lyricfind/search?reqtype=default&searchtype=track&limit=100&output=json&artist=Pavement&track=Carrot%20Rope'
2018-11-19 23:27:45,307 (7ff9bbfff710) :  DEBUG (threadkit:244) - Parallel task set MatchLyrics ended
2018-11-19 23:27:45,434 (7ff9bbfff710) :  DEBUG (model:229) - Serializing to /raid/data/module/Plex/sys/Plex Media Server/Metadata/Albums/0/87950ff4b6b55cdda4b75d806b295682daa94af.bundle/Contents/com.plexapp.agents.lyricfind/Info.xml
2018-11-19 23:27:45,442 (7ff9bbfff710) :  DEBUG (runtime:88) - Sending packed state data (109 bytes)
2018-11-19 23:27:45,444 (7ff9bbfff710) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
