[SlimDevices: Plugins] In praise of Group Players plugin

2021-05-09 Thread benh


I've been in the Squeezebox/LMS ecosystem for more than 15 years, and it
would not be an exaggeration to say that this system has been integral
to my life for much of that time.  It is practically the ONLY way I
listen to music at home.

I've always liked having music synchronized throughout the house but
HATED the Synchronize function, as it always seemed to find a way to
work in the least intuitive and helpful way for me.  So I rarely used
it, and I'd just have different things playing in different parts of the
house, which worked, if not optimally.

I became aware of the Group Players plugin not long ago, and gave it a
try.  

In a similar way to how my first Squeezebox fundamentally changed the
way I listen to music, Group Players has fundamentally changed how I, my
house, and my music interact.  This plugin went from unknown to vital in
less than a week.  Thanks, Philippe.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114549

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-25 Thread benh


By amazing coincidence, that error above is from 1-13_Last_Kiss.m4a. 
The error plus the bit immediately after, when the session ends:

[alac @ 0x56149523bc40] invalid samples per frame: 0/s speed=80.7x
Error while decoding stream #0:0: Invalid data found when processing
input
size=5876kB time=00:06:07.15 bitrate= 131.1kbits/s speed=80.9x
video:0kB audio:5768kB subtitle:0kB other streams:0kB global headers:0kB
muxing overhead: 1.874181%
[aac @ 0x561495222680] Qavg: 188.669


By the end of this whole deal, the last 30 seconds of that song will be
etched on my bones.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-25 Thread benh


I understand what you are saying, and your example is helpful.

I think perhaps I have been misunderstanding the nature of this
particular encoding error.  I had understood it to be an error in
encoding some audio at an arbitrary and random timecode.  What I now
speculate may be happening is that the error is at a fixed place near
the end of the file, perhaps in some kind of footer.  

Otherwise, I would expect some variability in when the file stops
playing, and it is very consistent.

I haven't been able to find adequate documentation of the ALAC format
yet to see how a file is structured, so I haven't really validated this
idea.

Some more information:

ffmpeg gives this error when transcoding one of the example files to
aac:
[alac @ 0x55794a5e1c40] invalid samples per frame: 0/s speed=79.3x
Error while decoding stream #0:0: Invalid data found when processing
input

Of note, I can transcode the original source flac with ffmpeg with no
issues, so the error seems to have been introduced when I used XLD to
transcode from FLAC to ALAC.

What's **really** interesting is that I can use ffmpeg to transcode the
original source FLAC and the XLD-transcoded (with error) ALAC both to
aac, and end up with *identical* files, despite the error thrown by
ffmpeg when transcoding the ALAC file.  That is to say, an md5 hash of
each outputted file is identical.  Both play to the very last note
outside of LMS, and I haven't yet tried them within LMS.

This suggests to me that the payload of the misencoded file is intact
and that the error is in a container structure.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-24 Thread benh


philippe_44 wrote: 
> - 10 seconds is likely related to the size of the output buffer. The
> error happens at the very last *decoded* bytes and you have always ~10s
> un-played audio in the buffer, likely - that explains regularity
> 
> - faad is always implicated when you use a SB3 in the mix as they don't
> do mp4/aac. In that case, LMS does the transcoding to flac
> 
> - you can take every file that ends early and try to run it through
> ffmpeg to verify

I'm still not quite understanding.  If I have 10 seconds of output
buffer at any given time, then I always have between 0 and 10 seconds of
decoded ready to play audio in that buffer.  It fluctuates, because
buffer.  I imagine that the buffer can fill much faster than it can
drain, because decoding can happen faster than real time, and because
buffer.


- When I start playing a file, that buffer fills, bits are streaming,
  and eventually audio comes out my speakers.
- When the decoder encounters a fatal error, the buffer stops being
  replenished and drains, and music stops coming out of my speakers
  about 10 seconds later.
- If that fatal error is at, say, 30 seconds into the file, I'd expect
  audio to be interrupted at about the 30 second mark.
- If that fatal error is at, say, the last 10 seconds of the file, I'd
  expect audio to be interrupted at about the last 10 seconds mark.
  


That is because I should hear the audio that precedes the fatal error,
and nothing after that point.

I don't understand how a fatal error at the :30 mark of a 2:00 song
would result in 1:50 of the song playing, rather than :30 of the song
playing.

Put another way, if I were having errors with these files at various
time markers, I'd immediately suspect the files.  And indeed, I did
until I started seeing that they were all stopping 10 seconds before the
actual end of the song.  And if the files exhibited the problem in all
playback circumstances, I'd definitely suspect the files.  But I know I
can fully play these files back in iTunes and when sent to a singular
player.

The consistency of when the error occurs (10 seconds before the end of
variable length songs) is notable, as is the fact that when playing
these files to a singular player, I hear the full song to completion. 
And given that these are files from different sources and encoded at
different times with different software...it's a lot of coincidence.

I'm not trying to say that the files are NOT incorrectly encoded. 
Rather that I don't understand why incorrect encoding results in these
files playing to completion in one circumstance, and not in another. 
I'd expect that if the file can be successfully decoded to completion in
one circumstance, it should be able to be successfully decoded to
completion in another circumstance.

