PDA

View Full Version : Spotty: Authentication and playback problems



AndyCoo
2018-04-22, 04:03
Hi,
I've been trying to get Spotty working on my Pi2B setup.
After going round in circles with authentication problems, playback problems (no sound or strange clicking) I'm now totally confused and could do with some guidance to work through all the issues please.
uname -a says:

Linux mediaserver-pi 4.1.19-v7+ #858 SMP Tue Mar 15 15:56:00 GMT 2016 armv7l GNU/Linux

and

ldd (Debian EGLIBC 2.13-38+rpi2+deb7u12) 2.13


Spotty Helper Application is set to .....arm-linux/spotty (v0.11.0)

I'm totally not sure whether I should be using spotty or spotty-muslhf.
When I select Auto, it always chooses the latter, but I think I read somewhere that muslhf shouldn't be used on arm.

When I go to Spotty app and play a track, streaming info says:

320kbps CBR, Ogg Vorbis (Spotify) (Converted to MP3)
Streaming appears to be working based on incrementing track times everywhere I look, but all I can hear is rhythmic crackling.

I've got some pastebin links with server logs in that I'll post after this, as apparently you can't post links in your first post.

Many thanks in advance for any help.

Andy

mherger
2018-04-22, 09:09
Can you tell me what OS this is? It seems to be running a rather old glibc. Please try the spotty-muslhf instead.

AndyCoo
2018-04-22, 11:43
Thanks for the quick reply Michael.
It's:

PRETTY_NAME="Raspbian GNU/Linux 7 (wheezy)"
NAME="Raspbian GNU/Linux"
VERSION_ID="7"
VERSION="7 (wheezy)"
ID=raspbian
ID_LIKE=debian
ANSI_COLOR="1;31"
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"


I have tried spotty-muslhf too, with different results. I got a bit confused about the file format options as they change when I change helpers. But standby.....I'll post my current logs first if poss.

AndyCoo
2018-04-22, 11:44
Original situation when I get strange clicking in playback.

I manually entered my Spotify credentials and when I hit Apply, server log says:

https://pastebin.com/SR4rpf9n

The Connect page successfully disappears and....

More log follows (this covers when I try to play a track):
https://pastebin.com/hu0CxK4b

mherger
2018-04-22, 12:14
It's looking good.
--

Michael

AndyCoo
2018-04-22, 12:18
Please try the spotty-muslhf instead.

OK, I changed to spotty-muslhf on the settings page.
Restarted the logitechmediaserver service
Went to Filetypes and changed FLAC to spotty/flac as it was disabled.
24944

Tried to play a track and seconds ticked up for about 2 then went back to zero.
Track info says:


Spirit
Future Islands
Singles
320kbps CBR, Ogg Vorbis (Spotify) (Converted to MP3)

Tried another track and same happened.
Logs for all the above are at:
https://pastebin.com/8SZi06QQ

AndyCoo
2018-04-22, 12:26
It's looking good.
--

Michael
Yeah, it all looks good but doesn't sound good :-)
From my very limited experience, it feels like a conversion issue, but I am lost on debugging this currently.

BTW, I've got a couple of posts outstanding with the mods.

mherger
2018-04-22, 21:47
You still didn't answer my question about the OS, did you? Might have
missed it somewhere.

> From my very limited experience, it feels like a conversion issue, but I
> am lost on debugging this currently.

Can you check whether it would play if you changed to streaming PCM
rather than FLAC or MP3?

Also: please set logging to INFO for plugin.spotty, try to play
something, then upload your server.log.zip to
https://www.dropbox.com/request/T3RctyzGgNg0oFDubq6a.

--

Michael

AndyCoo
2018-04-23, 09:24
This was supposed to be my answer to your OS question.
Is it the right info?


Thanks for the quick reply Michael.
It's:

PRETTY_NAME="Raspbian GNU/Linux 7 (wheezy)"
NAME="Raspbian GNU/Linux"
VERSION_ID="7"
VERSION="7 (wheezy)"
ID=raspbian
ID_LIKE=debian
ANSI_COLOR="1;31"
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

AndyCoo
2018-04-23, 09:38
Can you check whether it would play if you changed to streaming PCM
rather than FLAC or MP3?


I assume you mean by enabling each format in turn here:
24946

I did that and it _does_ change the format shown in the "converted to x" :
as in 1411kbps CBR, Ogg Vorbis (Spotify) (Converted to PCM) and it appears to stream in all cases, but sounds like the same clicky noise.

