PDA

View Full Version : Problem can't open file for:



MrClive
2018-03-08, 14:39
I'm running a SB Boom and SB Touch off a PI2 that has Max2Play installed on it and when I try and select anything on Spotify (via Spotty). I've just started getting the following error message.

Problem can't open file for:

I can play locally store music, but can't get anything working on Spotify on the Toch or Boom?

Any suggestions?

w3wilkes
2018-03-08, 22:25
Wonder if you should ask at the Max2Play forum
https://www.max2play.com/en/forums/

mherger
2018-03-08, 23:13
> I'm running a SB Boom and SB Touch off a PI2 that has Max2Play installed
> on it and when I try and select anything on Spotify (via Spotty). I've
> just started getting the following error message.

What does server.log say?

--

Michael

MrClive
2018-03-09, 00:24
> I'm running a SB Boom and SB Touch off a PI2 that has Max2Play installed
> on it and when I try and select anything on Spotify (via Spotty). I've
> just started getting the following error message.

What does server.log say?

--

Michael

Hi Michael

Thanks for getting back to me. I've gone into debug on Max2pay and get the following:-
#### SQUEEZESERVER LOG ####
[18-03-09 06:59:31.6673] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-09 07:01:28.6823] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 07:01:28.6914] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed

mherger
2018-03-09, 01:40
>> What does server.log say?

Please upload the full zipped server.log (download link can be found in
Settings/Information).

--

Michael

MrClive
2018-03-09, 07:08
Thanks Micheal, Iíll check when Iím home.


Sent from my iPhone using Tapatalk Pro

MrClive
2018-03-09, 12:32
Server log file, I think.

MrClive
2018-03-09, 14:59
Wonder if you should ask at the Max2Play forum
https://www.max2play.com/en/forums/

Logged with Max2play forum...no reply so far.


Sent from my iPhone using Tapatalk Pro

MrClive
2018-03-09, 15:00
Thanks Micheal, Iíll check when Iím home.


Sent from my iPhone using Tapatalk Pro

Iíve downloaded the sever log in zip file is this ok?


Sent from my iPhone using Tapatalk Pro

mherger
2018-03-09, 16:04
> I’ve downloaded the sever log in zip file is this ok?

You only sent a hand full of lines. Please provide the full file. Just
grab it from the link which provides the ZIP archive.

--

Michael

MrClive
2018-03-10, 01:49
> I’ve downloaded the sever log in zip file is this ok?

You only sent a hand full of lines. Please provide the full file. Just
grab it from the link which provides the ZIP archive.

--

Michael

The zip files from here?

24685

I get less info from the zip archive and everything than I do from the SErver log file which I've pasted below

[18-03-09 06:59:31.6673] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-09 07:01:28.6823] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 07:01:28.6914] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 09:43:00.1846] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 09:43:00.1918] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 11:17:31.9652] Slim::Display::Lib::Fonts::__ANON__ (85) Warning: Unable to load TrueType font support: Can't locate loadable object for module Font::FreeType in @INC (@INC contains: /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/5.14.2/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14.2/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14 /usr/share/squeezeboxserver/lib /usr/share/squeezeboxserver/CPAN /usr/share/squeezeboxserver /usr/share/squeezeboxserver/CPAN /usr/share/squeezeboxserver /usr/sbin /etc/perl /usr/local/lib/perl/5.14.2 /usr/local/share/perl/5.14.2 /usr/lib/perl5 /usr/share/perl5 /usr/lib/perl/5.14 /usr/share/perl/5.14 /usr/local/lib/site_perl .) at /usr/share/perl5/Slim/Display/Lib/Fonts.pm line 84
Compilation failed in require at /usr/share/perl5/Slim/Display/Lib/Fonts.pm line 84.
[18-03-09 21:33:36.7642] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 21:33:41.7628] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-10 06:35:56.1217] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-10 06:35:56.1290] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
2018-03-10 08:24:42 squeezeboxserver_safe stopped.
2018-03-10 08:24:42 squeezeboxserver_safe started.
[18-03-10 08:24:42.1863] Plugins::ShairTunes2::Plugin::revoke_publishPlayer (204) Send TERM sig to old publish player services. pids: 20386, 18795
[18-03-10 08:24:47.9871] main::init (387) Starting Logitech Media Server (v7.9.1, 1499900819, Fri Jul 21 04:39:21 UTC 2017) perl 5.014002 - arm-linux-gnueabihf-thread-multi-64int
[18-03-10 08:24:53.7159] Plugins::NPRRadio::Metadata::init (21) Register NPR meta provider, default icon=
[18-03-10 08:24:53.7621] Plugins::ShairTunes2::Plugin::initPlugin (91) Initialising 0.18 on arm-linux-gnueabihf-thread-multi-64int
[18-03-10 08:24:57.5738] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-10 08:24:58.1109] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Touch"

