Dec 23 18:05:10 v go-librespot[2004]: time="2025-12-23T18:05:10+09:00" level=error msg="did not receive last pong ack from accesspoint, 840s passed" Dec 23 18:05:10 v go-librespot[2004]: time="2025-12-23T18:05:10+09:00" level=trace msg="sent dealer ping" Dec 23 18:05:10 v go-librespot[2004]: time="2025-12-23T18:05:10+09:00" level=trace msg="received dealer pong" Dec 23 18:05:36 v go-librespot[2004]: time="2025-12-23T18:05:36+09:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Dec 23 18:05:36 v go-librespot[2004]: time="2025-12-23T18:05:36+09:00" level=debug msg="completed keyexchange" Dec 23 18:05:36 v go-librespot[2004]: time="2025-12-23T18:05:36+09:00" level=debug msg="completed challenge" Dec 23 18:05:40 v go-librespot[2004]: time="2025-12-23T18:05:40+09:00" level=trace msg="sent dealer ping" Dec 23 18:05:40 v go-librespot[2004]: time="2025-12-23T18:05:40+09:00" level=trace msg="received dealer pong" Dec 23 18:06:10 v go-librespot[2004]: time="2025-12-23T18:06:10+09:00" level=trace msg="sent dealer ping" Dec 23 18:06:10 v go-librespot[2004]: time="2025-12-23T18:06:10+09:00" level=trace msg="received dealer pong" Dec 23 18:06:27 v go-librespot[2004]: time="2025-12-23T18:06:27+09:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Dec 23 18:06:27 v go-librespot[2004]: time="2025-12-23T18:06:27+09:00" level=debug msg="connected to ap-gew4.spotify.com:80" Dec 23 18:06:27 v go-librespot[2004]: time="2025-12-23T18:06:27+09:00" level=debug msg="completed keyexchange" Dec 23 18:06:27 v go-librespot[2004]: time="2025-12-23T18:06:27+09:00" level=debug msg="completed challenge" Dec 23 18:06:28 v go-librespot[2004]: time="2025-12-23T18:06:28+09:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: BadCredentials " Dec 23 18:06:28 v go-librespot[2004]: fatal error: sync: Unlock of unlocked RWMutex Dec 23 18:06:28 v go-librespot[2004]: goroutine 91 gp=0xc0003148c0 m=0 mp=0x11348c0 [running]: Dec 23 18:06:28 v go-librespot[2004]: runtime.fatal({0xb74234?, 0x81d49a?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/panic.go:1042 +0x5c fp=0xc000193de0 sp=0xc000193db0 pc=0x44063c Dec 23 18:06:28 v go-librespot[2004]: sync.fatal({0xb74234?, 0x81d4e0?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/panic.go:1007 +0x18 fp=0xc000193e00 sp=0xc000193de0 pc=0x470b38 Dec 23 18:06:28 v go-librespot[2004]: sync.(*RWMutex).Unlock(0xc000276758) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/sync/rwmutex.go:208 +0x45 fp=0xc000193e38 sp=0xc000193e00 pc=0x482105 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc000276690) Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:329 +0x4cb fp=0xc000193fc8 sp=0xc000193e38 pc=0x81e02b Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:390 +0x25 fp=0xc000193fe0 sp=0xc000193fc8 pc=0x81e805 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000193fe8 sp=0xc000193fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 67 Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:390 +0x108 Dec 23 18:06:28 v go-librespot[2004]: goroutine 1 gp=0xc0000061c0 m=nil [select, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc000045c70?, 0x2?, 0x58?, 0xed?, 0xc000045c0c?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000179aa0 sp=0xc000179a80 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc000179c70, 0xc000045c08, 0x0?, 0x0, 0x40?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000179bc0 sp=0xc000179aa0 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc00041ac80, 0xc000179d80) Dec 23 18:06:28 v go-librespot[2004]: /src/zeroconf/zeroconf.go:283 +0x2a6 fp=0xc000179d08 sp=0xc000179bc0 pc=0x913c46 Dec 23 18:06:28 v go-librespot[2004]: main.(*App).withAppPlayer(0xc0001e8000, {0xc6e698, 0x1194a60}, 0xc000121680) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:321 +0x55c fp=0xc000179e10 sp=0xc000179d08 pc=0x9a9a5c Dec 23 18:06:28 v go-librespot[2004]: main.(*App).withCredentials(...) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:193 Dec 23 18:06:28 v go-librespot[2004]: main.(*App).SpotifyToken(0xc0001e8000, {0xc6e698, 0x1194a60}, {0xc00012aba0?, 0xb64373?}, {0xc0001dc000?, 0x2697?}) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:185 +0xcd fp=0xc000179e68 sp=0xc000179e10 pc=0x9a8f2d Dec 23 18:06:28 v go-librespot[2004]: main.main() Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:557 +0x698 fp=0xc000179f50 sp=0xc000179e68 pc=0x9aba78 Dec 23 18:06:28 v go-librespot[2004]: runtime.main() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:271 +0x29d fp=0xc000179fe0 sp=0xc000179f50 pc=0x4430bd Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000179fe8 sp=0xc000179fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: goroutine 2 gp=0xc000006fc0 m=nil [force gc (idle), 2 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x2f57112289c?, 0x0?, 0x0?, 0x0?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005cfa8 sp=0xc00005cf88 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.goparkunlock(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:408 Dec 23 18:06:28 v go-librespot[2004]: runtime.forcegchelper() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:326 +0xb3 fp=0xc00005cfe0 sp=0xc00005cfa8 pc=0x443373 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005cfe8 sp=0xc00005cfe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.init.6 in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:314 +0x1a Dec 23 18:06:28 v go-librespot[2004]: goroutine 3 gp=0xc000007180 m=nil [GC sweep wait]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x1133101?, 0x0?, 0x0?, 0x0?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005d780 sp=0xc00005d760 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.goparkunlock(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:408 Dec 23 18:06:28 v go-librespot[2004]: runtime.bgsweep(0xc00007e000) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgcsweep.go:318 +0xdf fp=0xc00005d7c8 sp=0xc00005d780 pc=0x42ea9f Dec 23 18:06:28 v go-librespot[2004]: runtime.gcenable.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:203 +0x25 fp=0xc00005d7e0 sp=0xc00005d7c8 pc=0x4233a5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005d7e8 sp=0xc00005d7e0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.gcenable in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:203 +0x66 Dec 23 18:06:28 v go-librespot[2004]: goroutine 4 gp=0xc000007340 m=nil [GC scavenge wait]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x12a782aa?, 0x1296c8d9?, 0x0?, 0x0?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005df78 sp=0xc00005df58 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.goparkunlock(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:408 Dec 23 18:06:28 v go-librespot[2004]: runtime.(*scavengerState).park(0x1133de0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005dfa8 sp=0xc00005df78 pc=0x42c449 Dec 23 18:06:28 v go-librespot[2004]: runtime.bgscavenge(0xc00007e000) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005dfc8 sp=0xc00005dfa8 pc=0x42c9f9 Dec 23 18:06:28 v go-librespot[2004]: runtime.gcenable.gowrap2() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:204 +0x25 fp=0xc00005dfe0 sp=0xc00005dfc8 pc=0x423345 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005dfe8 sp=0xc00005dfe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.gcenable in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:204 +0xa5 Dec 23 18:06:28 v go-librespot[2004]: goroutine 5 gp=0xc000007880 m=nil [finalizer wait]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x0?, 0xbcaa60?, 0x0?, 0xc0?, 0x2000000020?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003f4e20 sp=0xc0003f4e00 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.runfinq() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mfinal.go:194 +0x107 fp=0xc0003f4fe0 sp=0xc0003f4e20 pc=0x4223e7 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003f4fe8 sp=0xc0003f4fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.createfing in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mfinal.go:164 +0x3d Dec 23 18:06:28 v go-librespot[2004]: goroutine 20 gp=0xc000105a40 m=nil [IO wait, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc00004c508?, 0x0?, 0x40?, 0x5a?, 0xc000072ab0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000072a78 sp=0xc000072a58 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0xc000072b10?, 0x40c5c6?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000072ab0 sp=0xc000072a78 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84fe80, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000072ad0 sp=0xc000072ab0 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0x7?, 0x1?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000072af8 sp=0xc000072ad0 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).Accept(0xc000146980) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc000072ba0 sp=0xc000072af8 pc=0x4e634c Dec 23 18:06:28 v go-librespot[2004]: net.(*netFD).accept(0xc000146980) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc000072c58 sp=0xc000072ba0 pc=0x54eec9 Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPListener).accept(0xc000121640) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc000072c80 sp=0xc000072c58 pc=0x5650de Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPListener).Accept(0xc000121640) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc000072cb0 sp=0xc000072c80 pc=0x5642d0 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*onceCloseListener).Accept(0xc000198f30?) Dec 23 18:06:28 v go-librespot[2004]: :1 +0x24 fp=0xc000072cc8 sp=0xc000072cb0 pc=0x7124e4 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*Server).Serve(0xc000276000, {0xc6d560, 0xc000121640}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc000072df8 sp=0xc000072cc8 pc=0x6f043e Dec 23 18:06:28 v go-librespot[2004]: net/http.Serve(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2794 Dec 23 18:06:28 v go-librespot[2004]: main.(*ConcreteApiServer).serve(0xc0001d8510) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/api_server.go:630 +0x90c fp=0xc000072fc8 sp=0xc000072df8 pc=0x99f54c Dec 23 18:06:28 v go-librespot[2004]: main.NewApiServer.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/api_server.go:296 +0x25 fp=0xc000072fe0 sp=0xc000072fc8 pc=0x99e265 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000072fe8 sp=0xc000072fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by main.NewApiServer in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/api_server.go:296 +0x2ff Dec 23 18:06:28 v go-librespot[2004]: goroutine 9 gp=0xc000105c00 m=nil [IO wait]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc00006cd88?, 0x41ed58?, 0x80?, 0xc3?, 0xc000152b90?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006cd30 sp=0xc00006cd10 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006cd68 sp=0xc00006cd30 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84fb98, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00006cd88 sp=0xc00006cd68 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0xc00025e680?, 0x4166db?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006cdb0 sp=0xc00006cd88 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).RawRead(0xc00025e680, 0xc000152b90) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc00006ce10 sp=0xc00006cdb0 pc=0x4e6fca Dec 23 18:06:28 v go-librespot[2004]: net.(*rawConn).Read(0xc000114018, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc00006ce48 sp=0xc00006ce10 pc=0x5600f6 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc0003620a0, 0xc00027e5a0, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc00006cea0 sp=0xc00006ce48 pc=0x8b6324 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc0004e61f0, {0xc000534000, 0x10000, 0x10000}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x4ae fp=0xc00006cf48 sp=0xc00006cea0 pc=0x8b9dae Dec 23 18:06:28 v go-librespot[2004]: github.com/grandcat/zeroconf.(*Server).recv4(0xc000410060, 0xc0004e61e0) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xd0 fp=0xc00006cfc0 sp=0xc00006cf48 pc=0x90e990 Dec 23 18:06:28 v go-librespot[2004]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc00006cfe0 sp=0xc00006cfc0 pc=0x90e625 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006cfe8 sp=0xc00006cfe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Dec 23 18:06:28 v go-librespot[2004]: goroutine 58 gp=0xc000105dc0 m=nil [chan receive, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc00008a9c0?, 0xc0001485a0?, 0x0?, 0x0?, 0xc00005a6e8?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005a698 sp=0xc00005a678 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.chanrecv(0xc000100300, 0xc00005a798, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc00005a710 sp=0xc00005a698 pc=0x40f43f Dec 23 18:06:28 v go-librespot[2004]: runtime.chanrecv2(0x0?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc00005a738 sp=0xc00005a710 pc=0x40f072 Dec 23 18:06:28 v go-librespot[2004]: main.(*App).withAppPlayer.func1() Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:268 +0x72 fp=0xc00005a7e0 sp=0xc00005a738 pc=0x9aa552 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005a7e8 sp=0xc00005a7e0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by main.(*App).withAppPlayer in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:265 +0x419 Dec 23 18:06:28 v go-librespot[2004]: goroutine 8 gp=0xc000315a40 m=nil [GC worker (idle)]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x2f5719c9496?, 0xc000476440?, 0x1a?, 0xa?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000058f50 sp=0xc000058f30 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.gcBgMarkWorker() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc000058fe0 sp=0xc000058f50 pc=0x425485 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000058fe8 sp=0xc000058fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Dec 23 18:06:28 v go-librespot[2004]: goroutine 26 gp=0xc0002a0540 m=nil [GC worker (idle)]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x2f5717ab353?, 0x0?, 0x0?, 0x0?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000059750 sp=0xc000059730 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.gcBgMarkWorker() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc0000597e0 sp=0xc000059750 pc=0x425485 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000597e8 sp=0xc0000597e0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by runtime.gcBgMarkStartWorkers in goroutine 6 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Dec 23 18:06:28 v go-librespot[2004]: goroutine 84 gp=0xc0002a0700 m=nil [select, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc000521f38?, 0x5?, 0xd0?, 0x1d?, 0xc000521ee6?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000521d88 sp=0xc000521d68 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc000521f38, 0xc000521edc, 0x41303d?, 0x0, 0x414c30?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000521ea8 sp=0xc000521d88 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc00033a680) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc000521fc8 sp=0xc000521ea8 pc=0x807236 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.newConn.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc000521fe0 sp=0xc000521fc8 pc=0x806da5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000521fe8 sp=0xc000521fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by nhooyr.io/websocket.newConn in goroutine 79 Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Dec 23 18:06:28 v go-librespot[2004]: goroutine 10 gp=0xc000314000 m=nil [IO wait, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc000521d20?, 0x42b91c?, 0x0?, 0x60?, 0xc00040e000?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000071ce0 sp=0xc000071cc0 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000071d18 sp=0xc000071ce0 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84faa0, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000071d38 sp=0xc000071d18 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0xc00025e700?, 0x416845?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000071d60 sp=0xc000071d38 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).RawRead(0xc00025e700, 0xc00040e000) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000071dc0 sp=0xc000071d60 pc=0x4e6fca Dec 23 18:06:28 v go-librespot[2004]: net.(*rawConn).Read(0xc000114030, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000071df8 sp=0xc000071dc0 pc=0x5600f6 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc0003620c0, 0xc00008a180, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc000071e50 sp=0xc000071df8 pc=0x8b6324 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 23 18:06:28 v go-librespot[2004]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc0004e6240, {0xc000524000, 0x10000, 0x10000}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x38d fp=0xc000071f48 sp=0xc000071e50 pc=0x8bd14d Dec 23 18:06:28 v go-librespot[2004]: github.com/grandcat/zeroconf.(*Server).recv6(0xc000410060, 0xc0004e6230) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xd0 fp=0xc000071fc0 sp=0xc000071f48 pc=0x90eb50 Dec 23 18:06:28 v go-librespot[2004]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000071fe0 sp=0xc000071fc0 pc=0x90e5c5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Dec 23 18:06:28 v go-librespot[2004]: goroutine 59 gp=0xc0002a08c0 m=nil [chan receive, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x40eaa0?, 0xc0000061c0?, 0x4?, 0x0?, 0xc000521708?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000521680 sp=0xc000521660 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.chanrecv(0xc0001002a0, 0xc0005217b8, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc0005216f8 sp=0xc000521680 pc=0x40f43f Dec 23 18:06:28 v go-librespot[2004]: runtime.chanrecv2(0xc63fe0?, 0xc71b01?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc000521720 sp=0xc0005216f8 pc=0x40f072 Dec 23 18:06:28 v go-librespot[2004]: main.(*App).withAppPlayer.func2() Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:284 +0x87 fp=0xc0005217e0 sp=0xc000521720 pc=0x9aa1a7 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0005217e8 sp=0xc0005217e0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by main.(*App).withAppPlayer in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:281 +0x4d9 Dec 23 18:06:28 v go-librespot[2004]: goroutine 57 gp=0xc0002a0a80 m=nil [select, 14 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc000557c20?, 0x8?, 0xc8?, 0x0?, 0xc0005574cc?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000557148 sp=0xc000557128 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc000557c20, 0xc0005574bc, 0x1194a60?, 0x0, 0xe7?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000557268 sp=0xc000557148 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: main.(*AppPlayer).Run(0xc00025e780, {0xc6e698, 0x1194a60}, 0xc000100d20) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/player.go:590 +0x405 fp=0xc000557fb0 sp=0xc000557268 pc=0x9b0d45 Dec 23 18:06:28 v go-librespot[2004]: main.(*App).withAppPlayer.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:258 +0x2c fp=0xc000557fe0 sp=0xc000557fb0 pc=0x9aa62c Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000557fe8 sp=0xc000557fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by main.(*App).withAppPlayer in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/main.go:258 +0x379 Dec 23 18:06:28 v go-librespot[2004]: goroutine 56 gp=0xc000314700 m=nil [select, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc00005ff10?, 0x3?, 0x58?, 0xed?, 0xc00005fec2?) Dec 23 18:06:28 v volumio[1364]: info: Connection to go-librespot Websocket closed Dec 23 18:06:28 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005fd68 sp=0xc00005fd48 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc00005ff10, 0xc00005febc, 0xc0004e7310?, 0x0, 0xc0004d3580?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00005fe88 sp=0xc00005fd68 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc000322000) Dec 23 18:06:28 v go-librespot[2004]: /src/player/player.go:201 +0x205 fp=0xc00005ffc8 sp=0xc00005fe88 pc=0x887765 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/player/player.go:183 +0x25 fp=0xc00005ffe0 sp=0xc00005ffc8 pc=0x887325 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005ffe8 sp=0xc00005ffe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/player/player.go:183 +0x1da Dec 23 18:06:28 v go-librespot[2004]: goroutine 68 gp=0xc000314a80 m=nil [select, 2 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc0003f0f98?, 0x2?, 0x0?, 0x0?, 0xc0003f0f6c?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003f0e18 sp=0xc0003f0df8 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc0003f0f98, 0xc0003f0f68, 0x1133c40?, 0x0, 0x0?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0003f0f38 sp=0xc0003f0e18 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc000276690) Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:355 +0x7d fp=0xc0003f0fc8 sp=0xc0003f0f38 pc=0x81e43d Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:264 +0x25 fp=0xc0003f0fe0 sp=0xc0003f0fc8 pc=0x81dac5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003f0fe8 sp=0xc0003f0fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 57 Dec 23 18:06:28 v go-librespot[2004]: /src/ap/ap.go:264 +0xff Dec 23 18:06:28 v go-librespot[2004]: goroutine 70 gp=0xc000314c40 m=nil [select]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc000197f98?, 0x2?, 0x0?, 0x0?, 0xc000197f44?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000197de8 sp=0xc000197dc8 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc000197f98, 0xc000197f40, 0xc0001ee0e0?, 0x0, 0xc0004da350?, 0x1) Dec 23 18:06:28 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000197f08 sp=0xc000197de8 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc00015c1c0) Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:138 +0x8f fp=0xc000197fc8 sp=0xc000197f08 pc=0x8a2f0f Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:129 +0x25 fp=0xc000197fe0 sp=0xc000197fc8 pc=0x8a2de5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000197fe8 sp=0xc000197fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 57 Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:129 +0xf6 Dec 23 18:06:28 v go-librespot[2004]: goroutine 60 gp=0xc0002a0c40 m=nil [IO wait, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x4166db?, 0xc0001142c0?, 0x0?, 0x0?, 0xc00005aca8?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006fc08 sp=0xc00006fbe8 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0x7fa08d870d78?, 0x40c5c6?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006fc40 sp=0xc00006fc08 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84fc90, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00006fc60 sp=0xc00006fc40 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0x9?, 0x10?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006fc88 sp=0xc00006fc60 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).Accept(0xc00025e300) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc00006fd30 sp=0xc00006fc88 pc=0x4e634c Dec 23 18:06:28 v go-librespot[2004]: net.(*netFD).accept(0xc00025e300) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc00006fde8 sp=0xc00006fd30 pc=0x54eec9 Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPListener).accept(0xc000362000) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc00006fe10 sp=0xc00006fde8 pc=0x5650de Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPListener).Accept(0xc000362000) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc00006fe40 sp=0xc00006fe10 pc=0x5642d0 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*onceCloseListener).Accept(0xc6e660?) Dec 23 18:06:28 v go-librespot[2004]: :1 +0x24 fp=0xc00006fe58 sp=0xc00006fe40 pc=0x7124e4 Dec 23 18:06:28 v systemd[1]: go-librespot-daemon.service: Consumed 1.171s CPU time. Dec 23 18:06:28 v go-librespot[2004]: net/http.(*Server).Serve(0xc0002771d0, {0xc6d560, 0xc000362000}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc00006ff88 sp=0xc00006fe58 pc=0x6f043e Dec 23 18:06:28 v go-librespot[2004]: net/http.Serve(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2794 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 23 18:06:28 v go-librespot[2004]: /src/zeroconf/zeroconf.go:280 +0x85 fp=0xc00006ffe0 sp=0xc00006ff88 pc=0x913d65 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 23 18:06:28 v go-librespot[2004]: /src/zeroconf/zeroconf.go:280 +0x165 Dec 23 18:06:28 v go-librespot[2004]: goroutine 79 gp=0xc0003a21c0 m=nil [IO wait, 53 minutes]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0x7fa08cfb4bf8?, 0x0?, 0x0?, 0x0?, 0xb?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000177298 sp=0xc000177278 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0001772d0 sp=0xc000177298 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84f4d0, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0001772f0 sp=0xc0001772d0 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0xc0003aa480?, 0xc0003c2000?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000177318 sp=0xc0001772f0 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).Read(0xc0003aa480, {0xc0003c2000, 0x1000, 0x1000}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0001773b0 sp=0xc000177318 pc=0x4e229a Dec 23 18:06:28 v go-librespot[2004]: net.(*netFD).Read(0xc0003aa480, {0xc0003c2000?, 0x48d84f500?, 0x200000000?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0001773f8 sp=0xc0001773b0 pc=0x54cee5 Dec 23 18:06:28 v go-librespot[2004]: net.(*conn).Read(0xc000060620, {0xc0003c2000?, 0x454010?, 0x4434ee?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc000177440 sp=0xc0001773f8 pc=0x55c185 Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPConn).Read(0xc000177590?, {0xc0003c2000?, 0xc0001776a0?, 0x2?}) Dec 23 18:06:28 v go-librespot[2004]: :1 +0x25 fp=0xc000177470 sp=0xc000177440 pc=0x56db65 Dec 23 18:06:28 v go-librespot[2004]: io.(*multiReader).Read(0xc0004d9878, {0xc0003c2000, 0x1000, 0x1000}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0001774b8 sp=0xc000177470 pc=0x4dc913 Dec 23 18:06:28 v go-librespot[2004]: bufio.(*Reader).fill(0xc0003b8420) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0001774f0 sp=0xc0001774b8 pc=0x672d63 Dec 23 18:06:28 v go-librespot[2004]: bufio.(*Reader).ReadByte(0xc0003b8420) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc000177510 sp=0xc0001774f0 pc=0x673487 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.readFrameHeader(0xc0003b8420, {0xc00033a6e0, 0x8, 0x8}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0001775a0 sp=0xc000177510 pc=0x80a52b Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc00033a680, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0001776e0 sp=0xc0001775a0 pc=0x80bf79 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).readLoop(0xc00033a680, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc000177840 sp=0xc0001776e0 pc=0x80b8e5 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).reader(0xc00033a680, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc000177940 sp=0xc000177840 pc=0x80cee5 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).Read(0xc0001d8588?, {0xc6e660?, 0x1194a60?}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc000177970 sp=0xc000177940 pc=0x80b25e Dec 23 18:06:28 v go-librespot[2004]: main.(*ConcreteApiServer).serve.func17({0xc6d770, 0xc00015c540}, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /src/cmd/daemon/api_server.go:600 +0x3ae fp=0xc000177a68 sp=0xc000177970 pc=0x99fd4e Dec 23 18:06:28 v go-librespot[2004]: net/http.HandlerFunc.ServeHTTP(0xc0000a1110?, {0xc6d770?, 0xc00015c540?}, 0xc0003aa480?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000177a90 sp=0xc000177a68 pc=0x6ec9e9 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*ServeMux).ServeHTTP(0xc0000a12b0?, {0xc6d770, 0xc00015c540}, 0xc0002aea20) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc000177ae0 sp=0xc000177a90 pc=0x6ee8cd Dec 23 18:06:28 v go-librespot[2004]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xc6d770, 0xc00015c540}, 0xc0002aea20) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc000177b38 sp=0xc000177ae0 pc=0x99f7a4 Dec 23 18:06:28 v go-librespot[2004]: net/http.HandlerFunc.ServeHTTP(0x46ee19?, {0xc6d770?, 0xc00015c540?}, 0xc000196b68?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000177b60 sp=0xc000177b38 pc=0x6ec9e9 Dec 23 18:06:28 v go-librespot[2004]: net/http.serverHandler.ServeHTTP({0xc0003c0450?}, {0xc6d770?, 0xc00015c540?}, 0x6?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc000177b90 sp=0xc000177b60 pc=0x6f000e Dec 23 18:06:28 v go-librespot[2004]: net/http.(*conn).serve(0xc0003a6900, {0xc6e6d0, 0xc0000379e0}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc000177fb8 sp=0xc000177b90 pc=0x6eb3c8 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*Server).Serve.gowrap3() Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc000177fe0 sp=0xc000177fb8 pc=0x6f0828 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000177fe8 sp=0xc000177fe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by net/http.(*Server).Serve in goroutine 20 Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Dec 23 18:06:28 v go-librespot[2004]: goroutine 110 gp=0xc0002a1340 m=nil [IO wait]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xa0e300?, 0xc000040db0?, 0x18?, 0xb7?, 0xb?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00017b410 sp=0xc00017b3f0 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00017b448 sp=0xc00017b410 pc=0x43c257 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.runtime_pollWait(0x7fa08d84f6c0, 0x72) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00017b468 sp=0xc00017b448 pc=0x470225 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).wait(0xc0003aa100?, 0xc000359000?, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00017b490 sp=0xc00017b468 pc=0x4e0fa7 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*pollDesc).waitRead(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 18:06:28 v go-librespot[2004]: internal/poll.(*FD).Read(0xc0003aa100, {0xc000359000, 0x1000, 0x1000}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc00017b528 sp=0xc00017b490 pc=0x4e229a Dec 23 18:06:28 v go-librespot[2004]: net.(*netFD).Read(0xc0003aa100, {0xc000359000?, 0x7fa0d63301a8?, 0xc0000125d0?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc00017b570 sp=0xc00017b528 pc=0x54cee5 Dec 23 18:06:28 v go-librespot[2004]: net.(*conn).Read(0xc000060058, {0xc000359000?, 0xc00017b650?, 0x4166db?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc00017b5b8 sp=0xc00017b570 pc=0x55c185 Dec 23 18:06:28 v go-librespot[2004]: net.(*TCPConn).Read(0xb40560?, {0xc000359000?, 0xc00017b650?, 0x4f72da?}) Dec 23 18:06:28 v go-librespot[2004]: :1 +0x25 fp=0xc00017b5e8 sp=0xc00017b5b8 pc=0x56db65 Dec 23 18:06:28 v go-librespot[2004]: crypto/tls.(*atLeastReader).Read(0xc0000125d0, {0xc000359000?, 0x0?, 0xc0000125d0?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc00017b630 sp=0xc00017b5e8 pc=0x63285b Dec 23 18:06:28 v go-librespot[2004]: bytes.(*Buffer).ReadFrom(0xc0001ce2b0, {0xc67d60, 0xc0000125d0}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc00017b688 sp=0xc00017b630 pc=0x50e978 Dec 23 18:06:28 v go-librespot[2004]: crypto/tls.(*Conn).readFromUntil(0xc0001ce008, {0xc666c0, 0xc000060058}, 0xc00017b698?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc00017b6c0 sp=0xc00017b688 pc=0x632a3e Dec 23 18:06:28 v go-librespot[2004]: crypto/tls.(*Conn).readRecordOrCCS(0xc0001ce008, 0x0) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc00017b940 sp=0xc00017b6c0 pc=0x62fb4f Dec 23 18:06:28 v go-librespot[2004]: crypto/tls.(*Conn).readRecord(...) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 23 18:06:28 v go-librespot[2004]: crypto/tls.(*Conn).Read(0xc0001ce008, {0xc0002b5000, 0x1000, 0x2?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc00017b9b0 sp=0xc00017b940 pc=0x6363f6 Dec 23 18:06:28 v go-librespot[2004]: net/http.(*readWriteCloserBody).Read(0x0?, {0xc0002b5000?, 0x0?, 0xffffffffffffffff?}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/net/http/transport.go:2429 +0x88 fp=0xc00017b9e0 sp=0xc00017b9b0 pc=0x706da8 Dec 23 18:06:28 v go-librespot[2004]: bufio.(*Reader).fill(0xc0002b1980) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc00017ba18 sp=0xc00017b9e0 pc=0x672d63 Dec 23 18:06:28 v go-librespot[2004]: bufio.(*Reader).ReadByte(0xc0002b1980) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc00017ba38 sp=0xc00017ba18 pc=0x673487 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.readFrameHeader(0xc0002b1980, {0xc00033a3a0, 0x8, 0x8}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc00017bac8 sp=0xc00017ba38 pc=0x80a52b Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc00033a340, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc00017bc08 sp=0xc00017bac8 pc=0x80bf79 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).readLoop(0xc00033a340, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc00017bd68 sp=0xc00017bc08 pc=0x80b8e5 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).reader(0xc00033a340, {0xc6e660, 0x1194a60}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc00017be68 sp=0xc00017bd68 pc=0x80cee5 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).Read(0xc000472000?, {0xc6e660?, 0x1194a60?}) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc00017be98 sp=0xc00017be68 pc=0x80b25e Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc00015c1c0) Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:188 +0x67 fp=0xc00017bfc8 sp=0xc00017be98 pc=0x8a32c7 Dec 23 18:06:28 v go-librespot[2004]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:296 +0x25 fp=0xc00017bfe0 sp=0xc00017bfc8 pc=0x8a3e05 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00017bfe8 sp=0xc00017bfe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 69 Dec 23 18:06:28 v go-librespot[2004]: /src/dealer/dealer.go:296 +0xeb Dec 23 18:06:28 v go-librespot[2004]: goroutine 109 gp=0xc0002a1500 m=nil [select]: Dec 23 18:06:28 v go-librespot[2004]: runtime.gopark(0xc00005bf38?, 0x5?, 0x30?, 0xbe?, 0xc00005bee6?) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005bd88 sp=0xc00005bd68 pc=0x4434ee Dec 23 18:06:28 v go-librespot[2004]: runtime.selectgo(0xc00005bf38, 0xc00005bedc, 0xc0001de6c0?, 0x0, 0xc000448cc0?, 0x1) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00005bea8 sp=0xc00005bd88 pc=0x454865 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc00033a340) Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc00005bfc8 sp=0xc00005bea8 pc=0x807236 Dec 23 18:06:28 v go-librespot[2004]: nhooyr.io/websocket.newConn.gowrap1() Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc00005bfe0 sp=0xc00005bfc8 pc=0x806da5 Dec 23 18:06:28 v go-librespot[2004]: runtime.goexit({}) Dec 23 18:06:28 v go-librespot[2004]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005bfe8 sp=0xc00005bfe0 pc=0x4754a1 Dec 23 18:06:28 v go-librespot[2004]: created by nhooyr.io/websocket.newConn in goroutine 69 Dec 23 18:06:28 v go-librespot[2004]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Dec 23 18:06:28 v go-librespot[2003]: Aborted Dec 23 18:06:31 v volumio[1364]: info: Initializing connection to go-librespot Websocket Dec 23 18:06:31 v volumio[1364]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 18:06:31 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 23 18:06:31 v systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:31 v systemd[1]: go-librespot-daemon.service: Consumed 1.171s CPU time. Dec 23 18:06:31 v systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:31 v go-librespot[10826]: go-librespot daemon starting... Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=info msg="running go-librespot 0.4.0" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="app state loaded" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=info msg="zeroconf server listening on port 34407" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="obtained new client token: AACmHghUeQPqJGXtrISNQVYTjhDtgJPGHPnKPXS92iyxoKooCCVsKR8da03g1qgcQucSYflC96foeTXGv2svWP6ePLhGKKbE2keUKxLjt8dM1YLwrVivpeZVVTWLoguUrRWv6UlDGlBs6HiRaQe3jc0MMjqGNzEOLcQtQtTumGrgSCB8+sgjIVle0n6Q4ccmtqCz5SBgy4HSjsHVDWmrnaeGPaeygmfR8LFj318+K1VIzucNSP+VCfrT" Dec 23 18:06:31 v go-librespot[10827]: time="2025-12-23T18:06:31+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 23 18:06:32 v go-librespot[10827]: time="2025-12-23T18:06:32+09:00" level=debug msg="completed keyexchange" Dec 23 18:06:32 v go-librespot[10827]: time="2025-12-23T18:06:32+09:00" level=debug msg="completed challenge" Dec 23 18:06:32 v go-librespot[10827]: time="2025-12-23T18:06:32+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 23 18:06:32 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 18:06:32 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 18:06:34 v volumio[1364]: info: Initializing connection to go-librespot Websocket Dec 23 18:06:34 v volumio[1364]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 18:06:35 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 23 18:06:35 v systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:35 v systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:35 v go-librespot[10837]: go-librespot daemon starting... Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=info msg="running go-librespot 0.4.0" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="app state loaded" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=info msg="zeroconf server listening on port 37569" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="obtained new client token: AABrx7u2AlmjPaF5siWEsk6QRTIamcejtCbIoqMJrnVDpqcqguLN/fdGThv1DHuBIwe0ACh92DJ09gTN0h/Nf0lYgvzF8PfAmNixTKUV2stcMjTuwF3N4AnjjTjJpC+uSs7fWO3QZcxE5uWLKD/m5OnggYiT54lZQ55Z5MCK+kRV63dwzGQZsbneOCF2a4RLUIQM8JTBCBga2FeSmhW6I2RLkpkjdc2a7MFQGTEWvc9fwm0fBD5YZkbK" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="completed keyexchange" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=debug msg="completed challenge" Dec 23 18:06:35 v go-librespot[10838]: time="2025-12-23T18:06:35+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 23 18:06:35 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 18:06:35 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 18:06:37 v volumio[1364]: info: Initializing connection to go-librespot Websocket Dec 23 18:06:37 v volumio[1364]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 18:06:38 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 23 18:06:38 v systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:38 v systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:38 v go-librespot[10861]: go-librespot daemon starting... Dec 23 18:06:38 v go-librespot[10862]: time="2025-12-23T18:06:38+09:00" level=info msg="running go-librespot 0.4.0" Dec 23 18:06:38 v go-librespot[10862]: time="2025-12-23T18:06:38+09:00" level=debug msg="app state loaded" Dec 23 18:06:38 v go-librespot[10862]: time="2025-12-23T18:06:38+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=info msg="zeroconf server listening on port 43491" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="obtained new client token: AABdx1gEra8haS9R8manLF/vNnK70URAxz7hfaOqBnPBS/egzm1PtrIVChYcea22UeZs2hmChk39/OC5dTkqJwpHuHlqOkNbdbK6NCDoQ/n6QtWnFZKOUSLpANMbqwNtnoX7CL1jqankIQ0yP74gZxB1KdQk4+67Kj8t9KCzum5S8vs58P4/I86UkZZPNGb0Gt8TeDrG9kVGkNNR4m5tXdd5IcdSJ5o/+JJcre4Ndum6sxY9hb5ypWel" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="completed keyexchange" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=debug msg="completed challenge" Dec 23 18:06:39 v go-librespot[10862]: time="2025-12-23T18:06:39+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 23 18:06:39 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 18:06:39 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 18:06:40 v volumio[1364]: info: Initializing connection to go-librespot Websocket Dec 23 18:06:40 v volumio[1364]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 18:06:42 v systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 23 18:06:42 v systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:42 v systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 23 18:06:42 v go-librespot[10875]: go-librespot daemon starting... Dec 23 18:06:42 v go-librespot[10876]: time="2025-12-23T18:06:42+09:00" level=info msg="running go-librespot 0.4.0" Dec 23 18:06:42 v go-librespot[10876]: time="2025-12-23T18:06:42+09:00" level=debug msg="app state loaded" Dec 23 18:06:42 v go-librespot[10876]: time="2025-12-23T18:06:42+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 18:06:43 v volumio[1364]: info: Initializing connection to go-librespot Websocket Dec 23 18:06:43 v go-librespot[10876]: time="2025-12-23T18:06:43+09:00" level=debug msg="new websocket client" Dec 23 18:06:43 v volumio[1364]: info: Connection to go-librespot Websocket established Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=info msg="zeroconf server listening on port 44351" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="obtained new client token: AAA+m0Zh+Z8EebmM++gpqXOsfC4vr5RwHdXj424/pVQeOAHcGqJHciJkRXrZlIvu3zEaxOgIwiNlUDSPWr3mY/QLLMFz4jTKW8ThBbFHl0+hGhfuB2VPBRzTd9EsE5fHRwO3worJ9c/xNXVwNk0mhtLe4sc6S/q6uB4iMh+wP9TwaN86KhOa+xNHmuI99cejUFD6/1lLHrfxiIi+2j7OjKU8ohaTw80BRR1c5XeNwqvhuU8froQ6r6ZC" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="completed keyexchange" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=debug msg="completed challenge" Dec 23 18:06:45 v go-librespot[10876]: time="2025-12-23T18:06:45+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Dec 23 18:06:45 v systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 18:06:45 v systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 18:06:45 v volumio[1364]: info: Connection to go-librespot Websocket closed Dec 23 18:06:46 v volumio[1364]: info: Getting Spotify volume Dec 23 18:06:46 v volumio[1364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 18:06:46 v volumio[1364]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 18:06:46 v volumio[1364]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 23 18:06:46 v volumio[1364]: errno: -111, Dec 23 18:06:46 v volumio[1364]: code: 'ECONNREFUSED', Dec 23 18:06:46 v volumio[1364]: syscall: 'connect', Dec 23 18:06:46 v volumio[1364]: address: '127.0.0.1', Dec 23 18:06:46 v volumio[1364]: port: 9879, Dec 23 18:06:46 v volumio[1364]: response: undefined Dec 23 18:06:46 v volumio[1364]: } Dec 23 18:06:46 v volumio[1364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 18:06:46 v sudo[10901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 18:05' Dec 23 18:06:46 v sudo[10901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"