Dec 27 17:00:09 volumio go-librespot[6965]: time="2025-12-27T17:00:09+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": context deadline exceeded" Dec 27 17:00:09 volumio go-librespot[6965]: time="2025-12-27T17:00:09+01:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: i/o timeout" Dec 27 17:00:36 volumio go-librespot[6965]: time="2025-12-27T17:00:36+01:00" level=error msg="did not receive last pong ack from accesspoint, 1152s passed" Dec 27 17:00:39 volumio go-librespot[6965]: time="2025-12-27T17:00:39+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": context deadline exceeded" Dec 27 17:00:39 volumio go-librespot[6965]: time="2025-12-27T17:00:39+01:00" level=error msg="failed reconnecting accesspoint" error="failed to connect to AP ap-gew4.spotify.com:4070: dial tcp: lookup ap-gew4.spotify.com: i/o timeout" Dec 27 17:00:39 volumio go-librespot[6965]: fatal error: sync: Unlock of unlocked RWMutex Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914561 gp=0x1a7c368 m=8 mp=0x1880b08 [running]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.fatal({0x7a3604, 0x20}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x2889ee0 sp=0x2889ecc pc=0x597e0 Dec 27 17:00:39 volumio go-librespot[6965]: sync.fatal({0x7a3604, 0x20}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x2889eec sp=0x2889ee0 pc=0x8ff0c Dec 27 17:00:39 volumio go-librespot[6965]: sync.(*RWMutex).Unlock(0x1946768) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x2889f04 sp=0x2889eec pc=0xa0ec0 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x19466e8) Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:329 +0x4bc fp=0x2889fe4 sp=0x2889f04 pc=0x48c784 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:390 +0x28 fp=0x2889fec sp=0x2889fe4 pc=0x48d010 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2889fec sp=0x2889fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 914620 Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:390 +0x120 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 1 gp=0x1802128 m=nil [select, 13136 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a03d00 sp=0x1a03cec pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.selectgo(0x1a03e00, 0x19d5dcc, 0x0, 0x0, 0x2, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1a03da0 sp=0x1a03d00 pc=0x70e78 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1a20000, 0x1a03e90) Dec 27 17:00:39 volumio go-librespot[6965]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x1a03e44 sp=0x1a03da0 pc=0x5a8088 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).withAppPlayer(0x191ac00, {0x8874f0, 0xd0ad00}, 0x180f580) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:321 +0x554 fp=0x1a03ed0 sp=0x1a03e44 pc=0x63db54 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).withCredentials(...) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:193 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).SpotifyToken(0x191ac00, {0x8874f0, 0xd0ad00}, {0x19188e0, 0x1c}, {0x187c3c0, 0x13c}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x1a03f00 sp=0x1a03ed0 pc=0x63cfa8 Dec 27 17:00:39 volumio go-librespot[6965]: main.main() Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:557 +0x588 fp=0x1a03fa8 sp=0x1a03f00 pc=0x63feb4 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.main() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1a03fec sp=0x1a03fa8 pc=0x5cb30 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a03fec sp=0x1a03fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle), 2 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1844fd4 sp=0x1844fc0 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goparkunlock(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:408 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.forcegchelper() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1844fec sp=0x1844fd4 pc=0x5cefc Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1844fec sp=0x1844fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.init.5 in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:314 +0x1c Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 3 gp=0x1802d88 m=nil [GC sweep wait]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18457c4 sp=0x18457b0 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goparkunlock(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:408 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.bgsweep(0x1874000) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x18457e4 sp=0x18457c4 pc=0x45848 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcenable.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x18457ec sp=0x18457e4 pc=0x35968 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18457ec sp=0x18457ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcenable in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 4 gp=0x1802ea8 m=nil [GC scavenge wait]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1845fb4 sp=0x1845fa0 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goparkunlock(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:408 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.(*scavengerState).park(0xce0208) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1845fc8 sp=0x1845fb4 pc=0x42b68 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.bgscavenge(0x1874000) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1845fe4 sp=0x1845fc8 pc=0x432a0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcenable.gowrap2() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1845fec sp=0x1845fe4 pc=0x35914 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1845fec sp=0x1845fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcenable in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 5 gp=0x1803208 m=nil [finalizer wait, 9 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x184678c sp=0x1846778 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.runfinq() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x18467ec sp=0x184678c pc=0x34754 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18467ec sp=0x18467ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.createfing in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 8 gp=0x19ee908 m=nil [IO wait, 2400 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e40f0, 0x757c3f08, 0x2, 0x2, 0x5) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1cdad28 sp=0x1cdad14 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.netpollblock(0x757c3ef8, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1cdad40 sp=0x1cdad28 pc=0x548cc Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.runtime_pollWait(0x757c3ef8, 0x72) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1cdad54 sp=0x1cdad40 pc=0x8f4a8 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).wait(0x187f418, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1cdad68 sp=0x1cdad54 pc=0x10a318 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).waitRead(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*FD).Accept(0x187f400) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1cdadb0 sp=0x1cdad68 pc=0x10eb84 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*netFD).accept(0x187f400) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1cdae18 sp=0x1cdadb0 pc=0x17e4c4 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*TCPListener).accept(0x1826840) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1cdae30 sp=0x1cdae18 pc=0x196900 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*TCPListener).Accept(0x1826840) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1cdae4c sp=0x1cdae30 pc=0x19596c Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*onceCloseListener).Accept(0x1a44340) Dec 27 17:00:39 volumio go-librespot[6965]: :1 +0x34 fp=0x1cdae64 sp=0x1cdae4c pc=0x362b54 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*Server).Serve(0x1a3c0a8, {0x886cb8, 0x1826840}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1cdaefc sp=0x1cdae64 pc=0x341210 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.Serve(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2794 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*ConcreteApiServer).serve(0x18290e0) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x1cdafe4 sp=0x1cdaefc pc=0x6330b8 Dec 27 17:00:39 volumio go-librespot[6965]: main.NewApiServer.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x1cdafec sp=0x1cdafe4 pc=0x631de4 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1cdafec sp=0x1cdafec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by main.NewApiServer in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/api_server.go:296 +0x310 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 52 gp=0x19eea28 m=nil [IO wait, 2 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e40f0, 0x757c3d28, 0x2, 0x2, 0x5) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1cdbe84 sp=0x1cdbe70 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.netpollblock(0x757c3d18, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1cdbe9c sp=0x1cdbe84 pc=0x548cc Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.runtime_pollWait(0x757c3d18, 0x72) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1cdbeb0 sp=0x1cdbe9c pc=0x8f4a8 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).wait(0x19fc108, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1cdbec4 sp=0x1cdbeb0 pc=0x10a318 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).waitRead(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*FD).RawRead(0x19fc0f0, 0x1aec600) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1cdbef0 sp=0x1cdbec4 pc=0x10f788 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*rawConn).Read(0x18963d0, 0x1aec600) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1cdbf10 sp=0x1cdbef0 pc=0x190d28 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x180e5f0, 0x1aec5d0, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1cdbf40 sp=0x1cdbf10 pc=0x548824 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x19d8098, {0x1ad2000, 0x10000, 0x10000}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x1cdbf9c sp=0x1cdbf40 pc=0x54c80c Dec 27 17:00:39 volumio go-librespot[6965]: github.com/grandcat/zeroconf.(*Server).recv4(0x18925c0, 0x19d8090) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1cdbfe0 sp=0x1cdbf9c pc=0x5a309c Dec 27 17:00:39 volumio go-librespot[6965]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1cdbfec sp=0x1cdbfe0 pc=0x5a2d54 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1cdbfec sp=0x1cdbfec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 35 Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 53 gp=0x19eeb48 m=nil [IO wait, 13136 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e40f0, 0x757c3c88, 0x2, 0x2, 0x5) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1840e5c sp=0x1840e48 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.netpollblock(0x757c3c78, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1840e74 sp=0x1840e5c pc=0x548cc Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.runtime_pollWait(0x757c3c78, 0x72) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1840e88 sp=0x1840e74 pc=0x8f4a8 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).wait(0x19fc158, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1840e9c sp=0x1840e88 pc=0x10a318 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).waitRead(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*FD).RawRead(0x19fc140, 0x1812cc0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1840ec8 sp=0x1840e9c pc=0x10f788 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*rawConn).Read(0x18963f8, 0x1812cc0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1840ee8 sp=0x1840ec8 pc=0x190d28 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x180e600, 0x1812c90, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1840f18 sp=0x1840ee8 pc=0x548824 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 27 17:00:39 volumio go-librespot[6965]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x19d80c8, {0x1ac2000, 0x10000, 0x10000}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1840f9c sp=0x1840f18 pc=0x550194 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/grandcat/zeroconf.(*Server).recv6(0x18925c0, 0x19d80c0) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1840fe0 sp=0x1840f9c pc=0x5a3290 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1840fec sp=0x1840fe0 pc=0x5a2cf8 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1840fec sp=0x1840fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 35 Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 34 gp=0x1c9a008 m=nil [GC worker (idle), 276 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3fbc, 0x182ba28, 0x1a, 0xa, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ca0790 sp=0x1ca077c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcBgMarkWorker() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1ca07ec sp=0x1ca0790 pc=0x38590 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ca07ec sp=0x1ca07ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 14 gp=0x19ef0e8 m=nil [GC worker (idle)]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3fbc, 0x182ba40, 0x1a, 0xa, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1847790 sp=0x184777c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcBgMarkWorker() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x18477ec sp=0x1847790 pc=0x38590 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18477ec sp=0x18477ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 15 gp=0x19ef208 m=nil [GC worker (idle), 11 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3fbc, 0x182ba58, 0x1a, 0xa, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1847f90 sp=0x1847f7c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcBgMarkWorker() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1847fec sp=0x1847f90 pc=0x38590 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1847fec sp=0x1847fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 16 gp=0x19ef328 m=nil [GC worker (idle), 4810 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3fbc, 0x1890ff0, 0x1a, 0xa, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c9c790 sp=0x1c9c77c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gcBgMarkWorker() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c9c7ec sp=0x1c9c790 pc=0x38590 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c9c7ec sp=0x1c9c7ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 62 gp=0x18847e8 m=nil [IO wait, 1271 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e40f0, 0x757c3dc8, 0x2, 0x2, 0x5) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x18445e8 sp=0x18445d4 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.netpollblock(0x757c3db8, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1844600 sp=0x18445e8 pc=0x548cc Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.runtime_pollWait(0x757c3db8, 0x72) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1844614 sp=0x1844600 pc=0x8f4a8 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).wait(0x19fc0b8, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1844628 sp=0x1844614 pc=0x10a318 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).waitRead(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*FD).Accept(0x19fc0a0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1844670 sp=0x1844628 pc=0x10eb84 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*netFD).accept(0x19fc0a0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x18446d8 sp=0x1844670 pc=0x17e4c4 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*TCPListener).accept(0x1a100f0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x18446f0 sp=0x18446d8 pc=0x196900 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*TCPListener).Accept(0x1a100f0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x184470c sp=0x18446f0 pc=0x19596c Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*onceCloseListener).Accept(0x1b622e0) Dec 27 17:00:39 volumio go-librespot[6965]: :1 +0x34 fp=0x1844724 sp=0x184470c pc=0x362b54 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*Server).Serve(0x1a3d688, {0x886cb8, 0x1a100f0}) Dec 27 17:00:39 volumio volumio[14708]: info: Connection to go-librespot Websocket closed Dec 27 17:00:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x18447bc sp=0x1844724 pc=0x341210 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.Serve(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2794 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 27 17:00:39 volumio go-librespot[6965]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x18447ec sp=0x18447bc pc=0x5a8184 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18447ec sp=0x18447ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /src/zeroconf/zeroconf.go:280 +0x160 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 60 gp=0x1884fc8 m=nil [chan receive, 2400 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3f90, 0x18742b0, 0xe, 0x7, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1841748 sp=0x1841734 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv(0x1874280, 0x18417d0, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1841784 sp=0x1841748 pc=0x20078 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv2(0x1874280, 0x18417d0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1841798 sp=0x1841784 pc=0x1fbbc Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).withAppPlayer.func1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:268 +0x68 fp=0x18417ec sp=0x1841798 pc=0x63e794 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x18417ec sp=0x18417ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by main.(*App).withAppPlayer in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:265 +0x464 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 61 gp=0x18850e8 m=nil [chan receive, 4810 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3f90, 0x1874270, 0xe, 0x7, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1d3a740 sp=0x1d3a72c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv(0x1874240, 0x2885fe0, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1d3a77c sp=0x1d3a740 pc=0x20078 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv2(0x1874240, 0x2885fe0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1d3a790 sp=0x1d3a77c pc=0x1fbbc Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).withAppPlayer.func2() Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:284 +0x6c fp=0x1d3a7ec sp=0x1d3a790 pc=0x63e330 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1d3a7ec sp=0x1d3a7ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by main.(*App).withAppPlayer in goroutine 1 Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:281 +0x504 Dec 27 17:00:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 95 gp=0x1d13448 m=nil [IO wait, 13136 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e40f0, 0x757c38c8, 0x2, 0x2, 0x5) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x185b87c sp=0x185b868 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.netpollblock(0x757c38b8, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x185b894 sp=0x185b87c pc=0x548cc Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.runtime_pollWait(0x757c38b8, 0x72) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x185b8a8 sp=0x185b894 pc=0x8f4a8 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).wait(0x18143d8, 0x72, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185b8bc sp=0x185b8a8 pc=0x10a318 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*pollDesc).waitRead(...) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 27 17:00:39 volumio go-librespot[6965]: internal/poll.(*FD).Read(0x18143c0, {0x1a90000, 0x1000, 0x1000}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x185b904 sp=0x185b8bc pc=0x10b504 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*netFD).Read(0x18143c0, {0x1a90000, 0x1000, 0x1000}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x185b930 sp=0x185b904 pc=0x17c5d0 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*conn).Read(0x1831510, {0x1a90000, 0x1000, 0x1000}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x185b95c sp=0x185b930 pc=0x18caec Dec 27 17:00:39 volumio go-librespot[6965]: net.(*TCPConn).Read(0x1831510, {0x1a90000, 0x1000, 0x1000}) Dec 27 17:00:39 volumio go-librespot[6965]: :1 +0x44 fp=0x185b97c sp=0x185b95c pc=0x19fd40 Dec 27 17:00:39 volumio go-librespot[6965]: io.(*multiReader).Read(0x1b69390, {0x1a90000, 0x1000, 0x1000}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x185b9a8 sp=0x185b97c pc=0x105528 Dec 27 17:00:39 volumio go-librespot[6965]: bufio.(*Reader).fill(0x1b7f560) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x185b9cc sp=0x185b9a8 pc=0x2bddb4 Dec 27 17:00:39 volumio go-librespot[6965]: bufio.(*Reader).ReadByte(0x1b7f560) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x185b9d8 sp=0x185b9cc pc=0x2be638 Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.readFrameHeader(0x1b7f560, {0x1b2acb8, 0x8, 0x8}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x185ba18 sp=0x185b9d8 pc=0x47788c Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1b2ac88, {0x8874d0, 0xd0ad00}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x185bad8 sp=0x185ba18 pc=0x47a4f0 Dec 27 17:00:39 volumio systemd[1]: go-librespot-daemon.service: Consumed 7min 24.256s CPU time. Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).readLoop(0x1b2ac88, {0x8874d0, 0xd0ad00}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x185bb9c sp=0x185bad8 pc=0x479fd8 Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).reader(0x1b2ac88, {0x8874d0, 0xd0ad00}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x185bc28 sp=0x185bb9c pc=0x47b1ec Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).Read(0x1b2ac88, {0x8874d0, 0xd0ad00}) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x185bc50 sp=0x185bc28 pc=0x4798bc Dec 27 17:00:39 volumio go-librespot[6965]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x185bccc sp=0x185bc50 pc=0x633988 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.HandlerFunc.ServeHTTP(0x1a3a180, {0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x185bcdc sp=0x185bccc pc=0x33d7f4 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*ServeMux).ServeHTTP(0x1a30000, {0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x185bd18 sp=0x185bcdc pc=0x33f654 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x185bd40 sp=0x185bd18 pc=0x633388 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.HandlerFunc.ServeHTTP(0x1a34160, {0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x185bd50 sp=0x185bd40 pc=0x33d7f4 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.serverHandler.ServeHTTP({0x1a3c0a8}, {0x886dec, 0x1a3de08}, 0x1a3dd68) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x185bd6c sp=0x185bd50 pc=0x340de4 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*conn).serve(0x1d0f1a0, {0x887510, 0x1a46180}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x185bfdc sp=0x185bd6c pc=0x33c034 Dec 27 17:00:39 volumio go-librespot[6965]: net/http.(*Server).Serve.gowrap3() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x185bfec sp=0x185bfdc pc=0x341688 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x185bfec sp=0x185bfec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by net/http.(*Server).Serve in goroutine 8 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 97 gp=0x1d13568 m=nil [select, 2400 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ca36a8 sp=0x1ca3694 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.selectgo(0x1ca37a4, 0x1ca376c, 0x0, 0x0, 0x5, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1ca3748 sp=0x1ca36a8 pc=0x70e78 Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1b2ac88) Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1ca37e4 sp=0x1ca3748 pc=0x474738 Dec 27 17:00:39 volumio go-librespot[6965]: nhooyr.io/websocket.newConn.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1ca37ec sp=0x1ca37e4 pc=0x47432c Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ca37ec sp=0x1ca37ec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by nhooyr.io/websocket.newConn in goroutine 95 Dec 27 17:00:39 volumio go-librespot[6965]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914939 gp=0x1a22128 m=nil [chan receive]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3f90, 0x28b84b0, 0xe, 0x7, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ea2f84 sp=0x1ea2f70 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv(0x28b8480, 0x0, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1ea2fc0 sp=0x1ea2f84 pc=0x20078 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chanrecv1(0x28b8480, 0x0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1ea2fd4 sp=0x1ea2fc0 pc=0x1fb98 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*Resolver).lookupIPAddr.func2(0x28b8480, 0x7e3ae8) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/lookup.go:338 +0x24 fp=0x1ea2fe0 sp=0x1ea2fd4 pc=0x19d92c Dec 27 17:00:39 volumio go-librespot[6965]: net.(*Resolver).lookupIPAddr.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/lookup.go:353 +0x38 fp=0x1ea2fec sp=0x1ea2fe0 pc=0x18b5f8 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ea2fec sp=0x1ea2fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by net.(*Resolver).lookupIPAddr in goroutine 914561 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/lookup.go:353 +0x674 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914189 gp=0x1b93c28 m=nil [chan send (nil chan), 2 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x0, 0x0, 0x4, 0x1, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2883894 sp=0x2883880 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend(0x0, 0x2883a21, 0x1, 0x645d00) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:165 +0x598 fp=0x28838cc sp=0x2883894 pc=0x1f428 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend1(0x0, 0x2883a21) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x28838e4 sp=0x28838cc pc=0x1ee8c Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.Request.Reply(...) Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/recv.go:39 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*AppPlayer).Run(0x29f0230, {0x8874f0, 0xd0ad00}, 0x1a1db80) Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/player.go:607 +0x7b4 fp=0x2883fd8 sp=0x28838e4 pc=0x645d00 Dec 27 17:00:39 volumio go-librespot[6965]: main.(*App).withAppPlayer.func2.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:309 +0x40 fp=0x2883fec sp=0x2883fd8 pc=0x63e700 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2883fec sp=0x2883fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by main.(*App).withAppPlayer.func2 in goroutine 61 Dec 27 17:00:39 volumio go-librespot[6965]: /src/cmd/daemon/main.go:309 +0x2c8 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914188 gp=0x1b9d688 m=nil [select, 2400 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1d39e74 sp=0x1d39e60 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.selectgo(0x1d39f74, 0x1d39f3c, 0x0, 0x0, 0x3, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1d39f14 sp=0x1d39e74 pc=0x70e78 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1d9ac30) Dec 27 17:00:39 volumio go-librespot[6965]: /src/player/player.go:201 +0x1fc fp=0x1d39fe4 sp=0x1d39f14 pc=0x513660 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/player/player.go:183 +0x28 fp=0x1d39fec sp=0x1d39fe4 pc=0x513240 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1d39fec sp=0x1d39fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 61 Dec 27 17:00:39 volumio go-librespot[6965]: /src/player/player.go:183 +0x218 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914938 gp=0x1bd9c28 m=nil [runnable]: Dec 27 17:00:39 volumio go-librespot[6965]: net.(*nsswitchConfig).tryUpdate(0xcdff58) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/nss.go:49 +0x2bc fp=0x1cdcbb0 sp=0x1cdcbb0 pc=0x18e7f4 Dec 27 17:00:39 volumio go-librespot[6965]: net.getSystemNSS() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/nss.go:34 +0x1c fp=0x1cdcbbc sp=0x1cdcbb0 pc=0x18e3dc Dec 27 17:00:39 volumio go-librespot[6965]: net.(*conf).lookupOrder(0xca49b8, 0xcdfa30, {0x182a4c8, 0x13}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/conf.go:349 +0x3b4 fp=0x1cdcc88 sp=0x1cdcbbc pc=0x1706e4 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*conf).hostLookupOrder(0xca49b8, 0xcdfa30, {0x182a4c8, 0x13}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/conf.go:227 +0xb8 fp=0x1cdccc0 sp=0x1cdcc88 pc=0x170190 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*Resolver).lookupIP(0xcdfa30, {0x887530, 0x2876030}, {0x790fa8, 0x3}, {0x182a4c8, 0x13}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/lookup_unix.go:64 +0x50 fp=0x1cdcefc sp=0x1cdccc0 pc=0x18c1bc Dec 27 17:00:39 volumio go-librespot[6965]: net.(*Resolver).lookupIP-fm({0x887530, 0x2876030}, {0x790fa8, 0x3}, {0x182a4c8, 0x13}) Dec 27 17:00:39 volumio go-librespot[6965]: :1 +0x58 fp=0x1cdcf30 sp=0x1cdcefc pc=0x1a3f78 Dec 27 17:00:39 volumio go-librespot[6965]: net.init.func1({0x887530, 0x2876030}, 0x25500a0, {0x790fa8, 0x3}, {0x182a4c8, 0x13}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/hook.go:22 +0x4c fp=0x1cdcf60 sp=0x1cdcf30 pc=0x16d188 Dec 27 17:00:39 volumio go-librespot[6965]: net.(*Resolver).lookupIPAddr.func1() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/net/lookup.go:334 +0x58 fp=0x1cdcf9c sp=0x1cdcf60 pc=0x18b67c Dec 27 17:00:39 volumio go-librespot[6965]: internal/singleflight.(*Group).doCall(0xcdfa38, 0x2876060, {0x182a090, 0x17}, 0x2680120) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x1cdcfd4 sp=0x1cdcf9c pc=0x16b538 Dec 27 17:00:39 volumio go-librespot[6965]: internal/singleflight.(*Group).DoChan.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x1cdcfec sp=0x1cdcfd4 pc=0x16b4f0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1cdcfec sp=0x1cdcfec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by internal/singleflight.(*Group).DoChan in goroutine 914561 Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2fc Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 914201 gp=0x1c5efc8 m=nil [runnable]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1cd8eb8 sp=0x1cd8ea4 pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.selectgo(0x1cd8fd4, 0x1cd8f7c, 0x0, 0x0, 0x2, 0x1) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1cd8f58 sp=0x1cd8eb8 pc=0x70e78 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x19466e8) Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:355 +0x8c fp=0x1cd8fe4 sp=0x1cd8f58 pc=0x48cb50 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:264 +0x28 fp=0x1cd8fec sp=0x1cd8fe4 pc=0x48c248 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1cd8fec sp=0x1cd8fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 914189 Dec 27 17:00:39 volumio go-librespot[6965]: /src/ap/ap.go:264 +0x15c Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 904182 gp=0x1b6f8c8 m=nil [chan send, 4810 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3f90, 0x1b2f8b0, 0xf, 0x6, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1be1e50 sp=0x1be1e3c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend(0x1b2f880, 0x1be1ef0, 0x1, 0x533320) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:259 +0x378 fp=0x1be1e88 sp=0x1be1e50 pc=0x1f208 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend1(0x1b2f880, 0x1be1ef0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1be1ea0 sp=0x1be1e88 pc=0x1ee8c Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleMessage(0x1946848, 0x1d9aa00) Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/recv.go:181 +0x4f0 fp=0x1be1f2c sp=0x1be1ea0 pc=0x533320 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1946848) Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:210 +0x3fc fp=0x1be1fe4 sp=0x1be1f2c pc=0x532108 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:296 +0x28 fp=0x1be1fec sp=0x1be1fe4 pc=0x5329bc Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1be1fec sp=0x1be1fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 860297 Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:296 +0xf4 Dec 27 17:00:39 volumio go-librespot[6965]: goroutine 652872 gp=0x1b95e68 m=nil [chan send, 6246 minutes]: Dec 27 17:00:39 volumio go-librespot[6965]: runtime.gopark(0x7e3f90, 0x1875ab0, 0xf, 0x6, 0x2) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1a01e50 sp=0x1a01e3c pc=0x5d0c0 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend(0x1875a80, 0x1a01ef0, 0x1, 0x533320) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:259 +0x378 fp=0x1a01e88 sp=0x1a01e50 pc=0x1f208 Dec 27 17:00:39 volumio go-librespot[6965]: runtime.chansend1(0x1875a80, 0x1a01ef0) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x1a01ea0 sp=0x1a01e88 pc=0x1ee8c Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleMessage(0x1d10bb8, 0x1a72780) Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/recv.go:181 +0x4f0 fp=0x1a01f2c sp=0x1a01ea0 pc=0x533320 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1d10bb8) Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:210 +0x3fc fp=0x1a01fe4 sp=0x1a01f2c pc=0x532108 Dec 27 17:00:39 volumio go-librespot[6965]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:296 +0x28 fp=0x1a01fec sp=0x1a01fe4 pc=0x5329bc Dec 27 17:00:39 volumio go-librespot[6965]: runtime.goexit({}) Dec 27 17:00:39 volumio go-librespot[6965]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1a01fec sp=0x1a01fec pc=0x93d6c Dec 27 17:00:39 volumio go-librespot[6965]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 652824 Dec 27 17:00:39 volumio go-librespot[6965]: /src/dealer/dealer.go:296 +0xf4 Dec 27 17:00:39 volumio go-librespot[6964]: Aborted Dec 27 17:00:42 volumio volumio[14708]: info: Initializing connection to go-librespot Websocket Dec 27 17:00:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 27 17:00:42 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 27 17:00:42 volumio systemd[1]: go-librespot-daemon.service: Consumed 7min 24.256s CPU time. Dec 27 17:00:42 volumio volumio[14708]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 27 17:00:42 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 27 17:00:42 volumio go-librespot[6644]: go-librespot daemon starting... Dec 27 17:00:42 volumio go-librespot[6645]: time="2025-12-27T17:00:42+01:00" level=info msg="running go-librespot 0.4.0" Dec 27 17:00:42 volumio go-librespot[6645]: time="2025-12-27T17:00:42+01:00" level=debug msg="app state loaded" Dec 27 17:00:42 volumio go-librespot[6645]: time="2025-12-27T17:00:42+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 27 17:00:45 volumio volumio[14708]: info: Initializing connection to go-librespot Websocket Dec 27 17:00:45 volumio go-librespot[6645]: time="2025-12-27T17:00:45+01:00" level=debug msg="new websocket client" Dec 27 17:00:45 volumio volumio[14708]: info: Connection to go-librespot Websocket established Dec 27 17:00:46 volumio bash[6293]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 27 17:00:46 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 27 17:00:46 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 27 17:00:48 volumio volumio[14708]: info: Getting Spotify volume Dec 27 17:00:48 volumio volumio[14708]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 27 17:00:48 volumio volumio[14708]: info: CoreCommandRouter::volumioGetState Dec 27 17:00:48 volumio volumio[14708]: info: CorePlayQueue::getTrack 0 Dec 27 17:00:48 volumio volumio[14708]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 27 17:01:07 volumio volumio[14708]: error: error Dec 27 17:01:12 volumio go-librespot[6645]: time="2025-12-27T17:01:12+01: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" Dec 27 17:01:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 27 17:01:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 27 17:01:12 volumio volumio[14708]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 17:01:12 volumio volumio[14708]: Error: socket hang up Dec 27 17:01:12 volumio volumio[14708]: at connResetException (node:internal/errors:720:14) Dec 27 17:01:12 volumio volumio[14708]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 27 17:01:12 volumio volumio[14708]: at Socket.emit (node:events:526:35) Dec 27 17:01:12 volumio volumio[14708]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 27 17:01:12 volumio volumio[14708]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 27 17:01:12 volumio volumio[14708]: code: 'ECONNRESET', Dec 27 17:01:12 volumio volumio[14708]: response: undefined Dec 27 17:01:12 volumio volumio[14708]: } Dec 27 17:01:12 volumio volumio[14708]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 17:01:13 volumio sudo[6732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 17:00' Dec 27 17:01:13 volumio sudo[6732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"