I've attached the archive file too. Am I getting this from the right location?

mherger
2018-03-10, 23:11
> The zip files from here?
That's odd. But hey, that screenshot actually was very helpful: you seem
to have installed about every plugin with "spotty" in its name. Get rid
of the "Spotty Helper" plugins. Only use Spotty itself. The others force
the use of helpers for FreeBSD or AARCH64 - both of which are not what
you want.

--
--

Michael

MrClive
2018-03-11, 09:08
> The zip files from here?
That's odd. But hey, that screenshot actually was very helpful: you seem
to have installed about every plugin with "spotty" in its name. Get rid
of the "Spotty Helper" plugins. Only use Spotty itself. The others force
the use of helpers for FreeBSD or AARCH64 - both of which are not what
you want.

--
--

Michael

I did add these after I had problems. I've just removed them both but still have the same issue.

Any other suggestions?

This the latest server log. The zip file doesn't contain that much weirdly

Compilation failed in require at /usr/share/perl5/Slim/Display/Lib/Fonts.pm line 84.
[18-03-09 21:33:36.7642] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-09 21:33:41.7628] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-10 06:35:56.1217] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-10 06:35:56.1290] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
2018-03-10 08:24:42 squeezeboxserver_safe stopped.
2018-03-10 08:24:42 squeezeboxserver_safe started.
[18-03-10 08:24:42.1863] Plugins::ShairTunes2::Plugin::revoke_publishPlayer (204) Send TERM sig to old publish player services. pids: 20386, 18795
[18-03-10 08:24:47.9871] main::init (387) Starting Logitech Media Server (v7.9.1, 1499900819, Fri Jul 21 04:39:21 UTC 2017) perl 5.014002 - arm-linux-gnueabihf-thread-multi-64int
[18-03-10 08:24:53.7159] Plugins::NPRRadio::Metadata::init (21) Register NPR meta provider, default icon=
[18-03-10 08:24:53.7621] Plugins::ShairTunes2::Plugin::initPlugin (91) Initialising 0.18 on arm-linux-gnueabihf-thread-multi-64int
[18-03-10 08:24:57.5738] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-10 08:24:58.1109] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Touch"
[18-03-10 13:20:32.8981] Slim::Buttons::Common::__ANON__ (812) Error: No valid url found, not adding favorite!
[18-03-10 13:20:32.9005] Slim::Buttons::Common::__ANON__ (817) Backtrace:

frame 0: Slim::Utils::Log::logBacktrace (/usr/share/perl5/Slim/Buttons/Common.pm line 817)
frame 1: Slim::Buttons::Common::__ANON__ (/usr/share/perl5/Slim/Hardware/IR.pm line 1104)
frame 2: Slim::Hardware::IR::executeButton (/usr/share/perl5/Slim/Control/Commands.pm line 291)
frame 3: Slim::Control::Commands::buttonCommand (/usr/share/perl5/Slim/Control/Request.pm line 1883)
frame 4: (eval) (/usr/share/perl5/Slim/Control/Request.pm line 1883)
frame 5: Slim::Control::Request::execute (/usr/share/perl5/Slim/Control/Request.pm line 880)
frame 6: Slim::Control::Request::executeRequest (/usr/share/perl5/Slim/Player/Client.pm line 634)
frame 7: Slim::Player::Client::execute (/usr/share/perl5/Slim/Hardware/IR.pm line 1125)
frame 8: Slim::Hardware::IR::processCode (/usr/share/perl5/Slim/Hardware/IR.pm line 897)
frame 9: Slim::Hardware::IR::fireHold (/usr/share/perl5/Slim/Utils/Timers.pm line 266)
frame 10: (eval) (/usr/share/perl5/Slim/Utils/Timers.pm line 266)
frame 11: Slim::Utils::Timers::__ANON__ (/usr/share/perl5/Slim/Networking/IO/Select.pm line 168)
frame 12: (eval) (/usr/share/perl5/Slim/Networking/IO/Select.pm line 168)
frame 13: Slim::Networking::IO::Select::loop (/usr/sbin/squeezeboxserver line 727)
frame 14: main::idle (/usr/sbin/squeezeboxserver line 677)
frame 15: main::main (/usr/sbin/squeezeboxserver line 1213)

