Dec 29 12:32:06 volumio-hans-pi systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 29 12:32:06 volumio-hans-pi systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 29 12:32:06 volumio-hans-pi systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 29 12:32:28 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:28+01:00" level=trace msg="sent dealer ping" Dec 29 12:32:28 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:28+01:00" level=trace msg="received dealer pong" Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:34+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:34+01:00" level=debug msg="completed challenge" Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: time="2025-12-29T12:32:34+01:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: BadCredentials " Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: fatal error: sync: Unlock of unlocked RWMutex Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 98 gp=0x1d1d448 m=5 mp=0x1880008 [running]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.fatal({0x7a3604, 0x20}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x1b13ee0 sp=0x1b13ecc pc=0x597e0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: sync.fatal({0x7a3604, 0x20}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x1b13eec sp=0x1b13ee0 pc=0x8ff0c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: sync.(*RWMutex).Unlock(0x1bf21e8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x1b13f04 sp=0x1b13eec pc=0xa0ec0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1bf2168) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:329 +0x4bc fp=0x1b13fe4 sp=0x1b13f04 pc=0x48c784 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:260 +0x28 fp=0x1b13fec sp=0x1b13fe4 pc=0x48c29c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b13fec sp=0x1b13fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:260 +0x98 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 1 gp=0x1802128 m=nil [select, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c18d00 sp=0x1c18cec pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1c18e00, 0x1985dcc, 0x0, 0x0, 0x2, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c18da0 sp=0x1c18d00 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1829680, 0x1c18e90) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x1c18e44 sp=0x1c18da0 pc=0x5a8088 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).withAppPlayer(0x18c6c00, {0x8874f0, 0xd0ad00}, 0x18ab530) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:321 +0x554 fp=0x1c18ed0 sp=0x1c18e44 pc=0x63db54 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).withCredentials(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:193 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).SpotifyToken(0x18c6c00, {0x8874f0, 0xd0ad00}, {0x18c48c0, 0x1c}, {0x18a43c0, 0x13c}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x1c18f00 sp=0x1c18ed0 pc=0x63cfa8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.main() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:557 +0x588 fp=0x1c18fa8 sp=0x1c18f00 pc=0x63feb4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.main() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1c18fec sp=0x1c18fa8 pc=0x5cb30 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c18fec sp=0x1c18fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle), 3 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1844fd4 sp=0x1844fc0 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goparkunlock(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:408 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.forcegchelper() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1844fec sp=0x1844fd4 pc=0x5cefc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1844fec sp=0x1844fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.init.5 in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:314 +0x1c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 18 gp=0x1884248 m=nil [GC sweep wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18407c4 sp=0x18407b0 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goparkunlock(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:408 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.bgsweep(0x1890000) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x18407e4 sp=0x18407c4 pc=0x45848 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcenable.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x18407ec sp=0x18407e4 pc=0x35968 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18407ec sp=0x18407ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcenable in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 19 gp=0x1884368 m=nil [GC scavenge wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1840fb4 sp=0x1840fa0 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goparkunlock(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:408 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.(*scavengerState).park(0xce0208) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1840fc8 sp=0x1840fb4 pc=0x42b68 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.bgscavenge(0x1890000) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1840fe4 sp=0x1840fc8 pc=0x432a0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcenable.gowrap2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1840fec sp=0x1840fe4 pc=0x35914 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1840fec sp=0x1840fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcenable in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 20 gp=0x18846c8 m=nil [finalizer wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x184178c sp=0x1841778 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.runfinq() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x18417ec sp=0x184178c pc=0x34754 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18417ec sp=0x18417ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.createfing in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 23 gp=0x1885d48 m=nil [IO wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3f08, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c16d28 sp=0x1c16d14 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3ef8, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c16d40 sp=0x1c16d28 pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3ef8, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c16d54 sp=0x1c16d40 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x18a7468, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c16d68 sp=0x1c16d54 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Accept(0x18a7450) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1c16db0 sp=0x1c16d68 pc=0x10eb84 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).accept(0x18a7450) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1c16e18 sp=0x1c16db0 pc=0x17e4c4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPListener).accept(0x18b2810) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1c16e30 sp=0x1c16e18 pc=0x196900 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPListener).Accept(0x18b2810) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1c16e4c sp=0x1c16e30 pc=0x19596c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*onceCloseListener).Accept(0x187e340) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x34 fp=0x1c16e64 sp=0x1c16e4c pc=0x362b54 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*Server).Serve(0x18780a8, {0x886cb8, 0x18b2810}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1c16efc sp=0x1c16e64 pc=0x341210 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.Serve(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2794 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*ConcreteApiServer).serve(0x18acfc0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x1c16fe4 sp=0x1c16efc pc=0x6330b8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.NewApiServer.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x1c16fec sp=0x1c16fe4 pc=0x631de4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c16fec sp=0x1c16fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by main.NewApiServer in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/api_server.go:296 +0x310 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 5 gp=0x1802d88 m=nil [GC worker (idle), 16 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3fbc, 0x182a600, 0x1a, 0xa, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1845790 sp=0x184577c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcBgMarkWorker() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x18457ec sp=0x1845790 pc=0x38590 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18457ec sp=0x18457ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 6 gp=0x1802ea8 m=nil [GC worker (idle), 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3fbc, 0x182a618, 0x1a, 0xa, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1845f90 sp=0x1845f7c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcBgMarkWorker() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1845fec sp=0x1845f90 pc=0x38590 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1845fec sp=0x1845fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 7 gp=0x1802fc8 m=nil [GC worker (idle), 3 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3fbc, 0x182a630, 0x1a, 0xa, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1846790 sp=0x184677c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcBgMarkWorker() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x18467ec sp=0x1846790 pc=0x38590 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18467ec sp=0x18467ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 8 gp=0x18030e8 m=nil [GC worker (idle), 26 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3fbc, 0x182a648, 0x1a, 0xa, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1846f90 sp=0x1846f7c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gcBgMarkWorker() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1846fec sp=0x1846f90 pc=0x38590 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1846fec sp=0x1846fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 38 gp=0x19d86c8 m=nil [IO wait, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3968, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a9387c sp=0x1a93868 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3958, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1a93894 sp=0x1a9387c pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3958, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1a938a8 sp=0x1a93894 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1b56018, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a938bc sp=0x1a938a8 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Read(0x1b56000, {0x1b64000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1a93904 sp=0x1a938bc pc=0x10b504 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).Read(0x1b56000, {0x1b64000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1a93930 sp=0x1a93904 pc=0x17c5d0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*conn).Read(0x1892350, {0x1b64000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1a9395c sp=0x1a93930 pc=0x18caec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPConn).Read(0x1892350, {0x1b64000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x44 fp=0x1a9397c sp=0x1a9395c pc=0x19fd40 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: io.(*multiReader).Read(0x19c7e30, {0x1b64000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1a939a8 sp=0x1a9397c pc=0x105528 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).fill(0x1b3c510) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1a939cc sp=0x1a939a8 pc=0x2bddb4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).ReadByte(0x1b3c510) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1a939d8 sp=0x1a939cc pc=0x2be638 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.readFrameHeader(0x1b3c510, {0x19747b8, 0x8, 0x8}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1a93a18 sp=0x1a939d8 pc=0x47788c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1974788, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1a93ad8 sp=0x1a93a18 pc=0x47a4f0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readLoop(0x1974788, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1a93b9c sp=0x1a93ad8 pc=0x479fd8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).reader(0x1974788, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1a93c28 sp=0x1a93b9c pc=0x47b1ec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Read(0x1974788, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1a93c50 sp=0x1a93c28 pc=0x4798bc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1a93ccc sp=0x1a93c50 pc=0x633988 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.HandlerFunc.ServeHTTP(0x1830190, {0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1a93cdc sp=0x1a93ccc pc=0x33d7f4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*ServeMux).ServeHTTP(0x1874000, {0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1a93d18 sp=0x1a93cdc pc=0x33f654 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1a93d40 sp=0x1a93d18 pc=0x633388 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.HandlerFunc.ServeHTTP(0x180e190, {0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1a93d50 sp=0x1a93d40 pc=0x33d7f4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.serverHandler.ServeHTTP({0x18780a8}, {0x886dec, 0x19d41e8}, 0x19d4148) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1a93d6c sp=0x1a93d50 pc=0x340de4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*conn).serve(0x1b4e0c0, {0x887510, 0x1826198}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1a93fdc sp=0x1a93d6c pc=0x33c034 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*Server).Serve.gowrap3() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1a93fec sp=0x1a93fdc pc=0x341688 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a93fec sp=0x1a93fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by net/http.(*Server).Serve in goroutine 23 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 81 gp=0x19d87e8 m=nil [select]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a476a8 sp=0x1a47694 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1a477a4, 0x1a4776c, 0x0, 0x0, 0x5, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1a47748 sp=0x1a476a8 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x19748c8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1a477e4 sp=0x1a47748 pc=0x474738 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.newConn.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1a477ec sp=0x1a477e4 pc=0x47432c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a477ec sp=0x1a477ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by nhooyr.io/websocket.newConn in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 36 gp=0x1aa0368 m=nil [IO wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3d28, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a92e84 sp=0x1a92e70 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3d18, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1a92e9c sp=0x1a92e84 pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3d18, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1a92eb0 sp=0x1a92e9c pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1ac1008, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a92ec4 sp=0x1a92eb0 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).RawRead(0x1ac0ff0, 0x18988d0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1a92ef0 sp=0x1a92ec4 pc=0x10f788 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*rawConn).Read(0x1b6d9b8, 0x18988d0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1a92f10 sp=0x1a92ef0 pc=0x190d28 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1a8dd20, 0x18988a0, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1a92f40 sp=0x1a92f10 pc=0x548824 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x18005d8, {0x1bd4000, 0x10000, 0x10000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1a92f9c sp=0x1a92f40 pc=0x54c80c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/grandcat/zeroconf.(*Server).recv4(0x1bb8940, 0x18005d0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1a92fe0 sp=0x1a92f9c pc=0x5a309c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1a92fec sp=0x1a92fe0 pc=0x5a2d54 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a92fec sp=0x1a92fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 9 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 37 gp=0x1aa0488 m=nil [IO wait, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3c88, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1bc3e5c sp=0x1bc3e48 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3c78, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1bc3e74 sp=0x1bc3e5c pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3c78, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1bc3e88 sp=0x1bc3e74 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1ac1058, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1bc3e9c sp=0x1bc3e88 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).RawRead(0x1ac1040, 0x1b3c030) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1bc3ec8 sp=0x1bc3e9c pc=0x10f788 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*rawConn).Read(0x1b6d9d8, 0x1b3c030) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1bc3ee8 sp=0x1bc3ec8 pc=0x190d28 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1a8dd30, 0x1b3c000, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1bc3f18 sp=0x1bc3ee8 pc=0x548824 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1800608, {0x1bc4000, 0x10000, 0x10000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1bc3f9c sp=0x1bc3f18 pc=0x550194 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/grandcat/zeroconf.(*Server).recv6(0x1bb8940, 0x1800600) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1bc3fe0 sp=0x1bc3f9c pc=0x5a3290 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1bc3fec sp=0x1bc3fe0 pc=0x5a2cf8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1bc3fec sp=0x1bc3fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 9 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 110 gp=0x1d1c908 m=nil [select, 26 minutes]: Dec 29 12:32:34 volumio-hans-pi volumio[1211]: info: Connection to go-librespot Websocket closed Dec 29 12:32:34 volumio-hans-pi volumio[1211]: info: Connection to go-librespot Websocket closed Dec 29 12:32:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a44ea8 sp=0x1a44e94 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1a44fa4, 0x1a44f6c, 0x0, 0x0, 0x5, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1a44f48 sp=0x1a44ea8 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1c8eb48) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1a44fe4 sp=0x1a44f48 pc=0x474738 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.newConn.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1a44fec sp=0x1a44fe4 pc=0x47432c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a44fec sp=0x1a44fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by nhooyr.io/websocket.newConn in goroutine 87 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 336 gp=0x1d1ca28 m=nil [IO wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c35a8, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1855ad4 sp=0x1855ac0 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3598, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1855aec sp=0x1855ad4 pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3598, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1855b00 sp=0x1855aec pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1c3af68, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1855b14 sp=0x1855b00 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Read(0x1c3af50, {0x1c6f000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1855b5c sp=0x1855b14 pc=0x10b504 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).Read(0x1c3af50, {0x1c6f000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1855b88 sp=0x1855b5c pc=0x17c5d0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*conn).Read(0x1d48000, {0x1c6f000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1855bb4 sp=0x1855b88 pc=0x18caec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPConn).Read(0x1d48000, {0x1c6f000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x44 fp=0x1855bd4 sp=0x1855bb4 pc=0x19fd40 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*atLeastReader).Read(0x1b4a4a0, {0x1c6f000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1855c00 sp=0x1855bd4 pc=0x27c680 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bytes.(*Buffer).ReadFrom(0x1b8218c, {0x883674, 0x1b4a4a0}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1855c3c sp=0x1855c00 pc=0x139bd0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readFromUntil(0x1b82008, {0x882848, 0x1d48000}, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1855c64 sp=0x1855c3c pc=0x27c8dc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readRecordOrCCS(0x1b82008, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1855dc8 sp=0x1855c64 pc=0x279fdc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readRecord(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).Read(0x1b82008, {0x1c62000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1855df8 sp=0x1855dc8 pc=0x27fe4c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).Read(0x1800480, {0x1874124, 0x9, 0x9}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x1855e1c sp=0x1855df8 pc=0x2be440 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: io.ReadAtLeast({0x882a3c, 0x1800480}, {0x1874124, 0x9, 0x9}, 0x9) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1855e48 sp=0x1855e1c pc=0x1042c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: io.ReadFull(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/io/io.go:354 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.http2readFrameHeader({0x1874124, 0x9, 0x9}, {0x882a3c, 0x1800480}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1855e70 sp=0x1855e48 pc=0x2f733c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*http2Framer).ReadFrame(0x1874100) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1855eec sp=0x1855e70 pc=0x2f7ad4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*http2clientConnReadLoop).run(0x1855fdc) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1855fa4 sp=0x1855eec pc=0x31c2f0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*http2ClientConn).readLoop(0x18f1d08) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1855fe4 sp=0x1855fa4 pc=0x31b7e4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1855fec sp=0x1855fe4 pc=0x314830 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1855fec sp=0x1855fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Consumed 1.067s CPU time. Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by net/http.(*http2Transport).newClientConn in goroutine 335 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 70 gp=0x1d1cb48 m=nil [select, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a46e74 sp=0x1a46e60 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1a46f74, 0x1a46f3c, 0x0, 0x0, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1a46f14 sp=0x1a46e74 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1d1b9f0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/player/player.go:201 +0x1fc fp=0x1a46fe4 sp=0x1a46f14 pc=0x513660 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/player/player.go:183 +0x28 fp=0x1a46fec sp=0x1a46fe4 pc=0x513240 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a46fec sp=0x1a46fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/player/player.go:183 +0x218 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 71 gp=0x1d1cc68 m=nil [select]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1987844 sp=0x1987830 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1987e10, 0x1987a24, 0x0, 0x0, 0x8, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x19878e4 sp=0x1987844 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*AppPlayer).Run(0x1ac1090, {0x8874f0, 0xd0ad00}, 0x19f46c0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/player.go:590 +0x370 fp=0x1987fd8 sp=0x19878e4 pc=0x6458bc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).withAppPlayer.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:258 +0x40 fp=0x1987fec sp=0x1987fd8 pc=0x63e874 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1987fec sp=0x1987fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by main.(*App).withAppPlayer in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:258 +0x3d8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 72 gp=0x1d1cd88 m=nil [chan receive]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3f90, 0x18902b0, 0xe, 0x7, 0x2) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a47f48 sp=0x1a47f34 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.chanrecv(0x1890280, 0x1a47fd0, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1a47f84 sp=0x1a47f48 pc=0x20078 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.chanrecv2(0x1890280, 0x1a47fd0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1a47f98 sp=0x1a47f84 pc=0x1fbbc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).withAppPlayer.func1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:268 +0x68 fp=0x1a47fec sp=0x1a47f98 pc=0x63e794 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a47fec sp=0x1a47fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by main.(*App).withAppPlayer in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:265 +0x464 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 73 gp=0x1d1cea8 m=nil [chan receive, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e3f90, 0x1890270, 0xe, 0x7, 0x2) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a48740 sp=0x1a4872c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.chanrecv(0x1890240, 0x1a487e0, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1a4877c sp=0x1a48740 pc=0x20078 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.chanrecv2(0x1890240, 0x1a487e0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1a48790 sp=0x1a4877c pc=0x1fbbc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*App).withAppPlayer.func2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:284 +0x6c fp=0x1a487ec sp=0x1a48790 pc=0x63e330 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a487ec sp=0x1a487ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by main.(*App).withAppPlayer in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/main.go:281 +0x504 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 74 gp=0x1d1cfc8 m=nil [IO wait, 20 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3dc8, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a48de8 sp=0x1a48dd4 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3db8, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1a48e00 sp=0x1a48de8 pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3db8, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1a48e14 sp=0x1a48e00 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1ac0fb8, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a48e28 sp=0x1a48e14 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Accept(0x1ac0fa0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1a48e70 sp=0x1a48e28 pc=0x10eb84 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).accept(0x1ac0fa0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1a48ed8 sp=0x1a48e70 pc=0x17e4c4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPListener).accept(0x1997a10) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1a48ef0 sp=0x1a48ed8 pc=0x196900 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPListener).Accept(0x1997a10) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1a48f0c sp=0x1a48ef0 pc=0x19596c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*onceCloseListener).Accept(0x1d2e300) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x34 fp=0x1a48f24 sp=0x1a48f0c pc=0x362b54 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*Server).Serve(0x1967228, {0x886cb8, 0x1997a10}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1a48fbc sp=0x1a48f24 pc=0x341210 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.Serve(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2794 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x1a48fec sp=0x1a48fbc pc=0x5a8184 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a48fec sp=0x1a48fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/zeroconf/zeroconf.go:280 +0x160 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 87 gp=0x1d1d0e8 m=nil [IO wait, 27 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3828, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a9587c sp=0x1a95868 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c3818, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1a95894 sp=0x1a9587c pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c3818, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1a958a8 sp=0x1a95894 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1ac0388, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a958bc sp=0x1a958a8 pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Read(0x1ac0370, {0x1c63000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1a95904 sp=0x1a958bc pc=0x10b504 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).Read(0x1ac0370, {0x1c63000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1a95930 sp=0x1a95904 pc=0x17c5d0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*conn).Read(0x19ea350, {0x1c63000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1a9595c sp=0x1a95930 pc=0x18caec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPConn).Read(0x19ea350, {0x1c63000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x44 fp=0x1a9597c sp=0x1a9595c pc=0x19fd40 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: io.(*multiReader).Read(0x18ab690, {0x1c63000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1a959a8 sp=0x1a9597c pc=0x105528 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).fill(0x1d30ab0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1a959cc sp=0x1a959a8 pc=0x2bddb4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).ReadByte(0x1d30ab0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1a959d8 sp=0x1a959cc pc=0x2be638 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.readFrameHeader(0x1d30ab0, {0x1c8eb78, 0x8, 0x8}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1a95a18 sp=0x1a959d8 pc=0x47788c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1c8eb48, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1a95ad8 sp=0x1a95a18 pc=0x47a4f0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readLoop(0x1c8eb48, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1a95b9c sp=0x1a95ad8 pc=0x479fd8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).reader(0x1c8eb48, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1a95c28 sp=0x1a95b9c pc=0x47b1ec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Read(0x1c8eb48, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1a95c50 sp=0x1a95c28 pc=0x4798bc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1a95ccc sp=0x1a95c50 pc=0x633988 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.HandlerFunc.ServeHTTP(0x1830190, {0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1a95cdc sp=0x1a95ccc pc=0x33d7f4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*ServeMux).ServeHTTP(0x1874000, {0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1a95d18 sp=0x1a95cdc pc=0x33f654 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1a95d40 sp=0x1a95d18 pc=0x633388 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.HandlerFunc.ServeHTTP(0x180e190, {0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1a95d50 sp=0x1a95d40 pc=0x33d7f4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.serverHandler.ServeHTTP({0x18780a8}, {0x886dec, 0x18b0648}, 0x18b05a8) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1a95d6c sp=0x1a95d50 pc=0x340de4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*conn).serve(0x1d12480, {0x887510, 0x1826198}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1a95fdc sp=0x1a95d6c pc=0x33c034 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*Server).Serve.gowrap3() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1a95fec sp=0x1a95fdc pc=0x341688 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a95fec sp=0x1a95fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by net/http.(*Server).Serve in goroutine 23 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 80 gp=0x1aa05a8 m=nil [select, 26 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a456a8 sp=0x1a45694 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1a457a4, 0x1a4576c, 0x0, 0x0, 0x5, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1a45748 sp=0x1a456a8 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1974788) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1a457e4 sp=0x1a45748 pc=0x474738 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.newConn.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1a457ec sp=0x1a457e4 pc=0x47432c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a457ec sp=0x1a457ec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by nhooyr.io/websocket.newConn in goroutine 38 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 99 gp=0x1d1d688 m=nil [select, 3 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x185aeb8 sp=0x185aea4 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x185afd4, 0x185af7c, 0x0, 0x0, 0x2, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x185af58 sp=0x185aeb8 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x1bf2168) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:355 +0x8c fp=0x185afe4 sp=0x185af58 pc=0x48cb50 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:264 +0x28 fp=0x185afec sp=0x185afe4 pc=0x48c248 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x185afec sp=0x185afec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/ap/ap.go:264 +0x15c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 100 gp=0x1d1d7a8 m=nil [IO wait]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e40f0, 0xb57c3a08, 0x2, 0x2, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1981940 sp=0x198192c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.netpollblock(0xb57c39f8, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1981958 sp=0x1981940 pc=0x548cc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.runtime_pollWait(0xb57c39f8, 0x72) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x198196c sp=0x1981958 pc=0x8f4a8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).wait(0x1d1bb98, 0x72, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1981980 sp=0x198196c pc=0x10a318 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*pollDesc).waitRead(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: internal/poll.(*FD).Read(0x1d1bb80, {0x1c35000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x19819c8 sp=0x1981980 pc=0x10b504 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*netFD).Read(0x1d1bb80, {0x1c35000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x19819f4 sp=0x19819c8 pc=0x17c5d0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*conn).Read(0x1a87a40, {0x1c35000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1981a20 sp=0x19819f4 pc=0x18caec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net.(*TCPConn).Read(0x1a87a40, {0x1c35000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: :1 +0x44 fp=0x1981a40 sp=0x1981a20 pc=0x19fd40 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*atLeastReader).Read(0x1b4a590, {0x1c35000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1981a6c sp=0x1981a40 pc=0x27c680 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bytes.(*Buffer).ReadFrom(0x1a8b5cc, {0x883674, 0x1b4a590}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1981aa8 sp=0x1981a6c pc=0x139bd0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readFromUntil(0x1a8b448, {0x882848, 0x1a87a40}, 0x5) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1981ad0 sp=0x1981aa8 pc=0x27c8dc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readRecordOrCCS(0x1a8b448, 0x0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1981c34 sp=0x1981ad0 pc=0x279fdc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).readRecord(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: crypto/tls.(*Conn).Read(0x1a8b448, {0x1d32000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1981c64 sp=0x1981c34 pc=0x27fe4c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: net/http.(*readWriteCloserBody).Read(0x180e2a0, {0x1d32000, 0x1000, 0x1000}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x1981c84 sp=0x1981c64 pc=0x357b00 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).fill(0x1d30360) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1981ca8 sp=0x1981c84 pc=0x2bddb4 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: bufio.(*Reader).ReadByte(0x1d30360) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1981cb4 sp=0x1981ca8 pc=0x2be638 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.readFrameHeader(0x1d30360, {0x19748f8, 0x8, 0x8}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1981cf4 sp=0x1981cb4 pc=0x47788c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x19748c8, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1981db4 sp=0x1981cf4 pc=0x47a4f0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).readLoop(0x19748c8, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1981e78 sp=0x1981db4 pc=0x479fd8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).reader(0x19748c8, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1981f04 sp=0x1981e78 pc=0x47b1ec Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: nhooyr.io/websocket.(*Conn).Read(0x19748c8, {0x8874d0, 0xd0ad00}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1981f2c sp=0x1981f04 pc=0x4798bc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x18f9138) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:188 +0x58 fp=0x1981fe4 sp=0x1981f2c pc=0x531d64 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:125 +0x28 fp=0x1981fec sp=0x1981fe4 pc=0x53383c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1981fec sp=0x1981fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:125 +0x98 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 101 gp=0x1d1d8c8 m=nil [select]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c19e98 sp=0x1c19e84 pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1c19fd4, 0x1c19f60, 0x0, 0x0, 0x2, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c19f38 sp=0x1c19e98 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x18f9138) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:138 +0x8c fp=0x1c19fe4 sp=0x1c19f38 pc=0x5318dc Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:129 +0x28 fp=0x1c19fec sp=0x1c19fe4 pc=0x5337e8 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c19fec sp=0x1c19fec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/dealer/dealer.go:129 +0x15c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: goroutine 43 gp=0x19d8368 m=nil [select, 26 minutes]: Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c1dcb0 sp=0x1c1dc9c pc=0x5d0c0 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.selectgo(0x1c1de14, 0x1c1dd80, 0x0, 0x0, 0x3, 0x1) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c1dd50 sp=0x1c1dcb0 pc=0x70e78 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x1d2e1c0) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/audio/provider.go:63 +0x118 fp=0x1c1dfe4 sp=0x1c1dd50 pc=0x494340 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/audio/provider.go:53 +0x28 fp=0x1c1dfec sp=0x1c1dfe4 pc=0x49508c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: runtime.goexit({}) Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c1dfec sp=0x1c1dfec pc=0x93d6c Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 71 Dec 29 12:32:34 volumio-hans-pi go-librespot[2446]: /src/audio/provider.go:53 +0x60 Dec 29 12:32:34 volumio-hans-pi go-librespot[2445]: Aborted Dec 29 12:32:37 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:37 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 29 12:32:38 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Consumed 1.067s CPU time. Dec 29 12:32:38 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:38 volumio-hans-pi go-librespot[5388]: go-librespot daemon starting... Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="app state loaded" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=info msg="zeroconf server listening on port 34577" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="obtained new client token: AABXB4Wx56y30i++45trTcDZh+QFVh/qU0lzGXGnnqcgqLvm0Sp5Ivlfx7zeuHrKDziBhm8BKOfGOjs9yAhpWnLAF4+ylwXQBihUsiaeCyFsdoLap1mbK7B794TybX6Vms077MAQ7KsV+0jhSKqU7yZ8VMfNi8ig5OB7WpaSRk+enxICLD14P2zmwmStyOT8KgU5IYZkm1QlDxxSJs4NoFqEu/vdke+GupdIrlDBsyiMeWkzbHZPgnP0SQ==" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+01:00" level=debug msg="completed challenge" Dec 29 12:32:38 volumio-hans-pi go-librespot[5389]: time="2025-12-29T12:32:38+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 " Dec 29 12:32:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:40 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:40 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:41 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 29 12:32:41 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:41 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:41 volumio-hans-pi go-librespot[5396]: go-librespot daemon starting... Dec 29 12:32:41 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:41+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:41 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:41+01:00" level=debug msg="app state loaded" Dec 29 12:32:41 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=info msg="zeroconf server listening on port 32783" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="obtained new client token: AAAFdT2lBb2vx903bVM/N2hIWRNC9wrrCIv5Bj/owEGghc2yDBrdtyn5tgh5voMVeuQrQZJj217E/9v7+4s1cZCqPdhGDoE0EnUHh8LbyBp4szMY+Ae1uD0CBpCc6UpvBsN8ZZqgMN1Kr9mqoG3ggCG7mhVw8kLLQ+LPSJomD1Ueu83gLCQo7LXCmgzuWTBhctessrpM1utYjLtVRrFWsDsPkEi3jEOlBtpe9ffQr9/6ZYGn9mXl6aE=" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+01:00" level=debug msg="completed challenge" Dec 29 12:32:42 volumio-hans-pi go-librespot[5397]: time="2025-12-29T12:32:42+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 " Dec 29 12:32:42 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:42 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:43 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:43 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:45 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 29 12:32:45 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:45 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:45 volumio-hans-pi go-librespot[5404]: go-librespot daemon starting... Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="app state loaded" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=info msg="zeroconf server listening on port 42979" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="obtained new client token: AABRyM8iILmzQxXyLdYdjq2i6amGz3zcbT0liDI6ScAwqMXc66sUdWwZr3onBZFWRUt5avhvDqrQd9MxfiV5nA5ltAdSHRHtDIy6GAltPtWlHh4daY91UDKhpViFZidcR+l7iRfzVvko8y+2U4qiN4h6ONqmytn9ZlXExJrW66/bSG6EPz1H1dHX05hIseRrXvTpiZSnTaJtCEWeFv1pnmvmg1fseEh7XjZA5WsDgIMLVj/+mmFq0z2UuQ==" Dec 29 12:32:45 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:46 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:46+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:46 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:46+01:00" level=debug msg="completed challenge" Dec 29 12:32:46 volumio-hans-pi go-librespot[5405]: time="2025-12-29T12:32:46+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 " Dec 29 12:32:46 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:46 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:46 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:46 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:49 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 29 12:32:49 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:49 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:49 volumio-hans-pi go-librespot[5429]: go-librespot daemon starting... Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="app state loaded" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=info msg="zeroconf server listening on port 37025" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="obtained new client token: AABtR7CGeAvtH4ewhHWf7xPxkudzSoxhgQLP+wZvdlG6BzgyiFkynkfubVtFJraas8Iq1ghtcVu5y0UtkRQcP882BPExMV7bYsEY45/zZqP+ZGtp6p4bucTj39+gSHBeBbw4g2PxY2dcjPqCmviRBppRHlA85bvKo1CfhoACrKeia7D2irvy6RApz0noGH5iN4FOj+lljCbz2GHfE3BQ18vO7BDCehMoC8bswJhuAZgeGy6Vz+xDJjs9Sg==" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+01:00" level=debug msg="completed challenge" Dec 29 12:32:49 volumio-hans-pi go-librespot[5430]: time="2025-12-29T12:32:49+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 " Dec 29 12:32:49 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:49 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:49 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:49 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:52 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:52 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:53 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 29 12:32:53 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:53 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:53 volumio-hans-pi go-librespot[5440]: go-librespot daemon starting... Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="app state loaded" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=info msg="zeroconf server listening on port 43329" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="obtained new client token: AADiHQ5V2ShA/ssinMmCEgFwQEGN5jKp/3wjfxPUiRF/wDphptvehMqAFV2PT+3C/58vXf2V0CBlJ6GI5QlcKqoNgdWvDU2NGR2dSK5Tpri/KmCHVQ1bmQC+Pq0KZAX2tie28mdDmVTPrYE60SOPRD/IGVtSm6aM0wZ5HBiTqkeNcYSxCngq57Zy0qbkvAVGX2Kolw6+bCMs2KXBUnsNtqRDYJkN15hFLhHRQLkAqN7qmO2g4degpwVYrA==" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+01:00" level=debug msg="completed challenge" Dec 29 12:32:53 volumio-hans-pi go-librespot[5441]: time="2025-12-29T12:32:53+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 " Dec 29 12:32:53 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:53 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:55 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:55 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:32:56 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 29 12:32:56 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:56 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:32:56 volumio-hans-pi go-librespot[5449]: go-librespot daemon starting... Dec 29 12:32:56 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:56+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:32:56 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:56+01:00" level=debug msg="app state loaded" Dec 29 12:32:56 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:56+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=info msg="zeroconf server listening on port 37935" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="obtained new client token: AAACUCj6hAGuL6NHOz9BLpLiu46GrcPgPRo0wvbN1CRntnU1RSHRQ8VmAmmEFmc5NT0pFvEntNo854+DnzpFN0CLYRXNvU3VfyzVNK6ATMg1l3xXGA9QcEDCyJGG3GtTncr2nZszpZ6s6qmmF8jY9d6bObaJ3FvtnouOQHmjKaxaU7tepMuAyxmvVjs2PMmWoRZk8hPl1K2IwDf6A6cMSrPODEzVCNPWxHSUiIS68EP7X1hKrBq1Fys=" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="completed keyexchange" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+01:00" level=debug msg="completed challenge" Dec 29 12:32:57 volumio-hans-pi go-librespot[5450]: time="2025-12-29T12:32:57+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 " Dec 29 12:32:57 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:32:57 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:32:58 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:32:58 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:00 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 29 12:33:00 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:00 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:00 volumio-hans-pi go-librespot[5471]: go-librespot daemon starting... Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="app state loaded" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=info msg="zeroconf server listening on port 46509" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="obtained new client token: AAC0A5sxx8amLfkN+3DsBOO8Hi8e/BKq69zztJa3JDYx7mnxQ2wYGWD/N01OHRCdJly0CnPOwASYJxxRyfR0OXOvd464u3oRhken+z+GaWVCViprkGHj7LLmSIjePHxGJ77c6NBUwcNSMc5EVlAelJmBD2SmCJFjD7zCQoJXj3yvncyltsJaybV7Ofl8ev3zIupyzmdaFr11z8huUrQe7htjVaCTxSxAsCIXQwqnG2HOpHtpo46JZeWNbg==" Dec 29 12:33:00 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:00+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:01 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:01+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:01 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:01+01:00" level=debug msg="completed challenge" Dec 29 12:33:01 volumio-hans-pi go-librespot[5472]: time="2025-12-29T12:33:01+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 " Dec 29 12:33:01 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:01 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:01 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:01 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:04 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 29 12:33:04 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:04 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:04 volumio-hans-pi go-librespot[5479]: go-librespot daemon starting... Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="app state loaded" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=info msg="zeroconf server listening on port 40599" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="obtained new client token: AACyC0dCbmUhysZJxUY/a9W1lKma6dQlgz79bKkVFG+S/kSTfYziuAV2nCh9TaWCT0GKLp9UwV0tOubVdf0WEaRxyb+qDmWKDdXueysYyr22Tu9Wvhyb3SXfVbQdens7WaLWO2451xsz9+2WMWnADCI4rJ8ZUzLuVamBTalySv8qETaQ3remNkA8cP6l5kiGSgRcSkguO3ytjmfLXbF7fgHqwUy1Vo6i4O2YAYbUB6mFap/nuxAUKP+98g==" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+01:00" level=debug msg="completed challenge" Dec 29 12:33:04 volumio-hans-pi go-librespot[5480]: time="2025-12-29T12:33:04+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 " Dec 29 12:33:04 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:04 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:04 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:04 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:07 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:07 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:08 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 29 12:33:08 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:08 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:08 volumio-hans-pi go-librespot[5506]: go-librespot daemon starting... Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="app state loaded" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=info msg="zeroconf server listening on port 42959" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="obtained new client token: AAB5/ZS9FUsVGgby22dgNPoOqUoURz7jnTfGD5iASoxn2d08O4aW5qaSjGIro2RxUbFeDlLjSg5jUdXginkzsfpAakDguWkO2pnC80nvC5TTywBXp2qxE26DKLDwxsj+j8wgpehbmAYwHlFMdHLnv8SQ0yoixddhHmchGadHj8nMn/iivfeLwFP0/L6FDgoQ3D8eTmlzlPv4CKfFhFmVQHpidM5Je92aPCKMiSMLLNgtK/oE4to6w4RGrA==" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+01:00" level=debug msg="completed challenge" Dec 29 12:33:08 volumio-hans-pi go-librespot[5507]: time="2025-12-29T12:33:08+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 " Dec 29 12:33:08 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:08 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:10 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:10 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:11 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 29 12:33:11 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:11 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:11 volumio-hans-pi go-librespot[5515]: go-librespot daemon starting... Dec 29 12:33:11 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:11+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:11 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:11+01:00" level=debug msg="app state loaded" Dec 29 12:33:11 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:11+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=info msg="zeroconf server listening on port 37741" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="obtained new client token: AAAVO1zLfJjIFUQIKrf7ZNSRUQBRL/7lpQVe+wr5puIMHnNMfr+wkPgrvnzU6i9WinfJhB0nFY0GOJGkGRgaOqHzWyTWcUBN0oVKb1FTKui3bh895Lf74c9MjwaQ7IYzZZhKUoxVnty3Ll9nDLXsoOdCezRCz9Yb/LcIvnW0NmFvkpj/gfV/FQkO8sP0Fwdcog1tWJlvU4c2fu8Nrs/0R/ilgjFTU7HVCaFVlytaxTeGbondJALI79E=" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+01:00" level=debug msg="completed challenge" Dec 29 12:33:12 volumio-hans-pi go-librespot[5516]: time="2025-12-29T12:33:12+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 " Dec 29 12:33:12 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:12 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:13 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:13 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:15 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 29 12:33:15 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:15 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:15 volumio-hans-pi go-librespot[5524]: go-librespot daemon starting... Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="app state loaded" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=info msg="zeroconf server listening on port 38879" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="obtained new client token: AAA4iqyvL0kdqAxS5OUdqboNavVVf7irIwoQWSIIqJnk/8QdEdFYFuDUdo6wYCiZejgS+Y1PwC/CJoIO7GwVHI+7mJ+S/885tm7Ly1nW8KbGHlWvffrPjaQIrXc4wEgW5wEnsMPkUTgNX4ii9VAbaYcqcvjsHb6myQaBsFeWjHsq8dN3Vj1QoblACPv6CVoRn0aP0uoesYoyS+BGwt6a8Z3p/OoePm/cIraZqlVr/5Ep9HVDvk5RQnK//w==" Dec 29 12:33:15 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:15+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:16 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:16+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:16 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:16+01:00" level=debug msg="completed challenge" Dec 29 12:33:16 volumio-hans-pi go-librespot[5525]: time="2025-12-29T12:33:16+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 " Dec 29 12:33:16 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:16 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:16 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:16 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:19 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 29 12:33:19 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:19 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:19 volumio-hans-pi go-librespot[5546]: go-librespot daemon starting... Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="app state loaded" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=info msg="zeroconf server listening on port 38051" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="obtained new client token: AACVPfgp/Dq3xrnNMrNEBPVMxAzGVAnCFXE1V7A2lFz4FXM4vRKIilUWXSyOuKJKwwZB4Dl8WJ/PtjdwppJdBQskFODQ0VfOq/KNa2eW5xuF5CM8b4i7B+gCP38mxacz+hx3fenMr1vDqROArO+IPNlo1P8pASsmNLjTr0K0kw3rq6yEdZ120KmigQj0tyRHawWWoxN4habIf8RDjb+JQFW0Go0PuOAq1Q2ijrrKWnXHugZXt/+azi3nQA==" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+01:00" level=debug msg="completed challenge" Dec 29 12:33:19 volumio-hans-pi go-librespot[5547]: time="2025-12-29T12:33:19+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 " Dec 29 12:33:19 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:19 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:19 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:19 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:22 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:22 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:23 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 29 12:33:23 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:23 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:23 volumio-hans-pi go-librespot[5556]: go-librespot daemon starting... Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="app state loaded" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=info msg="zeroconf server listening on port 34521" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="obtained new client token: AABfsTyfCS73BLiKStVvRzOO7gdolpms3AogMc9M14aBz6zdnA3J59xdlO2W7IAap7liHi4jDLWGHi7ryioTxfvDkZL1R4AIeU3Sdt6Z4oyueNRFjxwHBrgZG6PrkDBqGxgndEear26HZzqbhj5mNo1BrLZe9R41zjlIb3GvH4WgcxXTT80W8wp8WHIII96j6Dg1PLurNrZqmN6oRzWzROqGJgALjQRw8uF2kbDcONaUal3SEB+pI5DaiQ==" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+01:00" level=debug msg="completed challenge" Dec 29 12:33:23 volumio-hans-pi go-librespot[5557]: time="2025-12-29T12:33:23+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 " Dec 29 12:33:23 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:23 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:25 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:25 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:26 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 29 12:33:26 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:26 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:26 volumio-hans-pi go-librespot[5567]: go-librespot daemon starting... Dec 29 12:33:26 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:26+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:26 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:26+01:00" level=debug msg="app state loaded" Dec 29 12:33:26 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=info msg="zeroconf server listening on port 33675" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="obtained new client token: AADp+c49VKS+EMkEYQC6keq0ibj2tJYKwEuwc6RTggqEntHB8LtsoTn4+muvqGx5bLHjnAfWqqt8lv9cfJ8QyokVSLltbH6+aZ1FHgmCliBsGiEynad9YMjwSoV7NbLGaY2U+LRxyFM2wWQh2H8AEIczmrzT4pIky8WRfnoGEu9ZuNY5spgxxwytariDJrWLHGqH9/vt4NAmVp9/gx+Lewa8maQ+yZqHf2nfT3k2BclUQu4W1Nc2Tps=" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+01:00" level=debug msg="completed challenge" Dec 29 12:33:27 volumio-hans-pi go-librespot[5568]: time="2025-12-29T12:33:27+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 " Dec 29 12:33:27 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:27 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:28 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:28 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:30 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 29 12:33:30 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:30 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:30 volumio-hans-pi go-librespot[5590]: go-librespot daemon starting... Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=debug msg="app state loaded" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=info msg="zeroconf server listening on port 46561" Dec 29 12:33:30 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:30+01:00" level=debug msg="obtained new client token: AADW3oDWTAWRJG5WfwI7CCXGNCVbVg+fjkK2zj5eoqj1sx974m1xzD0cX8ZVflxSKcf0M9mVnpRCMn8TLX4X10G/0kIR2S4a3PKJZOTD30hAisYiq8zMOXTrsBu2romIIs0G0YlBOfqpdeax5uF8WHf+sPOxRnLpkdn0wKzxG5xn5oCGGoo/sSe9RM1xVSeRel9k232SIzNf5OfYXP04M4dpmOa3I5ReZpiplNB0B69FlCTLHg/agTr9bQ==" Dec 29 12:33:31 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:31+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:31 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:31+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:31 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:31+01:00" level=debug msg="completed challenge" Dec 29 12:33:31 volumio-hans-pi go-librespot[5591]: time="2025-12-29T12:33:31+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 " Dec 29 12:33:31 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:31 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:31 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:31 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 29 12:33:34 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:34 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:34 volumio-hans-pi go-librespot[5598]: go-librespot daemon starting... Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="app state loaded" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=info msg="zeroconf server listening on port 45719" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="obtained new client token: AABNZ6nXkE4cFIT6lrXz+IlxWFTh/wF3y5aPjainE2BvefZRAcWpRfDWUC6cVWavqZj/qyhBETp00G7TsuvBiP7CYYkdXUsc+dl4aC2V52PNehyAqe4kNXkiAytDE5Ns10gVD5qFYnDxLPUjuhZbIPWZw30XCIXI156QstP3uuIHR0je7FxoiCNz6dP9gwBK58XARW47MN+UGlDhI8KMLZeObs/4/kTSf+yxEZmXCdQs04jNhft3lPtv6A==" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+01:00" level=debug msg="completed challenge" Dec 29 12:33:34 volumio-hans-pi go-librespot[5599]: time="2025-12-29T12:33:34+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 " Dec 29 12:33:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:34 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:34 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:34 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:37 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:37 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 29 12:33:38 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:38 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:38 volumio-hans-pi go-librespot[5622]: go-librespot daemon starting... Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="app state loaded" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=info msg="zeroconf server listening on port 45199" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="obtained new client token: AACA1hmpwH1Mdyh1mHH/iUGFgkD3l3KHP1op9WHVfH1X/rFixA2qrLyuqZGpFWhw/ZLwhscaEno50fYd3RXTiHOjMJiBcQAlz8eIuyio/7TKVGTj9zmNPuTbn2nX5Gzm4c3rKPqxoZIQVB2sElhpUqVizB0E1NDH04/nzwdGNI1IG6UyLGq9d+Piq+UEr7t7wR8MQTMWPtH39O+8Q4YlhuRd5pCCpMTbm63qL/h1iZ9jHy14fU5eQz4s9w==" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+01:00" level=debug msg="completed challenge" Dec 29 12:33:38 volumio-hans-pi go-librespot[5623]: time="2025-12-29T12:33:38+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 " Dec 29 12:33:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:38 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:40 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:40 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:41 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 29 12:33:41 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:41 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:41 volumio-hans-pi go-librespot[5630]: go-librespot daemon starting... Dec 29 12:33:41 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:41+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:41 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:41+01:00" level=debug msg="app state loaded" Dec 29 12:33:41 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:41+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=info msg="zeroconf server listening on port 41101" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="obtained new client token: AADcTYsnrDwLuQjNALXV1ydhjUQ+dcJfXOu6bUOHecZDear1XtF/mMkIKUn2nvIX8D2rqoaYM8W09PExXemHH038CxGNvd0C2sLPQWlo72eBwR42g1a13jAzBKRXRdR409G2sGOZ+uCgfP6Kxj7SucF/hPpccwOcIg/6fPbS0l998u2r8gulyDUqBnzEUZW/CeuULS5MMF9olKlm4WM0LFTFT6VyCE6BNJbl7km2W2GvLv9IRsTkFK4=" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+01:00" level=debug msg="completed challenge" Dec 29 12:33:42 volumio-hans-pi go-librespot[5631]: time="2025-12-29T12:33:42+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 " Dec 29 12:33:42 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:42 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: CorePlayQueue::getTrack 10 Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: CorePlayQueue::getTrack 11 Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: Prefetching next song Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: [1767008023143] ControllerSpotify::prefetch Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: Sending Spotify command with payload to local API: /player/add_to_queue Dec 29 12:33:43 volumio-hans-pi volumio[1211]: error: Failed to send command to Spotify local API: /player/add_to_queue: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:43 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:45 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Dec 29 12:33:45 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:45 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:45 volumio-hans-pi go-librespot[5638]: go-librespot daemon starting... Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="app state loaded" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=info msg="zeroconf server listening on port 35691" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="obtained new client token: AADsi7mS0Is5xnW5BaLuJr/S4QLGOdzyC1Hl7kkWMBLlSbswQaa1+c6D2smmmzpr93pRpW9mf6iikQXzAzIbZc5ozTT29cg1EWcm4vbM+ud5OiWjMFA5USMKxpTvY/Yo5dTjb86e0AzfgITp7Us2szGALyQ0ohVp/lQ/qJULDCigSOwVR0CoItfdjkDWNmhPwM0c1A8w2H2887X8rdxWF5+Xhu9bnIASgJ3cXE8C4CX/4YCThKgSCOpNIg==" Dec 29 12:33:45 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:45+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 29 12:33:46 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:46+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:46 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:46+01:00" level=debug msg="completed challenge" Dec 29 12:33:46 volumio-hans-pi go-librespot[5639]: time="2025-12-29T12:33:46+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 " Dec 29 12:33:46 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:46 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:46 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:46 volumio-hans-pi volumio[1211]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:47 volumio-hans-pi volumio[1211]: info: CoreStateMachine::startPlaybackTimer Dec 29 12:33:47 volumio-hans-pi volumio[1211]: info: CorePlayQueue::getTrack 11 Dec 29 12:33:48 volumio-hans-pi volumio[1211]: info: CoreStateMachine::pushState Dec 29 12:33:48 volumio-hans-pi volumio[1211]: info: CorePlayQueue::getTrack 11 Dec 29 12:33:48 volumio-hans-pi volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 12:33:48 volumio-hans-pi volumio[1211]: info: CoreCommandRouter::volumioPushState Dec 29 12:33:48 volumio-hans-pi volumio[1211]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 29 12:33:49 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Dec 29 12:33:49 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:49 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:49 volumio-hans-pi go-librespot[5663]: go-librespot daemon starting... Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="app state loaded" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=info msg="zeroconf server listening on port 34071" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="obtained new client token: AACsrWlvetFGlSKAavT+sOWVlU5Qy+mRAb3xcIJ7GFaP7BhCLl/r+lDMFXAluXg6e3Sohn5CChGSC7YYtyT43L3ufBXy3tfQ3Fj1CZYSgsVfNxdyWFTJ//KqilZziWcFlIw3HQNAaXfjd6NeK0+Z8yXxIAfkC8L2xMVXJeBKRTwIHFLOwPQswei3phRT+UbxsgtA8hmkQCzIz/R/XmFCJ1NGW0GXuoe5K7DKm6BrIZ1zqNbKPXtNaM06qA==" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 29 12:33:49 volumio-hans-pi volumio[1211]: info: Initializing connection to go-librespot Websocket Dec 29 12:33:49 volumio-hans-pi volumio[1211]: info: Connection to go-librespot Websocket established Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="new websocket client" Dec 29 12:33:49 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:49+01:00" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 29 12:33:50 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:50+01:00" level=debug msg="completed keyexchange" Dec 29 12:33:50 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:50+01:00" level=debug msg="completed challenge" Dec 29 12:33:50 volumio-hans-pi go-librespot[5664]: time="2025-12-29T12:33:50+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 " Dec 29 12:33:50 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 29 12:33:50 volumio-hans-pi volumio[1211]: info: Connection to go-librespot Websocket closed Dec 29 12:33:50 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 29 12:33:52 volumio-hans-pi volumio[1211]: info: Getting Spotify volume Dec 29 12:33:52 volumio-hans-pi volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 12:33:52 volumio-hans-pi volumio[1211]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 29 12:33:52 volumio-hans-pi volumio[1211]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 29 12:33:52 volumio-hans-pi volumio[1211]: errno: -111, Dec 29 12:33:52 volumio-hans-pi volumio[1211]: code: 'ECONNREFUSED', Dec 29 12:33:52 volumio-hans-pi volumio[1211]: syscall: 'connect', Dec 29 12:33:52 volumio-hans-pi volumio[1211]: address: '127.0.0.1', Dec 29 12:33:52 volumio-hans-pi volumio[1211]: port: 9879, Dec 29 12:33:52 volumio-hans-pi volumio[1211]: response: undefined Dec 29 12:33:52 volumio-hans-pi volumio[1211]: } Dec 29 12:33:52 volumio-hans-pi volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 12:33:53 volumio-hans-pi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Dec 29 12:33:53 volumio-hans-pi systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:53 volumio-hans-pi sudo[5687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 12:32' Dec 29 12:33:53 volumio-hans-pi sudo[5687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 12:33:53 volumio-hans-pi systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 29 12:33:53 volumio-hans-pi go-librespot[5684]: go-librespot daemon starting... Dec 29 12:33:53 volumio-hans-pi go-librespot[5689]: time="2025-12-29T12:33:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 29 12:33:53 volumio-hans-pi go-librespot[5689]: time="2025-12-29T12:33:53+01:00" level=debug msg="app state loaded" Dec 29 12:33:53 volumio-hans-pi go-librespot[5689]: time="2025-12-29T12:33:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"