-- Logs begin at Thu 2024-02-15 04:38:24 GMT, end at Sat 2024-04-27 20:57:56 BST. -- Apr 27 20:56:41 richdacvolumio go-librespot[1116]: time="2024-04-27T20:56:41+01:00" level=error msg="did not receive last pong from dealer, 60s passed" Apr 27 20:56:46 richdacvolumio go-librespot[1116]: time="2024-04-27T20:56:46+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Apr 27 20:57:10 richdacvolumio go-librespot[1116]: time="2024-04-27T20:57:10+01:00" level=error msg="did not receive last pong from dealer, 90s passed" Apr 27 20:57:10 richdacvolumio go-librespot[1116]: panic: runtime error: invalid memory address or nil pointer dereference Apr 27 20:57:10 richdacvolumio go-librespot[1116]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x0 pc=0x43aea8] Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 66 [running]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: panic({0x5a3810, 0x9eea20}) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:987 +0x414 fp=0x284ae50 sp=0x284adf4 pc=0x4ee28 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.panicmem() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:260 +0x4c fp=0x284ae5c sp=0x284ae50 pc=0x4d5cc Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.sigpanic() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/signal_unix.go:841 +0x26c fp=0x284ae78 sp=0x284ae5c pc=0x68750 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).writeClose(0x0, 0x3f4, {0x0, 0x0}) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/close_notjs.go:54 +0x14 fp=0x284aee4 sp=0x284ae7c pc=0x43aea8 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).closeHandshake(0x0, 0x3f4, {0x0, 0x0}) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/close_notjs.go:37 +0x80 fp=0x284af24 sp=0x284aee4 pc=0x43accc Apr 27 20:57:10 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).Close(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/close_notjs.go:31 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).pingTicker(0x2954b40) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:114 +0x26c fp=0x284afe4 sp=0x284af24 pc=0x4785d4 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: go-librespot/dealer.NewDealer.func1() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x28 fp=0x284afec sp=0x284afe4 pc=0x477d90 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x284afec sp=0x284afec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by go-librespot/dealer.NewDealer Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x174 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 1 [select, 3 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x29077e8 sp=0x29077d4 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.selectgo(0x2907a6c, 0x29079b0, 0x0, 0x0, 0x6, 0x1) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x2907890 sp=0x29077e8 pc=0x64d80 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.(*AppPlayer).Run(0x28b23f0, 0x29a2140) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:361 +0x1e4 fp=0x2907eac sp=0x2907890 pc=0x53c0d0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.(*App).withReusableCredentials(0x28a1ee0, {0x5abe70, 0x289c530}) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:218 +0x660 fp=0x2907f3c sp=0x2907eac pc=0x5382f0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.(*App).SpotifyToken(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:159 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.main() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:331 +0x524 fp=0x2907fc0 sp=0x2907f3c pc=0x538cf8 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.main() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:250 +0x268 fp=0x2907fec sp=0x2907fc0 pc=0x52288 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2907fec sp=0x2907fec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 2 [force gc (idle), 3 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f54, 0xa35150, 0x11, 0x14, 0x1) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283cfd8 sp=0x283cfc4 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goparkunlock(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.forcegchelper() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:305 +0xd4 fp=0x283cfec sp=0x283cfd8 pc=0x525d0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283cfec sp=0x283cfec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.init.5 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:293 +0x1c Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 3 [GC sweep wait]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f54, 0xa35750, 0xc, 0x14, 0x1) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283d7c8 sp=0x283d7b4 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goparkunlock(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.bgsweep(0x2862000) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcsweep.go:319 +0x104 fp=0x283d7e4 sp=0x283d7c8 pc=0x3c528 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gcenable.func1() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:178 +0x28 fp=0x283d7ec sp=0x283d7e4 pc=0x2cff4 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283d7ec sp=0x283d7ec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.gcenable Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:178 +0x74 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 4 [GC scavenge wait]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f54, 0xa35a30, 0xd, 0x14, 0x2) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283dfb4 sp=0x283dfa0 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goparkunlock(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:387 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.(*scavengerState).park(0xa35a30) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcscavenge.go:400 +0x64 fp=0x283dfc8 sp=0x283dfb4 pc=0x39e1c Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.bgscavenge(0x2862000) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcscavenge.go:633 +0x64 fp=0x283dfe4 sp=0x283dfc8 pc=0x3a560 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gcenable.func2() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:179 +0x28 fp=0x283dfec sp=0x283dfe4 pc=0x2cfa0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283dfec sp=0x283dfec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.gcenable Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:179 +0xbc Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 5 [finalizer wait, 9 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642e94, 0xa46af4, 0x10, 0x14, 0x1) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283c78c sp=0x283c778 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.runfinq() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mfinal.go:193 +0xfc fp=0x283c7ec sp=0x283c78c pc=0x2bed0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283c7ec sp=0x283c7ec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.createfing Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mfinal.go:163 +0x5c Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 18 [IO wait, 20 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f3c, 0xadc14f40, 0x2, 0x1b, 0x5) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x28385dc sp=0x28385c8 pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.netpollblock(0xadc14f34, 0x72, 0x0) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x28385f4 sp=0x28385dc pc=0x49f2c Apr 27 20:57:10 richdacvolumio go-librespot[1116]: internal/poll.runtime_pollWait(0xadc14f34, 0x72) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x2838608 sp=0x28385f4 pc=0x800b4 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).wait(0x28a6d84, 0x72, 0x0) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x283861c sp=0x2838608 pc=0xf7db0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).waitRead(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: internal/poll.(*FD).Accept(0x28a6d70) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:614 +0x2d0 fp=0x283866c sp=0x283861c pc=0xfc8d0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net.(*netFD).accept(0x28a6d70) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_unix.go:172 +0x20 fp=0x28386cc sp=0x283866c pc=0x281ba8 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net.(*TCPListener).accept(0x289c520) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock_posix.go:148 +0x20 fp=0x28386e4 sp=0x28386cc pc=0x29a540 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net.(*TCPListener).Accept(0x289c520) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/tcpsock.go:297 +0x30 fp=0x2838700 sp=0x28386e4 pc=0x299504 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net/http.(*onceCloseListener).Accept(0x2800180) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: :1 +0x34 fp=0x2838718 sp=0x2838700 pc=0x427558 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net/http.(*Server).Serve(0x290e000, {0x6ccfb0, 0x289c520}) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3059 +0x344 fp=0x28387b4 sp=0x2838718 pc=0x403550 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: net/http.Serve(...) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2581 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.(*ApiServer).serve(0x28a3840) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:438 +0x62c fp=0x28387e4 sp=0x28387b4 pc=0x532118 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: main.NewApiServer.func1() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:237 +0x28 fp=0x28387ec sp=0x28387e4 pc=0x5317d0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x28387ec sp=0x28387ec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by main.NewApiServer Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:237 +0x1dc Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 7 [GC worker (idle), 20 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642ea0, 0x2ae1f80, 0x1a, 0x14, 0x0) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2838f90 sp=0x2838f7c pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gcBgMarkWorker() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x2838fec sp=0x2838f90 pc=0x2fbb8 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2838fec sp=0x2838fec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.gcBgMarkStartWorkers Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: goroutine 8 [GC worker (idle), 20 minutes]: Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gopark(0x642ea0, 0x2894738, 0x1a, 0x14, 0x0) Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283e790 sp=0x283e77c pc=0x527b0 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.gcBgMarkWorker() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x283e7ec sp=0x283e790 pc=0x2fbb8 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:10 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283e7ec sp=0x283e7ec pc=0x84730 Apr 27 20:57:10 richdacvolumio go-librespot[1116]: created by runtime.gcBgMarkStartWorkers Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 24 [GC worker (idle), 3 minutes]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642ea0, 0x2c3e000, 0x1a, 0x14, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2839790 sp=0x283977c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gcBgMarkWorker() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x28397ec sp=0x2839790 pc=0x2fbb8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x28397ec sp=0x28397ec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by runtime.gcBgMarkStartWorkers Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 9 [GC worker (idle), 20 minutes]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642ea0, 0x2c3e018, 0x1a, 0x14, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x283ef90 sp=0x283ef7c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gcBgMarkWorker() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1275 +0x100 fp=0x283efec sp=0x283ef90 pc=0x2fbb8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x283efec sp=0x283efec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by runtime.gcBgMarkStartWorkers Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 56 [select]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x29ddea0 sp=0x29dde8c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x29ddfac, 0x29ddf94, 0x0, 0x0, 0x2, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x29ddf48 sp=0x29ddea0 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/ap.(*Accesspoint).pongAckTicker(0x29542d0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:301 +0x8c fp=0x29ddfe4 sp=0x29ddf48 pc=0x455290 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/ap.NewAccesspoint.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:66 +0x28 fp=0x29ddfec sp=0x29ddfe4 pc=0x453674 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x29ddfec sp=0x29ddfec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by go-librespot/ap.NewAccesspoint Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:66 +0x190 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 115 [select]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x296b638 sp=0x296b624 pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x296b7e8, 0x296b730, 0x0, 0x0, 0x4, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x296b6e0 sp=0x296b638 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).getConn(0x9f3a78, 0x2bc2380, {{}, 0x0, {0x2934380, 0x5}, {0x28984c0, 0x1b}, 0x1}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1382 +0x4a8 fp=0x296b80c sp=0x296b6e0 pc=0x4135b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).roundTrip(0x9f3a78, 0x2cd0080) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:590 +0x7bc fp=0x296b928 sp=0x296b80c pc=0x40f740 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).RoundTrip(0x9f3a78, 0x2cd0080) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/roundtrip.go:17 +0x24 fp=0x296b940 sp=0x296b928 pc=0x3f70dc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.send(0x2cd0080, {0x6cb560, 0x9f3a78}, {0x0, 0x0, 0x0}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:252 +0x52c fp=0x296ba78 sp=0x296b940 pc=0x3b710c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Client).send(0x2bc22e0, 0x2cd0080, {0x0, 0x0, 0x0}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:176 +0x94 fp=0x296babc sp=0x296ba78 pc=0x3b6a10 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Client).do(0x2bc22e0, 0x2cd0080) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:716 +0x948 fp=0x296bbd4 sp=0x296babc pc=0x3b8d90 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Client).Do(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/client.go:582 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.handshakeRequest({0x6cd2cc, 0x2bc22c0}, {0x29341c0, 0x1a3}, 0x296be20, 0x2bfe1fa, {0x2c3e0d8, 0x18}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/dial.go:173 +0x738 fp=0x296bca0 sp=0x296bbd4 pc=0x43ee90 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.dial({0x6cd2ac, 0x282a058}, {0x29341c0, 0x1a3}, 0x296be20, {0x0, 0x0}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/dial.go:101 +0x3dc fp=0x296bd84 sp=0x296bca0 pc=0x43de6c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.Dial(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/dial.go:63 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).connect(0x2954b40) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:69 +0x284 fp=0x296be3c sp=0x296bd84 pc=0x478040 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).reconnect(0x2954b40) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:236 +0x1c fp=0x296be5c sp=0x296be3c pc=0x4791a8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).reconnect-fm() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: :1 +0x28 fp=0x296be6c sp=0x296be5c pc=0x47a8b8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:18 +0x1c fp=0x296be78 sp=0x296be6c pc=0x450754 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x296bf1c, {0x6cc4c8, 0x2c8c060}, 0x0, {0x0, 0x0}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:88 +0xe8 fp=0x296bef4 sp=0x296be78 pc=0x450af4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x296bf78, {0x6cc4c8, 0x2c8c060}, 0x0, {0x0, 0x0}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x6c fp=0x296bf24 sp=0x296bef4 pc=0x45070c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: github.com/cenkalti/backoff/v4.RetryNotify(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: github.com/cenkalti/backoff/v4.Retry(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).recvLoop(0x2954b40) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:191 +0x564 fp=0x296bfe4 sp=0x296bf24 pc=0x478d58 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/dealer.(*Dealer).reconnect.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:241 +0x28 fp=0x296bfec sp=0x296bfe4 pc=0x4792b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x296bfec sp=0x296bfec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by go-librespot/dealer.(*Dealer).reconnect Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:241 +0x78 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 94 [syscall]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.cgocall(0x53e76c, 0x2d13ec4) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/cgocall.go:157 +0x5c fp=0x2d13eac sp=0x2d13e94 pc=0x18350 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C2func_getaddrinfo(0x2c3e168, 0x0, 0x2bc23e0, 0x2b4e150) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: _cgo_gotypes.go:95 +0x44 fp=0x2d13ec0 sp=0x2d13eac pc=0x2a1578 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C_getaddrinfo.func1(0x2c3e168, 0x0, 0x2bc23e0, 0x2b4e150) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix_cgo.go:74 +0x7c fp=0x2d13ee0 sp=0x2d13ec0 pc=0x2a18bc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C_getaddrinfo(0x2c3e168, 0x0, 0x2bc23e0, 0x2b4e150) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix_cgo.go:74 +0x34 fp=0x2d13f00 sp=0x2d13ee0 pc=0x2a1818 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoLookupIPCNAME({0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:153 +0x164 fp=0x2d13f88 sp=0x2d13f00 pc=0x272ba4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoIPLookup(0x29e84c0, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:210 +0x34 fp=0x2d13fd4 sp=0x2d13f88 pc=0x2731cc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoLookupIP.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:220 +0x48 fp=0x2d13fec sp=0x2d13fd4 pc=0x2735dc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2d13fec sp=0x2d13fec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by net.cgoLookupIP Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:220 +0xfc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 67 [select, 20 minutes]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2d0fe94 sp=0x2d0fe80 pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x2d0ffb0, 0x2d0ff84, 0x0, 0x0, 0x2, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x2d0ff3c sp=0x2d0fe94 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/player.(*Player).manageLoop(0x2864840) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/player/player.go:102 +0xd4 fp=0x2d0ffe4 sp=0x2d0ff3c pc=0x46922c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/player.NewPlayer.func2() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/player/player.go:86 +0x28 fp=0x2d0ffec sp=0x2d0ffe4 pc=0x469094 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2d0ffec sp=0x2d0ffec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by go-librespot/player.NewPlayer Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/player/player.go:86 +0x1bc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 26 [IO wait, 20 minutes]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f3c, 0xadc14afc, 0x2, 0x1b, 0x5) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2cf5880 sp=0x2cf586c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.netpollblock(0xadc14af0, 0x72, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x2cf5898 sp=0x2cf5880 pc=0x49f2c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.runtime_pollWait(0xadc14af0, 0x72) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x2cf58ac sp=0x2cf5898 pc=0x800b4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).wait(0x29fc0b4, 0x72, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cf58c0 sp=0x2cf58ac pc=0xf7db0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).waitRead(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*FD).Read(0x29fc0a0, {0x2a9d000, 0x1000, 0x1000}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:167 +0x240 fp=0x2cf5910 sp=0x2cf58c0 pc=0xf8fbc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*netFD).Read(0x29fc0a0, {0x2a9d000, 0x1000, 0x1000}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_posix.go:55 +0x38 fp=0x2cf593c sp=0x2cf5910 pc=0x27faec Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*conn).Read(0x2ba2088, {0x2a9d000, 0x1000, 0x1000}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/net.go:183 +0x48 fp=0x2cf596c sp=0x2cf593c pc=0x290a70 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*TCPConn).Read(0x2ba2088, {0x2a9d000, 0x1000, 0x1000}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: :1 +0x44 fp=0x2cf598c sp=0x2cf596c pc=0x2a514c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: io.(*multiReader).Read(0x280e260, {0x2a9d000, 0x1000, 0x1000}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/io/multi.go:26 +0xac fp=0x2cf59c0 sp=0x2cf598c pc=0xf2de0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: bufio.(*Reader).fill(0x29fa630) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/bufio/bufio.go:106 +0x10c fp=0x2cf59e4 sp=0x2cf59c0 pc=0x144f14 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: bufio.(*Reader).ReadByte(0x29fa630) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/bufio/bufio.go:265 +0x28 fp=0x2cf59f0 sp=0x2cf59e4 pc=0x1457c4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.readFrameHeader(0x29fa630, {0x2af0030, 0x8, 0x8}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2cf5a30 sp=0x2cf59f0 pc=0x440024 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2af0000, {0x6cd2ac, 0x282a058}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2cf5af0 sp=0x2cf5a30 pc=0x442b84 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).readLoop(0x2af0000, {0x6cd2ac, 0x282a058}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2cf5bb4 sp=0x2cf5af0 pc=0x44265c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).reader(0x2af0000, {0x6cd2ac, 0x282a058}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x10c fp=0x2cf5c40 sp=0x2cf5bb4 pc=0x44389c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).Reader(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).Read(0x2af0000, {0x6cd2ac, 0x282a058}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x2c fp=0x2cf5c68 sp=0x2cf5c40 pc=0x441f28 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: main.(*ApiServer).serve.func13({0x6cd0e4, 0x290e140}, 0x2aec000) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:418 +0x24c fp=0x2cf5cc4 sp=0x2cf5c68 pc=0x5325d0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.HandlerFunc.ServeHTTP(0x2810238, {0x6cd0e4, 0x290e140}, 0x2aec000) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2122 +0x34 fp=0x2cf5cd4 sp=0x2cf5cc4 pc=0x40026c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*ServeMux).ServeHTTP(0x28260c0, {0x6cd0e4, 0x290e140}, 0x2aec000) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2500 +0x164 fp=0x2cf5d00 sp=0x2cf5cd4 pc=0x401ab4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.serverHandler.ServeHTTP({0x290e000}, {0x6cd0e4, 0x290e140}, 0x2aec000) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:2936 +0x304 fp=0x2cf5d58 sp=0x2cf5d00 pc=0x402fc0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*conn).serve(0x29440c0, {0x6cd2ec, 0x2900018}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:1995 +0x654 fp=0x2cf5fdc sp=0x2cf5d58 pc=0x3feb34 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Server).Serve.func3() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x38 fp=0x2cf5fec sp=0x2cf5fdc pc=0x4039f4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2cf5fec sp=0x2cf5fec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by net/http.(*Server).Serve Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/server.go:3089 +0x4e0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 28 [select, 20 minutes]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2d0cea0 sp=0x2d0ce8c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x2d0cfbc, 0x2d0cf74, 0x0, 0x0, 0x5, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x2d0cf48 sp=0x2d0cea0 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2af0000) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x110 fp=0x2d0cfe4 sp=0x2d0cf48 pc=0x43d0c8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: nhooyr.io/websocket.newConn.func2() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2d0cfec sp=0x2d0cfe4 pc=0x43ccbc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2d0cfec sp=0x2d0cfec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by nhooyr.io/websocket.newConn Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x5b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 92 [select]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x284b8f0 sp=0x284b8dc pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x284ba4c, 0x284b9f0, 0x0, 0x0, 0x2, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x284b998 sp=0x284b8f0 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).lookupIPAddr(0xa35480, {0x6cd2cc, 0x2bc22c0}, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/lookup.go:334 +0x49c fp=0x284ba98 sp=0x284b998 pc=0x28ef7c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).internetAddrList(0xa35480, {0x6cd2cc, 0x2bc22c0}, {0x618ad2, 0x3}, {0x28984c0, 0x1b}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/ipsock.go:288 +0x6f8 fp=0x284bb2c sp=0x284ba98 pc=0x28cc68 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).resolveAddrList(0xa35480, {0x6cd2cc, 0x2bc22c0}, {0x618e80, 0x4}, {0x618ad2, 0x3}, {0x28984c0, 0x1b}, {0x0, ...}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/dial.go:234 +0x538 fp=0x284bbb4 sp=0x284bb2c pc=0x275d44 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Dialer).DialContext(0x28a6190, {0x6cd2cc, 0x2bc22c0}, {0x618ad2, 0x3}, {0x28984c0, 0x1b}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/dial.go:422 +0x458 fp=0x284bc88 sp=0x284bbb4 pc=0x276c7c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Dialer).DialContext-fm({0x6cd2cc, 0x2bc22c0}, {0x618ad2, 0x3}, {0x28984c0, 0x1b}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: :1 +0x58 fp=0x284bcb8 sp=0x284bc88 pc=0x428a9c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).dial(0x9f3a78, {0x6cd2cc, 0x2bc22c0}, {0x618ad2, 0x3}, {0x28984c0, 0x1b}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1176 +0x158 fp=0x284bcf0 sp=0x284bcb8 pc=0x412af8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).dialConn(0x9f3a78, {0x6cd2cc, 0x2bc22c0}, {{}, 0x0, {0x2934380, 0x5}, {0x28984c0, 0x1b}, 0x1}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1614 +0x694 fp=0x284bf68 sp=0x284bcf0 pc=0x4151fc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).dialConnFor(0x9f3a78, 0x2b3af00) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1456 +0x64 fp=0x284bfe0 sp=0x284bf68 pc=0x413fa4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net/http.(*Transport).queueForDial.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1425 +0x30 fp=0x284bfec sp=0x284bfe0 pc=0x413f14 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x284bfec sp=0x284bfec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by net/http.(*Transport).queueForDial Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/http/transport.go:1425 +0x9c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 15 [IO wait]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f3c, 0xadc14d6c, 0x2, 0x1b, 0x5) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x296ad88 sp=0x296ad74 pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.netpollblock(0xadc14d60, 0x72, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:527 +0x100 fp=0x296ada0 sp=0x296ad88 pc=0x49f2c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.runtime_pollWait(0xadc14d60, 0x72) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/netpoll.go:306 +0x54 fp=0x296adb4 sp=0x296ada0 pc=0x800b4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).wait(0x2cf00b4, 0x72, 0x0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x296adc8 sp=0x296adb4 pc=0xf7db0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*pollDesc).waitRead(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_poll_runtime.go:89 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/poll.(*FD).Read(0x2cf00a0, {0x2bfe010, 0x3, 0x3}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/poll/fd_unix.go:167 +0x240 fp=0x296ae18 sp=0x296adc8 pc=0xf8fbc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*netFD).Read(0x2cf00a0, {0x2bfe010, 0x3, 0x3}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/fd_posix.go:55 +0x38 fp=0x296ae44 sp=0x296ae18 pc=0x27faec Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*conn).Read(0x288e7d0, {0x2bfe010, 0x3, 0x3}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/net.go:183 +0x48 fp=0x296ae74 sp=0x296ae44 pc=0x290a70 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*TCPConn).Read(0x288e7d0, {0x2bfe010, 0x3, 0x3}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: :1 +0x44 fp=0x296ae94 sp=0x296ae74 pc=0x2a514c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: io.ReadAtLeast({0x6cb4d4, 0x288e7d0}, {0x2bfe010, 0x3, 0x3}, 0x3) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/io/io.go:332 +0x90 fp=0x296aec0 sp=0x296ae94 pc=0xf1b18 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: io.ReadFull(...) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/io/io.go:351 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/ap.(*shannonConn).receivePacket(0x2cb0030) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/shannon.go:80 +0x124 fp=0x296af20 sp=0x296aec0 pc=0x457ddc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/ap.(*Accesspoint).recvLoop(0x29542d0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:239 +0x58 fp=0x296afe4 sp=0x296af20 pc=0x454c1c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: go-librespot/ap.(*Accesspoint).reconnect.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:335 +0x28 fp=0x296afec sp=0x296afe4 pc=0x455778 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x296afec sp=0x296afec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by go-librespot/ap.(*Accesspoint).reconnect Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:335 +0x168 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 93 [select]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.gopark(0x642f84, 0x0, 0x9, 0x18, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:381 +0x100 fp=0x2cf7b80 sp=0x2cf7b6c pc=0x527b0 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.selectgo(0x2cf7c74, 0x2cf7c5c, 0x0, 0x0, 0x2, 0x1) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/select.go:327 +0xb70 fp=0x2cf7c28 sp=0x2cf7b80 pc=0x64d80 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoLookupIP({0x6cd28c, 0x29fa3f0}, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:221 +0x178 fp=0x2cf7cbc sp=0x2cf7c28 pc=0x2733b4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).lookupIP(0xa35480, {0x6cd28c, 0x29fa3f0}, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/lookup_unix.go:74 +0x114 fp=0x2cf7efc sp=0x2cf7cbc pc=0x2904f8 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).lookupIP-fm({0x6cd28c, 0x29fa3f0}, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: :1 +0x58 fp=0x2cf7f30 sp=0x2cf7efc pc=0x2a763c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.glob..func1({0x6cd28c, 0x29fa3f0}, 0x2b4e138, {0x618ad2, 0x3}, {0x28984c0, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/hook.go:23 +0x4c fp=0x2cf7f60 sp=0x2cf7f30 pc=0x2821c4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.(*Resolver).lookupIPAddr.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/lookup.go:326 +0x58 fp=0x2cf7f9c sp=0x2cf7f60 pc=0x28f914 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/singleflight.(*Group).doCall(0xa35488, 0x29fa420, {0x28984e0, 0x1b}, 0x2bc23a0) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x2cf7fd4 sp=0x2cf7f9c pc=0x27041c Apr 27 20:57:11 richdacvolumio go-librespot[1116]: internal/singleflight.(*Group).DoChan.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x2cf7fec sp=0x2cf7fd4 pc=0x2703d4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2cf7fec sp=0x2cf7fec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by internal/singleflight.(*Group).DoChan Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/internal/singleflight/singleflight.go:86 +0x320 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: goroutine 131 [syscall]: Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.cgocall(0x53e76c, 0x2d0d6c4) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/cgocall.go:157 +0x5c fp=0x2d0d6ac sp=0x2d0d694 pc=0x18350 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C2func_getaddrinfo(0x282c258, 0x0, 0x2a3c440, 0x2ba2348) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: _cgo_gotypes.go:95 +0x44 fp=0x2d0d6c0 sp=0x2d0d6ac pc=0x2a1578 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C_getaddrinfo.func1(0x282c258, 0x0, 0x2a3c440, 0x2ba2348) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix_cgo.go:74 +0x7c fp=0x2d0d6e0 sp=0x2d0d6c0 pc=0x2a18bc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net._C_getaddrinfo(0x282c258, 0x0, 0x2a3c440, 0x2ba2348) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix_cgo.go:74 +0x34 fp=0x2d0d700 sp=0x2d0d6e0 pc=0x2a1818 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoLookupIPCNAME({0x618ad2, 0x3}, {0x2c72180, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:153 +0x164 fp=0x2d0d788 sp=0x2d0d700 pc=0x272ba4 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoIPLookup(0x2c6a6c0, {0x618ad2, 0x3}, {0x2c72180, 0x17}) Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:210 +0x34 fp=0x2d0d7d4 sp=0x2d0d788 pc=0x2731cc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: net.cgoLookupIP.func1() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:220 +0x48 fp=0x2d0d7ec sp=0x2d0d7d4 pc=0x2735dc Apr 27 20:57:11 richdacvolumio go-librespot[1116]: runtime.goexit() Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x2d0d7ec sp=0x2d0d7ec pc=0x84730 Apr 27 20:57:11 richdacvolumio go-librespot[1116]: created by net.cgoLookupIP Apr 27 20:57:11 richdacvolumio go-librespot[1116]: /opt/hostedtoolcache/go/1.20.8/x64/src/net/cgo_unix.go:220 +0xfc Apr 27 20:57:11 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 27 20:57:11 richdacvolumio go-librespot[1116]: Aborted Apr 27 20:57:11 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 20:57:11 richdacvolumio volumio[891]: info: Connection to go-librespot Websocket closed Apr 27 20:57:14 richdacvolumio volumio[891]: info: Initializing connection to go-librespot Websocket Apr 27 20:57:14 richdacvolumio volumio[891]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 20:57:14 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 20:57:14 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 27 20:57:14 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Apr 27 20:57:14 richdacvolumio systemd[1]: Started go-librespot Daemon. Apr 27 20:57:14 richdacvolumio go-librespot[2841]: Librespot-go daemon starting... Apr 27 20:57:14 richdacvolumio go-librespot[2841]: time="2024-04-27T20:57:14+01:00" level=info msg="generated new device id: f78319587d7c4e972f2c13018a7ac502ff2f7bc1" Apr 27 20:57:14 richdacvolumio go-librespot[2841]: time="2024-04-27T20:57:14+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Apr 27 20:57:17 richdacvolumio volumio[891]: info: Initializing connection to go-librespot Websocket Apr 27 20:57:17 richdacvolumio go-librespot[2841]: time="2024-04-27T20:57:17+01:00" level=debug msg="new websocket client" Apr 27 20:57:17 richdacvolumio volumio[891]: info: Connection to go-librespot Websocket established Apr 27 20:57:20 richdacvolumio volumio[891]: info: Getting Spotify volume Apr 27 20:57:20 richdacvolumio volumio[891]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 27 20:57:20 richdacvolumio volumio[891]: info: CoreCommandRouter::volumioGetState Apr 27 20:57:20 richdacvolumio volumio[891]: info: CorePlayQueue::getTrack 0 Apr 27 20:57:20 richdacvolumio volumio[891]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 20:57:44 richdacvolumio go-librespot[2841]: time="2024-04-27T20:57:44+01:00" level=fatal msg="failed running with username and spotify token" error="failed obtaining client token: failed requesting clienttoken: Post \"https://clienttoken.spotify.com/v1/clienttoken\": dial tcp 35.186.224.25:443: i/o timeout" Apr 27 20:57:44 richdacvolumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 20:57:44 richdacvolumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 20:57:44 richdacvolumio volumio[891]: (node:891) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 27 20:57:44 richdacvolumio volumio[891]: at connResetException (internal/errors.js:607:14) Apr 27 20:57:44 richdacvolumio volumio[891]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 27 20:57:44 richdacvolumio volumio[891]: at Socket.emit (events.js:327:22) Apr 27 20:57:44 richdacvolumio volumio[891]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 20:57:44 richdacvolumio volumio[891]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 20:57:44 richdacvolumio volumio[891]: (Use `node --trace-warnings ...` to show where the warning was created) Apr 27 20:57:44 richdacvolumio volumio[891]: (node:891) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Apr 27 20:57:44 richdacvolumio volumio[891]: (node:891) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Apr 27 20:57:44 richdacvolumio volumio[891]: info: Connection to go-librespot Websocket closed Apr 27 20:57:47 richdacvolumio volumio[891]: info: Initializing connection to go-librespot Websocket Apr 27 20:57:47 richdacvolumio volumio[891]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 20:57:47 richdacvolumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 20:57:47 richdacvolumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Apr 27 20:57:47 richdacvolumio systemd[1]: Stopped go-librespot Daemon. Apr 27 20:57:47 richdacvolumio systemd[1]: Started go-librespot Daemon. Apr 27 20:57:47 richdacvolumio go-librespot[2894]: Librespot-go daemon starting... Apr 27 20:57:47 richdacvolumio go-librespot[2894]: time="2024-04-27T20:57:47+01:00" level=info msg="generated new device id: cd74e5aa32b5743c74d50453ae420273ce54f655" Apr 27 20:57:47 richdacvolumio go-librespot[2894]: time="2024-04-27T20:57:47+01:00" level=debug msg="stored credentials found for eviesdaddythemainman" Apr 27 20:57:50 richdacvolumio volumio[891]: info: Initializing connection to go-librespot Websocket Apr 27 20:57:50 richdacvolumio go-librespot[2894]: time="2024-04-27T20:57:50+01:00" level=debug msg="new websocket client" Apr 27 20:57:50 richdacvolumio volumio[891]: info: Connection to go-librespot Websocket established Apr 27 20:57:53 richdacvolumio volumio[891]: info: Getting Spotify volume Apr 27 20:57:53 richdacvolumio volumio[891]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 27 20:57:53 richdacvolumio volumio[891]: info: CoreCommandRouter::volumioGetState Apr 27 20:57:53 richdacvolumio volumio[891]: info: CorePlayQueue::getTrack 0 Apr 27 20:57:53 richdacvolumio volumio[891]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 27 20:57:56 richdacvolumio volumio[891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:57:56 richdacvolumio volumio[891]: Error: connect ETIMEDOUT 199.232.54.79:80 Apr 27 20:57:56 richdacvolumio volumio[891]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 27 20:57:56 richdacvolumio volumio[891]: errno: -110, Apr 27 20:57:56 richdacvolumio volumio[891]: code: 'ETIMEDOUT', Apr 27 20:57:56 richdacvolumio volumio[891]: syscall: 'connect', Apr 27 20:57:56 richdacvolumio volumio[891]: address: '199.232.54.79', Apr 27 20:57:56 richdacvolumio volumio[891]: port: 80 Apr 27 20:57:56 richdacvolumio volumio[891]: } Apr 27 20:57:56 richdacvolumio volumio[891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 20:57:56 richdacvolumio sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 20:56 Apr 27 20:57:56 richdacvolumio sudo[2913]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 06:28:23 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b343c71baf05cbc21bf1a530c8e51fc1"