PCP 6.1.0 - Issue with .cue. (LMS 8.0)

[amey01]

Hi all. I just upgraded from PCP from 6.0.0 to 6.1.0, and I can no longer play single FLAC files accompanied by a .cue file. It plays the first track only, then says file not found.

My other player is still on 6.0.0 and still plays fine. Both use usb output.

Any ideas?

[Paul Webster]

The player does not know that the track is the result of a .cue file so something else must be happening.

Perhaps you have different settings in LMS on the 2 players
Settings/Player/Basic Settings/Play Other Songs In Album

[bpa]

After the upgrade, did you completely rebuild the LMS database ? if not, it is worth a try.
Any messages in scanner.log or server.log ?

IIRC there was a change to flac utility to handle problem with cue files with some locales. It would be good to check with pcp developers if that change was part of 6.0 to 6.1

[amey01]

The player does not know that the track is the result of a .cue file so something else must be happening.

I didn't think so - but interesting that you've confirmed that........mmmmmmm

Perhaps you have different settings in LMS on the 2 players
Settings/Player/Basic Settings/Play Other Songs In Album

Definitely identical settings.

After the upgrade, did you completely rebuild the LMS database ? if not, it is worth a try.

Yes

And nothing that looks obvious in the logs.

IIRC there was a change to flac utility to handle problem with cue files with some locales. It would be good to check with pcp developers if that change was part of 6.0 to 6.1

Wow - interesting. Given all other suggestions have failed, this sounds promising. I'll have to investigate further. I noticed the PCP 7.0.0 beta has just been released, so I may try that too.

[bpa]

Wow - interesting. Given all other suggestions have failed, this sounds promising. I'll have to investigate further. I noticed the PCP 7.0.0 beta has just been released, so I may try that too.

It is very slim chance but it is possible something happened if the flac utility was replaced by a bad build.

To see what is going on - you could enable log player.source to INFO and try to play first track and then any track which generates the error. Copy all log entries from start of test to end of test (no editing) to a text file, zip and add to a post.

[paul-]

Nothing in LMS would have been updated from 6.0 to 6.1. Squeezelite might have changed.

Run the “update Libs” option on the LMS page to make sure you have the latest LMS binary package.

[amey01]

It is very slim chance but it is possible something happened if the flac utility was replaced by a bad build.

To see what is going on - you could enable log player.source to INFO and try to play first track and then any track which generates the error. Copy all log entries from start of test to end of test (no editing) to a text file, zip and add to a post.

This is so weird. It definitely works on my 6.0.0 player, and it definitely worked on the problem player when it was on 6.0.0 as well. But it doesn't work after upgrade to 6.1.0.

I got the log as above ^^ and attached it.

I note the following lines for one of the failed .CUE points - but I don't think it says much more than what I have already said - that is "file not found"........maybe you can see something that I can't?

[20-11-27 13:57:20.9158] Slim::Player::Source::_readNextChunk (372) end of file or error on socket, song pos: 1541365923
[20-11-27 13:57:20.9164] Slim::Player::Source::_readNextChunk (377) b8:27:eb:0a:9b:03 mark end of stream
[20-11-27 13:57:20.9170] Slim::Player::Source::_readNextChunk (385) Didn't stream any bytes for this song; mark it as failed
[20-11-27 13:57:20.9175] Slim::Player::StreamingController:layerStreamingFailed (2248) b8:27:eb:0a:9b:03
[20-11-27 13:57:20.9184] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20W ayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20W ayne].flac#2359-2619
[20-11-27 13:57:20.9227] Slim::Player::StreamingController::_Stop (610) Song queue is now 8

[amey01]

Nothing in LMS would have been updated from 6.0 to 6.1. Squeezelite might have changed.

Run the “update Libs” option on the LMS page to make sure you have the latest LMS binary package.

Just noting that LMS is not running on PCP - the PCPs are players only. LMS runs on its own dedicated Raspbian server.

[amey01]

Just tried a brand new build on 7.0.0-beta - same issue.

[bpa]

This is so weird. It definitely works on my 6.0.0 player, and it definitely worked on the problem player when it was on 6.0.0 as well. But it doesn't work after upgrade to 6.1.0.

First track is played natively by player - no transcoding and no use of flac as it is 1st track. The log seems to show 3 tracks being played. Did 2nd play ? As tranmscoding is the same for 2 & 3 - need to know if track 2 failed !!

[20-11-27 13:56:56.9179] Slim::Player::Song::new (108) index 0 -> file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac
[20-11-27 13:56:56.9190] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[20-11-27 13:56:56.9259] Slim::Player::StreamingController::_playersMessage (796) Now Playing: file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac
[20-11-27 13:56:56.9362] Slim::Player::Song::getNextSong (230) file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac
[20-11-27 13:56:56.9370] Slim::Player::StreamingController::_nextTrackReady (744) b8:27:eb:0a:9b:03: nextTrack will be index 0
[20-11-27 13:56:56.9377] Slim::Player::StreamingController::_Stream (1210) Song queue is now 0
[20-11-27 13:56:56.9382] Slim::Player::StreamingController::_Stream (1213) b8:27:eb:0a:9b:03: preparing to stream song index 0
[20-11-27 13:56:56.9388] Slim::Player::Song::open (367) file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac
[20-11-27 13:56:56.9412] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: -
[20-11-27 13:56:56.9419] Slim::Player::Song::open (391) seek=false time=0 canSeek=1
[20-11-27 13:56:56.9435] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: -
[20-11-27 13:56:56.9441] Slim::Player::Song::open (412) Transcoder: streamMode=I, streamformat=flc
[20-11-27 13:56:56.9449] Slim::Player::Song::open (468) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac]
[20-11-27 13:56:56.9466] Slim::Player::Protocols::File::open (80) duration: [239.183] size: [92163452] endian [] offset: [0] for file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Paul%20Simon%20-%20Graceland%2025th%20Anniversary%20Edition%20(2012)%20[24-96%20HD%20FLAC]/01%20The%20Boy%20In%20The%20Bubble.flac

