Apr 14 10:29:05 volumio go-librespot[1527]: time="2026-04-14T10:29:05+02:00" level=error msg="did not receive last pong from dealer, 960s passed" Apr 14 10:30:35 volumio go-librespot[1527]: time="2026-04-14T10:30:35+02:00" level=error msg="did not receive last pong ack from accesspoint, 1090s passed" Apr 14 10:30:35 volumio go-librespot[1527]: time="2026-04-14T10:30:35+02:00" level=error msg="failed reconnecting accesspoint" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.8.105:44630->34.158.1.133:4070: use of closed network connection" Apr 14 10:30:35 volumio go-librespot[1527]: fatal error: sync: Unlock of unlocked RWMutex Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 5908232 gp=0x1cf3448 m=5 mp=0x1880008 [running]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.fatal({0x7a3604, 0x20}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x1be8ee0 sp=0x1be8ecc pc=0x597e0 Apr 14 10:30:35 volumio go-librespot[1527]: sync.fatal({0x7a3604, 0x20}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x1be8eec sp=0x1be8ee0 pc=0x8ff0c Apr 14 10:30:35 volumio go-librespot[1527]: sync.(*RWMutex).Unlock(0x19bc8c8) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x1be8f04 sp=0x1be8eec pc=0xa0ec0 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x19bc848) Apr 14 10:30:35 volumio go-librespot[1527]: /src/ap/ap.go:329 +0x4bc fp=0x1be8fe4 sp=0x1be8f04 pc=0x48c784 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/ap/ap.go:390 +0x28 fp=0x1be8fec sp=0x1be8fe4 pc=0x48d010 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1be8fec sp=0x1be8fec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 5908228 Apr 14 10:30:35 volumio go-librespot[1527]: /src/ap/ap.go:390 +0x120 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 1 gp=0x1802128 m=nil [select, 970 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1bebd00 sp=0x1bebcec pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.selectgo(0x1bebe00, 0x1b27dcc, 0x0, 0x0, 0x2, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1bebda0 sp=0x1bebd00 pc=0x70e78 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x18c0a20, 0x1bebe90) Apr 14 10:30:35 volumio go-librespot[1527]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x1bebe44 sp=0x1bebda0 pc=0x5a8088 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).withAppPlayer(0x1a64480, {0x8874f0, 0xd0ad00}, 0x19140c0) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:321 +0x554 fp=0x1bebed0 sp=0x1bebe44 pc=0x63db54 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).withCredentials(...) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:193 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).SpotifyToken(0x1a64480, {0x8874f0, 0xd0ad00}, {0x1a2e5a0, 0x19}, {0x1ac0000, 0x13a}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x1bebf00 sp=0x1bebed0 pc=0x63cfa8 Apr 14 10:30:35 volumio go-librespot[1527]: main.main() Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:557 +0x588 fp=0x1bebfa8 sp=0x1bebf00 pc=0x63feb4 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.main() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1bebfec sp=0x1bebfa8 pc=0x5cb30 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1bebfec sp=0x1bebfec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 18 gp=0x1884248 m=nil [force gc (idle), 4 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18407d4 sp=0x18407c0 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goparkunlock(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:408 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.forcegchelper() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x18407ec sp=0x18407d4 pc=0x5cefc Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18407ec sp=0x18407ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.init.5 in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:314 +0x1c Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 19 gp=0x1884368 m=nil [GC sweep wait]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1840fc4 sp=0x1840fb0 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goparkunlock(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:408 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.bgsweep(0x1892000) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1840fe4 sp=0x1840fc4 pc=0x45848 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcenable.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1840fec sp=0x1840fe4 pc=0x35968 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1840fec sp=0x1840fec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcenable in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 20 gp=0x1884488 m=nil [GC scavenge wait]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18417b4 sp=0x18417a0 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goparkunlock(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:408 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.(*scavengerState).park(0xce0208) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x18417c8 sp=0x18417b4 pc=0x42b68 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.bgscavenge(0x1892000) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x18417e4 sp=0x18417c8 pc=0x432a0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcenable.gowrap2() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x18417ec sp=0x18417e4 pc=0x35914 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18417ec sp=0x18417ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcenable in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 34 gp=0x1a0a008 m=nil [finalizer wait, 481 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a1078c sp=0x1a10778 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.runfinq() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1a107ec sp=0x1a1078c pc=0x34754 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a107ec sp=0x1a107ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.createfing in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 37 gp=0x1a0afc8 m=nil [IO wait, 877 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e40f0, 0x75783f08, 0x2, 0x2, 0x5) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x185ad28 sp=0x185ad14 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.netpollblock(0x75783ef8, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x185ad40 sp=0x185ad28 pc=0x548cc Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.runtime_pollWait(0x75783ef8, 0x72) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x185ad54 sp=0x185ad40 pc=0x8f4a8 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).wait(0x1a31058, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185ad68 sp=0x185ad54 pc=0x10a318 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).waitRead(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*FD).Accept(0x1a31040) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x185adb0 sp=0x185ad68 pc=0x10eb84 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*netFD).accept(0x1a31040) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x185ae18 sp=0x185adb0 pc=0x17e4c4 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*TCPListener).accept(0x1a14570) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x185ae30 sp=0x185ae18 pc=0x196900 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*TCPListener).Accept(0x1a14570) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x185ae4c sp=0x185ae30 pc=0x19596c Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*onceCloseListener).Accept(0x1a01160) Apr 14 10:30:35 volumio go-librespot[1527]: :1 +0x34 fp=0x185ae64 sp=0x185ae4c pc=0x362b54 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*Server).Serve(0x1af00a8, {0x886cb8, 0x1a14570}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x185aefc sp=0x185ae64 pc=0x341210 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.Serve(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2794 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*ConcreteApiServer).serve(0x1a40120) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x185afe4 sp=0x185aefc pc=0x6330b8 Apr 14 10:30:35 volumio go-librespot[1527]: main.NewApiServer.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x185afec sp=0x185afe4 pc=0x631de4 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x185afec sp=0x185afec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by main.NewApiServer in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/api_server.go:296 +0x310 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 40 gp=0x1a0b0e8 m=nil [GC worker (idle), 23 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3fbc, 0x1a256c8, 0x1a, 0xa, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1844790 sp=0x184477c pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcBgMarkWorker() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x18447ec sp=0x1844790 pc=0x38590 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18447ec sp=0x18447ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcBgMarkStartWorkers in goroutine 53 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 94 gp=0x1946128 m=nil [select, 584 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ad6ea8 sp=0x1ad6e94 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.selectgo(0x1ad6fa4, 0x1ad6f6c, 0x0, 0x0, 0x5, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1ad6f48 sp=0x1ad6ea8 pc=0x70e78 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1c348c8) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1ad6fe4 sp=0x1ad6f48 pc=0x474738 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.newConn.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1ad6fec sp=0x1ad6fe4 pc=0x47432c Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ad6fec sp=0x1ad6fec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by nhooyr.io/websocket.newConn in goroutine 12 Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 55 gp=0x19465a8 m=nil [GC worker (idle)]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3fbc, 0x193f7e8, 0x1a, 0xa, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a0cf90 sp=0x1a0cf7c pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcBgMarkWorker() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1a0cfec sp=0x1a0cf90 pc=0x38590 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a0cfec sp=0x1a0cfec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcBgMarkStartWorkers in goroutine 53 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 56 gp=0x19466c8 m=nil [GC worker (idle), 608 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3fbc, 0x1a256e0, 0x1a, 0xa, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a0d790 sp=0x1a0d77c pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcBgMarkWorker() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1a0d7ec sp=0x1a0d790 pc=0x38590 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a0d7ec sp=0x1a0d7ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcBgMarkStartWorkers in goroutine 53 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 41 gp=0x1a0b328 m=nil [GC worker (idle), 124 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3fbc, 0x182a438, 0x1a, 0xa, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a11790 sp=0x1a1177c pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gcBgMarkWorker() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1a117ec sp=0x1a11790 pc=0x38590 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a117ec sp=0x1a117ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by runtime.gcBgMarkStartWorkers in goroutine 53 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 21 gp=0x18846c8 m=nil [IO wait, 4 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e40f0, 0x75783d28, 0x2, 0x2, 0x5) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1950e84 sp=0x1950e70 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.netpollblock(0x75783d18, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1950e9c sp=0x1950e84 pc=0x548cc Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.runtime_pollWait(0x75783d18, 0x72) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1950eb0 sp=0x1950e9c pc=0x8f4a8 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).wait(0x1936068, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1950ec4 sp=0x1950eb0 pc=0x10a318 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).waitRead(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*FD).RawRead(0x1936050, 0x18eed20) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1950ef0 sp=0x1950ec4 pc=0x10f788 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*rawConn).Read(0x1a06140, 0x18eed20) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1950f10 sp=0x1950ef0 pc=0x190d28 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x19140d0, 0x18eecf0, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1950f40 sp=0x1950f10 pc=0x548824 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x193c038, {0x1bb6000, 0x10000, 0x10000}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1950f9c sp=0x1950f40 pc=0x54c80c Apr 14 10:30:35 volumio go-librespot[1527]: github.com/grandcat/zeroconf.(*Server).recv4(0x1928980, 0x193c030) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1950fe0 sp=0x1950f9c pc=0x5a309c Apr 14 10:30:35 volumio go-librespot[1527]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1950fec sp=0x1950fe0 pc=0x5a2d54 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1950fec sp=0x1950fec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 42 Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 22 gp=0x18847e8 m=nil [IO wait, 970 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e40f0, 0x75783c88, 0x2, 0x2, 0x5) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ad765c sp=0x1ad7648 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.netpollblock(0x75783c78, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1ad7674 sp=0x1ad765c pc=0x548cc Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.runtime_pollWait(0x75783c78, 0x72) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1ad7688 sp=0x1ad7674 pc=0x8f4a8 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).wait(0x19360b8, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1ad769c sp=0x1ad7688 pc=0x10a318 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).waitRead(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*FD).RawRead(0x19360a0, 0x1933200) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1ad76c8 sp=0x1ad769c pc=0x10f788 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*rawConn).Read(0x1a06168, 0x1933200) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1ad76e8 sp=0x1ad76c8 pc=0x190d28 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1914110, 0x19331d0, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1ad7718 sp=0x1ad76e8 pc=0x548824 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Apr 14 10:30:35 volumio go-librespot[1527]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x193c068, {0x1ad8000, 0x10000, 0x10000}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1ad779c sp=0x1ad7718 pc=0x550194 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/grandcat/zeroconf.(*Server).recv6(0x1928980, 0x193c060) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1ad77e0 sp=0x1ad779c pc=0x5a3290 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1ad77ec sp=0x1ad77e0 pc=0x5a2cf8 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ad77ec sp=0x1ad77ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 42 Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 60 gp=0x18037a8 m=nil [chan receive, 877 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3f90, 0x1a04130, 0xe, 0x7, 0x2) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a0e748 sp=0x1a0e734 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chanrecv(0x1a04100, 0x1a0e7d0, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1a0e784 sp=0x1a0e748 pc=0x20078 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chanrecv2(0x1a04100, 0x1a0e7d0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1a0e798 sp=0x1a0e784 pc=0x1fbbc Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).withAppPlayer.func1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:268 +0x68 fp=0x1a0e7ec sp=0x1a0e798 pc=0x63e794 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a0e7ec sp=0x1a0e7ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by main.(*App).withAppPlayer in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:265 +0x464 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 61 gp=0x18038c8 m=nil [chan receive, 584 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e3f90, 0x1a040f0, 0xe, 0x7, 0x2) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ede740 sp=0x1ede72c pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chanrecv(0x1a040c0, 0x1e37fe0, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1ede77c sp=0x1ede740 pc=0x20078 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chanrecv2(0x1a040c0, 0x1e37fe0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1ede790 sp=0x1ede77c pc=0x1fbbc Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).withAppPlayer.func2() Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:284 +0x6c fp=0x1ede7ec sp=0x1ede790 pc=0x63e330 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ede7ec sp=0x1ede7ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by main.(*App).withAppPlayer in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:281 +0x504 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 62 gp=0x18039e8 m=nil [IO wait, 854 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e40f0, 0x75783dc8, 0x2, 0x2, 0x5) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a0f5e8 sp=0x1a0f5d4 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.netpollblock(0x75783db8, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1a0f600 sp=0x1a0f5e8 pc=0x548cc Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.runtime_pollWait(0x75783db8, 0x72) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1a0f614 sp=0x1a0f600 pc=0x8f4a8 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).wait(0x1936018, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a0f628 sp=0x1a0f614 pc=0x10a318 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).waitRead(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*FD).Accept(0x1936000) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1a0f670 sp=0x1a0f628 pc=0x10eb84 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*netFD).accept(0x1936000) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1a0f6d8 sp=0x1a0f670 pc=0x17e4c4 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*TCPListener).accept(0x1934048) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1a0f6f0 sp=0x1a0f6d8 pc=0x196900 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*TCPListener).Accept(0x1934048) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1a0f70c sp=0x1a0f6f0 pc=0x19596c Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*onceCloseListener).Accept(0x190aea0) Apr 14 10:30:35 volumio go-librespot[1527]: :1 +0x34 fp=0x1a0f724 sp=0x1a0f70c pc=0x362b54 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*Server).Serve(0x1af03c8, {0x886cb8, 0x1934048}) Apr 14 10:30:35 volumio volumio[1104]: info: Connection to go-librespot Websocket closed Apr 14 10:30:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1a0f7bc sp=0x1a0f724 pc=0x341210 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.Serve(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2794 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Apr 14 10:30:35 volumio go-librespot[1527]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x1a0f7ec sp=0x1a0f7bc pc=0x5a8184 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a0f7ec sp=0x1a0f7ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Apr 14 10:30:35 volumio go-librespot[1527]: /src/zeroconf/zeroconf.go:280 +0x160 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 12 gp=0x1a0b688 m=nil [IO wait, 970 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e40f0, 0x757838c8, 0x2, 0x2, 0x5) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x195487c sp=0x1954868 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.netpollblock(0x757838b8, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1954894 sp=0x195487c pc=0x548cc Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.runtime_pollWait(0x757838b8, 0x72) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x19548a8 sp=0x1954894 pc=0x8f4a8 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).wait(0x1b6e108, 0x72, 0x0) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x19548bc sp=0x19548a8 pc=0x10a318 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*pollDesc).waitRead(...) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 10:30:35 volumio go-librespot[1527]: internal/poll.(*FD).Read(0x1b6e0f0, {0x1b76000, 0x1000, 0x1000}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1954904 sp=0x19548bc pc=0x10b504 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*netFD).Read(0x1b6e0f0, {0x1b76000, 0x1000, 0x1000}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1954930 sp=0x1954904 pc=0x17c5d0 Apr 14 10:30:35 volumio go-librespot[1527]: net.(*conn).Read(0x1908838, {0x1b76000, 0x1000, 0x1000}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x195495c sp=0x1954930 pc=0x18caec Apr 14 10:30:35 volumio go-librespot[1527]: net.(*TCPConn).Read(0x1908838, {0x1b76000, 0x1000, 0x1000}) Apr 14 10:30:35 volumio go-librespot[1527]: :1 +0x44 fp=0x195497c sp=0x195495c pc=0x19fd40 Apr 14 10:30:35 volumio go-librespot[1527]: io.(*multiReader).Read(0x180fd10, {0x1b76000, 0x1000, 0x1000}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x19549a8 sp=0x195497c pc=0x105528 Apr 14 10:30:35 volumio go-librespot[1527]: bufio.(*Reader).fill(0x1b63920) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x19549cc sp=0x19549a8 pc=0x2bddb4 Apr 14 10:30:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 10:30:35 volumio go-librespot[1527]: bufio.(*Reader).ReadByte(0x1b63920) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x19549d8 sp=0x19549cc pc=0x2be638 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.readFrameHeader(0x1b63920, {0x1c348f8, 0x8, 0x8}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1954a18 sp=0x19549d8 pc=0x47788c Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1c348c8, {0x8874d0, 0xd0ad00}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1954ad8 sp=0x1954a18 pc=0x47a4f0 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).readLoop(0x1c348c8, {0x8874d0, 0xd0ad00}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1954b9c sp=0x1954ad8 pc=0x479fd8 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).reader(0x1c348c8, {0x8874d0, 0xd0ad00}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1954c28 sp=0x1954b9c pc=0x47b1ec Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).Reader(...) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Apr 14 10:30:35 volumio go-librespot[1527]: nhooyr.io/websocket.(*Conn).Read(0x1c348c8, {0x8874d0, 0xd0ad00}) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1954c50 sp=0x1954c28 pc=0x4798bc Apr 14 10:30:35 volumio go-librespot[1527]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x1954ccc sp=0x1954c50 pc=0x633988 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.HandlerFunc.ServeHTTP(0x1a07878, {0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1954cdc sp=0x1954ccc pc=0x33d7f4 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*ServeMux).ServeHTTP(0x1a64500, {0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1954d18 sp=0x1954cdc pc=0x33f654 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio go-librespot[1527]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1954d40 sp=0x1954d18 pc=0x633388 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.HandlerFunc.ServeHTTP(0x1a1d200, {0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1954d50 sp=0x1954d40 pc=0x33d7f4 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.serverHandler.ServeHTTP({0x1af00a8}, {0x886dec, 0x1cff408}, 0x18ba3c8) Apr 14 10:30:35 volumio systemd[1]: go-librespot-daemon.service: Consumed 20min 48.408s CPU time. Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1954d6c sp=0x1954d50 pc=0x340de4 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*conn).serve(0x1a40660, {0x887510, 0x1a14708}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1954fdc sp=0x1954d6c pc=0x33c034 Apr 14 10:30:35 volumio go-librespot[1527]: net/http.(*Server).Serve.gowrap3() Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1954fec sp=0x1954fdc pc=0x341688 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1954fec sp=0x1954fec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by net/http.(*Server).Serve in goroutine 37 Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/net/http/server.go:3285 +0x468 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 5908111 gp=0x29ee5a8 m=nil [chan send (nil chan)]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x0, 0x0, 0x4, 0x1, 0x2) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1b6d894 sp=0x1b6d880 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chansend(0x0, 0x1b6da21, 0x1, 0x645d00) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:165 +0x598 fp=0x1b6d8cc sp=0x1b6d894 pc=0x1f428 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.chansend1(0x0, 0x1b6da21) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1b6d8e4 sp=0x1b6d8cc pc=0x1ee8c Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/dealer.Request.Reply(...) Apr 14 10:30:35 volumio go-librespot[1527]: /src/dealer/recv.go:39 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*AppPlayer).Run(0x18c56d0, {0x8874f0, 0xd0ad00}, 0x187c300) Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/player.go:607 +0x7b4 fp=0x1b6dfd8 sp=0x1b6d8e4 pc=0x645d00 Apr 14 10:30:35 volumio go-librespot[1527]: main.(*App).withAppPlayer.func2.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:309 +0x40 fp=0x1b6dfec sp=0x1b6dfd8 pc=0x63e700 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1b6dfec sp=0x1b6dfec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by main.(*App).withAppPlayer.func2 in goroutine 61 Apr 14 10:30:35 volumio go-librespot[1527]: /src/cmd/daemon/main.go:309 +0x2c8 Apr 14 10:30:35 volumio go-librespot[1527]: goroutine 5908110 gp=0x364a248 m=nil [select, 584 minutes]: Apr 14 10:30:35 volumio go-librespot[1527]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1e81674 sp=0x1e81660 pc=0x5d0c0 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.selectgo(0x1e81774, 0x1e8173c, 0x0, 0x0, 0x3, 0x1) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1e81714 sp=0x1e81674 pc=0x70e78 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x18c5d60) Apr 14 10:30:35 volumio go-librespot[1527]: /src/player/player.go:201 +0x1fc fp=0x1e817e4 sp=0x1e81714 pc=0x513660 Apr 14 10:30:35 volumio go-librespot[1527]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Apr 14 10:30:35 volumio go-librespot[1527]: /src/player/player.go:183 +0x28 fp=0x1e817ec sp=0x1e817e4 pc=0x513240 Apr 14 10:30:35 volumio go-librespot[1527]: runtime.goexit({}) Apr 14 10:30:35 volumio go-librespot[1527]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1e817ec sp=0x1e817ec pc=0x93d6c Apr 14 10:30:35 volumio go-librespot[1527]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 61 Apr 14 10:30:35 volumio go-librespot[1527]: /src/player/player.go:183 +0x218 Apr 14 10:30:35 volumio go-librespot[1526]: Aborted Apr 14 10:30:38 volumio volumio[1104]: info: Initializing connection to go-librespot Websocket Apr 14 10:30:38 volumio volumio[1104]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 10:30:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 14 10:30:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 14 10:30:38 volumio systemd[1]: go-librespot-daemon.service: Consumed 20min 48.408s CPU time. Apr 14 10:30:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 14 10:30:38 volumio go-librespot[26974]: go-librespot daemon starting... Apr 14 10:30:38 volumio go-librespot[26975]: time="2026-04-14T10:30:38+02:00" level=info msg="running go-librespot 0.4.0" Apr 14 10:30:38 volumio go-librespot[26975]: time="2026-04-14T10:30:38+02:00" level=debug msg="app state loaded" Apr 14 10:30:38 volumio go-librespot[26975]: time="2026-04-14T10:30:38+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 14 10:30:41 volumio volumio[1104]: info: Initializing connection to go-librespot Websocket Apr 14 10:30:41 volumio go-librespot[26975]: time="2026-04-14T10:30:41+02:00" level=debug msg="new websocket client" Apr 14 10:30:41 volumio volumio[1104]: info: Connection to go-librespot Websocket established Apr 14 10:30:44 volumio volumio[1104]: info: Getting Spotify volume Apr 14 10:30:44 volumio volumio[1104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 14 10:30:44 volumio volumio[1104]: info: CoreCommandRouter::volumioGetState Apr 14 10:30:44 volumio volumio[1104]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Apr 14 10:30:48 volumio go-librespot[26975]: time="2026-04-14T10:30:48+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Apr 14 10:30:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 10:30:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 10:30:48 volumio volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 10:30:48 volumio volumio[1104]: Error: socket hang up Apr 14 10:30:48 volumio volumio[1104]: at connResetException (node:internal/errors:720:14) Apr 14 10:30:48 volumio volumio[1104]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 14 10:30:48 volumio volumio[1104]: at Socket.emit (node:events:526:35) Apr 14 10:30:48 volumio volumio[1104]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 14 10:30:48 volumio volumio[1104]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 14 10:30:48 volumio volumio[1104]: code: 'ECONNRESET', Apr 14 10:30:48 volumio volumio[1104]: response: undefined Apr 14 10:30:48 volumio volumio[1104]: } Apr 14 10:30:48 volumio volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 10:30:49 volumio sudo[27011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 10:29' Apr 14 10:30:49 volumio sudo[27011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"