Jan 11 18:18:05 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:05-07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX0GO2iStOATx" Jan 11 18:18:05 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:05-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="handling skip_next player command from e55d268794003a849201fc728dc73c7ee3a36284" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=trace msg="emitting websocket event: will_play" Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:1RY4hHMzlWRqNVyGm0kW7H","uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","play_origin":"playlist"}} Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="selected format OGG_VORBIS_320 (b66799f7c2c98b516b711cd4ffe6329a72fe189d)" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="requested aes key for file b66799f7c2c98b516b711cd4ffe6329a72fe189d, gid: 0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="fetched first chunk of 11, total size is 5317116 bytes" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=info msg="loaded track \"Case\" (paused: false, position: 0ms, duration: 135500ms, prefetched: false)" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=trace msg="scheduling prefetch in 106s" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=trace msg="emitting websocket event: metadata" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="sending successful reply for dealer request" Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","name":"Case","artist_names":["Diljit Dosanjh","Intense"],"album_name":"Ghost","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cb2f9520171129a3df7a241a","position":0,"duration":135500,"release_date":"year:2023 month:9 day:29","track_number":17,"disc_number":1}} Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:0TdWXMXyfTQ2qp5vvU9LID" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 18:18:07 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:07-07:00" level=trace msg="emitting websocket event: playing" Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:1RY4hHMzlWRqNVyGm0kW7H","uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","resume":false,"play_origin":"playlist"}} Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: PUSH STATE SPOTIFY Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: {"status":"play","service":"spop","title":"Case","artist":"Diljit Dosanjh, Intense","album":"Ghost","albumart":"https://i.scdn.co/image/ab67616d00001e02cb2f9520171129a3df7a241a","uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","trackType":"spotify","seek":0,"duration":135,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::servicePushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreStateMachine::pushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:18:07 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:07 volumio-lr volumio[1439]: info: [LastFM] Current track has sufficient metadata: title (Case) and artist (Diljit Dosanjh, Intense) passed on explicitly Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Jan 11 18:18:07 volumio-lr volumio[1439]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Case","url":"https://www.last.fm/music/Diljit+Dosanjh,+Intense/_/Case","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"263","playcount":"2017","artist":{"name":"Diljit Dosanjh, Intense","url":"https://www.last.fm/music/Diljit+Dosanjh,+Intense"},"userplaycount":"0","userloved":"0","toptags":""}} Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: PUSH STATE SPOTIFY Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: {"status":"play","service":"spop","title":"Case","artist":"Diljit Dosanjh, Intense","album":"Ghost","albumart":"https://i.scdn.co/image/ab67616d00001e02cb2f9520171129a3df7a241a","uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","trackType":"spotify","seek":0,"duration":135,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::servicePushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreStateMachine::pushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPushState Jan 11 18:18:07 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:18:07 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output Jan 11 18:18:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:07 volumio-lr volumio[1439]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Jan 11 18:18:13 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:13-07:00" level=trace msg="sent dealer ping" Jan 11 18:18:13 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:13-07:00" level=trace msg="received dealer pong" Jan 11 18:18:14 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:14-07:00" level=trace msg="received accesspoint ping" Jan 11 18:18:14 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:14-07:00" level=trace msg="received accesspoint pong ack" Jan 11 18:18:17 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:17 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:17 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:21 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:21-07:00" level=debug msg="handling pause player command from e55d268794003a849201fc728dc73c7ee3a36284" Jan 11 18:18:21 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:21-07:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jan 11 18:18:21 volumio-lr go-librespot[8369]: time="2026-01-11T18:18:21-07:00" level=debug msg="pause track at 0ms" Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGBUS: bus error Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0xa79dbc82 m=8 sigcode=1 addr=0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: signal arrived during cgo execution Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 58 gp=0x10845a8 m=8 mp=0x1080b08 [syscall]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.cgocall(0x6498b0, 0x1204ecc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/cgocall.go:157 +0x50 fp=0x1204eb4 sp=0x1204e9c pc=0x1d820 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/output._Cfunc_snd_pcm_close(0xf3b16898) Jan 11 18:18:21 volumio-lr go-librespot[8369]: _cgo_gotypes.go:440 +0x38 fp=0x1204ec8 sp=0x1204eb4 pc=0x4a39e0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause.func1(0x1416080) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/output/driver-alsa.go:470 +0x50 fp=0x1204ee4 sp=0x1204ec8 pc=0x4a81d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause(0x1416080) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/output/driver-alsa.go:470 +0xc0 fp=0x1204f14 sp=0x1204ee4 pc=0x4a80d8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x107eeb0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/player/player.go:264 +0x94c fp=0x1204fe4 sp=0x1204f14 pc=0x513db0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/player/player.go:183 +0x28 fp=0x1204fec sp=0x1204fe4 pc=0x513240 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1204fec sp=0x1204fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/player/player.go:183 +0x218 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 1 gp=0x1002128 m=nil [select, 106 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1245d00 sp=0x1245cec pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1245e00, 0x11d5dcc, 0x0, 0x0, 0x2, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1245da0 sp=0x1245d00 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1028540, 0x1245e90) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x1245e44 sp=0x1245da0 pc=0x5a8088 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).withAppPlayer(0x111ac00, {0x8874f0, 0xd0ad00}, 0x100f540) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:321 +0x554 fp=0x1245ed0 sp=0x1245e44 pc=0x63db54 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).withCredentials(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:193 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).SpotifyToken(0x111ac00, {0x8874f0, 0xd0ad00}, {0x1010fe0, 0xa}, {0x11ec000, 0x177}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x1245f00 sp=0x1245ed0 pc=0x63cfa8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.main() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:557 +0x588 fp=0x1245fa8 sp=0x1245f00 pc=0x63feb4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.main() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1245fec sp=0x1245fa8 pc=0x5cb30 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1245fec sp=0x1245fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 2 gp=0x10027e8 m=nil [force gc (idle), 3 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1044fd4 sp=0x1044fc0 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goparkunlock(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:408 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.forcegchelper() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1044fec sp=0x1044fd4 pc=0x5cefc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1044fec sp=0x1044fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.init.5 in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 3 gp=0x1002b48 m=nil [GC sweep wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10457c4 sp=0x10457b0 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goparkunlock(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:408 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.bgsweep(0x106c000) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x10457e4 sp=0x10457c4 pc=0x45848 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcenable.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x10457ec sp=0x10457e4 pc=0x35968 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10457ec sp=0x10457ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcenable in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 4 gp=0x1002c68 m=nil [GC scavenge wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045fb4 sp=0x1045fa0 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goparkunlock(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:408 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.(*scavengerState).park(0xce0208) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1045fc8 sp=0x1045fb4 pc=0x42b68 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.bgscavenge(0x106c000) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1045fe4 sp=0x1045fc8 pc=0x432a0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcenable.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1045fec sp=0x1045fe4 pc=0x35914 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1045fec sp=0x1045fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcenable in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 5 gp=0x1003208 m=nil [finalizer wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x104678c sp=0x1046778 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.runfinq() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x10467ec sp=0x104678c pc=0x34754 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10467ec sp=0x10467ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.createfing in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 8 gp=0x11ee908 m=nil [IO wait, 105 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41f08, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1046d28 sp=0x1046d14 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41ef8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1046d40 sp=0x1046d28 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41ef8, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1046d54 sp=0x1046d40 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x107f3c8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1046d68 sp=0x1046d54 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Accept(0x107f3b0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1046db0 sp=0x1046d68 pc=0x10eb84 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).accept(0x107f3b0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1046e18 sp=0x1046db0 pc=0x17e4c4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPListener).accept(0x1026828) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1046e30 sp=0x1046e18 pc=0x196900 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPListener).Accept(0x1026828) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1046e4c sp=0x1046e30 pc=0x19596c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*onceCloseListener).Accept(0x10a4340) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x34 fp=0x1046e64 sp=0x1046e4c pc=0x362b54 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Server).Serve(0x109c0a8, {0x886cb8, 0x1026828}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1046efc sp=0x1046e64 pc=0x341210 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.Serve(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*ConcreteApiServer).serve(0x1029080) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x1046fe4 sp=0x1046efc pc=0x6330b8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.NewApiServer.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x1046fec sp=0x1046fe4 pc=0x631de4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1046fec sp=0x1046fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by main.NewApiServer in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/api_server.go:296 +0x310 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 53 gp=0x11eea28 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41d28, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1203e84 sp=0x1203e70 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41d18, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1203e9c sp=0x1203e84 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41d18, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1203eb0 sp=0x1203e9c pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x135e108, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1203ec4 sp=0x1203eb0 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).RawRead(0x135e0f0, 0x146a510) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1203ef0 sp=0x1203ec4 pc=0x10f788 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*rawConn).Read(0x135c218, 0x146a510) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1203f10 sp=0x1203ef0 pc=0x190d28 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x12821f0, 0x146a4e0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1203f40 sp=0x1203f10 pc=0x548824 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x136a038, {0x14ea000, 0x10000, 0x10000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1203f9c sp=0x1203f40 pc=0x54c80c Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/grandcat/zeroconf.(*Server).recv4(0x1070900, 0x136a030) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1203fe0 sp=0x1203f9c pc=0x5a309c Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1203fec sp=0x1203fe0 pc=0x5a2d54 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1203fec sp=0x1203fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 54 gp=0x11eeb48 m=nil [IO wait, 106 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41c88, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x145ee5c sp=0x145ee48 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41c78, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x145ee74 sp=0x145ee5c pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41c78, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x145ee88 sp=0x145ee74 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x135e158, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x145ee9c sp=0x145ee88 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).RawRead(0x135e140, 0x14c3020) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x145eec8 sp=0x145ee9c pc=0x10f788 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*rawConn).Read(0x135c240, 0x14c3020) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x145eee8 sp=0x145eec8 pc=0x190d28 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1282200, 0x14c2ff0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x145ef18 sp=0x145eee8 pc=0x548824 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 11 18:18:21 volumio-lr go-librespot[8369]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x136a068, {0x14da000, 0x10000, 0x10000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x145ef9c sp=0x145ef18 pc=0x550194 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/grandcat/zeroconf.(*Server).recv6(0x1070900, 0x136a060) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x145efe0 sp=0x145ef9c pc=0x5a3290 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x145efec sp=0x145efe0 pc=0x5a2cf8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x145efec sp=0x145efec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 34 gp=0x145c008 m=nil [GC worker (idle)]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3fbc, 0x102a798, 0x1a, 0xa, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1462790 sp=0x146277c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcBgMarkWorker() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x14627ec sp=0x1462790 pc=0x38590 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14627ec sp=0x14627ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 14 gp=0x11eefc8 m=nil [GC worker (idle)]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3fbc, 0x102a7b0, 0x1a, 0xa, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1047790 sp=0x104777c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcBgMarkWorker() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10477ec sp=0x1047790 pc=0x38590 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10477ec sp=0x10477ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 15 gp=0x11ef0e8 m=nil [GC worker (idle)]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3fbc, 0x102a7c8, 0x1a, 0xa, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1047f90 sp=0x1047f7c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcBgMarkWorker() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1047fec sp=0x1047f90 pc=0x38590 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1047fec sp=0x1047fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 16 gp=0x11ef208 m=nil [GC worker (idle)]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3fbc, 0x102a7e0, 0x1a, 0xa, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x145e790 sp=0x145e77c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gcBgMarkWorker() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x145e7ec sp=0x145e790 pc=0x38590 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x145e7ec sp=0x145e7ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 80 gp=0x11ef7a8 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x145f6a8 sp=0x145f694 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x145f7a4, 0x145f76c, 0x0, 0x0, 0x5, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x145f748 sp=0x145f6a8 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x14fc148) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x145f7e4 sp=0x145f748 pc=0x474738 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.newConn.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x145f7ec sp=0x145f7e4 pc=0x47432c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x145f7ec sp=0x145f7ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by nhooyr.io/websocket.newConn in goroutine 59 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 59 gp=0x1084248 m=nil [chan receive]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3f90, 0x1520470, 0xe, 0x7, 0x2) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x11d75b0 sp=0x11d759c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv(0x1520440, 0x11d7678, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x11d75ec sp=0x11d75b0 pc=0x20078 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv1(0x1520440, 0x11d7678) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x11d7600 sp=0x11d75ec pc=0x1fb98 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/player.(*Player).Pause(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/player/player.go:382 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*AppPlayer).pause(0x135e190, {0x8874f0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/controls.go:448 +0x1ec fp=0x11d7690 sp=0x11d7600 pc=0x6395ec Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*AppPlayer).handlePlayerCommand(0x135e190, {0x8874f0, 0xd0ad00}, {0x2fc85add, {0x1677bc0, 0x28}, {{0x1a4e420, 0x5}, {0x0, 0x0}, ...}}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/player.go:299 +0xaa8 fp=0x11d77b0 sp=0x11d7690 pc=0x641c60 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*AppPlayer).handleDealerRequest(0x135e190, {0x8874f0, 0xd0ad00}, {0x15369c0, {0x1677b60, 0x24}, {0x2fc85add, {0x1677bc0, 0x28}, {{0x1a4e420, ...}, ...}}}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/player.go:374 +0x11c fp=0x11d78e4 sp=0x11d77b0 pc=0x6436ac Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*AppPlayer).Run(0x135e190, {0x8874f0, 0xd0ad00}, 0x1536180) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/player.go:602 +0x690 fp=0x11d7fd8 sp=0x11d78e4 pc=0x645bdc Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).withAppPlayer.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:258 +0x40 fp=0x11d7fec sp=0x11d7fd8 pc=0x63e874 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x11d7fec sp=0x11d7fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by main.(*App).withAppPlayer in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:258 +0x3d8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 60 gp=0x1084368 m=nil [chan receive, 105 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3f90, 0x106c2b0, 0xe, 0x7, 0x2) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x123e748 sp=0x123e734 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv(0x106c280, 0x123e7d0, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x123e784 sp=0x123e748 pc=0x20078 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv2(0x106c280, 0x123e7d0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x123e798 sp=0x123e784 pc=0x1fbbc Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).withAppPlayer.func1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:268 +0x68 fp=0x123e7ec sp=0x123e798 pc=0x63e794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x123e7ec sp=0x123e7ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by main.(*App).withAppPlayer in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:265 +0x464 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 61 gp=0x10846c8 m=nil [chan receive, 106 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3f90, 0x106c270, 0xe, 0x7, 0x2) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x123ef40 sp=0x123ef2c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv(0x106c240, 0x123efe0, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x123ef7c sp=0x123ef40 pc=0x20078 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv2(0x106c240, 0x123efe0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x123ef90 sp=0x123ef7c pc=0x1fbbc Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*App).withAppPlayer.func2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:284 +0x6c fp=0x123efec sp=0x123ef90 pc=0x63e330 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x123efec sp=0x123efec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by main.(*App).withAppPlayer in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/main.go:281 +0x504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 62 gp=0x10847e8 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41dc8, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x123f5e8 sp=0x123f5d4 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41db8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x123f600 sp=0x123f5e8 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41db8, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x123f614 sp=0x123f600 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x135e0b8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x123f628 sp=0x123f614 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Accept(0x135e0a0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x123f670 sp=0x123f628 pc=0x10eb84 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).accept(0x135e0a0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x123f6d8 sp=0x123f670 pc=0x17e4c4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPListener).accept(0x13600f0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x123f6f0 sp=0x123f6d8 pc=0x196900 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPListener).Accept(0x13600f0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x123f70c sp=0x123f6f0 pc=0x19596c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*onceCloseListener).Accept(0x1396540) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x34 fp=0x123f724 sp=0x123f70c pc=0x362b54 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Server).Serve(0x109c468, {0x886cb8, 0x13600f0}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x123f7bc sp=0x123f724 pc=0x341210 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.Serve(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x123f7ec sp=0x123f7bc pc=0x5a8184 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x123f7ec sp=0x123f7ec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/zeroconf/zeroconf.go:280 +0x160 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 98 gp=0x11efb08 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1206eb8 sp=0x1206ea4 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1206fd4, 0x1206f7c, 0x0, 0x0, 0x2, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1206f58 sp=0x1206eb8 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x14ca428) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:355 +0x8c fp=0x1206fe4 sp=0x1206f58 pc=0x48cb50 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:264 +0x28 fp=0x1206fec sp=0x1206fe4 pc=0x48c248 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1206fec sp=0x1206fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 59 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:264 +0x15c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 99 gp=0x11efc28 m=nil [chan receive]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e3f90, 0x15369f0, 0xe, 0x7, 0x2) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1251d60 sp=0x1251d4c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv(0x15369c0, 0x1251ddb, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1251d9c sp=0x1251d60 pc=0x20078 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.chanrecv1(0x15369c0, 0x1251ddb) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1251db0 sp=0x1251d9c pc=0x1fb98 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleRequest(0x11464d8, 0x153c320) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/recv.go:237 +0x1f8 fp=0x1251f2c sp=0x1251db0 pc=0x533ab4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x11464d8) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:213 +0x4d8 fp=0x1251fe4 sp=0x1251f2c pc=0x5321e4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:125 +0x28 fp=0x1251fec sp=0x1251fe4 pc=0x53383c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1251fec sp=0x1251fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 59 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:125 +0x98 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 100 gp=0x11efd48 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1054e98 sp=0x1054e84 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1054fd4, 0x1054f60, 0x0, 0x0, 0x2, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1054f38 sp=0x1054e98 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x11464d8) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:138 +0x8c fp=0x1054fe4 sp=0x1054f38 pc=0x5318dc Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:129 +0x28 fp=0x1054fec sp=0x1054fe4 pc=0x5337e8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1054fec sp=0x1054fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 59 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/dealer/dealer.go:129 +0x15c Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 128 gp=0x13018c8 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1202cb0 sp=0x1202c9c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1202e14, 0x1202d80, 0x0, 0x0, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1202d50 sp=0x1202cb0 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x1396400) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/audio/provider.go:63 +0x118 fp=0x1202fe4 sp=0x1202d50 pc=0x494340 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/audio/provider.go:53 +0x28 fp=0x1202fec sp=0x1202fe4 pc=0x49508c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1202fec sp=0x1202fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 59 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/audio/provider.go:53 +0x60 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 101 gp=0x134a008 m=nil [IO wait, 106 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c418c8, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x124987c sp=0x1249868 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c418b8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1249894 sp=0x124987c pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c418b8, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x12498a8 sp=0x1249894 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x14b8518, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x12498bc sp=0x12498a8 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x14b8500, {0x134c000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1249904 sp=0x12498bc pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x14b8500, {0x134c000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1249930 sp=0x1249904 pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x10f8e18, {0x134c000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x124995c sp=0x1249930 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x10f8e18, {0x134c000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x124997c sp=0x124995c pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.(*multiReader).Read(0x15005c0, {0x134c000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x12499a8 sp=0x124997c pc=0x105528 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).fill(0x1000690) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x12499cc sp=0x12499a8 pc=0x2bddb4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).ReadByte(0x1000690) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x12499d8 sp=0x12499cc pc=0x2be638 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.readFrameHeader(0x1000690, {0x11c87b8, 0x8, 0x8}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1249a18 sp=0x12499d8 pc=0x47788c Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x11c8788, {0x8874d0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1249ad8 sp=0x1249a18 pc=0x47a4f0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).readLoop(0x11c8788, {0x8874d0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1249b9c sp=0x1249ad8 pc=0x479fd8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).reader(0x11c8788, {0x8874d0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1249c28 sp=0x1249b9c pc=0x47b1ec Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).Read(0x11c8788, {0x8874d0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1249c50 sp=0x1249c28 pc=0x4798bc Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1249ccc sp=0x1249c50 pc=0x633988 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.HandlerFunc.ServeHTTP(0x109a180, {0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1249cdc sp=0x1249ccc pc=0x33d7f4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*ServeMux).ServeHTTP(0x1090000, {0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1249d18 sp=0x1249cdc pc=0x33f654 Jan 11 18:18:21 volumio-lr go-librespot[8369]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1249d40 sp=0x1249d18 pc=0x633388 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.HandlerFunc.ServeHTTP(0x1094160, {0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1249d50 sp=0x1249d40 pc=0x33d7f4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.serverHandler.ServeHTTP({0x109c0a8}, {0x886dec, 0x151cf08}, 0x151ce68) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1249d6c sp=0x1249d50 pc=0x340de4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*conn).serve(0x1524c60, {0x887510, 0x10a6180}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1249fdc sp=0x1249d6c pc=0x33c034 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Server).Serve.gowrap3() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1249fec sp=0x1249fdc pc=0x341688 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1249fec sp=0x1249fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*Server).Serve in goroutine 8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 116 gp=0x134a248 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1461ea8 sp=0x1461e94 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1461fa4, 0x1461f6c, 0x0, 0x0, 0x5, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1461f48 sp=0x1461ea8 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x11c8788) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1461fe4 sp=0x1461f48 pc=0x474738 Jan 11 18:18:21 volumio-lr go-librespot[8369]: nhooyr.io/websocket.newConn.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1461fec sp=0x1461fe4 pc=0x47432c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1461fec sp=0x1461fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by nhooyr.io/websocket.newConn in goroutine 101 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 298 gp=0x134b7a8 m=nil [IO wait, 1 minutes]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41828, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1250ad4 sp=0x1250ac0 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41818, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1250aec sp=0x1250ad4 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41818, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1250b00 sp=0x1250aec pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x10d2608, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1250b14 sp=0x1250b00 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x10d25f0, {0x1400000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1250b5c sp=0x1250b14 pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x10d25f0, {0x1400000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1250b88 sp=0x1250b5c pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x1491668, {0x1400000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1250bb4 sp=0x1250b88 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x1491668, {0x1400000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x1250bd4 sp=0x1250bb4 pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*atLeastReader).Read(0x100e980, {0x1400000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1250c00 sp=0x1250bd4 pc=0x27c680 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bytes.(*Buffer).ReadFrom(0x12ada4c, {0x883674, 0x100e980}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1250c3c sp=0x1250c00 pc=0x139bd0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readFromUntil(0x12ad8c8, {0x882848, 0x1491668}, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1250c64 sp=0x1250c3c pc=0x27c8dc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecordOrCCS(0x12ad8c8, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1250dc8 sp=0x1250c64 pc=0x279fdc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecord(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).Read(0x12ad8c8, {0x1426000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1250df8 sp=0x1250dc8 pc=0x27fe4c Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).Read(0x140e120, {0x1355ea4, 0x9, 0x9}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x1250e1c sp=0x1250df8 pc=0x2be440 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadAtLeast({0x882a3c, 0x140e120}, {0x1355ea4, 0x9, 0x9}, 0x9) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1250e48 sp=0x1250e1c pc=0x1042c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadFull(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:354 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.http2readFrameHeader({0x1355ea4, 0x9, 0x9}, {0x882a3c, 0x140e120}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1250e70 sp=0x1250e48 pc=0x2f733c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2Framer).ReadFrame(0x1355e80) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1250eec sp=0x1250e70 pc=0x2f7ad4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2clientConnReadLoop).run(0x1250fdc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1250fa4 sp=0x1250eec pc=0x31c2f0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2ClientConn).readLoop(0x1364108) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1250fe4 sp=0x1250fa4 pc=0x31b7e4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1250fec sp=0x1250fe4 pc=0x314830 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1250fec sp=0x1250fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*http2Transport).newClientConn in goroutine 297 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 270 gp=0x147ed88 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41b48, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x105ad14 sp=0x105ad00 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41b38, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x105ad2c sp=0x105ad14 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41b38, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x105ad40 sp=0x105ad2c pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x14b9eb8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x105ad54 sp=0x105ad40 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x14b9ea0, {0x159d20c, 0x3, 0x3}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x105ad9c sp=0x105ad54 pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x14b9ea0, {0x159d20c, 0x3, 0x3}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x105adc8 sp=0x105ad9c pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x12a5120, {0x159d20c, 0x3, 0x3}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x105adf4 sp=0x105adc8 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x12a5120, {0x159d20c, 0x3, 0x3}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x105ae14 sp=0x105adf4 pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadAtLeast({0x882848, 0x12a5120}, {0x159d20c, 0x3, 0x3}, 0x3) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x105ae40 sp=0x105ae14 pc=0x1042c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadFull(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:354 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x14683c0, {0x8874f0, 0xd0ad00}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/shannon.go:124 +0x3a0 fp=0x105af04 sp=0x105ae40 pc=0x48faf4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x14ca428) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:276 +0x68 fp=0x105afe4 sp=0x105af04 pc=0x48c330 Jan 11 18:18:21 volumio-lr go-librespot[8369]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:390 +0x28 fp=0x105afec sp=0x105afe4 pc=0x48d010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x105afec sp=0x105afec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 81 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /src/ap/ap.go:390 +0x120 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 303 gp=0x1085208 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41508, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x105bad4 sp=0x105bac0 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c414f8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x105baec sp=0x105bad4 pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c414f8, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x105bb00 sp=0x105baec pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x107efb8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x105bb14 sp=0x105bb00 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x107efa0, {0x144a000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x105bb5c sp=0x105bb14 pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x107efa0, {0x144a000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x105bb88 sp=0x105bb5c pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x1491880, {0x144a000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x105bbb4 sp=0x105bb88 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x1491880, {0x144a000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x105bbd4 sp=0x105bbb4 pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*atLeastReader).Read(0x100e630, {0x144a000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x105bc00 sp=0x105bbd4 pc=0x27c680 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bytes.(*Buffer).ReadFrom(0x137cf0c, {0x883674, 0x100e630}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x105bc3c sp=0x105bc00 pc=0x139bd0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readFromUntil(0x137cd88, {0x882848, 0x1491880}, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x105bc64 sp=0x105bc3c pc=0x27c8dc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecordOrCCS(0x137cd88, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x105bdc8 sp=0x105bc64 pc=0x279fdc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecord(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).Read(0x137cd88, {0x1504000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x105bdf8 sp=0x105bdc8 pc=0x27fe4c Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).Read(0x140f230, {0x1506024, 0x9, 0x9}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x105be1c sp=0x105bdf8 pc=0x2be440 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadAtLeast({0x882a3c, 0x140f230}, {0x1506024, 0x9, 0x9}, 0x9) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x105be48 sp=0x105be1c pc=0x1042c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: io.ReadFull(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/io/io.go:354 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.http2readFrameHeader({0x1506024, 0x9, 0x9}, {0x882a3c, 0x140f230}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x105be70 sp=0x105be48 pc=0x2f733c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2Framer).ReadFrame(0x1506000) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x105beec sp=0x105be70 pc=0x2f7ad4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2clientConnReadLoop).run(0x105bfdc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x105bfa4 sp=0x105beec pc=0x31c2f0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2ClientConn).readLoop(0x1364208) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x105bfe4 sp=0x105bfa4 pc=0x31b7e4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x105bfec sp=0x105bfe4 pc=0x314830 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x105bfec sp=0x105bfec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*http2Transport).newClientConn in goroutine 302 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 340 gp=0x1790248 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41968, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x124dafc sp=0x124dae8 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41958, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x124db14 sp=0x124dafc pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41958, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x124db28 sp=0x124db14 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x162ed88, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x124db3c sp=0x124db28 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x162ed70, {0x18a2000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x124db84 sp=0x124db3c pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x162ed70, {0x18a2000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x124dbb0 sp=0x124db84 pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x14919a0, {0x18a2000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x124dbdc sp=0x124dbb0 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x14919a0, {0x18a2000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x124dbfc sp=0x124dbdc pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*atLeastReader).Read(0x100e560, {0x18a2000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x124dc28 sp=0x124dbfc pc=0x27c680 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bytes.(*Buffer).ReadFrom(0x137d14c, {0x883674, 0x100e560}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x124dc64 sp=0x124dc28 pc=0x139bd0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readFromUntil(0x137cfc8, {0x882848, 0x14919a0}, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x124dc8c sp=0x124dc64 pc=0x27c8dc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecordOrCCS(0x137cfc8, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x124ddf0 sp=0x124dc8c pc=0x279fdc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecord(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).Read(0x137cfc8, {0x151b000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x124de20 sp=0x124ddf0 pc=0x27fe4c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).Read(0x1632428, {0x151b000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x124de54 sp=0x124de20 pc=0x3555cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).fill(0x1532570) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x124de78 sp=0x124de54 pc=0x2bddb4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).Peek(0x1532570, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x124de84 sp=0x124de78 pc=0x2bdf38 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).readLoop(0x1632428) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x124dfe4 sp=0x124de84 pc=0x35615c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Transport).dialConn.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x124dfec sp=0x124dfe4 pc=0x354a7c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x124dfec sp=0x124dfec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*Transport).dialConn in goroutine 330 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 341 gp=0x1790368 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1055eb0 sp=0x1055e9c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x1055fac, 0x1055f78, 0x0, 0x0, 0x2, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1055f50 sp=0x1055eb0 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).writeLoop(0x1632428) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x1055fe4 sp=0x1055f50 pc=0x357c1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Transport).dialConn.gowrap3() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x1055fec sp=0x1055fe4 pc=0x354a28 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1055fec sp=0x1055fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*Transport).dialConn in goroutine 330 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 362 gp=0x1790908 m=nil [IO wait]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e40f0, 0xf6c41be8, 0x2, 0x2, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1207afc sp=0x1207ae8 pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpollblock(0xf6c41bd8, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1207b14 sp=0x1207afc pc=0x548cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.runtime_pollWait(0xf6c41bd8, 0x72) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1207b28 sp=0x1207b14 pc=0x8f4a8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).wait(0x129e068, 0x72, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1207b3c sp=0x1207b28 pc=0x10a318 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*pollDesc).waitRead(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 11 18:18:21 volumio-lr go-librespot[8369]: internal/poll.(*FD).Read(0x129e050, {0x164a000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1207b84 sp=0x1207b3c pc=0x10b504 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*netFD).Read(0x129e050, {0x164a000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1207bb0 sp=0x1207b84 pc=0x17c5d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*conn).Read(0x186a9e0, {0x164a000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1207bdc sp=0x1207bb0 pc=0x18caec Jan 11 18:18:21 volumio-lr go-librespot[8369]: net.(*TCPConn).Read(0x186a9e0, {0x164a000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: :1 +0x44 fp=0x1207bfc sp=0x1207bdc pc=0x19fd40 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*atLeastReader).Read(0x12c63d0, {0x164a000, 0xa000, 0xa000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1207c28 sp=0x1207bfc pc=0x27c680 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bytes.(*Buffer).ReadFrom(0x13fef0c, {0x883674, 0x12c63d0}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1207c64 sp=0x1207c28 pc=0x139bd0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readFromUntil(0x13fed88, {0x882848, 0x186a9e0}, 0x5) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1207c8c sp=0x1207c64 pc=0x27c8dc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecordOrCCS(0x13fed88, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1207df0 sp=0x1207c8c pc=0x279fdc Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).readRecord(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 11 18:18:21 volumio-lr go-librespot[8369]: crypto/tls.(*Conn).Read(0x13fed88, {0x153b000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1207e20 sp=0x1207df0 pc=0x27fe4c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).Read(0x1146848, {0x153b000, 0x1000, 0x1000}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x1207e54 sp=0x1207e20 pc=0x3555cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).fill(0x14c2db0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1207e78 sp=0x1207e54 pc=0x2bddb4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: bufio.(*Reader).Peek(0x14c2db0, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x1207e84 sp=0x1207e78 pc=0x2bdf38 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).readLoop(0x1146848) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x1207fe4 sp=0x1207e84 pc=0x35615c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Transport).dialConn.gowrap2() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x1207fec sp=0x1207fe4 pc=0x354a7c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1207fec sp=0x1207fec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*Transport).dialConn in goroutine 386 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 363 gp=0x1790a28 m=nil [select]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x123beb0 sp=0x123be9c pc=0x5d0c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.selectgo(0x123bfac, 0x123bf78, 0x0, 0x0, 0x2, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x123bf50 sp=0x123beb0 pc=0x70e78 Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*persistConn).writeLoop(0x1146848) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x123bfe4 sp=0x123bf50 pc=0x357c1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: net/http.(*Transport).dialConn.gowrap3() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x123bfec sp=0x123bfe4 pc=0x354a28 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goexit({}) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x123bfec sp=0x123bfec pc=0x93d6c Jan 11 18:18:21 volumio-lr go-librespot[8369]: created by net/http.(*Transport).dialConn in goroutine 386 Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0xf3b16 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0xb1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x98 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0xa79dbc82 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0xf3b16880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0xf3b16880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0xf3b167f0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0x11f85c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x1080b08 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0x1204f84 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x10859e8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0xf7acf790 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf3afed30 Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0xf7a8e6f8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0xa79dbc82 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db4 m=0 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0xce0478 m=0 mp=0xce0d20 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0xce0de8, 0x80, 0x0, 0x0, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xffaf9b3c sp=0xffaf9b3c pc=0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0xce0de8, 0x0, 0xffffffffffffffff) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xffaf9b64 sp=0xffaf9b3c pc=0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notesleep(0xce0de8) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xffaf9b84 sp=0xffaf9b64 pc=0x25548 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mPark(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1761 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.stopm() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xffaf9b98 sp=0xffaf9b84 pc=0x61d2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.findRunnable() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xffaf9c44 sp=0xffaf9b98 pc=0x639cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.schedule() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xffaf9c60 sp=0xffaf9c44 pc=0x64f1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.park_m(0x11ef7a8) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xffaf9c80 sp=0xffaf9c60 pc=0x65794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mcall(0x880e90) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xffaf9c88 sp=0xffaf9c80 pc=0x920a0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0xce0de8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0xce2a20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0xce0478 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0x6b484570 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xffaf9b3c Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db8 m=2 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x10026c8 m=2 mp=0x1048588 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0xce2aac, 0x80, 0x0, 0xf747ccc8, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:417 +0x20 fp=0xf747cca8 sp=0xf747cca8 pc=0x94db8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0xce2aac, 0x0, 0x548acd6bc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:75 +0x178 fp=0xf747ccd0 sp=0xf747cca8 pc=0x5599c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notetsleep_internal(0xce2aac, 0x548acd6bc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:212 +0x124 fp=0xf747ccf8 sp=0xf747ccd0 pc=0x256cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notetsleep(0xce2aac, 0x548acd6bc) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:235 +0x48 fp=0xf747cd0c sp=0xf747ccf8 pc=0x2586c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.sysmon() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:6001 +0x2e0 fp=0xf747cd68 sp=0xf747cd0c pc=0x6a818 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf747cd78 sp=0xf747cd68 pc=0x6015c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart0() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf747cd8c sp=0xf747cd78 pc=0x600d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf747cd90 sp=0xf747cd8c pc=0x92040 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0xfffffffc Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0xf747ccc8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x10026c8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x3b9aca00 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf747cca8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x5599c Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0x60800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db4 m=3 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x1002a28 m=3 mp=0x1048b08 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0x1048bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf6afec2c sp=0xf6afec2c pc=0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0x1048bd0, 0x0, 0xffffffffffffffff) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf6afec54 sp=0xf6afec2c pc=0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notesleep(0x1048bd0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf6afec74 sp=0xf6afec54 pc=0x25548 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mPark(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1761 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.stopm() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf6afec88 sp=0xf6afec74 pc=0x61d2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.findRunnable() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf6afed34 sp=0xf6afec88 pc=0x639cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.schedule() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf6afed50 sp=0xf6afed34 pc=0x64f1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.park_m(0x145c368) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf6afed70 sp=0xf6afed50 pc=0x65794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mcall(0xd204e0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf6afed78 sp=0xf6afed70 pc=0x920a0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0x1048bd0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0xce2a20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x1002a28 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0x809d3155 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf6afec2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db4 m=4 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x1002ea8 m=4 mp=0x1049088 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0x1049150, 0x80, 0x0, 0x0, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf60fec2c sp=0xf60fec2c pc=0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0x1049150, 0x0, 0xffffffffffffffff) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf60fec54 sp=0xf60fec2c pc=0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notesleep(0x1049150) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf60fec74 sp=0xf60fec54 pc=0x25548 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mPark(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1761 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.stopm() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf60fec88 sp=0xf60fec74 pc=0x61d2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.findRunnable() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf60fed34 sp=0xf60fec88 pc=0x639cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.schedule() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf60fed50 sp=0xf60fed34 pc=0x64f1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.park_m(0x147ea28) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf60fed70 sp=0xf60fed50 pc=0x65794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mcall(0xd20590) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf60fed78 sp=0xf60fed70 pc=0x920a0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0x1049150 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0xce2a20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x1002ea8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0xbb8d8960 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf60fec2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x1b624 m=5 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x1084128 m=5 mp=0x1080008 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime/internal/syscall.Syscall6(0x15a, 0x4, 0xf58fd468, 0x80, 0x58a6, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/internal/syscall/asm_linux_arm.s:17 +0x20 fp=0xf58fd3ec sp=0xf58fd3ec pc=0x1b624 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime/internal/syscall.EpollWait(0x4, {0xf58fd468, 0x80, 0x80}, 0x80, 0x58a6) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/internal/syscall/syscall_linux.go:55 +0x60 fp=0xf58fd41c sp=0xf58fd3ec pc=0x1b57c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.netpoll(0x548b26b6a) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/netpoll_epoll.go:118 +0x17c fp=0xf58fdc88 sp=0xf58fd41c pc=0x554c0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.findRunnable() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3470 +0xbbc fp=0xf58fdd34 sp=0xf58fdc88 pc=0x63784 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.schedule() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf58fdd50 sp=0xf58fdd34 pc=0x64f1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.park_m(0x11ef7a8) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0xf58fdd70 sp=0xf58fdd50 pc=0x65794 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mcall(0xd204e0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0xf58fdd78 sp=0xf58fdd70 pc=0x920a0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0xfffffffc Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0xf58fd468 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x58a6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0x15a Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x1084128 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0xcac414 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0xbb8936d1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf58fd3ec Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x1b57c Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x1b624 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0x20800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db4 m=6 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x10030e8 m=6 mp=0x1049608 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0xd0b55c, 0x80, 0x0, 0x0, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf4cfed14 sp=0xf4cfed14 pc=0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0xd0b55c, 0x0, 0xffffffffffffffff) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf4cfed3c sp=0xf4cfed14 pc=0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notesleep(0xd0b55c) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf4cfed5c sp=0xf4cfed3c pc=0x25548 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.templateThread() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:2760 +0x90 fp=0xf4cfed68 sp=0xf4cfed5c pc=0x61be4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart1() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0xf4cfed78 sp=0xf4cfed68 pc=0x6015c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart0() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0xf4cfed8c sp=0xf4cfed78 pc=0x600d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mstart() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0xf4cfed90 sp=0xf4cfed8c pc=0x92040 Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0xd0b55c Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0xf44ff000 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0xffaf9a3f Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x10030e8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0x7 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf4cfed14 Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ----- Jan 11 18:18:21 volumio-lr go-librespot[8369]: SIGQUIT: quit Jan 11 18:18:21 volumio-lr go-librespot[8369]: PC=0x94db4 m=7 sigcode=0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: goroutine 0 gp=0x11eeea8 m=7 mp=0x104b708 [idle]: Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futex(0x104b7d0, 0x80, 0x0, 0x0, 0x0, 0x0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xf44fdb60 sp=0xf44fdb60 pc=0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.futexsleep(0x104b7d0, 0x0, 0xffffffffffffffff) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0xf44fdb88 sp=0xf44fdb60 pc=0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.notesleep(0x104b7d0) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0xf44fdba8 sp=0xf44fdb88 pc=0x25548 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.mPark(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:1761 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.stopm() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0xf44fdbbc sp=0xf44fdba8 pc=0x61d2c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.findRunnable() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0xf44fdc68 sp=0xf44fdbbc pc=0x639cc Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.schedule() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0xf44fdc84 sp=0xf44fdc68 pc=0x64f1c Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.goschedImpl(0x17905a8, 0x1) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4065 +0x198 fp=0xf44fdc9c sp=0xf44fdc84 pc=0x65944 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.gopreempt_m(...) Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/proc.go:4082 Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.newstack() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/stack.go:1070 +0x3b0 fp=0xf44fdd74 sp=0xf44fdc9c pc=0x781ac Jan 11 18:18:21 volumio-lr go-librespot[8369]: runtime.morestack() Jan 11 18:18:21 volumio-lr go-librespot[8369]: /usr/local/go/src/runtime/asm_arm.s:383 +0x60 fp=0xf44fdd78 sp=0xf44fdd74 pc=0x921ac Jan 11 18:18:21 volumio-lr go-librespot[8369]: trap 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: error 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: oldmask 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r0 0x104b7d0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r1 0x80 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r2 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r3 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r4 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r5 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r6 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r7 0xf0 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r8 0x1 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r9 0xce2a20 Jan 11 18:18:21 volumio-lr go-librespot[8369]: r10 0x11eeea8 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fp 0x6 Jan 11 18:18:21 volumio-lr go-librespot[8369]: ip 0x50a20b7 Jan 11 18:18:21 volumio-lr go-librespot[8369]: sp 0xf44fdb60 Jan 11 18:18:21 volumio-lr go-librespot[8369]: lr 0x55880 Jan 11 18:18:21 volumio-lr go-librespot[8369]: pc 0x94db4 Jan 11 18:18:21 volumio-lr go-librespot[8369]: cpsr 0xa0800010 Jan 11 18:18:21 volumio-lr go-librespot[8369]: fault 0x0 Jan 11 18:18:21 volumio-lr go-librespot[8368]: Aborted Jan 11 18:18:21 volumio-lr systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 11 18:18:21 volumio-lr volumio[1439]: info: Connection to go-librespot Websocket closed Jan 11 18:18:21 volumio-lr systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 11 18:18:21 volumio-lr systemd[1]: go-librespot-daemon.service: Consumed 2.396s CPU time. Jan 11 18:18:24 volumio-lr volumio[1439]: info: Initializing connection to go-librespot Websocket Jan 11 18:18:24 volumio-lr volumio[1439]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 18:18:24 volumio-lr systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jan 11 18:18:24 volumio-lr systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 18:18:24 volumio-lr systemd[1]: go-librespot-daemon.service: Consumed 2.396s CPU time. Jan 11 18:18:24 volumio-lr systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 18:18:24 volumio-lr go-librespot[19792]: go-librespot daemon starting... Jan 11 18:18:24 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:24-07:00" level=info msg="running go-librespot 0.4.0" Jan 11 18:18:24 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:24-07:00" level=debug msg="app state loaded" Jan 11 18:18:24 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:24-07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=info msg="zeroconf server listening on port 40629" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="obtained new client token: AAC/17wbTxZgny/SOzPUdtqdKXYldtNbcxBf9r7tddll1p6TUQzUcEI+BXJcTgL8q1bXjKVk3P/+wN2eaLvIPv9QgigsIxlxTzzN+pmh/GhWk1eH3Sg3P9U8M7cdcVie8MhjCwDxNj2T5L/kBwOBDN+xhCUFUey+u2GZHM5NpiEdW4rksntlwCRu82lBInM3cHKnmZ7RcPnnDVUNeDhs4RLkrQ9b9OWVCXxmROKSxjlKyEF1k46YbgI=" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="completed keyexchange" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="completed challenge" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=info msg="authenticated AP" username="co******ot" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=info msg="authenticated Login5" username="co******ot" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="initializing zeroconf session" username="co******ot" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="dealer connection opened" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=trace msg="starting accesspoint recv loop" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=trace msg="starting dealer recv loop" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=trace msg="received accesspoint ping" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="received connection id: MjUxNjlkMTgtODli...M0YwRTU0QkE3Ng==" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=trace msg="received accesspoint pong ack" Jan 11 18:18:25 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:25-07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 18:18:26 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:26 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:26 volumio-lr volumio[1439]: info: CoreStateMachine::stPlaybackTimer Jan 11 18:18:26 volumio-lr volumio[1439]: info: CoreStateMachine::servicePause Jan 11 18:18:26 volumio-lr volumio[1439]: info: CoreCommandRouter::servicePause Jan 11 18:18:26 volumio-lr volumio[1439]: info: Spotify Received pause Jan 11 18:18:26 volumio-lr volumio[1439]: SPOTIFY: SPOTIFY PAUSE Jan 11 18:18:26 volumio-lr volumio[1439]: SPOTIFY: {"status":"play","title":"Case","artist":"Diljit Dosanjh, Intense","album":"Ghost","albumart":"https://i.scdn.co/image/ab67616d00001e02cb2f9520171129a3df7a241a","uri":"spotify:track:0TdWXMXyfTQ2qp5vvU9LID","trackType":"spotify","codec":"ogg","seek":0,"duration":135,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":67,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jan 11 18:18:26 volumio-lr volumio[1439]: info: Sending Spotify command to local API: /player/pause Jan 11 18:18:27 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:27 volumio-lr volumio[1439]: info: Initializing connection to go-librespot Websocket Jan 11 18:18:27 volumio-lr volumio[1439]: info: Connection to go-librespot Websocket established Jan 11 18:18:27 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:27-07:00" level=debug msg="new websocket client" Jan 11 18:18:27 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:27 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:28 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:29 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:29 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:30 volumio-lr volumio[1439]: info: Getting Spotify volume Jan 11 18:18:30 volumio-lr volumio[1439]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 11 18:18:30 volumio-lr volumio[1439]: info: Spotify volume: 100 Jan 11 18:18:30 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:30 volumio-lr volumio[1439]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Jan 11 18:18:30 volumio-lr volumio[1439]: SPOTIFY: SPOTIFY VOLUME 100 Jan 11 18:18:30 volumio-lr volumio[1439]: SPOTIFY: VOLUMIO VOLUME 67 Jan 11 18:18:30 volumio-lr volumio[1439]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 11 18:18:30 volumio-lr volumio[1439]: info: Setting Spotify Volume from Volumio: 67 Jan 11 18:18:32 volumio-lr volumio[1439]: SPOTIFY: SETTING SPOTIFY VOLUME 67 Jan 11 18:18:32 volumio-lr volumio[1439]: info: Sending Spotify command with payload to local API: /player/volume Jan 11 18:18:32 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:32-07:00" level=debug msg="update volume requested to 43908/65535" Jan 11 18:18:32 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:32-07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 18:18:32 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:32-07:00" level=trace msg="emitting websocket event: volume" Jan 11 18:18:32 volumio-lr volumio[1439]: SPOTIFY: received: {"type":"volume","data":{"value":67,"max":100}} Jan 11 18:18:32 volumio-lr volumio[1439]: SPOTIFY: RECEIVED SPOTIFY VOLUME 67 Jan 11 18:18:33 volumio-lr volumio[1439]: info: VolumeController::SetAlsaVolume55 Jan 11 18:18:33 volumio-lr volumio[1439]: info: CoreStateMachine::pushState Jan 11 18:18:33 volumio-lr volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:18:33 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPushState Jan 11 18:18:33 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:18:33 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output Jan 11 18:18:33 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:33 volumio-lr volumio[1439]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 11 18:18:33 volumio-lr volumio[1439]: SPOTIFY: SPOTIFY VOLUME 67 Jan 11 18:18:33 volumio-lr volumio[1439]: SPOTIFY: VOLUMIO VOLUME 55 Jan 11 18:18:33 volumio-lr volumio[1439]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 11 18:18:33 volumio-lr volumio[1439]: info: Setting Spotify Volume from Volumio: 55 Jan 11 18:18:34 volumio-lr volumio[1439]: SPOTIFY: SETTING SPOTIFY VOLUME 55 Jan 11 18:18:34 volumio-lr volumio[1439]: info: Sending Spotify command with payload to local API: /player/volume Jan 11 18:18:34 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:34-07:00" level=debug msg="update volume requested to 36044/65535" Jan 11 18:18:34 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:34-07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 18:18:34 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:34-07:00" level=trace msg="emitting websocket event: volume" Jan 11 18:18:34 volumio-lr volumio[1439]: SPOTIFY: received: {"type":"volume","data":{"value":55,"max":100}} Jan 11 18:18:34 volumio-lr volumio[1439]: SPOTIFY: RECEIVED SPOTIFY VOLUME 55 Jan 11 18:18:35 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPause Jan 11 18:18:35 volumio-lr volumio[1439]: info: CoreStateMachine::pause Jan 11 18:18:37 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:37 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:37 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:47 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:55 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:55-07:00" level=trace msg="sent dealer ping" Jan 11 18:18:55 volumio-lr go-librespot[19793]: time="2026-01-11T18:18:55-07:00" level=trace msg="received dealer pong" Jan 11 18:18:57 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:18:57 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:57 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:18:59 volumio-lr volumio[1439]: info: [1768180739474] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Jan 11 18:18:59 volumio-lr volumio[1439]: info: [1768180739593] [RadioParadise] received new metadata: {"time":263,"artist":"J.S. Ondara","title":"A Seminar in Tokyo","album":"Spanish Villager No: 3","year":"2022","cover":"https://img.radioparadise.com/covers/l/18818.jpg","cover_med":"https://img.radioparadise.com/covers/m/18818.jpg","cover_small":"https://img.radioparadise.com/covers/s/18818.jpg"} Jan 11 18:19:07 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:19:09 volumio-lr volumio[1439]: info: Jan 11 18:19:09 volumio-lr volumio[1439]: ---------------------------- MPD announces system playlist update Jan 11 18:19:09 volumio-lr volumio[1439]: info: Ignoring MPD Status Update Jan 11 18:19:09 volumio-lr volumio[1439]: info: Jan 11 18:19:09 volumio-lr volumio[1439]: ---------------------------- MPD announces state update: player Jan 11 18:19:09 volumio-lr volumio[1439]: info: ControllerMpd::getState Jan 11 18:19:09 volumio-lr volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:19:09 volumio-lr volumio[1439]: info: ------------------------------ 1ms Jan 11 18:19:09 volumio-lr volumio[1439]: info: sendMpdCommand status took 1 milliseconds Jan 11 18:19:09 volumio-lr volumio[1439]: verbose: ControllerMpd::parseState Jan 11 18:19:09 volumio-lr volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:19:09 volumio-lr volumio[1439]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 18:19:09 volumio-lr volumio[1439]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:19:09 volumio-lr volumio[1439]: info: ControllerMpd::pushState Jan 11 18:19:09 volumio-lr volumio[1439]: info: CoreCommandRouter::servicePushState Jan 11 18:19:09 volumio-lr volumio[1439]: info: CoreStateMachine::pushState Jan 11 18:19:09 volumio-lr volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:19:09 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioPushState Jan 11 18:19:09 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:19:09 volumio-lr volumio[1439]: info: MRS: Pushing multiroomSync output Jan 11 18:19:09 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:19:09 volumio-lr volumio[1439]: info: ------------------------------ 6ms Jan 11 18:19:09 volumio-lr volumio[1439]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jan 11 18:19:15 volumio-lr volumio[1439]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 18:19:15 volumio-lr volumio[1439]: info: CURURI: artists:// Jan 11 18:19:15 volumio-lr volumio[1439]: info: Preload queue cleared Jan 11 18:19:17 volumio-lr volumio[1439]: info: CoreCommandRouter::volumioGetState Jan 11 18:19:17 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:19:17 volumio-lr volumio[1439]: info: Listing playlists Jan 11 18:19:18 volumio-lr volumio[1439]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Alberto%20Lizzio/ebca6d5d-8cc4-4903-bda7-c85cb6d051e7.jpg' Jan 11 18:19:20 volumio-lr volumio[1439]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Archive/55e0dc8e-f9b3-47f4-bb7a-fd3185b4792a.jpg' Jan 11 18:19:21 volumio-lr volumio[1439]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:19:21 volumio-lr volumio[1439]: TypeError: Cannot read properties of null (reading 'slice') Jan 11 18:19:21 volumio-lr volumio[1439]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 11 18:19:21 volumio-lr volumio[1439]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 11 18:19:21 volumio-lr volumio[1439]: at Object.onceWrapper (node:events:629:26) Jan 11 18:19:21 volumio-lr volumio[1439]: at ClientRequest.emit (node:events:514:28) Jan 11 18:19:21 volumio-lr volumio[1439]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jan 11 18:19:21 volumio-lr volumio[1439]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jan 11 18:19:21 volumio-lr volumio[1439]: at TLSSocket.socketOnData (node:_http_client:535:22) Jan 11 18:19:21 volumio-lr volumio[1439]: at TLSSocket.emit (node:events:514:28) Jan 11 18:19:21 volumio-lr volumio[1439]: at addChunk (node:internal/streams/readable:343:12) Jan 11 18:19:21 volumio-lr volumio[1439]: at readableAddChunk (node:internal/streams/readable:316:9) Jan 11 18:19:21 volumio-lr volumio[1439]: at Readable.push (node:internal/streams/readable:253:10) Jan 11 18:19:21 volumio-lr volumio[1439]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jan 11 18:19:21 volumio-lr volumio[1439]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:19:22 volumio-lr sudo[19920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 18:18' Jan 11 18:19:22 volumio-lr sudo[19920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"