I do think I'll run them all through ffmpeg and see what I can glean
from that.

Thanks again for all of your help with this weird situation.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


I'm also not sure there's not a bug in the Group Player and
Synchronization code.   I just went to play some stuff, and found my
Bedroom player synched with "Upstairs" which is a Group Player
consisting of Bedroom and Library.  I definitively did not do that.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


I really appreciate your efforts to figure this out.

I'm completely willing to believe that a single file is incorrectly
encoded.  I am less certain that random files are incorrectly encoded,
and that they are all incorrectly encoded such that the problem occurs
at the exact same point in their variable duration: 10 seconds before
the end.  I do hear audio through the end of the time of the file, and
that audio sounds consistent with the rest of the track, but I take your
point that it just may seem ok.

I'm also not quite sure that faad is implicated, as I haven't been able
to capture faad running during the playback of one of these tracks.

I'm more than willing to dig into the player config if you can help me
understand where to look.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


And just to prove this isn't a Motown problem, here's a pop track with
the same issue.  https://filebin.net/kvjhkgkx3x6yl0dt



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> So back to square one. Player dc:a6:32:f9:a1:eb is unhappy with the
> format of what it receives toward the end. What player is that? Can you
> reconfirm it works normally when played standalone? Can you also upload
> the file creating problem on filebin (or any other sharing solution:
> https://filebin.net/)?

dc:a6:32:f9:a1:eb is "Library".  It is indeed the one that has not liked
these various tracks.  I have just now played the Smokey track to
completion on Library as a standalone player.

https://filebin.net/71a9c48ebrw95tpo contains two tracks that both
exhibit the 10 second problem.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


OK.  I set the plugin.groups logging to debug, deleted that group
player, and created a new one, appending a 2 to the name.

The file played (yay!), but stopped 10 seconds before the end.  So now
this group acts like the others.

I literally created the original group as part of my testing, so I can
say with certainty that it was never manually synchronized to anything
else.

Logs attached.


+---+
|Filename: Smokey.log.newgroup.txt.gz   |
|Download: http://forums.slimdevices.com/attachment.php?attachmentid=34340|
+---+


benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


Nothing illuminating, I'm afraid.  This is, to confirm, an attempt to
play that track to the Radio/Library group that just doesn't play at
all.


Code:

[21-04-23 16:13:17.5988] Slim::Player::StreamingController::play (2120) 
02:00:96:66:70:5e
  [21-04-23 16:13:17.5997] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: Play in STOPPED-IDLE -> 
Slim::Player::StreamingController::_StopGetNext
  [21-04-23 16:13:17.5998] Slim::Player::StreamingController::_eventAction 
(283) params: index => 0 seekdata => undef
  [21-04-23 16:13:17.5999] Slim::Player::StreamingController::_Stop (610) Song 
queue is now 0
  [21-04-23 16:13:17.6000] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state STOPPED
  [21-04-23 16:13:17.6000] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 16:13:17.6004] Slim::Player::Song::new (109) index 0 -> 
file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6005] Slim::Player::Song::new (144) live=13
  [21-04-23 16:13:17.6006] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state TRACKWAIT
  [21-04-23 16:13:17.6006] Slim::Player::Song::DESTROY (154) 
DESTROY(Slim::Player::Song=ARRAY(0x55c037fd0080)) live=12: index=0, 
url=file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6009] Slim::Player::StreamingController::_playersMessage 
(796) Now Playing: file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6025] Slim::Player::Song::getNextSong (223) 
file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6026] Slim::Player::StreamingController::_nextTrackReady 
(744) 02:00:96:66:70:5e: nextTrack will be index 0
  [21-04-23 16:13:17.6027] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: NextTrackReady in STOPPED-TRACKWAIT -> 
Slim::Player::StreamingController::_Stream
  [21-04-23 16:13:17.6027] Slim::Player::StreamingController::_Stream (1210) 
Song queue is now 0
  [21-04-23 16:13:17.6028] Slim::Player::StreamingController::_Stream (1213) 
02:00:96:66:70:5e: preparing to stream song index 0
  [21-04-23 16:13:17.6029] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6031] Slim::Player::CapabilitiesHelper::supportedFormats 
(53) $VAR1 = [
  'Radio and Library'
  ];
  $VAR2 = {};
  [21-04-23 16:13:17.6032] Slim::Player::TranscodingHelper::getConvertCommand2 
(383) $VAR1 = [];
  $VAR1 = [];
  [21-04-23 16:13:17.6033] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Error: Didn't find any command matches for type: mp4
  [21-04-23 16:13:17.6033] Slim::Player::Song::open (384) seek=false time=0 
canSeek=0SEEK_ERROR_TRANSCODED
  [21-04-23 16:13:17.6034] Slim::Player::CapabilitiesHelper::supportedFormats 
(53) $VAR1 = [
  'Radio and Library'
  ];
  $VAR2 = {};
  [21-04-23 16:13:17.6035] Slim::Player::TranscodingHelper::getConvertCommand2 
