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

2021-04-25 Thread bpa


philippe_44 wrote: 
> My observation on the Smokey Robinson file was an error toward the
> *very* end (confirmed by both ffmpeg and my built-in decoder)

Log from ffmpeg of Smokey Robinson track - shows the error in input
stream - not a duration issue or EOF error.

Code:


  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55b5950ad700] stream 0, sample 7725, dts 
179374150
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55b5950ad700] stream 0, sample 7726, dts 
179397370
  01_The_Tears_Of_A_Clown.m4a: corrupt input packet in stream 0
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55b5950ad700] stream 0, sample 7727, dts 
179420590
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x55b5950ad700] stream 0, offset 0x2be9ec: partial 
file
  01_The_Tears_Of_A_Clown.m4a: Invalid data found when processing input
  No more output streams to write to, finishing.
  size=2792kB time=00:02:59.39 bitrate= 127.5kbits/s speed=2.02e+03x
  video:0kB audio:2760kB subtitle:0kB other streams:0kB global headers:0kB 
muxing overhead: 1.131146%
  Input file #0 (01_The_Tears_Of_A_Clown.m4a):
  Input stream #0:0 (audio): 7727 packets read (2826691 bytes); 
  Total: 7727 packets (2826691 bytes) demuxed
  Output file #0 (output.m4a):
  Output stream #0:0 (audio): 7727 packets muxed (2826691 bytes); 
  Total: 7727 packets (2826691 bytes) muxed
  0 frames successfully decoded, 0 decoding errors
  [AVIOContext @ 0x55b5950b50c0] Statistics: 2 seeks, 14 writeouts
  [AVIOContext @ 0x55b5950b6540] Statistics: 2877931 bytes read, 0 seeks
  




bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 bpa


benh wrote: 
> 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.

An expanded version of the conversion is below

Code:


  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x5592f983b6c0] stream 0, sample 3949, dts 
366782404
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x5592f983b6c0] stream 0, sample 3950, dts 
366875283
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x5592f983b6c0] stream 0, sample 3951, dts 
366968163
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x5592f983b6c0] stream 0, sample 3952, dts 
367061043
  [mov,mp4,m4a,3gp,3g2,mj2 @ 0x5592f983b6c0] stream 0, sample 3953, dts 
367153923
  [alac @ 0x5592f98b2bc0] invalid samples per frame: 0
  Error while decoding stream #0:0: Invalid data found when processing input
  [out_0_0 @ 0x5592f9907d40] EOF on sink link out_0_0:default.
  No more output streams to write to, finishing.
  size=5831kB time=00:06:07.15 bitrate= 130.1kbits/s speed=78.8x
  video:0kB audio:5768kB subtitle:0kB other streams:0kB global headers:0kB 
muxing overhead: 1.090229%
  Input file #0 (1-13_Last_Kiss.m4a):
  Input stream #0:0 (audio): 3954 packets read (40275288 bytes); 3953 frames 
decoded (16191488 samples); 
  Input stream #0:1 (video): 1 packets read (87523 bytes); 
  Total: 3955 packets (40362811 bytes) demuxed
  Output file #0 (output.m4a):
  Output stream #0:0 (audio): 15812 frames encoded (16191488 samples); 15813 
packets muxed (5906100 bytes); 
  Total: 15813 packets (5906100 bytes) muxed
  3953 frames successfully decoded, 1 decoding errors
  [AVIOContext @ 0x5592f9898280] Statistics: 2 seeks, 26 writeouts
  [aac @ 0x5592f9896b40] Qavg: 188.669
  [AVIOContext @ 0x5592f98444c0] Statistics: 40387228 bytes read, 0 seeks
  



Note it says zero samples in error - so no audio encoding error.

The "error" was that end of file was found before it was expected.
Below shows a dump of the file header which has a duration of 367246
msec which is 6 mins 17.246 sec.
During conversion the output converted time was 00:06:07.15  - so there
was fewer seconds than expected, so end of file was unexpected.

Using the same MPEG-4 utility, from the header output shows the duration
to be 367178 msec.


