mherger wrote: > If you enabled debug logging for plugin.spotty you'd see what exact > parameters it was using to run those auth calls etc. Please do so and > see where you'd get that Mercury error. Then we can try to run that > command from the command line.
Hello again... Below, I'm copying the resulting server log after I enabled debug logging for Spotty. I updated to the latest 7.9.2 build, cleared the log, rebooted the server and clicked on the "Playlist" and "Transfer Playback" links on the Spotty page. Let me know if you find anything that I can change/try later. I have to head out to work and couldn't see anything that I could act upon. I added XX to the last three octets of the Mac address as I believe this is also used as the S/N for Squeezeboxes [18-04-26 11:57:35.7699] main::init (387) Starting Logitech Media Server (v7.9.2, 1524593278, Wed Apr 25 07:38:10 WEDT 2018) perl 5.014001 - MSWin32-x86-multi-thread [18-04-26 11:57:43.0465] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: C:\ProgramData\Squeezebox\Cache\InstalledPlugins\Plugins\CustomScan\SQL\SQLite\dbupgrade_valuesort.sql : No such file or directory [18-04-26 11:57:43.0476] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: C:\ProgramData\Squeezebox\Cache\InstalledPlugins\Plugins\CustomScan\SQL\SQLite\dbupgrade_extravalue.sql : No such file or directory [18-04-26 11:57:43.0490] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: C:\ProgramData\Squeezebox\Cache\InstalledPlugins\Plugins\CustomScan\SQL\SQLite\dbupgrade_valuetype.sql : No such file or directory [18-04-26 11:57:43.2927] Plugins::NPRRadio::Metadata::init (21) Register NPR meta provider, default icon= [18-04-26 11:57:43.3636] Plugins::Spotty::Plugin::purgeAudioCache (464) Starting audio cache cleanup... [18-04-26 11:57:43.3666] Plugins::Spotty::Plugin::purgeAudioCache (487) Max. cache size is: 0MB, current cache size is 0MB [18-04-26 11:57:43.3677] Plugins::Spotty::Plugin::purgeAudioCache (518) Starting temporary file cleanup... (C:\Users\Luis\AppData\Local\Temp) [18-04-26 11:57:43.3728] Plugins::Spotty::Plugin::purgeAudioCache (538) Audio cache cleanup done! [18-04-26 11:57:48.6384] Plugins::Spotty::Plugin::findBin (775) Trying helper applicaton: C:\PROGRA~3\SQUEEZ~1\Cache\INSTAL~1\Plugins\Spotty\Bin\MSWIN3~1\spotty.exe [18-04-26 11:57:48.7341] Plugins::Spotty::Plugin::helperCheck (701) Found helper capabilities table: do { my $a = { debug => bless(do{\(my $o = 0)}, "JSON::XS::Boolean"), "lms-auth" => bless(do{\(my $o = 1)}, "JSON::XS::Boolean"), version => "0.11.0", "volume-normalisation" => 'fix', }; $a->{"volume-normalisation"} = \${$a->{"lms-auth"}}; $a; } [18-04-26 11:57:48.7345] Plugins::Spotty::Plugin::findBin (778) Found helper applicaton: C:\PROGRA~3\SQUEEZ~1\Cache\INSTAL~1\Plugins\Spotty\Bin\MSWIN3~1\spotty.exe [18-04-26 11:57:50.2980] Slim::Plugin::MusicMagic::Common::__ANON__ (85) Failed fetching filters from MusicIP [18-04-26 11:57:52.4007] Plugins::Spotty::Connect::DaemonManager::initHelpers (70) Initializing Spotty Connect helper daemons... [18-04-26 11:57:52.4012] Plugins::Spotty::Connect::DaemonManager::startHelper (96) Need to create Connect daemon for 00:04:20:XX:XX:XX [18-04-26 11:57:52.4079] Plugins::Spotty::Connect::Daemon::start (66) Starting Spotty Connect daemon: C:\PROGRA~3\SQUEEZ~1\Cache\INSTAL~1\Plugins\Spotty\Bin\MSWIN3~1\spotty.exe -c C:\ProgramData\Squeezebox\Cache\spotty\000420XXXXXX -n Transporter --disable-audio-cache --bitrate 96 --player-mac 00:04:20:XX:XX:XX --lms 192.168.1.100:9000 [18-04-26 11:58:52.0036] Plugins::Spotty::Connect::DaemonManager::initHelpers (70) Initializing Spotty Connect helper daemons... [18-04-26 11:59:52.0019] Plugins::Spotty::Connect::DaemonManager::initHelpers (70) Initializing Spotty Connect helper daemons... [18-04-26 12:00:51.9540] Plugins::Spotty::API::getToken (101) Didn't find cached token. Need to refresh. [18-04-26 12:00:51.9553] Plugins::Spotty::API::getToken (121) Trying to get access token: C:\PROGRA~3\SQUEEZ~1\Cache\INSTAL~1\Plugins\Spotty\Bin\MSWIN3~1\spotty.exe -n Squeezebox -c "C:\ProgramData\Squeezebox\Cache\spotty\cd148266" -i Transporter --get-token --scope "user-read-private,user-follow-modify,user-follow-read,user-library-read,user-library-modify,user-top-read,user-read-recently-played,user-read-playback-state,user-modify-playback-state,playlist-read-private,playlist-read-collaborative,playlist-modify-public,playlist-modify-private" [18-04-26 12:00:52.6891] Plugins::Spotty::API::getToken (124) Got response: error getting token MercuryError [18-04-26 12:00:52.6895] Plugins::Spotty::API::getToken (128) Failed to get Spotify access token: malformed JSON string, neither array, object, number, string or atom, at character offset 0 (before "error getting token ...") at C:\ProgramData\Squeezebox\Cache\InstalledPlugins/Plugins/Spotty/API.pm line 125. error getting token MercuryError [18-04-26 12:00:52.6900] Plugins::Spotty::API::getToken (145) Failed to get Spotify access token [18-04-26 12:00:52.6916] Plugins::Spotty::API::getToken (96) Found cached token: -1 [18-04-26 12:00:52.7439] Plugins::Spotty::Connect::DaemonManager::initHelpers (70) Initializing Spotty Connect helper daemons... [18-04-26 12:00:56.4027] Plugins::Spotty::API::getToken (96) Found cached token: -1 [18-04-26 12:00:56.4047] Plugins::Spotty::API::getToken (96) Found cached token: -1 [18-04-26 12:01:02.8171] Plugins::Spotty::API::getToken (96) Found cached token: -1 [18-04-26 12:01:02.8183] Plugins::Spotty::API::getToken (96) Found cached token: -1 ------------------------------------------------------------------------ tamanaco's Profile: http://forums.slimdevices.com/member.php?userid=4620 View this thread: http://forums.slimdevices.com/showthread.php?t=108898 _______________________________________________ plugins mailing list plugins@lists.slimdevices.com http://lists.slimdevices.com/mailman/listinfo/plugins