[18-03-10 13:20:54.8745] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-10 18:01:49.7122] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-10 19:06:04.8691] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-10 19:06:04.8764] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-11 07:14:29.5157] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-11 07:14:29.5230] Slim::Plugin::UPnP::Events::error (323) Subscribe/unsubscribe error: 412 Precondition Failed
[18-03-11 10:53:23.3929] Slim::Display::Lib::Fonts::__ANON__ (85) Warning: Unable to load TrueType font support: Can't locate loadable object for module Font::FreeType in @INC (@INC contains: /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /var/lib/squeezeboxserver/cache/InstalledPlugins /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/5.14.2/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14.2/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14/arm-linux-gnueabihf-thread-multi-64int/auto /usr/share/squeezeboxserver/CPAN/arch/arm-linux-gnueabihf-thread-multi-64int /usr/share/squeezeboxserver/CPAN/arch/5.14 /usr/share/squeezeboxserver/lib /usr/share/squeezeboxserver/CPAN /usr/share/squeezeboxserver /usr/share/squeezeboxserver/CPAN /usr/share/squeezeboxserver /usr/sbin /etc/perl /usr/local/lib/perl/5.14.2 /usr/local/share/perl/5.14.2 /usr/lib/perl5 /usr/share/perl5 /usr/lib/perl/5.14 /usr/share/perl/5.14 /usr/local/lib/site_perl .) at /usr/share/perl5/Slim/Display/Lib/Fonts.pm line 84
Compilation failed in require at /usr/share/perl5/Slim/Display/Lib/Fonts.pm line 84.
2018-03-11 15:25:27 squeezeboxserver_safe stopped.
2018-03-11 15:25:27 squeezeboxserver_safe started.
[18-03-11 15:25:27.9942] Plugins::ShairTunes2::Plugin::revoke_publishPlayer (204) Send TERM sig to old publish player services. pids: 17788, 17775
[18-03-11 15:25:33.8284] main::init (387) Starting Logitech Media Server (v7.9.1, 1499900819, Fri Jul 21 04:39:21 UTC 2017) perl 5.014002 - arm-linux-gnueabihf-thread-multi-64int
[18-03-11 15:25:39.6813] Plugins::NPRRadio::Metadata::init (21) Register NPR meta provider, default icon=
[18-03-11 15:25:39.7281] Plugins::ShairTunes2::Plugin::initPlugin (91) Initialising 0.18 on arm-linux-gnueabihf-thread-multi-64int
[18-03-11 15:25:43.5685] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Boom"
[18-03-11 15:25:43.8921] Plugins::ShairTunes2::Plugin::publishPlayer (221) start avahi-publish-service "Squeezebox Touch"

Man in a van
2018-03-11, 11:40
Hi Clive


[18-03-11 15:25:33.8284] main::init (387) Starting Logitech Media Server (v7.9.1, 1499900819, Fri Jul 21 04:39:21 UTC 2017) perl 5.014002 - arm-linux-gnueabihf-thread-multi-64int

Have you tried updating LMS?

Ronnie

MrClive
2018-03-11, 16:28
Hi Clive



Have you tried updating LMS?

Ronnie

Hi Ronnie

I havenít, Iíll give it a try.



Sent from my iPhone using Tapatalk Pro

MrClive
2018-03-11, 16:38
Some guy on max2play forum is recommending the below....

Iíll try updating LMS first....make any sense?


https://www.max2play.com/en/forums/topic/spotify-not-playing-on-lms-problem-cant-open-file-for/


Sent from my iPhone using Tapatalk Pro

mherger
2018-03-11, 22:58
Please enable logging for plugin.spotty. Play with it, grab the log file
(and yes, you should try to figure out why that zip file is almost empty).

Also: what does Settings/Advanced/Spotty report wrt. to the helper being
used?

--

Michael

MrClive
2018-03-12, 00:20
Do I do this after Iíve updated LMS or followed the instruction from the Max2play link?


Please enable logging for plugin.spotty. Play with it, grab the log file


Sorry, how do I do this?


Sent from my iPhone using Tapatalk Pro

mherger
2018-03-12, 01:14
> Do I do this after I’ve updated LMS or followed the instruction from the
> Max2play link?

That link suggests using a different plugin (which I'm surprised to see
alive, still). I wouldn't do that, as I'm the author of Spotty :-).

>> Please enable logging for plugin.spotty. Play with it, grab the log file
>
> Sorry, how do I do this?

Go to Settings/Advanced/Logging, find plugin.spotty and set it to INFO.
Apply. Then there's a link to grab the files at the top.

Settings/Advanced/Spotty would tell you which binary it's using, what
version. What does it say?

--

Michael

MrClive
2018-03-12, 12:08
Hi Clive



Have you tried updating LMS?

Ronnie

