Feb 09 11:43:02 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:02+05:00" level=trace msg="sent dealer ping" Feb 09 11:43:02 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:02+05:00" level=trace msg="received dealer pong" Feb 09 11:43:10 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:10+05:00" level=debug msg="fetched chunk 12/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:43:23 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:23+05:00" level=debug msg="fetched chunk 13/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:43:32 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:32+05:00" level=trace msg="sent dealer ping" Feb 09 11:43:32 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:32+05:00" level=trace msg="received dealer pong" Feb 09 11:43:35 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:35+05:00" level=debug msg="fetched chunk 14/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:43:46 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:46+05:00" level=debug msg="fetched chunk 15/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:43:57 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:43:57+05:00" level=debug msg="fetched chunk 16/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:44:02 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:02+05:00" level=trace msg="sent dealer ping" Feb 09 11:44:02 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:02+05:00" level=trace msg="received dealer pong" Feb 09 11:44:08 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:08+05:00" level=debug msg="fetched chunk 17/23, size: 524288" uri="spotify:track:18U9uBjo0x98xs3WcEuYa6" Feb 09 11:44:11 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:11+05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Feb 09 11:44:11 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:11+05:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8KRfycvekBls" Feb 09 11:44:11 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:11+05:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIYxrJ8kwVOL0" Feb 09 11:44:11 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:11+05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=info msg="playback was transferred to My Volumio 3" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=trace msg="closed output device because of stop command" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=debug msg="put connect state inactive" Feb 09 11:44:20 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:20+05:00" level=trace msg="emitting websocket event: inactive" Feb 09 11:44:20 my-volumio-digi volumio[1373]: SPOTIFY: received: {"type":"inactive","data":null} Feb 09 11:44:21 my-volumio-digi volumio[1373]: error: Failed to decode event: inactive Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: time="2026-02-09T11:44:21+05:00" level=trace msg="emitting websocket event: stopped" Feb 09 11:44:21 my-volumio-digi volumio[1373]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Feb 09 11:44:21 my-volumio-digi volumio[1373]: SPOTIFY: PUSH STATE SPOTIFY Feb 09 11:44:21 my-volumio-digi volumio[1373]: SPOTIFY: {"status":"stop","service":"spop","title":"Mann Meriyan","artist":"TAHIR ABBAS","album":"Mann Meriyan","albumart":"https://i.scdn.co/image/ab67616d00001e02bf88657f810762c56fabe786","uri":"spotify:track:18U9uBjo0x98xs3WcEuYa6","trackType":"spotify","seek":167000,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::servicePushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::pushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 0 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::volumioPushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 0 Feb 09 11:44:21 my-volumio-digi volumio[1373]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Mann Meriyan","artist":"TAHIR ABBAS","album":"Mann Meriyan","albumart":"https://i.scdn.co/image/ab67616d00001e02bf88657f810762c56fabe786","uri":"spotify:track:18U9uBjo0x98xs3WcEuYa6","trackType":"spotify","seek":167000,"duration":269,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 09 11:44:21 my-volumio-digi volumio[1373]: verbose: CURRENT POSITION 0 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::syncState stateService stop Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::syncState currentStatus play Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::play index undefined Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::pushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 1 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::volumioPushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 1 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::startPlaybackTimer Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 1 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: [1770619461052] ControllerSpotify::clearAddPlayTrack Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: Sending Spotify command with payload to local API: /player/play Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreStateMachine::pushState Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CorePlayQueue::getTrack 1 Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: CoreCommandRouter::volumioPushState Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: panic: send on closed channel Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 15 gp=0x1bf7688 m=3 mp=0x1848b08 [running]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: panic({0x6cfbf0, 0x881b80}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x1843748 sp=0x18436f4 pc=0x58bf8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.chansend(0x18aa280, 0x18437a8, 0x1, 0x63e7c8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/chan.go:283 +0x5e4 fp=0x1843780 sp=0x1843748 pc=0x1f474 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.chansend1(0x18aa280, 0x18437a8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1843798 sp=0x1843780 pc=0x1ee8c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).withAppPlayer.func1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:275 +0x9c fp=0x18437ec sp=0x1843798 pc=0x63e7c8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18437ec sp=0x18437ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by main.(*App).withAppPlayer in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:265 +0x464 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 1 gp=0x1802128 m=nil [select, 3 minutes]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1854d00 sp=0x1854cec pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.selectgo(0x1854e00, 0x19dddcc, 0x0, 0x0, 0x2, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1854da0 sp=0x1854d00 pc=0x70e78 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x18b1440, 0x1854e90) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x1854e44 sp=0x1854da0 pc=0x5a8088 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).withAppPlayer(0x1a0a100, {0x8874f0, 0xd0ad00}, 0x189a150) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:321 +0x554 fp=0x1854ed0 sp=0x1854e44 pc=0x63db54 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).withCredentials(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:193 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).SpotifyToken(0x1a0a100, {0x8874f0, 0xd0ad00}, {0x1920900, 0x1c}, {0x187c3c0, 0x13c}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x1854f00 sp=0x1854ed0 pc=0x63cfa8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.main() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:557 +0x588 fp=0x1854fa8 sp=0x1854f00 pc=0x63feb4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.main() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1854fec sp=0x1854fa8 pc=0x5cb30 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1854fec sp=0x1854fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle), 3 minutes]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1844fd4 sp=0x1844fc0 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goparkunlock(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:408 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.forcegchelper() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1844fec sp=0x1844fd4 pc=0x5cefc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1844fec sp=0x1844fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.init.5 in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:314 +0x1c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 3 gp=0x1802b48 m=nil [GC sweep wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18457c4 sp=0x18457b0 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goparkunlock(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:408 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.bgsweep(0x186c000) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x18457e4 sp=0x18457c4 pc=0x45848 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcenable.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x18457ec sp=0x18457e4 pc=0x35968 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18457ec sp=0x18457ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcenable in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 4 gp=0x1802c68 m=nil [GC scavenge wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1845fb4 sp=0x1845fa0 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goparkunlock(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:408 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.(*scavengerState).park(0xce0208) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1845fc8 sp=0x1845fb4 pc=0x42b68 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.bgscavenge(0x186c000) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1845fe4 sp=0x1845fc8 pc=0x432a0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcenable.gowrap2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1845fec sp=0x1845fe4 pc=0x35914 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1845fec sp=0x1845fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcenable in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 5 gp=0x1803448 m=nil [finalizer wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x184678c sp=0x1846778 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.runfinq() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x18467ec sp=0x184678c pc=0x34754 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18467ec sp=0x18467ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.createfing in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 20 gp=0x1884368 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051f08, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1840d28 sp=0x1840d14 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051ef8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1840d40 sp=0x1840d28 pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051ef8, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1840d54 sp=0x1840d40 pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1890298, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1840d68 sp=0x1840d54 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Accept(0x1890280) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1840db0 sp=0x1840d68 pc=0x10eb84 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).accept(0x1890280) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1840e18 sp=0x1840db0 pc=0x17e4c4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPListener).accept(0x189c2d0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1840e30 sp=0x1840e18 pc=0x196900 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPListener).Accept(0x189c2d0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1840e4c sp=0x1840e30 pc=0x19596c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*onceCloseListener).Accept(0x19ec760) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x34 fp=0x1840e64 sp=0x1840e4c pc=0x362b54 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Server).Serve(0x19c3cc8, {0x886cb8, 0x189c2d0}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1840efc sp=0x1840e64 pc=0x341210 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.Serve(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2794 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).serve(0x18b0000) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x1840fe4 sp=0x1840efc pc=0x6330b8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.NewApiServer.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x1840fec sp=0x1840fe4 pc=0x631de4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1840fec sp=0x1840fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by main.NewApiServer in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:296 +0x310 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 16 gp=0x1884488 m=nil [runnable]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.asyncPreempt2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/preempt.go:307 +0x38 fp=0x185bcac sp=0x185bca0 pc=0x5b450 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.asyncPreempt() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/preempt_arm.s:44 +0x98 fp=0x185bd68 sp=0x185bcac pc=0x948c4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: encoding/hex.Decode({0x1a04150, 0x28, 0x30}, {0x1a04150, 0x28, 0x30}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/encoding/hex/hex.go:87 fp=0x185bd6c sp=0x185bd6c pc=0x1314b4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: encoding/hex.DecodeString(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/encoding/hex/hex.go:142 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/devgianlu/go-librespot/session.NewSessionFromOptions({0x8874f0, 0xd0ad00}, 0x185bf18) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/session/session.go:51 +0x60 fp=0x185bed8 sp=0x185bd6c pc=0x5458e0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).newAppPlayer(0x1a0a100, {0x8874f0, 0xd0ad00}, {0x6daf48, 0x189c0f0}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:121 +0x204 fp=0x185bf50 sp=0x185bed8 pc=0x63cb28 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).SpotifyToken.(*App).withCredentials.func1({0x8874f0, 0xd0ad00}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:195 +0x26c fp=0x185bf90 sp=0x185bf50 pc=0x63d248 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*App).withAppPlayer.func2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:298 +0x14c fp=0x185bfec sp=0x185bf90 pc=0x63e410 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x185bfec sp=0x185bfec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by main.(*App).withAppPlayer in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/main.go:281 +0x504 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 71 gp=0x18845a8 m=nil [IO wait, 3 minutes]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x760518c8, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1b6c87c sp=0x1b6c868 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x760518b8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1b6c894 sp=0x1b6c87c pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x760518b8, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1b6c8a8 sp=0x1b6c894 pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1a2e018, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1b6c8bc sp=0x1b6c8a8 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Read(0x1a2e000, {0x1a34000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1b6c904 sp=0x1b6c8bc pc=0x10b504 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).Read(0x1a2e000, {0x1a34000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1b6c930 sp=0x1b6c904 pc=0x17c5d0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*conn).Read(0x1cc2058, {0x1a34000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1b6c95c sp=0x1b6c930 pc=0x18caec Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPConn).Read(0x1cc2058, {0x1a34000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x44 fp=0x1b6c97c sp=0x1b6c95c pc=0x19fd40 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: io.(*multiReader).Read(0x1aa6840, {0x1a34000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1b6c9a8 sp=0x1b6c97c pc=0x105528 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bufio.(*Reader).fill(0x1a0cd20) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1b6c9cc sp=0x1b6c9a8 pc=0x2bddb4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bufio.(*Reader).ReadByte(0x1a0cd20) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1b6c9d8 sp=0x1b6c9cc pc=0x2be638 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.readFrameHeader(0x1a0cd20, {0x1b943f8, 0x8, 0x8}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1b6ca18 sp=0x1b6c9d8 pc=0x47788c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1b943c8, {0x8874d0, 0xd0ad00}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1b6cad8 sp=0x1b6ca18 pc=0x47a4f0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).readLoop(0x1b943c8, {0x8874d0, 0xd0ad00}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1b6cb9c sp=0x1b6cad8 pc=0x479fd8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).reader(0x1b943c8, {0x8874d0, 0xd0ad00}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1b6cc28 sp=0x1b6cb9c pc=0x47b1ec Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).Reader(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).Read(0x1b943c8, {0x8874d0, 0xd0ad00}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1b6cc50 sp=0x1b6cc28 pc=0x4798bc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1b6cccc sp=0x1b6cc50 pc=0x633988 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.HandlerFunc.ServeHTTP(0x1831798, {0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1b6ccdc sp=0x1b6cccc pc=0x33d7f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*ServeMux).ServeHTTP(0x1922b00, {0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1b6cd18 sp=0x1b6ccdc pc=0x33f654 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1b6cd40 sp=0x1b6cd18 pc=0x633388 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.HandlerFunc.ServeHTTP(0x180f4c0, {0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1b6cd50 sp=0x1b6cd40 pc=0x33d7f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.serverHandler.ServeHTTP({0x19c3cc8}, {0x886dec, 0x1a163c8}, 0x19c2dc8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1b6cd6c sp=0x1b6cd50 pc=0x340de4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*conn).serve(0x1829680, {0x887510, 0x18266a8}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1b6cfdc sp=0x1b6cd6c pc=0x33c034 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Server).Serve.gowrap3() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1b6cfec sp=0x1b6cfdc pc=0x341688 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b6cfec sp=0x1b6cfec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*Server).Serve in goroutine 20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3285 +0x468 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 34 gp=0x1bf6128 m=nil [GC worker (idle)]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3fbc, 0x1ba3ec0, 0x1a, 0xa, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ce0790 sp=0x1ce077c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcBgMarkWorker() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1ce07ec sp=0x1ce0790 pc=0x38590 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ce07ec sp=0x1ce07ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 8 gp=0x19f0a28 m=nil [GC worker (idle)]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3fbc, 0x182b5d8, 0x1a, 0xa, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1844790 sp=0x184477c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcBgMarkWorker() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x18447ec sp=0x1844790 pc=0x38590 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18447ec sp=0x18447ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 35 gp=0x1bf6248 m=nil [GC worker (idle)]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3fbc, 0x1ba3ed8, 0x1a, 0xa, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ce0f90 sp=0x1ce0f7c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcBgMarkWorker() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1ce0fec sp=0x1ce0f90 pc=0x38590 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ce0fec sp=0x1ce0fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 36 gp=0x1bf6368 m=nil [GC worker (idle)]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3fbc, 0x182b5f0, 0x1a, 0xa, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ce1790 sp=0x1ce177c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gcBgMarkWorker() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1ce17ec sp=0x1ce1790 pc=0x38590 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ce17ec sp=0x1ce17ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 9 gp=0x18847e8 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051d28, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1b6de84 sp=0x1b6de70 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051d18, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1b6de9c sp=0x1b6de84 pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051d18, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1b6deb0 sp=0x1b6de9c pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1ae7e18, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1b6dec4 sp=0x1b6deb0 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).RawRead(0x1ae7e00, 0x2c3fb90) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1b6def0 sp=0x1b6dec4 pc=0x10f788 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*rawConn).Read(0x1a02cc8, 0x2c3fb90) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1b6df10 sp=0x1b6def0 pc=0x190d28 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1b57770, 0x2c3fb60, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1b6df40 sp=0x1b6df10 pc=0x548824 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1801508, {0x1a52000, 0x10000, 0x10000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1b6df9c sp=0x1b6df40 pc=0x54c80c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/grandcat/zeroconf.(*Server).recv4(0x1a09e00, 0x1801500) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1b6dfe0 sp=0x1b6df9c pc=0x5a309c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1b6dfec sp=0x1b6dfe0 pc=0x5a2d54 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b6dfec sp=0x1b6dfec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 29 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 10 gp=0x1884908 m=nil [IO wait, 3 minutes]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051c88, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1cdf65c sp=0x1cdf648 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051c78, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1cdf674 sp=0x1cdf65c pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051c78, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1cdf688 sp=0x1cdf674 pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1ae7e68, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1cdf69c sp=0x1cdf688 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).RawRead(0x1ae7e50, 0x18afe30) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1cdf6c8 sp=0x1cdf69c pc=0x10f788 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*rawConn).Read(0x1a02cf0, 0x18afe30) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1cdf6e8 sp=0x1cdf6c8 pc=0x190d28 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1b57780, 0x18afe00, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1cdf718 sp=0x1cdf6e8 pc=0x548824 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1801538, {0x1b00000, 0x10000, 0x10000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1cdf79c sp=0x1cdf718 pc=0x550194 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/grandcat/zeroconf.(*Server).recv6(0x1a09e00, 0x1801530) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1cdf7e0 sp=0x1cdf79c pc=0x5a3290 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1cdf7ec sp=0x1cdf7e0 pc=0x5a2cf8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1cdf7ec sp=0x1cdf7ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 29 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 58629 gp=0x1c825a8 m=nil [chan receive]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e3f90, 0x1a98930, 0xe, 0x7, 0x2) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1856bb0 sp=0x1856b9c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.chanrecv(0x1a98900, 0x1856c7c, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1856bec sp=0x1856bb0 pc=0x20078 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.chanrecv1(0x1a98900, 0x1856c7c) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1856c00 sp=0x1856bec pc=0x1fb98 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).handleRequest(0x18b0000, {{0x79134a, 0x4}, {0x6eba88, 0x1826990}, 0x1a98900}, {0x886dec, 0x19c2be8}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:319 +0x74 fp=0x1856c8c sp=0x1856c00 pc=0x631f94 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).serve.func4({0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/api_server.go:412 +0x148 fp=0x1856ccc sp=0x1856c8c pc=0x634b60 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.HandlerFunc.ServeHTTP(0x1831658, {0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1856cdc sp=0x1856ccc pc=0x33d7f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*ServeMux).ServeHTTP(0x1922b00, {0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1856d18 sp=0x1856cdc pc=0x33f654 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1856d40 sp=0x1856d18 pc=0x633388 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.HandlerFunc.ServeHTTP(0x180f4c0, {0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1856d50 sp=0x1856d40 pc=0x33d7f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.serverHandler.ServeHTTP({0x19c3cc8}, {0x886dec, 0x19c2be8}, 0x1b4d548) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1856d6c sp=0x1856d50 pc=0x340de4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*conn).serve(0x18b0a80, {0x887510, 0x18266a8}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1856fdc sp=0x1856d6c pc=0x33c034 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Server).Serve.gowrap3() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1856fec sp=0x1856fdc pc=0x341688 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1856fec sp=0x1856fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*Server).Serve in goroutine 20 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3285 +0x468 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 58611 gp=0x1c826c8 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051788, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c606b4 sp=0x1c606a0 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051778, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c606cc sp=0x1c606b4 pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051778, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c606e0 sp=0x1c606cc pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x18b52d8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c606f4 sp=0x1c606e0 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Read(0x18b52c0, {0x2f5c3cd, 0x1, 0x1}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c6073c sp=0x1c606f4 pc=0x10b504 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).Read(0x18b52c0, {0x2f5c3cd, 0x1, 0x1}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1c60768 sp=0x1c6073c pc=0x17c5d0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*conn).Read(0x1af97c0, {0x2f5c3cd, 0x1, 0x1}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1c60794 sp=0x1c60768 pc=0x18caec Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPConn).Read(0x1af97c0, {0x2f5c3cd, 0x1, 0x1}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x44 fp=0x1c607b4 sp=0x1c60794 pc=0x19fd40 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*connReader).backgroundRead(0x2f5c3c0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x1c607e4 sp=0x1c607b4 pc=0x335cfc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*connReader).startBackgroundRead.gowrap2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x1c607ec sp=0x1c607e4 pc=0x335c34 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c607ec sp=0x1c607ec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*connReader).startBackgroundRead in goroutine 58629 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:677 +0x160 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 82 gp=0x19f0128 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051dc8, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1846de8 sp=0x1846dd4 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051db8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1846e00 sp=0x1846de8 pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051db8, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1846e14 sp=0x1846e00 pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1ae7dc8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1846e28 sp=0x1846e14 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Accept(0x1ae7db0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1846e70 sp=0x1846e28 pc=0x10eb84 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).accept(0x1ae7db0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1846ed8 sp=0x1846e70 pc=0x17e4c4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPListener).accept(0x18ce168) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1846ef0 sp=0x1846ed8 pc=0x196900 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPListener).Accept(0x18ce168) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1846f0c sp=0x1846ef0 pc=0x19596c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*onceCloseListener).Accept(0x1b881e0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x34 fp=0x1846f24 sp=0x1846f0c pc=0x362b54 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Server).Serve(0x18a6288, {0x886cb8, 0x18ce168}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1846fbc sp=0x1846f24 pc=0x341210 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.Serve(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/server.go:2794 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x1846fec sp=0x1846fbc pc=0x5a8184 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1846fec sp=0x1846fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/zeroconf/zeroconf.go:280 +0x160 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 63 gp=0x1bf65a8 m=nil [runnable]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.asyncPreempt2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/preempt.go:307 +0x38 fp=0x1b19e24 sp=0x1b19e18 pc=0x5b450 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.asyncPreempt() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/preempt_arm.s:44 +0x98 fp=0x1b19ee0 sp=0x1b19e24 pc=0x948c4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/sirupsen/logrus.(*Entry).Log(0x1a08140, 0x5, {0x1b19f04, 0x1, 0x1}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:302 fp=0x1b19ee4 sp=0x1b19ee4 pc=0x520240 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/sirupsen/logrus.(*Entry).Logf(0x1a08140, 0x5, {0x79c9ed, 0x18}, {0x0, 0x0, 0x0}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:349 +0xc0 fp=0x1b19f0c sp=0x1b19ee4 pc=0x520654 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/sirupsen/logrus.(*Entry).Debugf(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:358 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.LogrusAdapter.Debugf(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/cmd/daemon/log.go:17 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: main.(*LogrusAdapter).Debugf(0x1a02140, {0x79c9ed, 0x18}, {0x0, 0x0, 0x0}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x68 fp=0x1b19f2c sp=0x1b19f0c pc=0x647144 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x194e798) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/dealer/dealer.go:192 +0x5e8 fp=0x1b19fe4 sp=0x1b19f2c pc=0x5322f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/dealer/dealer.go:125 +0x28 fp=0x1b19fec sp=0x1b19fe4 pc=0x53383c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b19fec sp=0x1b19fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 14 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/dealer/dealer.go:125 +0x98 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 99 gp=0x1bf6b48 m=nil [select]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1843ea8 sp=0x1843e94 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.selectgo(0x1843fa4, 0x1843f6c, 0x0, 0x0, 0x5, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1843f48 sp=0x1843ea8 pc=0x70e78 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1b943c8) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1843fe4 sp=0x1843f48 pc=0x474738 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: nhooyr.io/websocket.newConn.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1843fec sp=0x1843fe4 pc=0x47432c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1843fec sp=0x1843fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by nhooyr.io/websocket.newConn in goroutine 71 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 118 gp=0x1885328 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x760516e8, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18f6afc sp=0x18f6ae8 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x760516d8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x18f6b14 sp=0x18f6afc pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x760516d8, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x18f6b28 sp=0x18f6b14 pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x18b4ba8, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x18f6b3c sp=0x18f6b28 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Read(0x18b4b90, {0x1f12000, 0xa000, 0xa000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x18f6b84 sp=0x18f6b3c pc=0x10b504 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).Read(0x18b4b90, {0x1f12000, 0xa000, 0xa000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x18f6bb0 sp=0x18f6b84 pc=0x17c5d0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*conn).Read(0x1cc2118, {0x1f12000, 0xa000, 0xa000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x18f6bdc sp=0x18f6bb0 pc=0x18caec Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPConn).Read(0x1cc2118, {0x1f12000, 0xa000, 0xa000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x44 fp=0x18f6bfc sp=0x18f6bdc pc=0x19fd40 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*atLeastReader).Read(0x180fc10, {0x1f12000, 0xa000, 0xa000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x18f6c28 sp=0x18f6bfc pc=0x27c680 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bytes.(*Buffer).ReadFrom(0x1bf018c, {0x883674, 0x180fc10}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x18f6c64 sp=0x18f6c28 pc=0x139bd0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readFromUntil(0x1bf0008, {0x882848, 0x1cc2118}, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x18f6c8c sp=0x18f6c64 pc=0x27c8dc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readRecordOrCCS(0x1bf0008, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x18f6df0 sp=0x18f6c8c pc=0x279fdc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readRecord(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:588 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).Read(0x1bf0008, {0x1a10000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x18f6e20 sp=0x18f6df0 pc=0x27fe4c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*persistConn).Read(0x1c8c218, {0x1a10000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x18f6e54 sp=0x18f6e20 pc=0x3555cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bufio.(*Reader).fill(0x1ce6660) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x18f6e78 sp=0x18f6e54 pc=0x2bddb4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bufio.(*Reader).Peek(0x1ce6660, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x18f6e84 sp=0x18f6e78 pc=0x2bdf38 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*persistConn).readLoop(0x1c8c218) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x18f6fe4 sp=0x18f6e84 pc=0x35615c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Transport).dialConn.gowrap2() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x18f6fec sp=0x18f6fe4 pc=0x354a7c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18f6fec sp=0x18f6fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*Transport).dialConn in goroutine 95 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 119 gp=0x1885448 m=nil [select]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1b17eb0 sp=0x1b17e9c pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.selectgo(0x1b17fac, 0x1b17f78, 0x0, 0x0, 0x2, 0x1) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1b17f50 sp=0x1b17eb0 pc=0x70e78 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*persistConn).writeLoop(0x1c8c218) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x1b17fe4 sp=0x1b17f50 pc=0x357c1c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*Transport).dialConn.gowrap3() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x1b17fec sp=0x1b17fe4 pc=0x354a28 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b17fec sp=0x1b17fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*Transport).dialConn in goroutine 95 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: goroutine 58627 gp=0x18e25a8 m=nil [IO wait]: Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.gopark(0x7e40f0, 0x76051968, 0x2, 0x2, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1b15ad4 sp=0x1b15ac0 pc=0x5d0c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.netpollblock(0x76051958, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1b15aec sp=0x1b15ad4 pc=0x548cc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.runtime_pollWait(0x76051958, 0x72) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1b15b00 sp=0x1b15aec pc=0x8f4a8 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).wait(0x1a2e158, 0x72, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1b15b14 sp=0x1b15b00 pc=0x10a318 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*pollDesc).waitRead(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: internal/poll.(*FD).Read(0x1a2e140, {0x1965000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1b15b5c sp=0x1b15b14 pc=0x10b504 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*netFD).Read(0x1a2e140, {0x1965000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1b15b88 sp=0x1b15b5c pc=0x17c5d0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*conn).Read(0x1af9550, {0x1965000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1b15bb4 sp=0x1b15b88 pc=0x18caec Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net.(*TCPConn).Read(0x1af9550, {0x1965000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: :1 +0x44 fp=0x1b15bd4 sp=0x1b15bb4 pc=0x19fd40 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*atLeastReader).Read(0x180f9d0, {0x1965000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1b15c00 sp=0x1b15bd4 pc=0x27c680 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bytes.(*Buffer).ReadFrom(0x1bf180c, {0x883674, 0x180f9d0}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1b15c3c sp=0x1b15c00 pc=0x139bd0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readFromUntil(0x1bf1688, {0x882848, 0x1af9550}, 0x5) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1b15c64 sp=0x1b15c3c pc=0x27c8dc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readRecordOrCCS(0x1bf1688, 0x0) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1b15dc8 sp=0x1b15c64 pc=0x279fdc Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).readRecord(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:588 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: crypto/tls.(*Conn).Read(0x1bf1688, {0x21e7000, 0x1000, 0x1000}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1b15df8 sp=0x1b15dc8 pc=0x27fe4c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: bufio.(*Reader).Read(0x18af290, {0x1923124, 0x9, 0x9}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x1b15e1c sp=0x1b15df8 pc=0x2be440 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: io.ReadAtLeast({0x882a3c, 0x18af290}, {0x1923124, 0x9, 0x9}, 0x9) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1b15e48 sp=0x1b15e1c pc=0x1042c0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: io.ReadFull(...) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/io/io.go:354 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.http2readFrameHeader({0x1923124, 0x9, 0x9}, {0x882a3c, 0x18af290}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1b15e70 sp=0x1b15e48 pc=0x2f733c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*http2Framer).ReadFrame(0x1923100) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1b15eec sp=0x1b15e70 pc=0x2f7ad4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*http2clientConnReadLoop).run(0x1b15fdc) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1b15fa4 sp=0x1b15eec pc=0x31c2f0 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*http2ClientConn).readLoop(0x1947d08) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1b15fe4 sp=0x1b15fa4 pc=0x31b7e4 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1b15fec sp=0x1b15fe4 pc=0x314830 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: runtime.goexit({}) Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b15fec sp=0x1b15fec pc=0x93d6c Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: created by net/http.(*http2Transport).newClientConn in goroutine 58626 Feb 09 11:44:21 my-volumio-digi go-librespot[2037]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Feb 09 11:44:21 my-volumio-digi go-librespot[2036]: Aborted Feb 09 11:44:21 my-volumio-digi volumio[1373]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: Connection to go-librespot Websocket closed Feb 09 11:44:21 my-volumio-digi volumio[1373]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 09 11:44:21 my-volumio-digi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Feb 09 11:44:21 my-volumio-digi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 09 11:44:21 my-volumio-digi systemd[1]: go-librespot-daemon.service: Consumed 10.901s CPU time. Feb 09 11:44:24 my-volumio-digi volumio[1373]: info: Initializing connection to go-librespot Websocket Feb 09 11:44:24 my-volumio-digi volumio[1373]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 09 11:44:24 my-volumio-digi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 09 11:44:24 my-volumio-digi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:24 my-volumio-digi systemd[1]: go-librespot-daemon.service: Consumed 10.901s CPU time. Feb 09 11:44:24 my-volumio-digi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:24 my-volumio-digi go-librespot[2649]: go-librespot daemon starting... Feb 09 11:44:24 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:24+05:00" level=info msg="running go-librespot 0.4.0" Feb 09 11:44:24 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:24+05:00" level=debug msg="app state loaded" Feb 09 11:44:24 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:24+05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 09 11:44:25 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:25+05:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 09 11:44:25 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:25+05:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 09 11:44:25 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:25+05:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 09 11:44:25 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:25+05:00" level=info msg="zeroconf server listening on port 33083" Feb 09 11:44:26 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:26+05:00" level=debug msg="obtained new client token: AAA6ZeG6kS0cDuxhhjZRYYkDaFERaeZu7F9Fj126HA2cjnxLsoiPlcQ7UCcd2VaK+LdvYlw+MTX3AK5GuwWKdpRv3xf1ZbFtJX3v1UB7mSablBggbzqInWEfwviHRwa1ZfVVwoeLMP+oPca7u0v4Qzx8cB2vLr0q/jDWGbMRIa81TI4MQwsbU8pfiCRylXJQBlJmgB6U0kvAqbiLudhPPD9Qektv/a4mtcK60lTnQ9Nu/1ImsAIuy3k3lw==" Feb 09 11:44:26 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:26+05:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 09 11:44:26 my-volumio-digi go-librespot[2650]: time="2026-02-09T11:44:26+05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 09 11:44:26 my-volumio-digi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 09 11:44:26 my-volumio-digi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 09 11:44:27 my-volumio-digi volumio[1373]: info: Initializing connection to go-librespot Websocket Feb 09 11:44:27 my-volumio-digi volumio[1373]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 09 11:44:29 my-volumio-digi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 09 11:44:29 my-volumio-digi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:29 my-volumio-digi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:29 my-volumio-digi go-librespot[2658]: go-librespot daemon starting... Feb 09 11:44:29 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:29+05:00" level=info msg="running go-librespot 0.4.0" Feb 09 11:44:29 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:29+05:00" level=debug msg="app state loaded" Feb 09 11:44:29 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:29+05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=info msg="zeroconf server listening on port 41249" Feb 09 11:44:30 my-volumio-digi volumio[1373]: info: Initializing connection to go-librespot Websocket Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="new websocket client" Feb 09 11:44:30 my-volumio-digi volumio[1373]: info: Connection to go-librespot Websocket established Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="obtained new client token: AAAUyhiaTxzKPr8HsRygyhDWDHdfD4LskKsZl0AcvYqMpW8pVM3bU+XkJBv9DjUQtqqEbd8Y9M/3Yk61wUgF5UDIhISVS964OZ9kP9kvAoWtQGezJ55cWdViHZY7s/70CCuahIbmo6kdjD1ER4W14TagBv8Q9i41AauES2HiOXDHa/MPhUZ6pdrurtnHRoIRnzoDf+HfiymNKUFbsbnwbEX3PexVe6sNkl2pVvNs1uT4BGMOJ2wvYd7RHQfw" Feb 09 11:44:30 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:30+05:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Feb 09 11:44:31 my-volumio-digi go-librespot[2659]: time="2026-02-09T11:44:31+05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 09 11:44:31 my-volumio-digi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 09 11:44:31 my-volumio-digi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 09 11:44:31 my-volumio-digi volumio[1373]: info: Connection to go-librespot Websocket closed Feb 09 11:44:33 my-volumio-digi volumio[1373]: info: Getting Spotify volume Feb 09 11:44:33 my-volumio-digi volumio[1373]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 11:44:33 my-volumio-digi volumio[1373]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 09 11:44:33 my-volumio-digi volumio[1373]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 09 11:44:33 my-volumio-digi volumio[1373]: errno: -111, Feb 09 11:44:33 my-volumio-digi volumio[1373]: code: 'ECONNREFUSED', Feb 09 11:44:33 my-volumio-digi volumio[1373]: syscall: 'connect', Feb 09 11:44:33 my-volumio-digi volumio[1373]: address: '127.0.0.1', Feb 09 11:44:33 my-volumio-digi volumio[1373]: port: 9879, Feb 09 11:44:33 my-volumio-digi volumio[1373]: response: undefined Feb 09 11:44:33 my-volumio-digi volumio[1373]: } Feb 09 11:44:33 my-volumio-digi volumio[1373]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 11:44:34 my-volumio-digi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 09 11:44:34 my-volumio-digi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:34 my-volumio-digi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 09 11:44:34 my-volumio-digi go-librespot[2683]: go-librespot daemon starting... Feb 09 11:44:34 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:34+05:00" level=info msg="running go-librespot 0.4.0" Feb 09 11:44:34 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:34+05:00" level=debug msg="app state loaded" Feb 09 11:44:34 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:34+05:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=info msg="zeroconf server listening on port 35261" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=debug msg="obtained new client token: AABxXDhhFUtDA2pAk0bi0Z6wROMk9n8q3pckfI3luftxLuReFj/aIJZyKQMRdu3bQCRM77jzJgAGtW7IO3gJoUlob60+jtnXRuY/m50+PQWneHQCf35NTHcmxT5O1qEo5sE5U5pTqnjQKY+n5ejyuOyUr9W2GYLtGVF0HYKjxV6JU0oFtFwnIAHdlvjI8AmBTsQoZZWGsbmR38836cHVTYDmg1pZ5SW2FAekytxKg37PE8vu/9BViY4GqyXO" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Feb 09 11:44:35 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:35+05:00" level=debug msg="connected to ap-gew1.spotify.com:443" Feb 09 11:44:36 my-volumio-digi go-librespot[2684]: time="2026-02-09T11:44:36+05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Feb 09 11:44:36 my-volumio-digi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 09 11:44:36 my-volumio-digi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 09 11:44:36 my-volumio-digi sudo[2704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 11:43' Feb 09 11:44:36 my-volumio-digi sudo[2704]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"