400 Bad Request problem w/ LMS 7.9.2 on piCoreplayer

[partymonkey]

I'm getting the same error with any app that seems to be linked to squeezebox.com. This started w/ the LMS update on piCoreplayer to 7.9.2.

The log of my LMS gives me a hint to the problem, but I don't know how to fix it. I believe what's happening is that the request for app access is being made via the mysqueezebox.com with an SSL port (443) but the http request is being done w/ http and not https.

Can anyone help point to how this can be fixed in LMS?


[18-04-21 10:42:14.6541] main::init (387) Starting Logitech Media Server (v7.9.2, 1524136933, Thu Apr 19 13:42:50 CEST 2018) perl 5.024000 - arm-linux-gnueabihf-thread-multi-64int
[18-04-21 10:42:21.2904] Slim::Utils::Misc::msg (1252) Warning: [10:42:21.2867] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/local/slimserver/Slim/Schema.pm line 1877
[18-04-21 10:42:43.9806] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/.../opml/stations
400 Bad Request
[18-04-21 10:42:43.9843] Slim::Control::XMLBrowser::_cliQuery_error (1677) Error: While retrieving [http://www.mysqueezebox.com:443/api/...pml/stations]: [400 Bad Request]
[18-04-21 10:43:05.9635] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...etAlbumsForYou
400 Bad Request
[18-04-21 10:43:05.9671] Slim::Control::XMLBrowser::_cliQuery_error (1677) Error: While retrieving [http://www.mysqueezebox.com:443/api/...AlbumsForYou]: [400 Bad Request]
[18-04-21 10:43:58.0699] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-21 10:48:06.3112] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-21 10:51:47.6857] Slim::Utils::Scanner::Remote::__ANON__ (193) Error: Can't connect to remote server to retrieve playlist for, http://201.212.5.144/aspen: Connect timed out: .
[18-04-21 10:52:12.8789] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-21 10:52:12.8824] Slim::Control::XMLBrowser::_cliQuery_error (1677) Error: While retrieving [http://www.mysqueezebox.com:443/api/...braryUpdated]: [400 Bad Request]
[18-04-21 10:57:06.8562] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-21 10:57:06.8599] Slim::Control::XMLBrowser::_cliQuery_error (1677) Error: While retrieving [http://www.mysqueezebox.com:443/api/...braryUpdated]: [400 Bad Request]
[18-04-21 19:55:26.8389] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-21 19:58:37.7989] main::init (387) Starting Logitech Media Server (v7.9.2, 1524136933, Thu Apr 19 13:42:50 CEST 2018) perl 5.024000 - arm-linux-gnueabihf-thread-multi-64int
[18-04-21 19:58:43.2212] Slim::Utils::Misc::msg (1252) Warning: [19:58:43.2177] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/local/slimserver/Slim/Schema.pm line 1877
[18-04-21 19:59:49.1581] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/.../opml/stations
400 Bad Request
[18-04-21 20:02:10.9059] main::init (387) Starting Logitech Media Server (v7.9.2, 1524136933, Thu Apr 19 13:42:50 CEST 2018) perl 5.024000 - arm-linux-gnueabihf-thread-multi-64int
[18-04-21 20:02:16.4312] Slim::Utils::Misc::msg (1252) Warning: [20:02:16.4278] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/local/slimserver/Slim/Schema.pm line 1877
[18-04-21 20:02:41.9599] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/.../opml/stations
400 Bad Request

[jtvogt]

Hi,

got the the problem with Deezer (registered on mysqueebox.com) and LMS 7.9.2 1524136933 (@ Thu Apr 19 13:37:16 CEST 2018) using x86_64-linux build:

Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...%20Charts&id=0

Scheme should be https instead of http.

[pinkdot]

Hi,

got the the problem with Deezer (registered on mysqueebox.com) and LMS 7.9.2 1524136933 (@ Thu Apr 19 13:37:16 CEST 2018) using x86_64-linux build:

Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...%20Charts&id=0

Scheme should be https instead of http.

We have seen the same issues with the Synology packages. Reverting one of the latest commits did the trick.
https://forums.slimdevices.com/showt...l=1#post912205

If this is not possible in your case, revert to the latest 7.9.1 build or wait till Michael fixes the issue.

[mherger]

> Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting
> http://www.mysqueezebox.com:443/api/...%20Charts&id=0
>
> Scheme should be https instead of http.

Yes, that's wrong. I wonder why it would use the port there, as afaik we
don't add it to the URL. Could you please upload your full
server.log.zip file to my dropbox?

https://www.dropbox.com/request/T3RctyzGgNg0oFDubq6a

And could you please all confirm you don't see any warning wrt.
IO::Socket::SSL etc.? Are you using any of Spotty or RadioParadise plugins?

Thanks!

--

Michael

[partymonkey]

Thanks Michael, for your reply.

I pasted the entire server.log (and I uploaded it to your dropbox). I don't know how to get something with more detail, if you can help me either turn on more detail or find a more complete log, happy to do so. (I use picoplayer 3.5 as an LMS and squeezebox radios, so my understanding of the intricate parts of LMS is low).
No IO SSL errors.