Iím running 7.9.1


Sent from my iPhone using Tapatalk Pro

Man in a van
2018-03-12, 12:17
Hi Clive


[18-03-11 15:25:33.8284] main::init (387) Starting Logitech Media Server (v7.9.1, 1499900819, Fri Jul 21 04:39:21 UTC 2017) perl 5.014002 - arm-linux-gnueabihf-thread-multi-64int

from your logs



Have you tried updating LMS?

Ronnie


Iím running 7.9.1


Sent from my iPhone using Tapatalk Pro

So am I


Logitech Media Server Version: 7.9.1 - 1520781386 @ Sun Mar 11 18:33:23 CET 2018

MrClive
2018-03-12, 13:03
Looking at my available versions of LMS on Max2play 7.9.1 is the latest one. How do I update? Sorry for being a numpty.


Sent from my iPhone using Tapatalk Pro

MrClive
2018-03-12, 13:40
Hi Ronnie

OK I've got this working again. I updated Max2play it's showing version Version 2.45 Raspberry PI.

Could I have just updated by going into SQUEEZEBOX SERVER selecting Show Available versions, selecting Nightly 7.9 and then clicked on Squeezebox Server start installation

I've also sorted the serverlog thing out. What do you think it was?

slartibartfast
2018-03-12, 13:48
Hi Ronnie

OK I've got this working again. I updated Max2play it's showing version Version 2.45 Raspberry PI.

Could I have just updated by going into SQUEEZEBOX SERVER selecting Show Available versions, selecting Nightly 7.9 and then clicked on Squeezebox Server start installation

I've also sorted the serverlog thing out. What do you think it was?Updating max2play doesn't update LMS so you still need to do that using the method you mention.

Sent from my SM-G900F using Tapatalk

MrClive
2018-03-12, 13:50
Updating max2play doesn't update LMS so you still need to do that using the method you mention.

Sent from my SM-G900F using Tapatalk

Ah Thanks for the confirmation, I haven't done it yet, but it's now working without doing it.

MrClive
2018-03-12, 13:52
Updating now, the flashing screen every 3 seconds.......its all coming flooding back

MrClive
2018-03-12, 14:05
Think it's updated. What do you think it was? Because it started working after the Max2play update.

Man in a van
2018-03-12, 15:30
Clive, I have no idea, but very often Mr. Herger suggest running "the latest and greatest" as the first step ;)

I don't use Max2Play much these days, mostly run a mac-mini as the primary server and play with piCorePlayer on occasion, prefer plain Raspbian on a pi at the mo.:)

Ronnie

MrClive
2018-03-12, 15:40
Clive, I have no idea, but very often Mr. Herger suggest running "the latest and greatest" as the first step ;)

I don't use Max2Play much these days, mostly run a mac-mini as the primary server and play with piCorePlayer on occasion, prefer plain Raspbian on a pi at the mo.:)

Ronnie

Cheers Ronnie.
How easy is it to set up plain Raspbian on a PI?


Sent from my iPhone using Tapatalk Pro

Man in a van
2018-03-13, 01:19
Not too difficult, it's like riding a bike, fall off a few times, wobble along, Tour de France:rolleyes::) (helps to have a wheezy chest, allegedly:confused:).

I'll send you details in a day or so, save clutter on this thread.

Ronnie.

mherger
2018-03-13, 02:39
> Think it's updated. What do you think it was? Because it started working
> after the Max2play update.

I think there's a problem with older max2play version's glibc.



--

Michael

MrClive
2018-03-13, 05:25
> Think it's updated. What do you think it was? Because it started working
> after the Max2play update.

I think there's a problem with older max2play version's glibc.



--

Michael

Thanks Michael

alc23
2018-09-06, 22:41
Hi Michael - I recently started receiving this error whenever playing FLAC files with sample rates greater than 48khz.

The problems started occurring after updating to Logitech Media Server Version: 7.9.2 - 1536072399 @ Tue Sep 4 17:08:12. No errors in server.log.

I have worked around this by overwriting the files in C:\Program Files (x86)\Squeezebox\server\Bin\MSWin32-x86-multi-thread with older versions (from 2015).

I'm happy that things are working for me again, but thought I should let you know.
--Adam

mherger
2018-09-07, 02:23
> Hi Michael - I recently started receiving this error whenever playing
> FLAC files with sample rates greater than 48khz.
>
> The problems started occurring after updating to Logitech Media Server
> Version: 7.9.2 - 1536072399 @ Tue Sep 4 17:08:12. No errors in
> server.log.
>
> I have worked around this by overwriting the files in C:\Program Files
> (x86)\Squeezebox\server\Bin\MSWin32-x86-multi-thread with older versions
> (from 2015).

