[SlimDevices: Plugins] Group Players: Songs stop prematurely with an error
I have begun to have (or just noticed I have) a problem with Group Players. Some songs stop playing prematurely, seemingly exactly 10 seconds early, and display an error on the player screen: Problem: Can't open file for: And the following error is logged in LMS: [21-04-18 22:54:21.6627] Slim::Player::Squeezebox2::statHandler (149) Error: : Decoder does not support file format, code 0 I have confirmed that these files play normally using other music players, such as iTunes. I can play the SAME file on a single pCP rather than a group player, and it completes normally with no error logged. Files are ALAC or AAC, for the most part. The problem appears to remain persistent for a given file, in that I can re-add that song to my queue and the problem will recur while players are grouped. It's not every song, just some. I'm not sure when it began, but I have errors going back to 4/13. I'm running the Group Players plugin v0.14.3 I'm running the official Docker version: Logitech Media Server Version: 8.1.2 - 1618550311 @ Fri Apr 16 08:36:43 CEST 2021 Hostname: 2cc3948a001f Server IP Address: 192.168.10.40 Server HTTP Port Number: 9000 Operating system: Debian (Docker) - EN - utf8 Platform Architecture: x86_64-linux Perl Version: 5.24.1 - x86_64-linux-gnu-thread-multi Audio::Scan: 1.02 IO::Socket::SSL: 2.044 Database Version: DBD::SQLite 1.58 (sqlite 3.22.0) Total Players Recognized: 6 benh's Profile: http://forums.slimdevices.com/member.php?userid=6732 View this thread: http://forums.slimdevices.com/showthread.php?t=114439 ___ plugins mailing list plugins@lists.slimdevices.com http://lists.slimdevices.com/mailman/listinfo/plugins
Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error
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
Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error
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
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
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
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
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
> > 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
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
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
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
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
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
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
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
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
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
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
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] Slim::Player::StreamingController::nextso
Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error
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
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
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
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
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
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
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
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
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
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
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
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
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
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
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 plugins@l
Re: [SlimDevices: Plugins] Group Players: Songs stop prematurely with an error
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
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
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
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
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
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
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
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
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
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
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
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
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
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
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
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
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&p=1020043&viewfull=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
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
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
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