Jan 28 10:59:28 volumio-livingroom go-librespot[24270]: time="2026-01-28T10:59:28+01:00" level=trace msg="sent dealer ping" Jan 28 10:59:28 volumio-livingroom go-librespot[24270]: time="2026-01-28T10:59:28+01:00" level=trace msg="received dealer pong" Jan 28 10:59:46 volumio-livingroom go-librespot[24270]: time="2026-01-28T10:59:46+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Jan 28 10:59:58 volumio-livingroom go-librespot[24270]: time="2026-01-28T10:59:58+01:00" level=trace msg="sent dealer ping" Jan 28 10:59:58 volumio-livingroom go-librespot[24270]: time="2026-01-28T10:59:58+01:00" level=trace msg="received dealer pong" Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: time="2026-01-28T11:00:20+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: time="2026-01-28T11:00:20+01:00" level=debug msg="completed keyexchange" Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: time="2026-01-28T11:00:20+01:00" level=debug msg="completed challenge" Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: time="2026-01-28T11:00:20+01:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: fatal error: sync: Unlock of unlocked RWMutex Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 98 gp=0x2688a28 m=4 mp=0x244d808 [running]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.fatal({0x919772, 0x20}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/panic.go:1116 +0x38 fp=0x2605eb0 sp=0x2605e9c pc=0x6c060 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: sync.fatal({0x919772, 0x20}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/panic.go:1026 +0x24 fp=0x2605ebc sp=0x2605eb0 pc=0xa9504 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: sync.(*RWMutex).Unlock(0x25f6608) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/sync/rwmutex.go:212 +0x50 fp=0x2605ed4 sp=0x2605ebc pc=0xbbe3c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x25f6588) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:342 +0x4c0 fp=0x2605fe4 sp=0x2605ed4 pc=0x4f6928 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:273 +0x28 fp=0x2605fec sp=0x2605fe4 pc=0x4f643c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2605fec sp=0x2605fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 53 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:273 +0x98 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 1 gp=0x2402128 m=nil [select, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x28f2ce8 sp=0x28f2cd4 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x28f2df8, 0x2605dc4, 0x0, 0x0, 0x2, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x28f2d98 sp=0x28f2ce8 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x242a3c0, 0x28f2e90) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/zeroconf/zeroconf.go:283 +0x21c fp=0x28f2e3c sp=0x28f2d98 pc=0x61db0c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).withAppPlayer(0x2526800, {0xa260ac, 0xfdcff0}, 0x24117c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:340 +0x580 fp=0x28f2ed0 sp=0x28f2e3c pc=0x6ab088 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).withCredentials(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:209 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).SpotifyToken(0x2526800, {0xa260ac, 0xfdcff0}, {0x2413020, 0x9}, {0x25f0120, 0x11f}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x28f2f00 sp=0x28f2ed0 pc=0x6aa4b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.main() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:593 +0x660 fp=0x28f2fa8 sp=0x28f2f00 pc=0x6ad58c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.main() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x28f2fec sp=0x28f2fa8 pc=0x6f390 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x28f2fec sp=0x28f2fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 2 gp=0x24027e8 m=nil [force gc (idle), 3 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2448fd4 sp=0x2448fc0 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goparkunlock(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:466 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.forcegchelper() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2448fec sp=0x2448fd4 pc=0x6f7f4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2448fec sp=0x2448fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.init.6 in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:361 +0x1c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 3 gp=0x2402b48 m=nil [GC sweep wait]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24497c4 sp=0x24497b0 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goparkunlock(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:466 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.bgsweep(0x242e050) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x24497e4 sp=0x24497c4 pc=0x5768c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcenable.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x24497ec sp=0x24497e4 pc=0x46c3c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24497ec sp=0x24497ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcenable in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 4 gp=0x2402c68 m=nil [GC scavenge wait]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2449fb4 sp=0x2449fa0 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goparkunlock(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:466 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.(*scavengerState).park(0xfd20d0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2449fc8 sp=0x2449fb4 pc=0x54a28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.bgscavenge(0x242e050) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2449fe4 sp=0x2449fc8 pc=0x5516c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcenable.gowrap2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2449fec sp=0x2449fe4 pc=0x46be8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2449fec sp=0x2449fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcenable in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 5 gp=0x2402fc8 m=nil [GOMAXPROCS updater (idle), 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244a7a4 sp=0x244a790 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goparkunlock(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:466 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.updateMaxProcsGoroutine() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x244a7ec sp=0x244a7a4 pc=0x7f174 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 6 gp=0x2403328 m=nil [finalizer wait, 73 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244af88 sp=0x244af74 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.runFinalizers() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x244afec sp=0x244af88 pc=0x45888 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244afec sp=0x244afec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.createfing in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 7 gp=0x25de908 m=nil [cleanup wait, 11 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24487a4 sp=0x2448790 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goparkunlock(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:466 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.(*cleanupQueue).dequeue(0xfd2248) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x24487c4 sp=0x24487a4 pc=0x41cb0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.runCleanups() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x24487ec sp=0x24487c4 pc=0x4284c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24487ec sp=0x24487ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 10 gp=0x25df8c8 m=nil [IO wait, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf6991e10, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2456cf0 sp=0x2456cdc pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf6991e00, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2456d08 sp=0x2456cf0 pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf6991e00, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2456d1c sp=0x2456d08 pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x250b328, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2456d30 sp=0x2456d1c pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).Accept(0x250b310) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2456d78 sp=0x2456d30 pc=0x1395b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*netFD).accept(0x250b310) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x2456de0 sp=0x2456d78 pc=0x1aa9d8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*TCPListener).accept(0x2400630) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2456e34 sp=0x2456de0 pc=0x1c2304 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*TCPListener).Accept(0x2400630) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x2456e50 sp=0x2456e34 pc=0x1c134c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*onceCloseListener).Accept(0x24a4080) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: :1 +0x34 fp=0x2456e68 sp=0x2456e50 pc=0x367710 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*Server).Serve(0x24b4008, {0xa258a8, 0x2400630}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x2456f00 sp=0x2456e68 pc=0x3408c8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.Serve(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2971 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*ConcreteApiServer).serve(0x242a960) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x2456fe4 sp=0x2456f00 pc=0x69fdac Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.NewApiServer.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x2456fec sp=0x2456fe4 pc=0x69eae4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2456fec sp=0x2456fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by main.NewApiServer in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/api_server.go:332 +0x310 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 52 gp=0x25df9e8 m=nil [select, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2695674 sp=0x2695660 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x2695780, 0x269574c, 0x0, 0x0, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2695724 sp=0x2695674 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x268e460) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/player/player.go:215 +0x1f4 fp=0x26957e4 sp=0x2695724 pc=0x5818e0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/player/player.go:197 +0x28 fp=0x26957ec sp=0x26957e4 pc=0x5814c8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26957ec sp=0x26957ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/player/player.go:197 +0x220 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 53 gp=0x25dfc28 m=nil [select, 21 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2607930 sp=0x260791c pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x2607f70, 0x2607b20, 0x0, 0x0, 0xa, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x26079e0 sp=0x2607930 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*AppPlayer).Run(0x24b6190, {0xa260ac, 0xfdcff0}, 0x27f4180, 0x27f41c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/player.go:661 +0x368 fp=0x2607fd4 sp=0x26079e0 pc=0x6b3970 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).withAppPlayer.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:274 +0x48 fp=0x2607fec sp=0x2607fd4 pc=0x6abe18 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2607fec sp=0x2607fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by main.(*App).withAppPlayer in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:274 +0x410 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 34 gp=0x25dfd48 m=nil [GC worker (idle), 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d90, 0x24d2400, 0x1c, 0xa, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244b788 sp=0x244b774 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkWorker(0x28d61c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244b7e4 sp=0x244b788 pc=0x49f34 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244b7ec sp=0x244b7e4 pc=0x49e14 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244b7ec sp=0x244b7ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 35 gp=0x2482368 m=nil [GC worker (idle), 11 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d90, 0x24d2600, 0x1c, 0xa, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244bf88 sp=0x244bf74 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkWorker(0x28d61c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244bfe4 sp=0x244bf88 pc=0x49f34 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244bfec sp=0x244bfe4 pc=0x49e14 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244bfec sp=0x244bfec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 36 gp=0x2482488 m=nil [GC worker (idle), 3 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d90, 0x24d2800, 0x1c, 0xa, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x27f0f88 sp=0x27f0f74 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkWorker(0x28d61c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x27f0fe4 sp=0x27f0f88 pc=0x49f34 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x27f0fec sp=0x27f0fe4 pc=0x49e14 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x27f0fec sp=0x27f0fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 37 gp=0x24825a8 m=nil [GC worker (idle), 13 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d90, 0x24d2a00, 0x1c, 0xa, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b1788 sp=0x26b1774 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkWorker(0x28d61c0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x26b17e4 sp=0x26b1788 pc=0x49f34 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x26b17ec sp=0x26b17e4 pc=0x49e14 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b17ec sp=0x26b17ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 100 gp=0x26886c8 m=nil [IO wait]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf5fcd610, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x285495c sp=0x2854948 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf5fcd600, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2854974 sp=0x285495c pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf5fcd600, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2854988 sp=0x2854974 pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x268e748, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x285499c sp=0x2854988 pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).Read(0x268e730, {0x260f000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x28549e4 sp=0x285499c pc=0x135f74 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*netFD).Read(0x268e730, {0x260f000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2854a10 sp=0x28549e4 pc=0x1a8c28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*conn).Read(0x2432008, {0x260f000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x2854a3c sp=0x2854a10 pc=0x1b842c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: crypto/tls.(*atLeastReader).Read(0x28ac7f0, {0x260f000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x2854a68 sp=0x2854a3c pc=0x266c34 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bytes.(*Buffer).ReadFrom(0x25fa18c, {0xa22858, 0x28ac7f0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x2854aa4 sp=0x2854a68 pc=0x160e08 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: crypto/tls.(*Conn).readFromUntil(0x25fa008, {0xa21798, 0x2432008}, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x2854acc sp=0x2854aa4 pc=0x266e90 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: crypto/tls.(*Conn).readRecordOrCCS(0x25fa008, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x2854c2c sp=0x2854acc pc=0x2645b4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: crypto/tls.(*Conn).readRecord(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/crypto/tls/conn.go:589 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: crypto/tls.(*Conn).Read(0x25fa008, {0x24b9000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x2854c5c sp=0x2854c2c pc=0x26a378 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*readWriteCloserBody).Read(0x2494010, {0x24b9000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x2854c7c sp=0x2854c5c pc=0x3577bc Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).fill(0x2493860) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2854ca0 sp=0x2854c7c pc=0x2b6390 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).ReadByte(0x2493860) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2854cac sp=0x2854ca0 pc=0x2b6c0c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.readFrameHeader(0x2493860, {0x254a67c, 0x8, 0x8}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x2854cec sp=0x2854cac pc=0x498164 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x254a648, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x2854dac sp=0x2854cec pc=0x49b01c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readLoop(0x254a648, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x2854e70 sp=0x2854dac pc=0x49ab08 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).reader(0x254a648, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x2854ef4 sp=0x2854e70 pc=0x49bc20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Read(0x254a648, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x2854f1c sp=0x2854ef4 pc=0x49a0a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x25f6168) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:188 +0x58 fp=0x2854fe4 sp=0x2854f1c pc=0x5a1198 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:125 +0x28 fp=0x2854fec sp=0x2854fe4 pc=0x5a2c94 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2854fec sp=0x2854fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 53 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:125 +0x98 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 20 gp=0x25dea28 m=nil [IO wait]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf6991810, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x28ece88 sp=0x28ece74 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf6991800, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 28 11:00:20 volumio-livingroom volumio[1388]: info: Connection to go-librespot Websocket closed Jan 28 11:00:20 volumio-livingroom volumio[1388]: info: Connection to go-librespot Websocket closed Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x28ecea0 sp=0x28ece88 pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf6991800, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x28eceb4 sp=0x28ecea0 pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x24b6108, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28ecec8 sp=0x28eceb4 pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).RawRead(0x24b60f0, 0x28ba900) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x28ecef4 sp=0x28ecec8 pc=0x13a168 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*rawConn).Read(0x2686380, 0x28ba900) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x28ecf14 sp=0x28ecef4 pc=0x1bc9b4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x27981d0, 0x28ba510, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x28ecf44 sp=0x28ecf14 pc=0x5b7e28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x260c068, {0x2836000, 0x10000, 0x10000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x1a0 fp=0x28ecf9c sp=0x28ecf44 pc=0x5bbb1c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/grandcat/zeroconf.(*Server).recv4(0x2628740, 0x260c060) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xdc fp=0x28ecfe0 sp=0x28ecf9c pc=0x618bf8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x28ecfec sp=0x28ecfe0 pc=0x6188c8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x28ecfec sp=0x28ecfec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 38 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 21 gp=0x25deb48 m=nil [IO wait, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf6991610, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26b1e60 sp=0x26b1e4c pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf6991600, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26b1e78 sp=0x26b1e60 pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf6991600, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x26b1e8c sp=0x26b1e78 pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x24b6158, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x26b1ea0 sp=0x26b1e8c pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).RawRead(0x24b6140, 0x26bc030) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x26b1ecc sp=0x26b1ea0 pc=0x13a168 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*rawConn).Read(0x26863a8, 0x26bc030) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x26b1eec sp=0x26b1ecc pc=0x1bc9b4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x27981e0, 0x26bc000, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x26b1f1c sp=0x26b1eec pc=0x5b7e28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x260c098, {0x2826000, 0x10000, 0x10000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x188 fp=0x26b1f9c sp=0x26b1f1c pc=0x5bf588 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/grandcat/zeroconf.(*Server).recv6(0x2628740, 0x260c090) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xdc fp=0x26b1fe0 sp=0x26b1f9c pc=0x618ddc Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x26b1fec sp=0x26b1fe0 pc=0x61886c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26b1fec sp=0x26b1fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 38 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 99 gp=0x2688d88 m=nil [select, 3 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x27ebea4 sp=0x27ebe90 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x27ebfd4, 0x27ebf78, 0x0, 0x0, 0x2, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x27ebf54 sp=0x27ebea4 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x25f6588) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:368 +0x8c fp=0x27ebfe4 sp=0x27ebf54 pc=0x4f6d1c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:277 +0x28 fp=0x27ebfec sp=0x27ebfe4 pc=0x4f63e8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x27ebfec sp=0x27ebfec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 53 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/ap/ap.go:277 +0x15c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 54 gp=0x27f2368 m=nil [chan receive, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d64, 0x247a1b8, 0xe, 0x7, 0x2) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244573c sp=0x2445728 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.chanrecv(0x247a180, 0x24457d0, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2445784 sp=0x244573c pc=0x3414c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.chanrecv2(0x247a180, 0x24457d0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2445798 sp=0x2445784 pc=0x33bf0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).withAppPlayer.func1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:284 +0x68 fp=0x24457ec sp=0x2445798 pc=0x6abd40 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24457ec sp=0x24457ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by main.(*App).withAppPlayer in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:281 +0x49c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 55 gp=0x27f2488 m=nil [chan receive, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960d64, 0x247a178, 0xe, 0x7, 0x2) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2445f40 sp=0x2445f2c pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.chanrecv(0x247a140, 0x2445fe0, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2445f88 sp=0x2445f40 pc=0x3414c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.chanrecv2(0x247a140, 0x2445fe0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2445f9c sp=0x2445f88 pc=0x33bf0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*App).withAppPlayer.func2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:300 +0x6c fp=0x2445fec sp=0x2445f9c pc=0x6ab8a4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2445fec sp=0x2445fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by main.(*App).withAppPlayer in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/main.go:297 +0x53c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 56 gp=0x27f25a8 m=nil [IO wait, 63 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf6991a10, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24465ac sp=0x2446598 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf6991a00, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24465c4 sp=0x24465ac pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf6991a00, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24465d8 sp=0x24465c4 pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x24b60b8, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24465ec sp=0x24465d8 pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).Accept(0x24b60a0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x2446634 sp=0x24465ec pc=0x1395b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*netFD).accept(0x24b60a0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x244669c sp=0x2446634 pc=0x1aa9d8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*TCPListener).accept(0x260c000) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x24466f0 sp=0x244669c pc=0x1c2304 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*TCPListener).Accept(0x260c000) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x244670c sp=0x24466f0 pc=0x1c134c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*onceCloseListener).Accept(0x2894a80) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: :1 +0x34 fp=0x2446724 sp=0x244670c pc=0x367710 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*Server).Serve(0x25f6218, {0xa258a8, 0x260c000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x24467bc sp=0x2446724 pc=0x3408c8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.Serve(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2971 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x24467ec sp=0x24467bc pc=0x61dc08 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24467ec sp=0x24467ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/zeroconf/zeroconf.go:280 +0x154 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 33 gp=0x27f27e8 m=nil [select]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444e94 sp=0x2444e80 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x2444fa8, 0x2444f6c, 0x0, 0x0, 0x5, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2444f44 sp=0x2444e94 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x254a648) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x2444fe4 sp=0x2444f44 pc=0x494d20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.newConn.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x2444fec sp=0x2444fe4 pc=0x4949b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2444fec sp=0x2444fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by nhooyr.io/websocket.newConn in goroutine 53 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 101 gp=0x2688488 m=nil [select]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x27efe84 sp=0x27efe70 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x27effd4, 0x27eff5c, 0x0, 0x0, 0x2, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x27eff34 sp=0x27efe84 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x25f6168) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:138 +0x8c fp=0x27effe4 sp=0x27eff34 pc=0x5a0cfc Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:129 +0x28 fp=0x27effec sp=0x27effe4 pc=0x5a2c40 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x27effec sp=0x27effec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 53 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/dealer/dealer.go:129 +0x15c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 107 gp=0x27f2fc8 m=nil [IO wait, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf5fcd210, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24c78d4 sp=0x24c78c0 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf5fcd200, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24c78ec sp=0x24c78d4 pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf5fcd200, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24c7900 sp=0x24c78ec pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x2902b08, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24c7914 sp=0x24c7900 pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).Read(0x2902af0, {0x271a000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x24c795c sp=0x24c7914 pc=0x135f74 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*netFD).Read(0x2902af0, {0x271a000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x24c7988 sp=0x24c795c pc=0x1a8c28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*conn).Read(0x2432a20, {0x271a000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x24c79b4 sp=0x24c7988 pc=0x1b842c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: io.(*multiReader).Read(0x2755d30, {0x271a000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x24c79e0 sp=0x24c79b4 pc=0xef364 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).fill(0x26a8cf0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x24c7a04 sp=0x24c79e0 pc=0x2b6390 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).ReadByte(0x26a8cf0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x24c7a10 sp=0x24c7a04 pc=0x2b6c0c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.readFrameHeader(0x26a8cf0, {0x254aa3c, 0x8, 0x8}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x24c7a50 sp=0x24c7a10 pc=0x498164 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x254aa08, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x24c7b10 sp=0x24c7a50 pc=0x49b01c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readLoop(0x254aa08, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x24c7bd4 sp=0x24c7b10 pc=0x49ab08 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).reader(0x254aa08, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x24c7c58 sp=0x24c7bd4 pc=0x49bc20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Read(0x254aa08, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x24c7c80 sp=0x24c7c58 pc=0x49a0a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x24c7cfc sp=0x24c7c80 pc=0x6a062c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.HandlerFunc.ServeHTTP(0x2498188, {0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c7d0c sp=0x24c7cfc pc=0x33cf4c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*ServeMux).ServeHTTP(0x2490000, {0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x24c7d4c sp=0x24c7d0c pc=0x33ee3c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x24c7d74 sp=0x24c7d4c pc=0x69ffa8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.HandlerFunc.ServeHTTP(0x2494140, {0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24c7d84 sp=0x24c7d74 pc=0x33cf4c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.serverHandler.ServeHTTP({0x24b4008}, {0xa25968, 0x26ee798}, 0x26ee638) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x24c7da0 sp=0x24c7d84 pc=0x35c2b8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*conn).serve(0x28867e0, {0xa260c8, 0x24a21e0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x24c7fdc sp=0x24c7da0 pc=0x33b008 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*Server).Serve.gowrap3() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x24c7fec sp=0x24c7fdc pc=0x340d40 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24c7fec sp=0x24c7fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by net/http.(*Server).Serve in goroutine 10 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 109 gp=0x27f30e8 m=nil [select, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444694 sp=0x2444680 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x24447a8, 0x244476c, 0x0, 0x0, 0x5, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2444744 sp=0x2444694 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x254aa08) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x24447e4 sp=0x2444744 pc=0x494d20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.newConn.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x24447ec sp=0x24447e4 pc=0x4949b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24447ec sp=0x24447ec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by nhooyr.io/websocket.newConn in goroutine 107 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 115 gp=0x27f3328 m=nil [IO wait, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960ecc, 0xf5fcd010, 0x2, 0x2, 0x5) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26018d4 sp=0x26018c0 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.netpollblock(0xf5fcd000, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26018ec sp=0x26018d4 pc=0x675a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.runtime_pollWait(0xf5fcd000, 0x72) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2601900 sp=0x26018ec pc=0xa8864 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).wait(0x24f8d38, 0x72, 0x0) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2601914 sp=0x2601900 pc=0x134df4 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*pollDesc).waitRead(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: internal/poll.(*FD).Read(0x24f8d20, {0x24fc000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x260195c sp=0x2601914 pc=0x135f74 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*netFD).Read(0x24f8d20, {0x24fc000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2601988 sp=0x260195c pc=0x1a8c28 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net.(*conn).Read(0x2687b58, {0x24fc000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x26019b4 sp=0x2601988 pc=0x1b842c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: io.(*multiReader).Read(0x28adc40, {0x24fc000, 0x1000, 0x1000}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x26019e0 sp=0x26019b4 pc=0xef364 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).fill(0x2885170) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2601a04 sp=0x26019e0 pc=0x2b6390 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: bufio.(*Reader).ReadByte(0x2885170) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2601a10 sp=0x2601a04 pc=0x2b6c0c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.readFrameHeader(0x2885170, {0x24dfcfc, 0x8, 0x8}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x2601a50 sp=0x2601a10 pc=0x498164 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x24dfcc8, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x2601b10 sp=0x2601a50 pc=0x49b01c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).readLoop(0x24dfcc8, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x2601bd4 sp=0x2601b10 pc=0x49ab08 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).reader(0x24dfcc8, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x2601c58 sp=0x2601bd4 pc=0x49bc20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).Read(0x24dfcc8, {0xa26090, 0xfdcff0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x2601c80 sp=0x2601c58 pc=0x49a0a0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x2601cfc sp=0x2601c80 pc=0x6a062c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.HandlerFunc.ServeHTTP(0x2498188, {0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2601d0c sp=0x2601cfc pc=0x33cf4c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*ServeMux).ServeHTTP(0x2490000, {0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2601d4c sp=0x2601d0c pc=0x33ee3c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2601d74 sp=0x2601d4c pc=0x69ffa8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.HandlerFunc.ServeHTTP(0x2494140, {0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2601d84 sp=0x2601d74 pc=0x33cf4c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.serverHandler.ServeHTTP({0x24b4008}, {0xa25968, 0x25f7978}, 0x25f7818) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2601da0 sp=0x2601d84 pc=0x35c2b8 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*conn).serve(0x294fbc0, {0xa260c8, 0x24a21e0}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2601fdc sp=0x2601da0 pc=0x33b008 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: net/http.(*Server).Serve.gowrap3() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2601fec sp=0x2601fdc pc=0x340d40 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2601fec sp=0x2601fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by net/http.(*Server).Serve in goroutine 10 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: goroutine 117 gp=0x27f3448 m=nil [select, 75 minutes]: Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2446e94 sp=0x2446e80 pc=0xa97c0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.selectgo(0x2446fa8, 0x2446f6c, 0x0, 0x0, 0x5, 0x1) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2446f44 sp=0x2446e94 pc=0x85000 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x24dfcc8) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x2446fe4 sp=0x2446f44 pc=0x494d20 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: nhooyr.io/websocket.newConn.gowrap1() Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x2446fec sp=0x2446fe4 pc=0x4949b0 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: runtime.goexit({}) Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2446fec sp=0x2446fec pc=0xb0874 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: created by nhooyr.io/websocket.newConn in goroutine 115 Jan 28 11:00:20 volumio-livingroom go-librespot[24270]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 28 11:00:20 volumio-livingroom go-librespot[24269]: Aborted Jan 28 11:00:23 volumio-livingroom volumio[1388]: info: Initializing connection to go-librespot Websocket Jan 28 11:00:23 volumio-livingroom volumio[1388]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 11:00:23 volumio-livingroom systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 28 11:00:23 volumio-livingroom systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 28 11:00:23 volumio-livingroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 28 11:00:23 volumio-livingroom go-librespot[32204]: go-librespot daemon starting... Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=info msg="running go-librespot 0.6.2" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=debug msg="app state loaded" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01: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-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=info msg="zeroconf server listening on port 36355" Jan 28 11:00:23 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:23+01:00" level=debug msg="obtained new client token: AABHmw2RB2syyL5wHPCi2YOFhgxON7e9NWbWRC9POn1Pmnjg5ESa9jSVp4krZxRYzzh8BsPvj+f4oHWfbC/Qj3njztr3lBJ89ou/DLUblh4A7FoymKgxZEXDFDRvtgIbvEC/mD4wVtCgPaxwITX5ckNNSXBr2uPN4WiQ6Cs4CODCXKbDgCyO/U4uj3zC+OGKEw88tV5CPvnMee6iAB3z+UDp5BfaR/35i8PKVMgABI0eAgm075xoWJc5" Jan 28 11:00:24 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:24+01: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" Jan 28 11:00:24 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:24+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Jan 28 11:00:24 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:24+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Jan 28 11:00:24 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:24+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 11:00:24 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:24+01:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 28 11:00:25 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:25+01:00" level=debug msg="completed keyexchange" Jan 28 11:00:25 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:25+01:00" level=debug msg="completed challenge" Jan 28 11:00:25 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:25+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:25 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:25+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Jan 28 11:00:26 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:26+01:00" level=debug msg="connected to ap-gae2.spotify.com:80" Jan 28 11:00:26 volumio-livingroom volumio[1388]: info: Initializing connection to go-librespot Websocket Jan 28 11:00:26 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:26+01:00" level=debug msg="new websocket client" Jan 28 11:00:26 volumio-livingroom volumio[1388]: info: Connection to go-librespot Websocket established Jan 28 11:00:26 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:26+01:00" level=debug msg="completed keyexchange" Jan 28 11:00:26 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:26+01:00" level=debug msg="completed challenge" Jan 28 11:00:26 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:26+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:27 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:27+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 28 11:00:27 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:27+01:00" level=debug msg="completed keyexchange" Jan 28 11:00:27 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:27+01:00" level=debug msg="completed challenge" Jan 28 11:00:27 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:27+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=debug msg="connected to ap-gew1.spotify.com:80" Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=debug msg="completed keyexchange" Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=debug msg="completed challenge" Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:28 volumio-livingroom go-librespot[32205]: time="2026-01-28T11:00:28+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Jan 28 11:00:28 volumio-livingroom volumio[1388]: info: Connection to go-librespot Websocket closed Jan 28 11:00:28 volumio-livingroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 28 11:00:28 volumio-livingroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 28 11:00:29 volumio-livingroom volumio[1388]: info: Getting Spotify volume Jan 28 11:00:29 volumio-livingroom volumio[1388]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:00:29 volumio-livingroom volumio[1388]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 28 11:00:29 volumio-livingroom volumio[1388]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 28 11:00:29 volumio-livingroom volumio[1388]: errno: -111, Jan 28 11:00:29 volumio-livingroom volumio[1388]: code: 'ECONNREFUSED', Jan 28 11:00:29 volumio-livingroom volumio[1388]: syscall: 'connect', Jan 28 11:00:29 volumio-livingroom volumio[1388]: address: '127.0.0.1', Jan 28 11:00:29 volumio-livingroom volumio[1388]: port: 9879, Jan 28 11:00:29 volumio-livingroom volumio[1388]: response: undefined Jan 28 11:00:29 volumio-livingroom volumio[1388]: } Jan 28 11:00:29 volumio-livingroom volumio[1388]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:00:29 volumio-livingroom sudo[32244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 10:59' Jan 28 11:00:29 volumio-livingroom sudo[32244]: 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"