Which particular file? Would going back to 7.9.1 work, too?

We did indeed update flac/sox last week. Hopefully they didn't break
this...

What does server.log say when you try to play such a file? You'll
probably have to enable logging for player.source to get more information.

--

Michael

alc23
2018-09-07, 21:21
Hi Michael - I've reverted to the 7.9.2 files. Here's the "Info" level logging on Player.Source when I play a 24/176 track:


[18-09-08 14:18:57.2622] Slim::Player::Song::open (364) file:////MUSICSERVER/Music/Library/Albums/Creedence%20Clearwater%20Revival%20-%20Green%20River%20[1969%20album]/03%20-%20Tombstone%20Shadow.flac
[18-09-08 14:18:57.2639] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2653] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2655] Slim::Player::Song::open (388) seek=false time=0 canSeek=2
[18-09-08 14:18:57.2665] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2669] Slim::Player::Song::open (409) Transcoder: streamMode=F, streamformat=flc
[18-09-08 14:18:57.2680] Slim::Player::TranscodingHelper::tokenizeConvertCo mmand2 (617) Using command for conversion: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -dcs -- "\\MUSICSERVER\Music\Library\Albums\Creedence Clearwater Revival - Green River [1969 album]\03 - Tombstone Shadow.flac" | "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe" -q -t wav - -t flac -C 0 -r 88200 - |
[18-09-08 14:18:57.2684] Slim::Player::Song::open (559) Tokenized command: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -dcs -- "\\MUSICSERVER\Music\Library\Albums\Creedence Clearwater Revival - Green River [1969 album]\03 - Tombstone Shadow.flac" | "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe" -q -t wav - -t flac -C 0 -r 88200 - |
[18-09-08 14:18:57.3159] Slim::Player::StreamingController::_Stream (1265) 00:04:20:23:7e:a6: stream
[18-09-08 14:18:57.3224] Slim::Player::StreamingController::_Stream (1302) Song queue is now 0
[18-09-08 14:18:57.3227] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state BUFFERING
[18-09-08 14:18:57.3229] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state STREAMING
[18-09-08 14:18:57.4465] Slim::Player::Source::_readNextChunk (373) end of file or error on socket, song pos: 3966515
[18-09-08 14:18:57.4468] Slim::Player::Source::_readNextChunk (378) 00:04:20:23:7e:a6 mark end of stream
[18-09-08 14:18:57.4472] Slim::Player::Source::_readNextChunk (386) Didn't stream any bytes for this song; mark it as failed
[18-09-08 14:18:57.4474] Slim::Player::StreamingController::playerStreaming Failed (2228) 00:04:20:23:7e:a6
[18-09-08 14:18:57.4477] Slim::Player::StreamingController::_playersMessage (789) Problem: Can't open file for:: file:////MUSICSERVER/Music/Library/Albums/Creedence%20Clearwater%20Revival%20-%20Green%20River%20[1969%20album]/03%20-%20Tombstone%20Shadow.flac
[18-09-08 14:18:57.4543] Slim::Player::StreamingController::_Stop (603) Song queue is now 0
[18-09-08 14:18:57.4555] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state STOPPED
[18-09-08 14:18:57.4559] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state IDLE
[18-09-08 14:18:57.4567] Slim::Player::StreamingController::_willRetry (1391) no retry data
[18-09-08 14:18:57.4573] Slim::Player::StreamingController::nextsong (882) The next song is number 0, was 0
[18-09-08 14:18:58.0572] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:58.0578] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -

alc23
2018-09-07, 21:29
Would going back to 7.9.1 work, too?


I was on 7.9.1 prior to updating to 7.9.2 and had no problems playing hi-rez files. I no longer have the 7.9.1 flac/sox executable easily available to test, without reverting my entire install.

bpa
2018-09-08, 04:05
As a sanity check - make sure both the flac and sox exe actually run on your system.

In a command prompt window try going (i.e. CD) to the C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\ directory and then run try running flac.exe and sox.exe - they should eachproduce a banner message.

If these run OK - then it is possible there is an issue with socketwrapper.exe and security software - does any transcoding work onm your system ? (e.g. playing an AAC stream to an older SB3/Boom/Transporter type player)

If they run OK - check if any transcoding works on your system (e.g. playing an AAC stream to an older SB player such as Boom, Receiver, SB3 or Transporter)

