-- Logs begin at Thu 2019-02-14 13:12:00 +03, end at Sat 2026-02-07 16:22:11 +03. -- Feb 07 16:21:02 volumio ntpd[683]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 07 16:21:23 volumio go-librespot[5365]: time="2026-02-07T16:21:23+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Feb 07 16:21:30 volumio ntpd[683]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 07 16:21:51 volumio go-librespot[5365]: time="2026-02-07T16:21:51+03: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: device or resource busy" Feb 07 16:21:51 volumio go-librespot[5365]: fatal error: sync: Unlock of unlocked RWMutex Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 94 gp=0x24ccea8 m=4 mp=0x2049088 [running]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.fatal({0x7a3604, 0x20}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x248fee0 sp=0x248fecc pc=0x597e0 Feb 07 16:21:51 volumio go-librespot[5365]: sync.fatal({0x7a3604, 0x20}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x248feec sp=0x248fee0 pc=0x8ff0c Feb 07 16:21:51 volumio go-librespot[5365]: sync.(*RWMutex).Unlock(0x22d81e8) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x248ff04 sp=0x248feec pc=0xa0ec0 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x22d8168) Feb 07 16:21:51 volumio go-librespot[5365]: /src/ap/ap.go:329 +0x4bc fp=0x248ffe4 sp=0x248ff04 pc=0x48c784 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/ap/ap.go:260 +0x28 fp=0x248ffec sp=0x248ffe4 pc=0x48c29c Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x248ffec sp=0x248ffec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 59 Feb 07 16:21:51 volumio go-librespot[5365]: /src/ap/ap.go:260 +0x98 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 1 gp=0x2002128 m=nil [select, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2207d00 sp=0x2207cec pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.selectgo(0x2207e00, 0x20f9dcc, 0x0, 0x0, 0x2, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2207da0 sp=0x2207d00 pc=0x70e78 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x212e420, 0x2207e90) Feb 07 16:21:51 volumio go-librespot[5365]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x2207e44 sp=0x2207da0 pc=0x5a8088 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).withAppPlayer(0x20a2600, {0x8874f0, 0xd0ad00}, 0x2228010) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:321 +0x554 fp=0x2207ed0 sp=0x2207e44 pc=0x63db54 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).withCredentials(...) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:193 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).SpotifyToken(0x20a2600, {0x8874f0, 0xd0ad00}, {0x20e8f30, 0xf}, {0x21b8140, 0x12b}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x2207f00 sp=0x2207ed0 pc=0x63cfa8 Feb 07 16:21:51 volumio go-librespot[5365]: main.main() Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:557 +0x588 fp=0x2207fa8 sp=0x2207f00 pc=0x63feb4 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.main() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2207fec sp=0x2207fa8 pc=0x5cb30 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2207fec sp=0x2207fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle), 3 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044fd4 sp=0x2044fc0 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goparkunlock(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:408 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.forcegchelper() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2044fec sp=0x2044fd4 pc=0x5cefc Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2044fec sp=0x2044fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.init.5 in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:314 +0x1c Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 18 gp=0x2084248 m=nil [GC sweep wait]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407c4 sp=0x20407b0 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goparkunlock(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:408 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.bgsweep(0x2090000) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x20407e4 sp=0x20407c4 pc=0x45848 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcenable.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x20407ec sp=0x20407e4 pc=0x35968 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcenable in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 19 gp=0x2084368 m=nil [GC scavenge wait]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fb4 sp=0x2040fa0 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goparkunlock(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:408 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.(*scavengerState).park(0xce0208) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2040fc8 sp=0x2040fb4 pc=0x42b68 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.bgscavenge(0x2090000) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2040fe4 sp=0x2040fc8 pc=0x432a0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcenable.gowrap2() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x35914 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcenable in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 3 gp=0x2002fc8 m=nil [finalizer wait, 17 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x204578c sp=0x2045778 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.runfinq() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x20457ec sp=0x204578c pc=0x34754 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20457ec sp=0x20457ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.createfing in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 34 gp=0x221c008 m=nil [IO wait, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e40f0, 0x76058f08, 0x2, 0x2, 0x5) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2222528 sp=0x2222514 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.netpollblock(0x76058ef8, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2222540 sp=0x2222528 pc=0x548cc Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.runtime_pollWait(0x76058ef8, 0x72) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2222554 sp=0x2222540 pc=0x8f4a8 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).wait(0x20e12d8, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2222568 sp=0x2222554 pc=0x10a318 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).waitRead(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*FD).Accept(0x20e12c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x22225b0 sp=0x2222568 pc=0x10eb84 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*netFD).accept(0x20e12c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2222618 sp=0x22225b0 pc=0x17e4c4 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*TCPListener).accept(0x20e2780) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2222630 sp=0x2222618 pc=0x196900 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*TCPListener).Accept(0x20e2780) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x222264c sp=0x2222630 pc=0x19596c Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*onceCloseListener).Accept(0x207f280) Feb 07 16:21:51 volumio go-librespot[5365]: :1 +0x34 fp=0x2222664 sp=0x222264c pc=0x362b54 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*Server).Serve(0x2107ae8, {0x886cb8, 0x20e2780}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x22226fc sp=0x2222664 pc=0x341210 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.Serve(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2794 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*ConcreteApiServer).serve(0x20d8d20) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x22227e4 sp=0x22226fc pc=0x6330b8 Feb 07 16:21:51 volumio go-librespot[5365]: main.NewApiServer.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x22227ec sp=0x22227e4 pc=0x631de4 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22227ec sp=0x22227ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by main.NewApiServer in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/api_server.go:296 +0x310 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 13 gp=0x221c128 m=nil [IO wait, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e40f0, 0x76058be8, 0x2, 0x2, 0x5) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x220ae84 sp=0x220ae70 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.netpollblock(0x76058bd8, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x220ae9c sp=0x220ae84 pc=0x548cc Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.runtime_pollWait(0x76058bd8, 0x72) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x220aeb0 sp=0x220ae9c pc=0x8f4a8 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).wait(0x24a4108, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x220aec4 sp=0x220aeb0 pc=0x10a318 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).waitRead(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*FD).RawRead(0x24a40f0, 0x2098090) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x220aef0 sp=0x220aec4 pc=0x10f788 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*rawConn).Read(0x20922b8, 0x2098090) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x220af10 sp=0x220aef0 pc=0x190d28 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x200e4b0, 0x2098060, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x220af40 sp=0x220af10 pc=0x548824 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2492098, {0x21d8000, 0x10000, 0x10000}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x220af9c sp=0x220af40 pc=0x54c80c Feb 07 16:21:51 volumio go-librespot[5365]: github.com/grandcat/zeroconf.(*Server).recv4(0x2108780, 0x2492090) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x220afe0 sp=0x220af9c pc=0x5a309c Feb 07 16:21:51 volumio go-librespot[5365]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x220afec sp=0x220afe0 pc=0x5a2d54 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x220afec sp=0x220afec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 24 Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 14 gp=0x221c248 m=nil [IO wait, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e40f0, 0x76058b48, 0x2, 0x2, 0x5) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x204265c sp=0x2042648 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.netpollblock(0x76058b38, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2042674 sp=0x204265c pc=0x548cc Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.runtime_pollWait(0x76058b38, 0x72) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2042688 sp=0x2042674 pc=0x8f4a8 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).wait(0x24a4158, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x204269c sp=0x2042688 pc=0x10a318 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).waitRead(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*FD).RawRead(0x24a4140, 0x2551b90) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x20426c8 sp=0x204269c pc=0x10f788 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*rawConn).Read(0x20922d8, 0x2551b90) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x20426e8 sp=0x20426c8 pc=0x190d28 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x200e4c0, 0x2551b60, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2042718 sp=0x20426e8 pc=0x548824 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 07 16:21:51 volumio go-librespot[5365]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x24920c8, {0x2568000, 0x10000, 0x10000}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x204279c sp=0x2042718 pc=0x550194 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/grandcat/zeroconf.(*Server).recv6(0x2108780, 0x24920c0) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x20427e0 sp=0x204279c pc=0x5a3290 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x20427ec sp=0x20427e0 pc=0x5a2cf8 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 24 Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 38 gp=0x221c488 m=nil [IO wait, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e40f0, 0x76058e68, 0x2, 0x2, 0x5) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205687c sp=0x2056868 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.netpollblock(0x76058e58, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2056894 sp=0x205687c pc=0x548cc Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.runtime_pollWait(0x76058e58, 0x72) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20568a8 sp=0x2056894 pc=0x8f4a8 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).wait(0x222c108, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20568bc sp=0x20568a8 pc=0x10a318 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).waitRead(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*FD).Read(0x222c0f0, {0x2242000, 0x1000, 0x1000}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2056904 sp=0x20568bc pc=0x10b504 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*netFD).Read(0x222c0f0, {0x2242000, 0x1000, 0x1000}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2056930 sp=0x2056904 pc=0x17c5d0 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*conn).Read(0x22120f8, {0x2242000, 0x1000, 0x1000}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x205695c sp=0x2056930 pc=0x18caec Feb 07 16:21:51 volumio go-librespot[5365]: net.(*TCPConn).Read(0x22120f8, {0x2242000, 0x1000, 0x1000}) Feb 07 16:21:51 volumio go-librespot[5365]: :1 +0x44 fp=0x205697c sp=0x205695c pc=0x19fd40 Feb 07 16:21:51 volumio go-librespot[5365]: io.(*multiReader).Read(0x22280f0, {0x2242000, 0x1000, 0x1000}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x20569a8 sp=0x205697c pc=0x105528 Feb 07 16:21:51 volumio go-librespot[5365]: bufio.(*Reader).fill(0x223c0c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x20569cc sp=0x20569a8 pc=0x2bddb4 Feb 07 16:21:51 volumio go-librespot[5365]: bufio.(*Reader).ReadByte(0x223c0c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x20569d8 sp=0x20569cc pc=0x2be638 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.readFrameHeader(0x223c0c0, {0x224e038, 0x8, 0x8}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2056a18 sp=0x20569d8 pc=0x47788c Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x224e008, {0x8874d0, 0xd0ad00}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2056ad8 sp=0x2056a18 pc=0x47a4f0 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).readLoop(0x224e008, {0x8874d0, 0xd0ad00}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2056b9c sp=0x2056ad8 pc=0x479fd8 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).reader(0x224e008, {0x8874d0, 0xd0ad00}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2056c28 sp=0x2056b9c pc=0x47b1ec Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).Reader(...) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).Read(0x224e008, {0x8874d0, 0xd0ad00}) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2056c50 sp=0x2056c28 pc=0x4798bc Feb 07 16:21:51 volumio go-librespot[5365]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2056ccc sp=0x2056c50 pc=0x633988 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.HandlerFunc.ServeHTTP(0x2030330, {0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2056cdc sp=0x2056ccc pc=0x33d7f4 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*ServeMux).ServeHTTP(0x2118600, {0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2056d18 sp=0x2056cdc pc=0x33f654 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2056d40 sp=0x2056d18 pc=0x633388 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.HandlerFunc.ServeHTTP(0x200e430, {0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2056d50 sp=0x2056d40 pc=0x33d7f4 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.serverHandler.ServeHTTP({0x2107ae8}, {0x886dec, 0x2214508}, 0x2214328) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2056d6c sp=0x2056d50 pc=0x340de4 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*conn).serve(0x22320c0, {0x887510, 0x2026240}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2056fdc sp=0x2056d6c pc=0x33c034 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*Server).Serve.gowrap3() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2056fec sp=0x2056fdc pc=0x341688 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2056fec sp=0x2056fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by net/http.(*Server).Serve in goroutine 34 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:3285 +0x468 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 40 gp=0x221c6c8 m=nil [select, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22236a8 sp=0x2223694 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.selectgo(0x22237a4, 0x222376c, 0x0, 0x0, 0x5, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2223748 sp=0x22236a8 pc=0x70e78 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x224e008) Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x22237e4 sp=0x2223748 pc=0x474738 Feb 07 16:21:51 volumio go-librespot[5365]: nhooyr.io/websocket.newConn.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x22237ec sp=0x22237e4 pc=0x47432c Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22237ec sp=0x22237ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by nhooyr.io/websocket.newConn in goroutine 38 Feb 07 16:21:51 volumio go-librespot[5365]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 8 gp=0x2003688 m=nil [GC worker (idle), 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3fbc, 0x202aac8, 0x1a, 0xa, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2045f90 sp=0x2045f7c pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcBgMarkWorker() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2045fec sp=0x2045f90 pc=0x38590 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2045fec sp=0x2045fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 41 gp=0x221c7e8 m=nil [GC worker (idle)]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3fbc, 0x202aae0, 0x1a, 0xa, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2223f90 sp=0x2223f7c pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcBgMarkWorker() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2223fec sp=0x2223f90 pc=0x38590 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2223fec sp=0x2223fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 9 gp=0x20037a8 m=nil [GC worker (idle), 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3fbc, 0x20e7f50, 0x1a, 0xa, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2046790 sp=0x204677c pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcBgMarkWorker() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20467ec sp=0x2046790 pc=0x38590 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20467ec sp=0x20467ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 42 gp=0x221c908 m=nil [GC worker (idle), 3 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3fbc, 0x223e888, 0x1a, 0xa, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2224790 sp=0x222477c pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gcBgMarkWorker() Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x22247ec sp=0x2224790 pc=0x38590 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22247ec sp=0x22247ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 58 gp=0x2003208 m=nil [select, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2041e74 sp=0x2041e60 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.selectgo(0x2041f74, 0x2041f3c, 0x0, 0x0, 0x3, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2041f14 sp=0x2041e74 pc=0x70e78 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x22c5950) Feb 07 16:21:51 volumio go-librespot[5365]: /src/player/player.go:201 +0x1fc fp=0x2041fe4 sp=0x2041f14 pc=0x513660 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/player/player.go:183 +0x28 fp=0x2041fec sp=0x2041fe4 pc=0x513240 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2041fec sp=0x2041fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/player/player.go:183 +0x218 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 59 gp=0x20038c8 m=nil [chan send (nil chan), 5 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x0, 0x0, 0x4, 0x1, 0x2) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20fb894 sp=0x20fb880 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chansend(0x0, 0x20fba21, 0x1, 0x645d00) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:165 +0x598 fp=0x20fb8cc sp=0x20fb894 pc=0x1f428 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chansend1(0x0, 0x20fba21) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x20fb8e4 sp=0x20fb8cc pc=0x1ee8c Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/dealer.Request.Reply(...) Feb 07 16:21:51 volumio go-librespot[5365]: /src/dealer/recv.go:39 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*AppPlayer).Run(0x24a4190, {0x8874f0, 0xd0ad00}, 0x23cb640) Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/player.go:607 +0x7b4 fp=0x20fbfd8 sp=0x20fb8e4 pc=0x645d00 Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).withAppPlayer.gowrap1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:258 +0x40 fp=0x20fbfec sp=0x20fbfd8 pc=0x63e874 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20fbfec sp=0x20fbfec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by main.(*App).withAppPlayer in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:258 +0x3d8 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 60 gp=0x20039e8 m=nil [chan receive, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3f90, 0x20901f0, 0xe, 0x7, 0x2) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2340f48 sp=0x2340f34 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chanrecv(0x20901c0, 0x2340fd0, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2340f84 sp=0x2340f48 pc=0x20078 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chanrecv2(0x20901c0, 0x2340fd0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2340f98 sp=0x2340f84 pc=0x1fbbc Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).withAppPlayer.func1() Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:268 +0x68 fp=0x2340fec sp=0x2340f98 pc=0x63e794 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2340fec sp=0x2340fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by main.(*App).withAppPlayer in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:265 +0x464 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 61 gp=0x2003b08 m=nil [chan receive, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e3f90, 0x20901b0, 0xe, 0x7, 0x2) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2341740 sp=0x234172c pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chanrecv(0x2090180, 0x23417e0, 0x1) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x234177c sp=0x2341740 pc=0x20078 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.chanrecv2(0x2090180, 0x23417e0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2341790 sp=0x234177c pc=0x1fbbc Feb 07 16:21:51 volumio go-librespot[5365]: main.(*App).withAppPlayer.func2() Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:284 +0x6c fp=0x23417ec sp=0x2341790 pc=0x63e330 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23417ec sp=0x23417ec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by main.(*App).withAppPlayer in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/cmd/daemon/main.go:281 +0x504 Feb 07 16:21:51 volumio go-librespot[5365]: goroutine 62 gp=0x2003c28 m=nil [IO wait, 19 minutes]: Feb 07 16:21:51 volumio go-librespot[5365]: runtime.gopark(0x7e40f0, 0x76058c88, 0x2, 0x2, 0x5) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2341de8 sp=0x2341dd4 pc=0x5d0c0 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.netpollblock(0x76058c78, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2341e00 sp=0x2341de8 pc=0x548cc Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.runtime_pollWait(0x76058c78, 0x72) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2341e14 sp=0x2341e00 pc=0x8f4a8 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).wait(0x24a40b8, 0x72, 0x0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2341e28 sp=0x2341e14 pc=0x10a318 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*pollDesc).waitRead(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 07 16:21:51 volumio go-librespot[5365]: internal/poll.(*FD).Accept(0x24a40a0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2341e70 sp=0x2341e28 pc=0x10eb84 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*netFD).accept(0x24a40a0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2341ed8 sp=0x2341e70 pc=0x17e4c4 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*TCPListener).accept(0x24a60c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2341ef0 sp=0x2341ed8 pc=0x196900 Feb 07 16:21:51 volumio go-librespot[5365]: net.(*TCPListener).Accept(0x24a60c0) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2341f0c sp=0x2341ef0 pc=0x19596c Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*onceCloseListener).Accept(0x2330520) Feb 07 16:21:51 volumio go-librespot[5365]: :1 +0x34 fp=0x2341f24 sp=0x2341f0c pc=0x362b54 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.(*Server).Serve(0x22de468, {0x886cb8, 0x24a60c0}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2341fbc sp=0x2341f24 pc=0x341210 Feb 07 16:21:51 volumio go-librespot[5365]: net/http.Serve(...) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/net/http/server.go:2794 Feb 07 16:21:51 volumio go-librespot[5365]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 07 16:21:51 volumio go-librespot[5365]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x2341fec sp=0x2341fbc pc=0x5a8184 Feb 07 16:21:51 volumio go-librespot[5365]: runtime.goexit({}) Feb 07 16:21:51 volumio go-librespot[5365]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2341fec sp=0x2341fec pc=0x93d6c Feb 07 16:21:51 volumio go-librespot[5365]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 07 16:21:51 volumio go-librespot[5365]: /src/zeroconf/zeroconf.go:280 +0x160 Feb 07 16:21:51 volumio go-librespot[5365]: Aborted Feb 07 16:21:51 volumio volumio[703]: info: Connection to go-librespot Websocket closed Feb 07 16:21:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Feb 07 16:21:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 07 16:21:54 volumio volumio[703]: info: Initializing connection to go-librespot Websocket Feb 07 16:21:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 07 16:21:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 132. Feb 07 16:21:54 volumio systemd[1]: Stopped go-librespot Daemon. Feb 07 16:21:54 volumio systemd[1]: Started go-librespot Daemon. Feb 07 16:21:54 volumio go-librespot[8062]: go-librespot daemon starting... Feb 07 16:21:54 volumio go-librespot[8062]: time="2026-02-07T16:21:54+03:00" level=info msg="running go-librespot 0.4.0" Feb 07 16:21:54 volumio go-librespot[8062]: time="2026-02-07T16:21:54+03:00" level=debug msg="app state loaded" Feb 07 16:21:54 volumio go-librespot[8062]: time="2026-02-07T16:21:54+03:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 16:21:58 volumio ntpd[683]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 07 16:22:07 volumio go-librespot[8062]: time="2026-02-07T16:22:07+03:00" level=debug msg="new websocket client" Feb 07 16:22:07 volumio volumio[703]: info: Connection to go-librespot Websocket established Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=info msg="zeroconf server listening on port 34799" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="obtained new client token: AAAx8nsFJuXaI/UI2oa1TE1vKCwDbLm7nXH8Qeqvy4yfAsVNr1/l47JD/sTXIpgusDj0arWziA051jwlRM5F9OTB1UM/RvkZksI0HJVJJwigNVa8Opuia7pyppgwzOn39zIuGDx7VNPMJNrcsU7SUXROrQtQdtb3uWePj1cEYxcEjPJOOoCcJ6eCucYuHXCwXOG2pK2lTCLu9lsvVmKLJSI7bE8Znbu4K/INP7EQhQtlALilepM/pcLcGi0=" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="completed keyexchange" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="completed challenge" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=info msg="authenticated AP" username="du***********ya" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=info msg="authenticated Login5" username="du***********ya" Feb 07 16:22:09 volumio go-librespot[8062]: time="2026-02-07T16:22:09+03:00" level=debug msg="initializing zeroconf session" username="du***********ya" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="dealer connection opened" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=trace msg="starting accesspoint recv loop" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=trace msg="starting dealer recv loop" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=trace msg="received accesspoint ping" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="received connection id: MDVkZGZiMmYtMzI5...MEU2RjhFQ0IzQg==" Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=trace msg="received accesspoint pong ack" Feb 07 16:22:10 volumio volumio[703]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 16:22:10 volumio volumio[703]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Feb 07 16:22:10 volumio volumio[703]: code: 'auth/network-request-failed', Feb 07 16:22:10 volumio volumio[703]: a: null Feb 07 16:22:10 volumio volumio[703]: } Feb 07 16:22:10 volumio volumio[703]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 16:22:10 volumio go-librespot[8062]: time="2026-02-07T16:22:10+03:00" level=debug msg="put connect state because NEW_DEVICE" Feb 07 16:22:11 volumio sudo[8140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-07 16:21 Feb 07 16:22:11 volumio sudo[8140]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"