(383) $VAR1 = [];
  $VAR1 = [];
  [21-04-23 16:13:17.6036] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Error: Didn't find any command matches for type: mp4
  [21-04-23 16:13:17.6037] Slim::Player::Song::open (415) Error: Couldn't 
create command line for mp4 playback for 
[file:///music/Smokey%20Robinson/TearsOfAClown.m4a]
  [21-04-23 16:13:17.6037] Slim::Player::StreamingController::_playersMessage 
(796) Problem: Unable to play file type for:: 
file:///music/Smokey%20Robinson/TearsOfAClown.m4a
  [21-04-23 16:13:17.6039] Slim::Player::StreamingController::_willRetry (1408) 
no retry data
  [21-04-23 16:13:17.6040] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 16:13:17.6041] Slim::Player::StreamingController::nextsong (889) 
The next song is number 0, was 0
  [21-04-23 16:13:17.6041] Slim::Player::StreamingController::_eventAction 
(302) 02:00:96:66:70:5e: NextTrackReady - new state STOPPED-IDLE




I then rebooted the pCP.  Attached is the (slightly different) log from
the next attempt to play that track.  $VAR1 is populated...


+---+
|Filename: pCP-log.txt.gz   |
|Download: http://forums.slimdevices.com/attachment.php?attachmentid=34339|
+---+


benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> Right, so this is the insanity: it means that the players are saying
> that they do not support any common format. When LMS does a sync group,
> it builds a list of supported codecs by retaining the overlapping ones
> from all players and feeds that to the transcoder to find a matching
> rule. The problem is is that there is zero common codecs...
> The "common" codec is done the same way, where it's a static group or a
> group player.
> 
> Now, the sync group of the group player is built on the fly, but it is
> build *before* the transcoding rule search happen, so I have no idea
> what's happening with your system. If you feel adventurous, you can
> change that function in Slim::Player::CapabilitiesHelper
> > > > 
> > 
> > Thanks.  This is fascinating.  I'll make the change to the code and post
> > more logs.
> > 
> > From a software perspective, this should be a very very vanilla LMS
> > install.  I'm running the official Docker version on PhotonOS, and my
> > docker config is nothing out of the ordinary.  I don't recall making any
> > changes to the persistent config.  My docker run is using --net, so the
> > LMS is effectively directly connected to its network.
> > 
> > The problematic player should also be fairly vanilla.  It's a Pi 4B with
> > a Hifiberry Dac2 HD running pCP, over wifi.  My LMS and Players are on
> > different subnets, but the players are running with a -s flag pointing
> > at the IP of my LMS.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


Code:

[21-04-23 14:24:14.2140] Plugins::ShairTunes2W::Plugin::publishPlayer (440) 
avahi-publish-player not in path
  [21-04-23 14:24:14.2140] Plugins::ShairTunes2W::Plugin::publishPlayer (449) 
dns-sd not in path
  [21-04-23 14:24:14.2141] Plugins::ShairTunes2W::Plugin::publishPlayer (458) 
mDNSPublish not in path
  [21-04-23 14:24:14.2142] Plugins::ShairTunes2W::Plugin::publishPlayer (460) 
using built-in helper: 
/config/cache/InstalledPlugins/Plugins/ShairTunes2W/Bin/shairport_helper-x64-linux
  [21-04-23 14:24:14.2172] Plugins::ShairTunes2W::Plugin::addPlayer (346) 
create client Slim::Player::Squeezebox2=ARRAY(0x5632c8793cc0) with proc 
Proc::Background=HASH(0x5632c8d05a50)
  [21-04-23 14:25:01.4139] Slim::Player::StreamingController::play (2120) 
02:00:96:66:70:5e
  [21-04-23 14:25:01.4148] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: Play in STOPPED-IDLE -> 
Slim::Player::StreamingController::_StopGetNext
  [21-04-23 14:25:01.4149] Slim::Player::StreamingController::_eventAction 
(283) params: index => 0 seekdata => undef
  [21-04-23 14:25:01.4150] Slim::Player::StreamingController::_Stop (610) Song 
queue is now 0
  [21-04-23 14:25:01.4151] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state STOPPED
  [21-04-23 14:25:01.4151] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 14:25:01.4155] Slim::Player::Song::new (109) index 0 -> 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4156] Slim::Player::Song::new (144) live=13
  [21-04-23 14:25:01.4157] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state TRACKWAIT
  [21-04-23 14:25:01.4158] Slim::Player::Song::DESTROY (154) 
DESTROY(Slim::Player::Song=ARRAY(0x5632c6229550)) live=12: index=0, 
url=file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4161] Slim::Player::StreamingController::_playersMessage 
(796) Now Playing: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4177] Slim::Player::Song::getNextSong (223) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4178] Slim::Player::StreamingController::_nextTrackReady 
(744) 02:00:96:66:70:5e: nextTrack will be index 0
  [21-04-23 14:25:01.4179] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: NextTrackReady in STOPPED-TRACKWAIT -> 
Slim::Player::StreamingController::_Stream
  [21-04-23 14:25:01.4179] Slim::Player::StreamingController::_Stream (1210) 
Song queue is now 0
  [21-04-23 14:25:01.4180] Slim::Player::StreamingController::_Stream (1213) 