It happens to me with any plugin that has a relationship to mysqueezebox.com. In my case, I get these errors with Pandora or with Napster. But it only happens when I try to navigate the menus of either in the squeezebox, and it happens when it has to make a request to the service
.
for example: in Napster:
Napster -> My Library -> error
Napster -> Search -> Search for Albums -> enter the text -> error

in Pandora:
Pandora -> Your Stations -> error

However, I have both a Napster Playlist, a Napster Station, and a Pandora station all saved in the squeezebox/lms My Favorites. If I launch one of those, it plays it just fine. It seems like the problem is in the menu navigation requests...

[pinkdot]

Some at the Synology thread said that Tidal wasn't working anymore after updating to 7.9.2. The previous 7.9.1 build I made was from before April 11th.
So, I revert this commit in the 7.9.2 build, thinking that was causing the issue and stopped Tidal from working. After reverting the commit Tidal was working again.


Did a fresh install (including all commits):

Logitech Media Server Version: 7.9.2 - 0008.1524136933 @ Thu Apr 19 13:42:50 CEST 2018Hostname: pinkdot
Server IP Address: xxx.xxx.x.x
Server HTTP Port Number: 9002
Operating system: Synology DiskStation - EN - utf8
Platform Architecture: armle-linux
Perl Version: 5.24.0 - armle-linux
Audio::Scan: 0.95
Database Version: DBD::SQLite 1.34_01 (sqlite 3.7.7.1)
Total Players Recognized: 3

Actions
Webui --> My Apps --> Tidal --> Genres --> There was an error loading the remote feed for : (400 Bad Request)

[18-04-22 19:55:11.5064] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/wimp/v1/opml/genres
400 Bad Request

Webui --> My Apps --> Tidal --> My Favorites --> Artists --> There was an error loading the remote feed for : (400 Bad Request)

[18-04-22 20:04:34.3643] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/wimp/v1/opml/favorites?type=artists
400 Bad Request

[jtvogt]

> Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting
> http://www.mysqueezebox.com:443/api/...%20Charts&id=0
>
> Scheme should be https instead of http.

Yes, that's wrong. I wonder why it would use the port there, as afaik we
don't add it to the URL. Could you please upload your full
server.log.zip file to my dropbox?

https://www.dropbox.com/request/T3RctyzGgNg0oFDubq6a

And could you please all confirm you don't see any warning wrt.
IO::Socket::SSL etc.? Are you using any of Spotty or RadioParadise plugins?

Thanks!

--

Michael

Hi Michael,

I've uploaded my server.log (cleared before restart of LMS, selected Deezer->"Top Charts" afterwards from Web-GUI).
No warnings wrt. SSL. But I'm using the spotty-Plugin which is working just fine, also TuneIn (registered like Deezer within www.mysqueezebox.com) is working fine.

Many thanks!

[mherger]

Oh my... this isn't a 7.9.2 bug. But an ages old bug in mysb.com code
which got exposed by LMS enforcement of https. Good news is: it should
work again, without the need for a 7.9.2 update.

If you don't want to wait until your cache has expired, go to Settings/Information,
take a note of the cache folder. Shut down LMS, delete the cache.db inside your
cache folder, restart LMS.

--

Michael

[jtvogt]

Oh my... this isn't a 7.9.2 bug. But an ages old bug in mysb.com code
which got exposed by LMS enforcement of https. Good news is: it should
work again, without the need for a 7.9.2 update.

If you don't want to wait until your cache has expired, go to Settings/Information,
take a note of the cache folder. Shut down LMS, delete the cache.db inside your
cache folder, restart LMS.

--

Michael

Hi Michael,

didn't help: shutted down LMS, deleted cache.db, started LMS, selected Deezer -> still showing "400 bad request" in server.log

Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...%20Charts&id=0

Many thanks!

[partymonkey]

Oh my... this isn't a 7.9.2 bug. But an ages old bug in mysb.com code
which got exposed by LMS enforcement of https. Good news is: it should
work again, without the need for a 7.9.2 update.

If you don't want to wait until your cache has expired, go to Settings/Information,
take a note of the cache folder. Shut down LMS, delete the cache.db inside your
cache folder, restart LMS.

--

Michael

Hi Michael, I followed your steps, to shutdown LMS and delete the cache.db file from /usr/local/slimserver/Cache folder on piCorePlayer, restart LMS, and I still have the same error:

[18-04-22 20:15:04.8048] main::init (387) Starting Logitech Media Server (v7.9.2, 1524136933, Thu Apr 19 13:42:50 CEST 2018) perl 5.024000 - arm-linux-gnueabihf-thread-multi-64int
[18-04-22 20:15:10.3593] Slim::Utils::Misc::msg (1252) Warning: [20:15:10.3555] DBIx::Class::ResultSet::update_or_create(): Query returned more than one row. SQL that returns multiple rows is DEPRECATED for ->find and ->single at /usr/local/slimserver/Slim/Schema.pm line 1877
[18-04-22 20:16:36.5586] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/...LibraryUpdated
400 Bad Request
[18-04-22 20:16:46.2064] Slim::Formats::XML::gotErrorViaHTTP (339) Error: getting http://www.mysqueezebox.com:443/api/.../opml/stations
400 Bad Request

Thanks for always helping out...