slartibartfast
2018-09-08, 04:45
> Hi Michael - I recently started receiving this error whenever playing
> FLAC files with sample rates greater than 48khz.
>
> The problems started occurring after updating to Logitech Media Server
> Version: 7.9.2 - 1536072399 @ Tue Sep 4 17:08:12. No errors in
> server.log.
>
> I have worked around this by overwriting the files in C:\Program Files
> (x86)\Squeezebox\server\Bin\MSWin32-x86-multi-thread with older versions
> (from 2015).

Which particular file? Would going back to 7.9.1 work, too?

We did indeed update flac/sox last week. Hopefully they didn't break
this...

What does server.log say when you try to play such a file? You'll
probably have to enable logging for player.source to get more information.

--

MichaelFWIW I am running the latest 7.9.2 and tried playing a 24 bit 192kHz track (upsampled from 44.1kHz) and it played successfully on a Touch displaying 5074kbps VBR flc in the web UI. The latest FLAC/SOX have not broken things.

Sent from my SM-G900F using Tapatalk

bpa
2018-09-08, 06:04
FWIW I am running the latest 7.9.2 and tried playing a 24 bit 192kHz track (upsampled from 44.1kHz) and it played successfully on a Touch displaying 5074kbps VBR flc in the web UI. The latest FLAC/SOX have not broken things.

The cause could by flac, sox not working on user's system - so there is somehting specific. If flac and sox work on users system the problem could be transcoding system (e.g socketwrapper) as used to be susceptible to security s/w which is why checking transcoding would be helpful.

ralphy
2018-09-08, 06:24
Hi Michael - I've reverted to the 7.9.2 files. Here's the "Info" level logging on Player.Source when I play a 24/176 track:


[18-09-08 14:18:57.2622] Slim::Player::Song::open (364) file:////MUSICSERVER/Music/Library/Albums/Creedence%20Clearwater%20Revival%20-%20Green%20River%20[1969%20album]/03%20-%20Tombstone%20Shadow.flac
[18-09-08 14:18:57.2639] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2653] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2655] Slim::Player::Song::open (388) seek=false time=0 canSeek=2
[18-09-08 14:18:57.2665] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:57.2669] Slim::Player::Song::open (409) Transcoder: streamMode=F, streamformat=flc
[18-09-08 14:18:57.2680] Slim::Player::TranscodingHelper::tokenizeConvertCo mmand2 (617) Using command for conversion: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -dcs -- "\\MUSICSERVER\Music\Library\Albums\Creedence Clearwater Revival - Green River [1969 album]\03 - Tombstone Shadow.flac" | "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe" -q -t wav - -t flac -C 0 -r 88200 - |
[18-09-08 14:18:57.2684] Slim::Player::Song::open (559) Tokenized command: "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -dcs -- "\\MUSICSERVER\Music\Library\Albums\Creedence Clearwater Revival - Green River [1969 album]\03 - Tombstone Shadow.flac" | "C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe" -q -t wav - -t flac -C 0 -r 88200 - |
[18-09-08 14:18:57.3159] Slim::Player::StreamingController::_Stream (1265) 00:04:20:23:7e:a6: stream
[18-09-08 14:18:57.3224] Slim::Player::StreamingController::_Stream (1302) Song queue is now 0
[18-09-08 14:18:57.3227] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state BUFFERING
[18-09-08 14:18:57.3229] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state STREAMING
[18-09-08 14:18:57.4465] Slim::Player::Source::_readNextChunk (373) end of file or error on socket, song pos: 3966515
[18-09-08 14:18:57.4468] Slim::Player::Source::_readNextChunk (378) 00:04:20:23:7e:a6 mark end of stream
[18-09-08 14:18:57.4472] Slim::Player::Source::_readNextChunk (386) Didn't stream any bytes for this song; mark it as failed
[18-09-08 14:18:57.4474] Slim::Player::StreamingController::playerStreaming Failed (2228) 00:04:20:23:7e:a6
[18-09-08 14:18:57.4477] Slim::Player::StreamingController::_playersMessage (789) Problem: Can't open file for:: file:////MUSICSERVER/Music/Library/Albums/Creedence%20Clearwater%20Revival%20-%20Green%20River%20[1969%20album]/03%20-%20Tombstone%20Shadow.flac
[18-09-08 14:18:57.4543] Slim::Player::StreamingController::_Stop (603) Song queue is now 0
[18-09-08 14:18:57.4555] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state STOPPED
[18-09-08 14:18:57.4559] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state IDLE
[18-09-08 14:18:57.4567] Slim::Player::StreamingController::_willRetry (1391) no retry data
[18-09-08 14:18:57.4573] Slim::Player::StreamingController::nextsong (882) The next song is number 0, was 0
[18-09-08 14:18:58.0572] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[18-09-08 14:18:58.0578] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
From a command prompt what output if any to do get when you run?