02:00:96:66:70:5e: preparing to stream song index 0
  [21-04-23 14:25:01.4181] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4183] Slim::Player::TranscodingHelper::getConvertCommand2 
(383) $VAR1 = [];
  $VAR1 = [];
  [21-04-23 14:25:01.4184] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Error: Didn't find any command matches for type: mp4
  [21-04-23 14:25:01.4184] Slim::Player::Song::open (384) seek=false time=0 
canSeek=0SEEK_ERROR_TRANSCODED
  [21-04-23 14:25:01.4186] Slim::Player::TranscodingHelper::getConvertCommand2 
(383) $VAR1 = [];
  $VAR1 = [];
  [21-04-23 14:25:01.4186] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Error: Didn't find any command matches for type: mp4
  [21-04-23 14:25:01.4187] Slim::Player::Song::open (415) Error: Couldn't 
create command line for mp4 playback for 
[file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a]
  [21-04-23 14:25:01.4188] Slim::Player::StreamingController::_playersMessage 
(796) Problem: Unable to play file type for:: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 14:25:01.4190] Slim::Player::StreamingController::_willRetry (1408) 
no retry data
  [21-04-23 14:25:01.4190] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 14:25:01.4191] Slim::Player::StreamingController::nextsong (889) 
The next song is number 0, was 0
  [21-04-23 14:25:01.4192] Slim::Player::StreamingController::_eventAction 
(302) 02:00:96:66:70:5e: NextTrackReady - new state STOPPED-IDLE




benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> Would mind attaching your convert.conf and custom-convert.conf?

Here is convert.conf from the Docker container.  I named it
convert.conf.docker for clarity.

I do not have a custom-convert.conf that I'm aware of, with the
exception of these:

% find ./ -name custom-convert.conf -print
./config/cache/InstalledPlugins/Plugins/DSDPlayer/helper/src/doc/custom-convert.conf
./config/cache/InstalledPlugins/Plugins/ShairTunes2W/custom-convert.conf


+---+
|Filename: convert.conf.docker.gz   |
|Download: http://forums.slimdevices.com/attachment.php?attachmentid=34338|
+---+


benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> BTW, on this combination
> 
> Does it fail as well if the players are statically synchronized?

When the players are statically synchronized, the track plays and then
stops at 10 seconds before the end.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


OK, here's that log.  This worked, in that the song played through to
completion.

I don't seem to have a convert.conf or custom-convert.conf anywhere in
the normally-accessible portion of my Docker setup, so I'll have to
export it from the Docker image.  Be a bit for that.


+---+
|Filename: Smokey.log.txt.gz|
|Download: http://forums.slimdevices.com/attachment.php?attachmentid=34337|
+---+


benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> Can you take that log but just with the pCP that causes the issue when
> synced with the SB radio? (the case were it does not play at all). I'm
> baffled by the fact that LMS says that it can't find a transcoding rule
> for an mp4 *file*, and knowing that you're using 8.1.2. 
> 
> If it works, then that implies that when sync'ing, as stdin is required,
> then LMS does not find a solution for playing a *file* on two players
> supporting mp4, which it does. I just did the test on an exact same
> setup.

To clarify, do you want a log from a play attempt from JUST the pCP that
is a problem, or from a group containing the Radio and that pCP?  The
above log is the latter.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> But still, log in debug should contain more. Are you sure it was the
> full log and debug?

I agree that it should.  I can say definitively that player.source is in
debug.  If there are other modules I should set debug on, happy to do
so.  These logs are coming from the web UI, from
/config/logs/server.log.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


bpa wrote: 
> What you thought was "relevant".
> Sometime what is missing is more important - and only context can show
> what is missing.

You got it.  Here is a more complete snippet of that log, with several
lines of previous state and future state included.  This is everything
that was logged.


Code:

[21-04-23 12:37:05.6266] Slim::Player::StreamingController::_eventAction 
(272) dc:a6:32:d2:86:3d: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:10.0061] Slim::Player::StreamingController::_eventAction 
(272) 00:04:20:12:d8:af: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:10.0071] Slim::Player::StreamingController::_eventAction 
(272) dc:a6:32:f9:a1:eb: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:10.0073] Slim::Player::StreamingController::_eventAction 
(272) dc:a6:32:d2:86:3d: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:10.0079] Slim::Player::StreamingController::_eventAction 
(272) 00:04:20:12:d8:af: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:10.0161] Slim::Player::StreamingController::_eventAction 
(272) 00:04:20:2b:4b:93: StatusHeartbeat in STOPPED-IDLE -> 
Slim::Player::StreamingController::_NoOp
  [21-04-23 12:37:13.2231] Slim::Player::StreamingController::play (2120) 
02:00:96:66:70:5e
  [21-04-23 12:37:13.2233] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: Play in STOPPED-IDLE -> 
Slim::Player::StreamingController::_StopGetNext
  [21-04-23 12:37:13.2233] Slim::Player::StreamingController::_eventAction 
(283) params: seekdata => undef index => 0
  [21-04-23 12:37:13.2234] Slim::Player::StreamingController::_Stop (610) Song 
