Squeezeplay skipping to next track randomly

[itm]

When I'm playing random song mixes on Squeezeplay 7.8.0 (on Windows 10) I frequently get songs skipping to the next track before completion. I'm running LMS 7.9.1 on Linux.
When I tried to download logs to investigate (from the LMS web GUI) I got an empty server log.
My logging settings on the LMS web interface are set to "Error" for all log types (which presumably tells me that the skipping is not caused by errors, as the server log was empty?). Which log setting(s) do I need to change to get more info on what's causing the random skipping?

EDIT: I should add that there is no wireless in the equation - both the LMS server and the Windows 10 renderer machine are connected to the same switch via Ethernet

[itm]

OK I managed to get some logging info....the skip occurred at about 16:17 today (4/2/19). It was playing Tom Waits "Mr Siegal" (on player 0a:00:27:00:00:02), then skipped before the end and started playing the Stereophonics "Don't Let Me Down". I don't see anything obvious in here. Can anyone else spot anything?

[d6jg]

Is your PC wired or wireless ? Network interruption can cause skipping

[itm]

Is your PC wired or wireless ? Network interruption can cause skipping

The PC renderer and LMS Server are both wired into the same switch

[d6jg]

The PC renderer and LMS Server are both wired into the same switch

Your log shows PAUSE a couple of times at 16:17.

[itm]

Your log shows PAUSE a couple of times at 16:17.

That's not something that I initiated - I left it playing when I downloaded the logs
???

[biomass]

I noticed the same behaviour. A large portion of my collection is encoded as Variable Bit Rate MP3. Nothing visible in the logs.. Albums play fine on Touch and Radios's but SqueezePlay skips randomly, usually near the end of a track.

[sle118]

OK I managed to get some logging info....the skip occurred at about 16:17 today (4/2/19). It was playing Tom Waits "Mr Siegal" (on player 0a:00:27:00:00:02), then skipped before the end and started playing the Stereophonics "Don't Let Me Down". I don't see anything obvious in here. Can anyone else spot anything?

I can't tell for sure if this is related, but I did troubleshoot a similar problem which occurred while streaming Spotify. If you are comfortable with compiling a C program using visual studio, you could try my fix:

https://github.com/Logitech/slimserver-tools/pull/3

Otherwise, I could make my compiled binary available for you to test.

[bpa]

I can't tell for sure if this is related, but I did troubleshoot a similar problem which occurred while streaming Spotify. If you are comfortable with compiling a C program using visual studio, you could try my fix:

https://github.com/Logitech/slimserver-tools/pull/3

Otherwise, I could make my compiled binary available for you to test.

The OP log shows direct MP3 streaming - so I don't think socketwrapper is the issue.

That said OP should post player.source INFO logging to confirm no trasncoding is taking place - never sure of Squeezeplay capabiltiies as it depends on the build.

[Mastropietro]

Are you sure transcoding could create issues like this?

[bpa]

Are you sure transcoding could create issues like this?

sle118 has found some instances where transcoding on Windows only can cut off last few second of some services.

[fizze]

I'm experiencing similar issues, mostly notable when listening to longer songs using SqueezePlay.
This does not happen on any of my SqueezeBoxes.
It is more noticeable for longer tracks, but no transcoding is taking place, and the (windows 10) client where SqueezePlay runs is wired to the LMS instance, as are all other SqueezeBoxes.

[biomass]

I used to use LMS 7.7.6-116 from the Synology package, and after posting here, I am now using LMS 7.9.1-168 from the beta packages list. My Squeezeplay.exe is 7.6.2 built October 18, 2012. The skipping of tracks is still there. So I'm suspecting it may be a Squeezeplay issue, or at least I would like to see the Squeezeplay logs.

Looking at the source code there is a possibililty to log, but that seems to be disabled on the Windows build. I got the source from Github, but getting that to build with VS 2019 needs work.

[bpa]

I used to use LMS 7.7.6-116 from the Synology package, and after posting here, I am now using LMS 7.9.1-168 from the beta packages list. My Squeezeplay.exe is 7.6.2 built October 18, 2012. The skipping of tracks is still there. So I'm suspecting it may be a Squeezeplay issue, or at least I would like to see the Squeezeplay logs.

Looking at the source code there is a possibililty to log, but that seems to be disabled on the Windows build. I got the source from Github, but getting that to build with VS 2019 needs work.

Start with the LMS logs first - they'll be more informative.

[biomass]

@bpa: I started humming 'I've got a feeling' by the Black Eyed Peas when I read your comment

So I went looking in the Server Settings and set the (player.source) - Player Source Audio & Conversion Logging to 'Debug' and I created a playlist starting with 1 CBR mp3, and then followed by an album of VBR mp3 files. The skipping happened on the second track of the VBR tracks, it stopped around 19 seconds early.
It appears to be initiated by Squeezeplay:

Line  7831: [19-12-08 12:41:09.3947] Slim::Player::Protocols::File::open (81) duration: [325] size: [5204428] endian [] offset: [1470] for tmp:///volume2/music/Jimi%20Hendrix/1Track%20No01.mp3
Line  8865: [19-12-08 12:46:24.8872] Slim::Player::Protocols::File::open (81) duration: [248.933] size: [6622031] endian [] offset: [1083] for file:///volume2/music/Lana%20Del%20Rey/NFR/001%20Track001.mp3
Line  9710: [19-12-08 12:50:33.8492] Slim::Player::Protocols::File::open (81) duration: [247.146] size: [7244677] endian [] offset: [1029] for file:///volume2/music/Lana%20Del%20Rey/NFR/002%20Track002.mp3
Line 10491: [19-12-08 12:54:21.8143] Slim::Player::Protocols::File::open (81) duration: [577.2] size: [16197534] endian [] offset: [2028] for file:///volume2/music/Lana%20Del%20Rey/NFR/003%20Track003.mp3

Line  7857: [19-12-08 12:41:09.6395] Slim::Player::StreamingController::_Playing (361) Song 0 has now started playing
Line  8919: [19-12-08 12:46:34.8244] Slim::Player::StreamingController::_Playing (361) Song 1 has now started playing (326.849 s later than song 0)
Line  9756: [19-12-08 12:50:43.7873] Slim::Player::StreamingController::_Playing (361) Song 2 has now started playing (248.629 s later than song 1)
Line 10542: [19-12-08 12:54:31.7937] Slim::Player::StreamingController::_Playing (361) Song 3 has now started playing (228.064 s later than song 2?? (19 seconds early??))

[19-12-08 12:54:31.7901] Slim::Player::StreamingController::playerTrackStarted (2180) 00:00:00:00:00:00 squeezeplay