AndyCoo
2018-04-23, 09:42
Also: please set logging to INFO for plugin.spotty, try to play
something, then upload your server.log.zip to


I could, but it doesn't need a zip, the only log line I get when I click play is:

[18-04-23 16:34:35.2263] Plugins::Spotty::Plugin::purgeAudioCacheAfterXTrac ks (450) Played 5 song(s) since last audio cache purge.

AndyCoo
2018-04-23, 10:39
With DEBUG on (player.source) - Player Source Audio & Conversion Logging


I get:


[18-04-23 16:41:30.4113] Slim::Player::StreamingController::_eventAction (271) 00:c6:10:a0:aa:eb: Resume in PAUSED-IDLE -> Slim::Player::StreamingController::_JumpOrResume
[18-04-23 16:41:30.4146] Slim::Player::StreamingController::_Stop (603) Song queue is now 0
[18-04-23 16:41:30.4165] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state STOPPED
[18-04-23 16:41:30.4185] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state IDLE
[18-04-23 16:41:30.4204] Slim::Player::StreamingController::_Stream (1129) 00:c6:10:a0:aa:eb: got song from params, song index 0
[18-04-23 16:41:30.4224] Slim::Player::StreamingController::_Stream (1203) Song queue is now 0
[18-04-23 16:41:30.4241] Slim::Player::StreamingController::_Stream (1206) 00:c6:10:a0:aa:eb: preparing to stream song index 0
[18-04-23 16:41:30.4260] Slim::Player::Song::open (363) spotify://track:5SxkdsY1ufZzoq9iXceLw9
[18-04-23 16:41:30.4319] Plugins::Spotty::Plugin::purgeAudioCacheAfterXTrac ks (450) Played 1 song(s) since last audio cache purge.
[18-04-23 16:41:30.4352] Slim::Player::Song::open (387) seek=true time=36.7704380474091 canSeek=2
[18-04-23 16:41:30.4383] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-alc-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4404] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-alc-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4421] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-alc-iPengiPod-*
[18-04-23 16:41:30.4437] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-alc-*-*
[18-04-23 16:41:30.4456] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-aac-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4474] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-aac-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4492] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-aac-iPengiPod-*
[18-04-23 16:41:30.4510] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-aac-*-*
[18-04-23 16:41:30.4535] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-ogg-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4552] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-ogg-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4568] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-ogg-iPengiPod-*
[18-04-23 16:41:30.4584] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-ogg-*-*
[18-04-23 16:41:30.4600] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-flc-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4615] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-flc-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4631] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-flc-iPengiPod-*
[18-04-23 16:41:30.4647] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-flc-*-*
[18-04-23 16:41:30.4662] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if spt-flc-*-* is enabled
[18-04-23 16:41:30.4679] Slim::Player::TranscodingHelper::enabledFormat (217) There are 2 disabled formats...
[18-04-23 16:41:30.4695] Slim::Player::TranscodingHelper::enabledFormat (223) Testing spt-flc-*-* vs spt-flc-*-*
[18-04-23 16:41:30.4715] Slim::Player::TranscodingHelper::enabledFormat (227) ** spt-flc-*-* Disabled **
[18-04-23 16:41:30.4730] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-mp3-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4746] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-mp3-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4761] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-mp3-iPengiPod-*
[18-04-23 16:41:30.4778] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-mp3-*-*
[18-04-23 16:41:30.4793] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if spt-mp3-*-* is enabled
[18-04-23 16:41:30.4808] Slim::Player::TranscodingHelper::enabledFormat (217) There are 2 disabled formats...
[18-04-23 16:41:30.4824] Slim::Player::TranscodingHelper::enabledFormat (223) Testing spt-flc-*-* vs spt-mp3-*-*
[18-04-23 16:41:30.4839] Slim::Player::TranscodingHelper::enabledFormat (223) Testing spt-mp3-*-* vs spt-mp3-*-*
[18-04-23 16:41:30.4854] Slim::Player::TranscodingHelper::enabledFormat (227) ** spt-mp3-*-* Disabled **
[18-04-23 16:41:30.4868] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-pcm-iPengiPod-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4885] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-pcm-*-00:c6:10:a0:aa:eb
[18-04-23 16:41:30.4900] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-pcm-iPengiPod-*
[18-04-23 16:41:30.4916] Slim::Player::TranscodingHelper::checkBin (242) Checking formats for: spt-pcm-*-*
[18-04-23 16:41:30.4931] Slim::Player::TranscodingHelper::enabledFormat (209) Checking to see if spt-pcm-*-* is enabled
[18-04-23 16:41:30.4947] Slim::Player::TranscodingHelper::enabledFormat (217) There are 2 disabled formats...
[18-04-23 16:41:30.4961] Slim::Player::TranscodingHelper::enabledFormat (223) Testing spt-flc-*-* vs spt-pcm-*-*
[18-04-23 16:41:30.4978] Slim::Player::TranscodingHelper::enabledFormat (223) Testing spt-mp3-*-* vs spt-pcm-*-*
[18-04-23 16:41:30.4994] Slim::Player::TranscodingHelper::checkBin (250) enabled
[18-04-23 16:41:30.5010] Slim::Player::TranscodingHelper::checkBin (252) Found command: TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lqueezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty [spotty] -n Squeezebox -c "/lib/squeezeboxserver/cache/spotty/ddce5d49" --single-track $FILE$ --bitrate 320 --disable-discovery --disable-audio-cache $START$
[18-04-23 16:41:30.5033] Slim::Player::TranscodingHelper::getConvertCommand 2 (446) Matched: spt->pcm via: TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty [spotty] -n ezebox -c "/var/lib/squeezeboxserver/cache/spotty/ddce5d49" --single-track $FILE$ --bitrate 320 --disable-discovery --disable-audio-cache $START$
[18-04-23 16:41:30.5050] Slim::Player::Song::open (408) Transcoder: streamMode=R, streamformat=pcm
[18-04-23 16:41:30.5086] Slim::Player::TranscodingHelper::tokenizeConvertCo mmand2 (617) Using command for conversion: TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserveche/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty r/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/arm-linux/spotty" -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/ddce5d49" --single-track "spotify://track:5SxkdsY1ufZzoceLw9" --bitrate 320 --disable-discovery --disable-audio-cache --start-position 36.7704380474091 & |
[18-04-23 16:41:30.5107] Slim::Player::Song::open (558) Tokenized command: TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxse/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty TMPDIR=/var/lib/squeezeboxserver/cache/spotty "/var/lib/squeezeboxserver/cache/InstalledPns/Plugins/Spotty/Bin/arm-linux/spotty" -n Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/ddce5d49" --single-track "spotify://track:5SxkdsY1ufZzoq9iXceLw9" --bitrate 320 --disable-discoverdisable-audio-cache --start-position 36.7704380474091 & |
[18-04-23 16:41:30.5384] Slim::Player::SongStreamController::new (31) live=1
[18-04-23 16:41:30.5447] Slim::Player::StreamingController::_Stream (1265) 00:c6:10:a0:aa:eb: stream
[18-04-23 16:41:30.5530] Slim::Player::StreamingController::_Stream (1302) Song queue is now 0
[18-04-23 16:41:30.5554] Slim::Player::StreamingController::_setPlayingStat e (2357) new playing state BUFFERING
[18-04-23 16:41:30.5573] Slim::Player::StreamingController::_setStreamingSt ate (2366) new streaming state STREAMING
[18-04-23 16:41:30.5592] Slim::Player::StreamingController::_eventAction (303) 00:c6:10:a0:aa:eb: Resume - new state BUFFERING-STREAMING
[18-04-23 16:41:30.5627] Slim::Player::Source::playmode (96) 00:c6:10:a0:aa:eb: Current playmode: play
[18-04-23 16:41:30.6024] Slim::Player::StreamingController::_eventAction (271) 00:c6:10:a0:aa:eb: StatusHeartbeat in BUFFERING-STREAMING -> Slim::Player::StreamingController::_NoOp

AndyCoo
2018-04-23, 10:45
Hi Michael,

Thanks for your help so far.
I've submitted a post including the DEBUG from (player.source) - Player Source Audio & Conversion Logging, but it's waiting for mod approval so I've put the important looking bits into a file and uploaded to your dropbox.

The conversion evaluation doesn't seem to have any obvious errors and given that FLAC, PCM & MP3 all sound pretty much the same, I'm wondering if there is a problem with the support for ogg vorbis on my server....

Andy

mherger
2018-04-23, 11:26
If only you followed my advise, I'd be so much more motivated to help. Yes, your system is Raspbian based. If it is Raspbian, then please update. As I said: your libc is rather old. If it is not Raspbian, then tell me what it is.

I didn't ask for the debug statements you enabled. But for plugin.spotty. And I asked for the full file. Because if you knew what was important, then you wouldn't have to ask for help.