queue is now 0
  [21-04-23 12:37:13.2235] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state STOPPED
  [21-04-23 12:37:13.2235] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 12:37:13.2330] Slim::Player::Song::new (109) index 0 -> 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2331] Slim::Player::Song::new (144) live=86
  [21-04-23 12:37:13.2332] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state TRACKWAIT
  [21-04-23 12:37:13.2332] Slim::Player::Song::DESTROY (154) 
DESTROY(Slim::Player::Song=ARRAY(0x55b29ac0bad0)) live=85: index=0, 
url=file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2335] Slim::Player::StreamingController::_playersMessage 
(796) Now Playing: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2347] Slim::Player::Song::getNextSong (223) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2348] Slim::Player::StreamingController::_nextTrackReady 
(744) 02:00:96:66:70:5e: nextTrack will be index 0
  [21-04-23 12:37:13.2348] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: NextTrackReady in STOPPED-TRACKWAIT -> 
Slim::Player::StreamingController::_Stream
  [21-04-23 12:37:13.2349] Slim::Player::StreamingController::_Stream (1210) 
Song queue is now 0
  [21-04-23 12:37:13.2350] Slim::Player::StreamingController::_Stream (1213) 
02:00:96:66:70:5e: preparing to stream song index 0
  [21-04-23 12:37:13.2350] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2353] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 12:37:13.2353] Slim::Player::Song::open (384) seek=false time=0 
canSeek=0SEEK_ERROR_TRANSCODED
  [21-04-23 12:37:13.2354] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 12:37:13.2355] Slim::Player::Song::open (415) Error: Couldn't 
create command line for mp4 playback for 
[file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a]
  [21-04-23 12:37:13.2356] Slim::Player::StreamingController::_playersMessage 
(796) Problem: Unable to play file type for:: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 12:37:13.2358] Slim::Player::StreamingController::_willRetry (1408) 
no retry data
  [21-04-23 12:37:13.2358] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 12:37:13.2359] 

Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> This is interesting & strange ... can you put that loglevel to debug. It
> will be verbose but will give a better idea of what the rule matcher is
> trying to do
> 
> [edit]: sorry, I saw it's debug now but I was expecting to see more. Do
> you have a modified convert.conf or custom-convert.conf?

Nope, those are bone stock, as far as I can recall.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


bpa wrote: 
> This log looks very short. Lines look missing. Did you enable
> player.source to INFO ?
> 
> What are the LMS MPEG4 settings in WebUI Settings/Advanced/Filetype

LMS MPEG4 Settings are:


Code:

MPEG-4  
  
  AAC   (I, F)  Native
  FLAC  (F) faad/flac
  MP3   (F) faad/lame
  PCM   (F) faad




Well, it is short, because I only cut and pasted the contiguous section
of log that was relevant to this transaction.  Player pretty much goes
from idle to nope to idle.  

Here's a (slightly) lengthier contiguous snippet from a separate
attempt. This is a continuous segment of log from start to end.  I have
the leg level set to debug now, not info.


Code:

[21-04-23 11:20:59.3563] Slim::Player::StreamingController::play (2120) 
02:00:96:66:70:5e
  [21-04-23 11:20:59.3565] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: Play in STOPPED-IDLE -> 
Slim::Player::StreamingController::_StopGetNext
  [21-04-23 11:20:59.3567] Slim::Player::StreamingController::_eventAction 
(283) params: seekdata => undef index => 0
  [21-04-23 11:20:59.3568] Slim::Player::StreamingController::_Stop (610) Song 
queue is now 0
  [21-04-23 11:20:59.3568] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state STOPPED
  [21-04-23 11:20:59.3569] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 11:20:59.3572] Slim::Player::Song::new (109) index 0 -> 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3574] Slim::Player::Song::new (144) live=80
  [21-04-23 11:20:59.3574] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state TRACKWAIT
  [21-04-23 11:20:59.3575] Slim::Player::Song::DESTROY (154) 
DESTROY(Slim::Player::Song=ARRAY(0x55b29b0d26e8)) live=79: index=0, 
url=file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3578] Slim::Player::StreamingController::_playersMessage 
(796) Now Playing: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3591] Slim::Player::Song::getNextSong (223) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3592] Slim::Player::StreamingController::_nextTrackReady 
(744) 02:00:96:66:70:5e: nextTrack will be index 0
  [21-04-23 11:20:59.3595] Slim::Player::StreamingController::_eventAction 
(272) 02:00:96:66:70:5e: NextTrackReady in STOPPED-TRACKWAIT -> 
Slim::Player::StreamingController::_Stream
  [21-04-23 11:20:59.3596] Slim::Player::StreamingController::_Stream (1210) 
Song queue is now 0
  [21-04-23 11:20:59.3597] Slim::Player::StreamingController::_Stream (1213) 
02:00:96:66:70:5e: preparing to stream song index 0
  [21-04-23 11:20:59.3597] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3600] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 11:20:59.3600] Slim::Player::Song::open (384) seek=false time=0 
canSeek=0SEEK_ERROR_TRANSCODED
  [21-04-23 11:20:59.3601] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 11:20:59.3602] Slim::Player::Song::open (415) Error: Couldn't 