2nd track - has to use flac and sox to find 2nd track and stream to player. Does it play ? Looks like it or possibly stopped ????

[20-11-27 13:57:11.9361] Slim::Player::StreamingController::_Stream (1210) Song queue is now 0
[20-11-27 13:57:11.9366] Slim::Player::StreamingController::_Stream (1213) b8:27:eb:0a:9b:03: preparing to stream song index 0
[20-11-27 13:57:11.9373] Slim::Player::Song::open (367) file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne].flac#0-382
[20-11-27 13:57:11.9399] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[20-11-27 13:57:11.9421] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[20-11-27 13:57:11.9427] Slim::Player::Song::open (391) seek=false time=0 canSeek=2
[20-11-27 13:57:11.9445] Slim::Player::TranscodingHelper::getConvertCommand2 (470) Matched: flc->flc via: [flac] -dcs $START$ $END$ -- $FILE$ | [sox] -q -t wav - -t flac -C 0 $RESAMPLE$ -
[20-11-27 13:57:11.9451] Slim::Player::Song::open (412) Transcoder: streamMode=I, streamformat=flc
[20-11-27 13:57:11.9457] Slim::Player::Song::open (468) Opening stream (no direct streaming) using Slim::Player::Protocols::File [file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne].flac#0-382]
[20-11-27 13:57:11.9727] Slim::Player::Protocols::File::open (80) duration: [382] size: [252846987] endian [] offset: [0] for file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne].flac#0-382
[20-11-27 13:57:11.9733] Slim::Player::Protocols::File::open (97) Opening file /wd/BUiPod/FLAC Music/20130202/20200922/Sade - Full LPs Discography 1984-2010/Sade - Promise [1st pressed US Mastered by Frankford Wayne]/Sade - Promise [1st pressed US Mastered by Frankford Wayne].flac
[20-11-27 13:57:11.9750] Slim::Player::Protocols::File::open (189) Seeking in 0 into /wd/BUiPod/FLAC Music/20130202/20200922/Sade - Full LPs Discography 1984-2010/Sade - Promise [1st pressed US Mastered by Frankford Wayne]/Sade - Promise [1st pressed US Mastered by Frankford Wayne].flac
[20-11-27 13:57:11.9759] Slim::Player::Song::open (489) URL is a song (audio): file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne].flac#0-382, type=flc
[20-11-27 13:57:11.9775] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (641) Using command for conversion: "/usr/share/squeezeboxserver/Bin/armhf-linux/flac" -dcs --until=6:22.00 -- - | "/usr/share/squeezeboxserver/Bin/armhf-linux/sox" -q -t wav - -t flac -C 0 -r 96000 -
[20-11-27 13:57:11.9781] Slim::Player::Song::open (565) Tokenized command: "/usr/share/squeezeboxserver/Bin/armhf-linux/flac" -dcs --until=6:22.00 -- - | "/usr/share/squeezeboxserver/Bin/armhf-linux/sox" -q -t wav - -t flac -C 0 -r 96000 -
[20-11-27 13:57:12.0184] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:0a:9b:03: stream

3rd track fails

[20-11-27 13:57:18.7046] Slim::Player::Song::open (489) URL is a song (audio): file:///wd/BUiPod/FLAC%20Music/20130202/20200922/Sade%20-%20Full%20LPs%20Discography%201984-2010/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne]/Sade%20-%20Promise%20[1st%20pressed%20US%20Mastered%20by%20Frankford%20Wayne].flac#1068-1395, type=flc
[20-11-27 13:57:18.7077] Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (641) Using command for conversion: "/usr/share/squeezeboxserver/Bin/armhf-linux/flac" -dcs --skip=17:48.00 --until=23:15.00 -- - | "/usr/share/squeezeboxserver/Bin/armhf-linux/sox" -q -t wav - -t flac -C 0 -r 96000 -
[20-11-27 13:57:18.7090] Slim::Player::Song::open (565) Tokenized command: "/usr/share/squeezeboxserver/Bin/armhf-linux/flac" -dcs --skip=17:48.00 --until=23:15.00 -- - | "/usr/share/squeezeboxserver/Bin/armhf-linux/sox" -q -t wav - -t flac -C 0 -r 96000 -
[20-11-27 13:57:18.7813] Slim::Player::StreamingController::_Stream (1282) b8:27:eb:0a:9b:03: stream
[20-11-27 13:57:18.7872] Slim::Player::StreamingController::_Stream (1319) Song queue is now 3
[20-11-27 13:57:18.7888] Slim::Player::StreamingController::_setPlayingState (2377) new playing state BUFFERING
[20-11-27 13:57:18.7898] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state STREAMING
[20-11-27 13:57:18.9801] Slim::Player::Source::_readNextChunk (372) end of file or error on socket, song pos: 703389903
[20-11-27 13:57:18.9812] Slim::Player::Source::_readNextChunk (377) b8:27:eb:0a:9b:03 mark end of stream
[20-11-27 13:57:18.9825] Slim::Player::Source::_readNextChunk (385) Didn't stream any bytes for this song; mark it as failed