Code:


  File:
  major brand:  M4A 
  minor version:0
  compatible brand: M4A 
  compatible brand: mp42
  compatible brand: isom
  fast start:   yes
  
  Movie:
  duration:   367247 ms
  time scale: 44100
  fragments:  no
  
  Found 1 Tracks
  Track 1:
  flags:1 ENABLED
  id:   1
  type: Audio
  duration: 367246 ms
  language: ```
  media:
  sample count: 3954
  timescale:44100
  duration: 16195584 (media timescale units)
  duration: 367247 (ms)
  bitrate (computed): 877.345 Kbps
  Sample Description 0
  Coding:  alac (Apple Lossless Audio)
  Sample Rate: 44100
  Sample Size: 16
  Channels:2
  




bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 philippe_44


benh wrote: 
> 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.

My observation on the Smokey Robinso file was an error toward the *very*
end (confirmed by both ffmpeg and my built-in decoder) which again
caused the player to raise an error (STMn) and as a result LMS
immediately stops playback for *synchronized players*, discarding all
what is in the decoded buffer, which can be a lot as decoding happens
way ahead of playback. Standalone players are not affected neither are
groups when there is transcoding happening as faad does not/cannot
return an error, simply it returns EoF and all the previously decoded
audio is already piped to flac and will be played.



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


bpa wrote: 
> I checked out the container structure of the 1-13_Last_Kiss.m4a file and
> it was OK. 
> 
> Which is the file that ffmpeg throws up an error ?  I'll check it out. 
> The issue might be something like metadata which can be Apple
> proprietary.

This one has en error
https://forums.slimdevices.com/showthread.php?114439-Group-Players-Songs-stop-prematurely-with-an-error=1020043=1#post1020043



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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


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 bpa


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

I checked out the container structure of the 1-13_Last_Kiss.m4a file and
it was OK. 

Which is the file that ffmpeg throws up an error ?  I'll check it out. 
The issue might be something like metadata which can be Apple
proprietary.



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 philippe_44

ThatÂ’s the situation I explained above. Try decode error happens when
the output buffer is still full of unheard audio because decoding
happens way in advance.

For standalone players, when LMS receives the decode error notification,
it let the player go to the end of whatever it has in its buffer.

For syncs players, LMS stops all players as soon as it receives the
decode excite indication. So you can see that it might easily be with 10
seconds of audio still in the buffer



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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-24 Thread philippe_44


benh wrote: 
> 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.

I've seen some random sync when the group is being disassembled and I
re-allocate players to the sync group they belonged to. I've not been
able to catch the issue though.



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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 when
> played standalone, 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.

- 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



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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:
> 
> 

So, here is my take of the story, now that I have the file:

The file is incorrectly encoded toward the end (verified with ffmpeg).
Slimproto returns a decoding error when it reaches that part (verified
with my SqueezeESP32).  Remember that the decode error can happen long
*before* the audio reaches the DAC, in fact it entirely depends how big
is the output buffer. I think the error happens at the very last bytes
of the file.

When transcoding happens, the decoding error is not anymore raised by
the player, but in faad and I think faad simply ends the decoding and
passes everything it can to flac, to re-encode. So, the track is played
till the quasi-end, hence it "seems" to work.

When no transcoding happens (for example players all support mp4/aac),
then all players will send a decoding error but some might send it
sooner, some later, depending on their buffer's length. When LMS
receives the decoding error, it stops playback, even if there is data in
the DAC (output) buffer.

I would suspect that your pCP have different buffers and/or different
timing for raising the error, hence the Library pCP, when going directly
mp4/aac *and* with another player will cause the "early decoding error"
issue. I would have to look at LMS code, but I suspect things are
different when a player is standalone.



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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.

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/)?



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


But... comparing logs and refreshing my memory on my own code, I'm
pretty sure that the issue is that your Group player (the virtual
player) is not using a special StreamingController, so the group is not
build on "play" request. It should never happen unless the group has
been "damaged" for example by being synchronized to a real player as a
slave. In that case, it's "special" StreamingController is replaced by a
"stock" version that does not do the on-the-fly assembly. The more I'm
thinking about that, the more it makes sense. So please delete that
group and re-create another one with the same members. I think if we
look inside the server.prefs, we'll find that something is conflicting
with that little fellow.



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


So last thing, can you put the Group player log to debug and take the
same log again so that I can see why the group is not assembled *before*
checking for common codecs?



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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.  It didn't play, but $VAR1
> is populated...

Still, it confirms that the group is not assembled whent he test is made
to find codec and that's why playback fails. It should be assembled,
though!



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

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

___
plugins mailing list

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 philippe_44


benh wrote: 
> Log with Data :: Dumper added, and debug for player.source enabled.
> 
> > 
Code:

  >   > 
  > [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 

> > 
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

Code:


  sub supportedFormats {
my $client = shift;

my @supportedformats = ();
my %formatcounter= ();
  
my @playergroup = $client->syncGroupActiveMembers();
  
foreach my $everyclient (@playergroup) {
foreach my $supported ($everyclient->formats()) {
$formatcounter{$supported}++;
}
}
  
  use Data::Dumper;
  my @list = map {$_->name} @playergroup;
  Slim::Utils::Log::logger('player.source')->error(Dumper(\@list, 
\%formatcounter));

foreach my $testformat ($client->formats()) {
if (($formatcounter{$testformat} || 0) == @playergroup) {
push @supportedformats, $testformat;
}
}

return @supportedformats;
  }
  




LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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

At this point I don't know. Maybe you could edit the
/usr/share/perl5/Slim/Player/TranscodingHelper.pm file and add the lines
in red, around #393

Code:


  use Data::Dumper;
  $log->info(Dumper(\@profiles), Dumper(\@supportedformats));
  
# Test each profile in turn
PROFILE: foreach (@profiles) {
my $instance = 0;
INSTANCE:
  


And post what you have now in log



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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: 
> 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 philippe_44


BTW, on this combination
benh wrote: 
> So here is something interesting.  I have found a combination of players
> that don't work AT ALL.
> SB Radio and Library pCP/SL: Track won't even start playing.  Here's the
> debug from that:
> 
Does it fail as well if the players are statically synchronized?



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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.

The former, I'd like to see what JUST that pCP does. Would mind
attaching your convert.conf and custom-convert.conf?



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> 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.

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.



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 philippe_44


benh wrote: 
> Nope, those are bone stock, as far as I can recall.

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



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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: 
> 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 bpa


benh wrote: 
> Well, it is short.  That is 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.  

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



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 philippe_44


benh wrote: 
> 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.  That is 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 player.source log 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

> > 

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



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

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 bpa


benh wrote: 
> 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

> > 

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



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 philippe_44


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?



LMS 8.2 on Odroid-C4 - *SqueezeAMP!*, 5xRadio, 5xBoom, 2xDuet, 1xTouch,
1xSB3. Sonos PLAY:3, PLAY:5, Marantz NR1603, Foobar2000, ShairPortW,
2xChromecast Audio, Chromecast v1 and v2, Squeezelite on Pi,  Yamaha
WX-010, AppleTV 4, Airport Express, GGMM E5, RivaArena 1 & 3

philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
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 bpa


benh wrote: 
> 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.

I think it makes sense to check the transcode chain in LMS especially as
it is within a Docker setup.

Changes were made in 8.* to handle MPEG4 files which also generate
similar symptoms.

You have an issue which is ALAC, AAC/MPEG4 and Group player with
possible . It is likely there is ony one crticial factor so the aim
first is to simplify and find the critical factor.
Since problem does NOT happen with player which include SB3 - I think
transcoding is NOT the issue so the problem may be associated with
playing "natively" (i.e. sending M4A/AAC or M4A/ALAC) to players.



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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


> 
> 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 bpa


benh wrote: 
> 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

Without seeing the original command you used I cannot answer
definitively. The file name extensions affect ffmpeg behaviour.  


> 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

> 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
> and appears to be about 10 seconds in.  
Generally ignore the on-screen timer behaviour - it is generated locally
within browser and not closely tied to action until the file has been
playing and audio is heard.

> When the next track actually starts, the progress bar goes back to the
> beginning of that new 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.

This seems normal. Time display on browser is started when user presses
"play" - the timer will then be updated by LMS with real time value once
playing has actually started. 
In this because there is a problem in playing the file - the timer
update becomes apparent.

Can you confirm that the LMS supplied "faad" runs OK within your docker
environment. What does the faad banner show in terms of version and
patches.

To see what is going on try enabling player.source to INFO.  
Note time and try to play a known single file unti the error "cannot
play for file " shows  and then note end time.
Copy into a text file, all log entries from server.log from start  time
to end time 
Zip text file and attach to a post.



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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 bpa


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

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
  




bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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-23 Thread bpa


benh wrote: 
> 
> [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/synchronized player, and it completes normally with
> no error logged.  Files are ALAC or AAC, for the most part.
> 

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)



bpa's Profile: http://forums.slimdevices.com/member.php?userid=1806
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