create command line for mp4 playback for 
[file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a]
  [21-04-23 11:20:59.3603] Slim::Player::StreamingController::_playersMessage 
(796) Problem: Unable to play file type for:: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:20:59.3605] Slim::Player::StreamingController::_willRetry (1408) 
no retry data
  [21-04-23 11:20:59.3605] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 11:20:59.3606] Slim::Player::StreamingController::nextsong (889) 
The next song is number 0, was 0
  [21-04-23 11:20:59.3607] Slim::Player::StreamingController::_eventAction 
(302) 02:00:96:66:70:5e: NextTrackReady - new state STOPPED-IDLE




benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


So here is something interesting.  I have found a combination of players
that don't work AT ALL.

I went through and created various group players in various combinations
to see if there was a common player factor.

Two pCP/SL: 10 Second issue
SB Radio and BOTH pCP/SL: 10 Second issue

SB3 and BOTH pCP/SL: No issue
SB3 and Bedroom pCP/SL: No issue
SB3 and Library pCP/SL: No issue
SB Radio and Bedroom pCP/SL: No issue

SB Radio and Library pCP/SL: Track won't even start playing.  Here's the
debug from that:


Code:

[21-04-23 11:08:45.4894] Slim::Player::StreamingController::_Stream (1213) 
02:00:96:66:70:5e: preparing to stream song index 0
  [21-04-23 11:08:45.4895] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:08:45.4897] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 11:08:45.4898] Slim::Player::Song::open (384) seek=false time=0 
canSeek=0SEEK_ERROR_TRANSCODED
  [21-04-23 11:08:45.4899] Slim::Player::TranscodingHelper::getConvertCommand2 
(485) Error: Didn't find any command matches for type: mp4
  [21-04-23 11:08:45.4899] Slim::Player::Song::open (415) Error: Couldn't 
create command line for mp4 playback for 
[file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a]
  [21-04-23 11:08:45.4900] Slim::Player::StreamingController::_playersMessage 
(796) Problem: Unable to play file type for:: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 11:08:45.4902] Slim::Player::StreamingController::_willRetry (1408) 
no retry data
  [21-04-23 11:08:45.4903] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 11:08:45.4903] Slim::Player::StreamingController::nextsong (889) 
The next song is number 0, was 0




benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


philippe_44 wrote: 
> If it happens only with groups, have you checked all individual players
> with the same file one by one? Are they all the exact same squeezelite
> version? Other than that, can you upload somewhere one problematic file?

It happens with synchronized players as well as groups.  I haven't
checked every single individual player, but I can reliably report that
it does not happen when playing to either of two PiCorePlayers both
running Squeezelite v1.9.9-1372-pCP, nor to an SB3 running 137, nor an
SB Radio running 8.0.1-r16844.  I can upload a file, where would you
like it?



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


I don't think faad is running/being called.  I didn't see it running at
any point during playing of this file.

Here's what faad says:


Code:

root@2cc3948a001f:/lms/Bin/x86_64-linux# ./faad
  *** Ahead Software MPEG-4 AAC Decoder V2.7 **
  
  Patched for Squeezebox Server:
  * ALAC decoder integrated
  * Seeking support with -j and -e switches
  * STDIN support
  * utgg win32 STDOUT patch
  * Source at https://github.com/ralph-irving/faad2
  
  Build: Sep 15 2020
  Copyright 2002-2004: Ahead Software AG
  http://www.audiocoding.com
  Floating point version
  
  This program is free software; you can redistribute it and/or modify
  it under the terms of the GNU General Public License.
  
  **



There wasn't much in the log for a single play, so it is included here
as a CODE block rather than a zipped text file:


Code:

[21-04-23 10:22:53.9404] Slim::Player::StreamingController::skip (2127) 
02:00:1f:a7:3a:73
  [21-04-23 10:22:53.9408] Slim::Player::StreamingController::_Stop (610) Song 
queue is now 5
  [21-04-23 10:22:53.9419] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state STOPPED
  [21-04-23 10:22:53.9420] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state IDLE
  [21-04-23 10:22:53.9421] Slim::Player::StreamingController::nextsong (889) 
The next song is number 6, was 5
  [21-04-23 10:22:53.9519] Slim::Player::Song::new (109) index 6 -> 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 10:22:53.9520] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state TRACKWAIT
  [21-04-23 10:22:53.9523] Slim::Player::StreamingController::_playersMessage 
(796) Now Playing: 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 10:22:53.9534] Slim::Player::Song::getNextSong (223) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 10:22:53.9535] Slim::Player::StreamingController::_nextTrackReady 
(744) 02:00:1f:a7:3a:73: nextTrack will be index 6
  [21-04-23 10:22:53.9536] Slim::Player::StreamingController::_Stream (1210) 
Song queue is now 6
  [21-04-23 10:22:53.9536] Slim::Player::StreamingController::_Stream (1213) 
02:00:1f:a7:3a:73: preparing to stream song index 6
  [21-04-23 10:22:53.9537] Slim::Player::Song::open (360) 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 10:22:53.9540] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Matched: mp4->aac via: -
  [21-04-23 10:22:53.9541] Slim::Player::Song::open (384) seek=false time=0 