"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe"

and

"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe"

Could you provide access to the file 03 - Tombstone Shadow.flac for me to test?

Thanks.

slartibartfast
2018-09-08, 06:36
The cause could by flac, sox not working on user's system - so there is somehting specific. If flac and sox work on users system the problem could be transcoding system (e.g socketwrapper) as used to be susceptible to security s/w which is why checking transcoding would be helpful.Yes it must be specific to his system.

Sent from my SM-G900F using Tapatalk

alc23
2018-09-08, 18:45
From a command prompt what output if any to do get when you run?

"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe"

and

"C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\sox.exe"

Could you provide access to the file 03 - Tombstone Shadow.flac for me to test?


I can run both FLAC and SOX from the command prompt and they output their expected banner and usage instructions.

File is available here (167mb):
https://1drv.ms/u/s!AuatgRK2Y56bguc6PjSaGFk5lJPatw

alc23
2018-09-08, 18:50
Yes it must be specific to his system.


As further info, my LMS is running on a Windows 10 machine. No third-party security software installed... but Windows Firewall/Defender is enabled.

I didn't have an issue on 7.9.1 or earlier versions.

alc23
2018-09-08, 19:29
In a command prompt window try going (i.e. CD) to the C:\PROGRA~2\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\ directory and then run try running flac.exe and sox.exe - they should eachproduce a banner message.

If these run OK - then it is possible there is an issue with socketwrapper.exe and security software - does any transcoding work onm your system ? (e.g. playing an AAC stream to an older SB3/Boom/Transporter type player)

If they run OK - check if any transcoding works on your system (e.g. playing an AAC stream to an older SB player such as Boom, Receiver, SB3 or Transporter)

Banner messages for FLAC ans SOX are produced at the command prompt.

AAC format streams to my SB Boom OK. I used the stream from http://stream.open.fm/33

There is some stuff in server.log when I play this, not sure if it's expected or not.

[18-09-09 12:23:54.0011] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-wma-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0015] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-wma-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0016] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-wma-boom-*
[18-09-09 12:23:54.0019] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-wma-*-*
[18-09-09 12:23:54.0022] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-ogg-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0023] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-ogg-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0026] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-ogg-boom-*
[18-09-09 12:23:54.0029] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-ogg-*-*
[18-09-09 12:23:54.0030] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-flc-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0033] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-flc-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0035] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-flc-boom-*
[18-09-09 12:23:54.0037] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-flc-*-*
[18-09-09 12:23:54.0039] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if aac-flc-*-* is enabled
[18-09-09 12:23:54.0042] Slim::Player::TranscodingHelper::checkBin (250) enabled
[18-09-09 12:23:54.0043] Slim::Player::TranscodingHelper::checkBin (252) Found command: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes -
[18-09-09 12:23:54.0046] Slim::Player::TranscodingHelper::getConvertCommand 2 (373) Rejecting [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - because no available stream mode supported: R
[18-09-09 12:23:54.0049] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-aif-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0050] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-aif-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0053] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-aif-boom-*
[18-09-09 12:23:54.0054] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-aif-*-*
[18-09-09 12:23:54.0057] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-pcm-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0059] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-pcm-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0061] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-pcm-boom-*
[18-09-09 12:23:54.0063] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-pcm-*-*
[18-09-09 12:23:54.0066] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if aac-pcm-*-* is enabled
[18-09-09 12:23:54.0067] Slim::Player::TranscodingHelper::checkBin (250) enabled
[18-09-09 12:23:54.0070] Slim::Player::TranscodingHelper::checkBin (252) Found command: [faad] -q -w -f 2 -b 1 $FILE$
[18-09-09 12:23:54.0071] Slim::Player::TranscodingHelper::getConvertCommand 2 (373) Rejecting [faad] -q -w -f 2 -b 1 $FILE$ because no available stream mode supported: R
[18-09-09 12:23:54.0074] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-mp3-boom-00:04:20:1e:30:2d
[18-09-09 12:23:54.0077] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-mp3-*-00:04:20:1e:30:2d
[18-09-09 12:23:54.0078] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-mp3-boom-*
[18-09-09 12:23:54.0081] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: aac-mp3-*-*
[18-09-09 12:23:54.0084] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if aac-mp3-*-* is enabled
[18-09-09 12:23:54.0085] Slim::Player::TranscodingHelper::checkBin (250) enabled
[18-09-09 12:23:54.0088] Slim::Player::TranscodingHelper::checkBin (252) Found command: [faad] -q -w -f 1 $FILE$ | [lame] --silent -q $QUALITY$ $BITRATE$ - -
[18-09-09 12:23:54.0412] Slim::Player::TranscodingHelper::checkBin (275) couldn't find binary for: lame
[18-09-09 12:23:54.0444] Slim::Player::TranscodingHelper::getConvertCommand 2 (443) Error: Didn't find any command matches for type: aac
[18-09-09 12:23:54.2944] Slim::Player::Pipeline::sysread (282) Pipeline doesn't have pending bytes - trying to get some from source
[18-09-09 12:23:54.2948] Slim::Player::Pipeline::sysread (304) Attempting to write to pipeline writer
[18-09-09 12:23:54.2952] Slim::Player::Pipeline::sysread (310) Wrote 4136 bytes to pipeline writer