canSeek=1
  [21-04-23 10:22:53.9543] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Matched: mp4->aac via: -
  [21-04-23 10:22:53.9543] Slim::Player::Song::open (414) Transcoder: 
streamMode=I, streamformat=aac
  [21-04-23 10:22:53.9544] Slim::Player::Song::open (470) Opening stream (no 
direct streaming) using Slim::Player::Protocols::File 
[file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a]
  [21-04-23 10:22:53.9545] Slim::Player::Protocols::File::open (80) duration: 
[179.443] size: [2877931] endian [] offset: [0] for 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a
  [21-04-23 10:22:53.9546] Slim::Player::Protocols::File::open (97) Opening 
file /music/Smokey Robinson & The Miracles/Solid Gold Soul 1970/01 The Tears Of 
A Clown.m4a
  [21-04-23 10:22:53.9569] Slim::Player::Protocols::File::open (189) Seeking in 
0 into /music/Smokey Robinson & The Miracles/Solid Gold Soul 1970/01 The Tears 
Of A Clown.m4a
  [21-04-23 10:22:53.9570] Slim::Player::Song::open (491) URL is a song 
(audio): 
file:///music/Smokey%20Robinson%20&%20The%20Miracles/Solid%20Gold%20Soul%201970/01%20The%20Tears%20Of%20A%20Clown.m4a,
 type=mp4
  [21-04-23 10:22:53.9654] Slim::Player::StreamingController::_Stream (1282) 
02:00:1f:a7:3a:73: stream
  [21-04-23 10:22:53.9656] Slim::Player::StreamingController::_Stream (1282) 
dc:a6:32:f9:a1:eb: stream
  [21-04-23 10:22:53.9660] Slim::Player::StreamingController::_Stream (1282) 
dc:a6:32:d2:86:3d: stream
  [21-04-23 10:22:53.9663] Slim::Player::StreamingController::_Stream (1319) 
Song queue is now 6
  [21-04-23 10:22:53.9664] Slim::Player::StreamingController::_setPlayingState 
(2377) new playing state BUFFERING
  [21-04-23 10:22:53.9665] 
Slim::Player::StreamingController::_setStreamingState (2386) new streaming 
state STREAMING
  [21-04-23 10:22:53.9975] Slim::Player::TranscodingHelper::getConvertCommand2 
(488) Matched: mp4->aac via: -
  

Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


> 
> For the ALAC files, they are transcodes from flac, and are tracks in
> different albums by different artists from different sources. On one
> album, other tracks exhibit the same issue in a grouped player or set of
> synchronized players, but not to a singular player. On another, it's
> just the one track.
> 
> I don't understand

I mean to say that the ALAC files that are all problematic were all
originally FLAC, transcoded to ALAC and then included in my LMS library.
I use iTunes to manage my library, so all of my lossless music is ALAC.
And indeed, I have a LOT of ALAC files that were transcoded from FLAC,
so this isn't unusual, but thought I'd mention it.

I also mean to convey that the problematic tracks are not all from the
same album or artist or source, so there's no obvious commonality in
that regard.  I have an entire album where this last 10 seconds thing
seems to be an issue with every track, and then I have a single track
out of 27 on another album where the problem exhibits.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


bpa wrote: 
> If you are messing with the file. You should check whether the file
> FASTSTART enabled (i.e. streamable) is an issue. 
> 
> A file is "streamable" when the index to audio frames is placed at start
> of the file (i.e Flag FASTSTART is enabled when file is created).
> 
> The following command will make a MPEG4 file streamable.  The audio
> encoding is not changed.
> > 
Code:

  >   > 
  > ffmpeg -i input.m4a -acodec copy -movflags +faststart output-audio.mp4
  > 

> > 

I ran that command on the AAC/M4A file:


Code:

Stream mapping:
  Stream #0:0 -> #0:0 (copy)
  Press [q] to stop, [?] for help
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55ea023e6980] Packet corrupt (stream = 0, dts = 
7911424).
  01 The Tears Of A Clown.m4a: corrupt input packet in stream 0
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55ea023e6980] stream 0, offset 0x2be9ec: partial 
file
  01 The Tears Of A Clown.m4a: Invalid data found when processing input
  [ipod @ 0x55ea023fe6c0] Starting second pass: moving the moov atom to the 
beginning of the file
  size=2792kB time=00:02:59.39 bitrate= 127.5kbits/s speed= 373x
  video:0kB audio:2760kB subtitle:0kB other streams:0kB global headers:0kB 
muxing overhead: 1.131287%



The problem persists after running that command.  I'm not sure if the
errors given indicate an actual problem or not.  

For the ALAC files, they are transcodes from flac, and are tracks in
different albums by different artists from different sources.  On one
album, other tracks exhibit the same issue in a grouped player or set of
synchronized players, but not to a singular player.  On another, it's
just the one track.

When played in a singular player, problematic tracks do something
strange, visually.  In Material Skin while watching the progress bar,
the track hits the 10 to 9 seconds left mark, the audio from the problem
track continues to play, but the progress bar switches to the next
track.  It's as if the player is confused about what's happening.  When
played as a group, when the track hits the 10 to 9 seconds left mark,
the audio skips to the next track at the same time the progress bar
does.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-23 Thread benh


bpa wrote: 
> This error is often (not exclusively) shown when LMS tries to stream an
> "unstreamable" MPEG-4/AAC source for native decoder.   
> Phillippe would be best placed to answer the questions but more
> information will help.
> 
> 1. Are all the players identical ? are they all all squeeze lite or is
> there a Radio, Touch, Jiveplay, etc. in the mix
> 2. Identify a single problem file and give full format/codec details as
> LMS sees it (i.e. drill down on track and show webUI left hand pane)

The players in this Group were all Squeezelite.  Different DACs/HATs,
but Squeezelite.  I just created a Group that includes an SB3 and those
2 Squeezelite players, and the problem did not exhibit.

An example problem file is 16/44.1, 722kbps VBR ALAC.  Another is
16/44.1 877kbps ALAC.  Another is 16/44.1 128k CBR M4A/AAC.

If there are other files in the queue, the next song in the queue begins
to play no problem, but the last 10 seconds of the problem track are
truncated.

As noted above, this seems to occur with synchronized players as well as
grouped players, and the files play fine on a non-grouped/synced
player.

I may try to re-transcode a file from the source flac to alac again and
see what happens.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-22 Thread benh


This also happens if I synchronize players the normal way, it turns out.
So it's probably not a group players problem.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


[SlimDevices: Plugins] Group Players: Songs stop prematurely with an error

2021-04-22 Thread benh


I have begun to have (or just noticed I have) a problem with Group
Players.  Some songs stop playing prematurely, seemingly exactly 10
seconds early, and display an error on the player screen:

Problem: Can't open file for: 

And the following error is logged in LMS:

[21-04-18 22:54:21.6627] Slim::Player::Squeezebox2::statHandler (149)
Error: : Decoder does not
support file format, code 0

I have confirmed that these files play normally using other music
players, such as iTunes.  I can play the SAME file on a single pCP
rather than a group player, and it completes normally with no error
logged.  Files are ALAC or AAC, for the most part.

The problem appears to remain persistent for a given file, in that I can
re-add that song to my queue and the problem will recur while players
are grouped.

It's not every song, just some.  I'm not sure when it began, but I have
errors going back to 4/13.

I'm running the Group Players plugin v0.14.3

I'm running the official Docker version:

Logitech Media Server Version: 8.1.2 - 1618550311 @ Fri Apr 16 08:36:43
CEST 2021
Hostname: 2cc3948a001f
Server IP Address: 192.168.10.40
Server HTTP Port Number: 9000
Operating system: Debian (Docker) - EN - utf8
Platform Architecture: x86_64-linux
Perl Version: 5.24.1 - x86_64-linux-gnu-thread-multi
Audio::Scan: 1.02
IO::Socket::SSL: 2.044
Database Version: DBD::SQLite 1.58 (sqlite 3.22.0)
Total Players Recognized: 6



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=114439

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] iPeng support thread

2021-03-14 Thread benh


pippin wrote: 
> Unfortunately that would make iPeng lose the capability to also use
> things like lock screen controls, remote control (e.g. from a dock) and
> use of the buttons.
> You can also not select the App type at runtime.

Ah, that explains it.  Thanks.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=51929

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] iPeng support thread

2021-03-13 Thread benh


pippin wrote: 
> That's a very messy behavior of some car audio systems.
> Essentially, what the car's system is doing is triggering a play command
> to be sent to the last active audio App.
> In your case, that's iPeng. iOS wakes up iPeng and tells it to play,
> it's like using the lock screen control. If iPeng is still connected to
> your WiFi it does just that.
> 
> The correct workaround would be to keep the car audio system from
> automatically playing music whenever your phone gets connected. Or to
> make sure iPeng is no longer on WiFi.
> A workaround would be to use a different audio App after iPeng, that
> would then play automatically in your car.

Your explanation makes perfect sense, thanks.

It seems to me like iPeng is sometimes a control app, and sometimes
(generally only if playback is in use) a music app.  I wonder if there
is a way to have iOS not treat it like a music app if playback isn't
enabled?



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=51929

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins


Re: [SlimDevices: Plugins] iPeng support thread

2021-03-12 Thread benh


First, I've been a happy iPeng user for many years.  It's a key part of
my music ecosystem and I could not live without it.

I use iPeng at home over Wifi to control my various LMS players. 
Generally, I do not play music from my phone via bluetooth at home.

In the car, I DO use Bluetooth to play music to my car stereo, and my
phone automatically connects to my car.  However, much of the time, the
music that comes up on the car stereo screen is my most recently
iPeng-controlled LMS player's last played song.

What's interesting is that it also *starts that player playing*, since
I'm still connected to my home Wifi.

So I get in my car and start it up, and music begins to play in the
house as I drive away.

I don't have playback enabled on iPeng, but it seems like iPeng is
showing up as a music source in my car, and I wonder if/how I can change
that behavior.



benh's Profile: http://forums.slimdevices.com/member.php?userid=6732
View this thread: http://forums.slimdevices.com/showthread.php?t=51929

___
plugins mailing list
plugins@lists.slimdevices.com
http://lists.slimdevices.com/mailman/listinfo/plugins