ralphy
2018-09-09, 06:42
I can run both FLAC and SOX from the command prompt and they output their expected banner and usage instructions.

File is available here (167mb):
https://1drv.ms/u/s!AuatgRK2Y56bguc6PjSaGFk5lJPatw

Thank you. I've downloaded the flac file and can reproduce your issue.

I compiled two windows flac 1.3.2 from git binaries; cygwin (new one included in lms) and visual studio (which is much slower than the cygwin version in testing).
Both of these builds fail with the can't open file: error. when using a UNC file path.

I recompiled the old lms flac 1.2.1 using the same visual studio that I used to build the 1.3.2 above and it works.

This may take some time to troubleshoot.

MrClive
2018-09-09, 07:42
Iím getting the same trying to play Spotify streaming via LMS on a raspberry pi


Sent from my iPhone using Tapatalk Pro

slartibartfast
2018-09-09, 08:16
Iím getting the same trying to play Spotify streaming via LMS on a raspberry pi


Sent from my iPhone using Tapatalk ProWhat have you changed since you got it working the last time you had the problem?

Sent from my SM-G900F using Tapatalk

MrClive
2018-09-09, 09:45
What have you changed since you got it working the last time you had the problem?

Sent from my SM-G900F using Tapatalk

Iíve not changed anything. The only thing I have noticed is that the clock is an hour early on the Boom. Iíll log in and RE boot when Iím home.


Sent from my iPhone using Tapatalk Pro

slartibartfast
2018-09-09, 09:46
Iíve not changed anything. The only thing I have noticed is that the clock is an hour early on the Boom. Iíll log in and RE boot when Iím home.


Sent from my iPhone using Tapatalk ProI would probably update LMS and Spotty as well.

Sent from my SM-G900F using Tapatalk

ralphy
2018-09-09, 11:53
I can run both FLAC and SOX from the command prompt and they output their expected banner and usage instructions.

File is available here (167mb):
https://1drv.ms/u/s!AuatgRK2Y56bguc6PjSaGFk5lJPatw


Thank you. I've downloaded the flac file and can reproduce your issue.

I compiled two windows flac 1.3.2 from git binaries; cygwin (new one included in lms) and visual studio (which is much slower than the cygwin version in testing).
Both of these builds fail with the can't open file: error. when using a UNC file path.

I recompiled the old lms flac 1.2.1 using the same visual studio that I used to build the 1.3.2 above and it works.

This may take some time to troubleshoot.

So there was a bug in the flac windows only unicode handling (https://github.com/xiph/flac/commit/a8e9857e389b2f5fb73a0148bb8238a2d8ada090) which is used for UNC paths and was fixed after the git snapshot we used.

Please update to the latest LMS 7.9.2 and then replace flac.exe with the one in this zip file (https://www.dropbox.com/s/zrc0t31avpeuypf/flac.zip?dl=1) and report back whether it works or not.

Thanks,

alc23
2018-09-09, 18:03
Please update to the latest LMS 7.9.2 and then replace flac.exe with the one in this zip file (https://www.dropbox.com/s/zrc0t31avpeuypf/flac.zip?dl=1) and report back whether it works or not.


Hi Ralphy - I've updated to Logitech Media Server Version: 7.9.2 - 1536072399 @ Fri Sep 7 and replaced FLAC.exe as instructed. Hi-rez playback works! I have tried a variety of different files/sample rates that I was having trouble with before and haven't had any issues.

Thanks for your prompt investigation and fix of this.
--Adam

ralphy
2018-09-16, 05:16
Hi Ralphy - I've updated to Logitech Media Server Version: 7.9.2 - 1536072399 @ Fri Sep 7 and replaced FLAC.exe as instructed. Hi-rez playback works! I have tried a variety of different files/sample rates that I was having trouble with before and haven't had any issues.

Thanks for your prompt investigation and fix of this.
--Adam

The fixed windows flac.exe is now included with LMS 7.9.2 builds newer than September 11, 2018.