Jun 02 16:50:08 volumio go-librespot[1831]: time="2026-06-02T16:50:08-06:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-gew1.spotify.com: device or resource busy" Jun 02 16:50:20 volumio go-librespot[1831]: time="2026-06-02T16:50:20-06:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp: lookup ap-gew4.spotify.com: device or resource busy" Jun 02 16:50:23 volumio go-librespot[1831]: time="2026-06-02T16:50:23-06:00" level=error msg="did not receive last pong from dealer, 120s passed" Jun 02 16:50:26 volumio volumiossh-tunnel[2085]: Timeout, server us2.myvolumio.org not responding. Jun 02 16:50:26 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:50:26 volumio autossh[2082]: starting ssh (count 2) Jun 02 16:50:26 volumio autossh[2082]: ssh child pid is 3540 Jun 02 16:50:35 volumio volumiossh-tunnel[3540]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:50:35 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:50:35 volumio autossh[2082]: starting ssh (count 3) Jun 02 16:50:35 volumio autossh[2082]: ssh child pid is 3586 Jun 02 16:50:45 volumio go-librespot[1831]: time="2026-06-02T16:50:45-06:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:443, retrying with a different AP" error="dial tcp: lookup ap-guc3.spotify.com: device or resource busy" Jun 02 16:50:48 volumio volumiossh-tunnel[3586]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:50:48 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:50:48 volumio autossh[2082]: starting ssh (count 4) Jun 02 16:50:48 volumio autossh[2082]: ssh child pid is 3637 Jun 02 16:50:53 volumio go-librespot[1831]: time="2026-06-02T16:50:53-06:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" Jun 02 16:50:53 volumio go-librespot[1831]: time="2026-06-02T16:50:53-06:00" level=error msg="did not receive last pong from dealer, 150s passed" Jun 02 16:50:53 volumio go-librespot[1831]: panic: runtime error: invalid memory address or nil pointer dereference Jun 02 16:50:53 volumio go-librespot[1831]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x18 pc=0x8a6d9b] Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 99 gp=0xc000483180 m=3 mp=0xc000063008 [running]: Jun 02 16:50:53 volumio go-librespot[1831]: panic({0xbbb580?, 0x1420450?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/panic.go:802 +0x168 fp=0xc000490ed8 sp=0xc000490e28 pc=0x4889e8 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.panicmem(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/panic.go:262 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.sigpanic() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/signal_unix.go:925 +0x359 fp=0xc000490f38 sp=0xc000490ed8 pc=0x48afb9 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0001463c0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:380 +0x1fb fp=0xc000490fc8 sp=0xc000490f38 pc=0x8a6d9b Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:277 +0x25 fp=0xc000490fe0 sp=0xc000490fc8 pc=0x8a62a5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000490fe8 sp=0xc000490fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 59 Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:277 +0xff Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 1 gp=0xc000002380 m=nil [select, 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000045c58?, 0x2?, 0x40?, 0xe?, 0xc000045bf4?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0003c3a68 sp=0xc0003c3a48 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc0003c3c58, 0xc000045bf0, 0xc000060070?, 0x0, 0x0?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0003c3ba8 sp=0xc0003c3a68 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc0005379a0, 0xc0003c3d80) Jun 02 16:50:53 volumio go-librespot[1831]: /src/zeroconf/zeroconf.go:300 +0x2aa fp=0xc0003c3cf8 sp=0xc0003c3ba8 pc=0x9b2aca Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).withAppPlayer(0xc000184d00, {0xe22968, 0x14ad0c0}, 0xc000276a00) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:340 +0x5d0 fp=0xc0003c3e10 sp=0xc0003c3cf8 pc=0xa3fd70 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).withCredentials(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:209 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).SpotifyToken(0xc000184d00, {0xe22968, 0x14ad0c0}, {0xc000014e60?, 0xcfc256?}, {0xc0000a23c0?, 0x2697?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:201 +0xc5 fp=0xc0003c3e68 sp=0xc0003c3e10 pc=0xa3f1e5 Jun 02 16:50:53 volumio go-librespot[1831]: main.main() Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:620 +0x745 fp=0xc0003c3f50 sp=0xc0003c3e68 pc=0xa42245 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.main() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:285 +0x29d fp=0xc0003c3fe0 sp=0xc0003c3f50 pc=0x45509d Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0003c3fe8 sp=0xc0003c3fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 2 gp=0xc000002e00 m=nil [force gc (idle), 3 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x439039c8c9?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005cfa8 sp=0xc00005cf88 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goparkunlock(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:466 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.forcegchelper() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:373 +0xb3 fp=0xc00005cfe0 sp=0xc00005cfa8 pc=0x4553d3 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005cfe8 sp=0xc00005cfe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.init.7 in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:361 +0x1a Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 3 gp=0xc000003340 m=nil [GC sweep wait]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005d780 sp=0xc00005d760 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goparkunlock(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:466 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.bgsweep(0xc00007e000) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgcsweep.go:323 +0xdf fp=0xc00005d7c8 sp=0xc00005d780 pc=0x43f29f Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcenable.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:212 +0x25 fp=0xc00005d7e0 sp=0xc00005d7c8 pc=0x4332a5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005d7e8 sp=0xc00005d7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcenable in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:212 +0x66 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 4 gp=0xc000003500 m=nil [GC scavenge wait]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x122406fb?, 0x121cd801?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005df78 sp=0xc00005df58 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goparkunlock(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:466 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.(*scavengerState).park(0x148bba0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005dfa8 sp=0xc00005df78 pc=0x43cd29 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.bgscavenge(0xc00007e000) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005dfc8 sp=0xc00005dfa8 pc=0x43d2d9 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcenable.gowrap2() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:213 +0x25 fp=0xc00005dfe0 sp=0xc00005dfc8 pc=0x433245 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005dfe8 sp=0xc00005dfe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcenable in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:213 +0xa5 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 5 gp=0xc000003a40 m=nil [GOMAXPROCS updater (idle), 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005e788 sp=0xc00005e768 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goparkunlock(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:466 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.updateMaxProcsGoroutine() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:6720 +0xe7 fp=0xc00005e7e0 sp=0xc00005e788 pc=0x4632c7 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005e7e8 sp=0xc00005e7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:6708 +0x37 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 6 gp=0xc0000aa000 m=nil [finalizer wait, 3 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x0?, 0xd57e00?, 0xa0?, 0x1?, 0x2000000020?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005c620 sp=0xc00005c600 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.runFinalizers() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mfinal.go:210 +0x107 fp=0xc00005c7e0 sp=0xc00005c620 pc=0x4321a7 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005c7e8 sp=0xc00005c7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.createfing in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mfinal.go:172 +0x3d Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 7 gp=0xc0000aae00 m=nil [cleanup wait, 3 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x7f09e8182c00?, 0xc00005ef90?, 0xb5?, 0x7a?, 0x7f09e8182c00?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005ef68 sp=0xc00005ef48 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goparkunlock(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:466 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.(*cleanupQueue).dequeue(0x148bfe0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mcleanup.go:439 +0xc5 fp=0xc00005efa0 sp=0xc00005ef68 pc=0x42f3c5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.runCleanups() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mcleanup.go:635 +0x45 fp=0xc00005efe0 sp=0xc00005efa0 pc=0x42fa85 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mcleanup.go:589 +0xa5 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 10 gp=0xc0000aafc0 m=nil [IO wait, 5 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00006ca58 sp=0xc00006ca38 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.netpollblock(0xc00006caa8?, 0x41f886?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc00006ca90 sp=0xc00006ca58 pc=0x44d977 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.runtime_pollWait(0x7f0a3147de00, 0x72) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc00006cab0 sp=0xc00006ca90 pc=0x4880e5 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).wait(0xc000182900?, 0x900000036?, 0x0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006cad8 sp=0xc00006cab0 pc=0x50b687 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).waitRead(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*FD).Accept(0xc000182900) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc00006cb80 sp=0xc00006cad8 pc=0x510aac Jun 02 16:50:53 volumio go-librespot[1831]: net.(*netFD).accept(0xc000182900) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc00006cc38 sp=0xc00006cb80 pc=0x57d2a9 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*TCPListener).accept(0xc000089300) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc00006cc88 sp=0xc00006cc38 pc=0x592a1b Jun 02 16:50:53 volumio go-librespot[1831]: net.(*TCPListener).Accept(0xc000089300) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc00006ccb8 sp=0xc00006cc88 pc=0x591bf0 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*onceCloseListener).Accept(0xc000140870?) Jun 02 16:50:53 volumio go-librespot[1831]: :1 +0x24 fp=0xc00006ccd0 sp=0xc00006ccb8 pc=0x73b104 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*Server).Serve(0xc00013a000, {0xe218d0, 0xc000089300}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc00006ce00 sp=0xc00006ccd0 pc=0x71306c Jun 02 16:50:53 volumio go-librespot[1831]: net/http.Serve(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2971 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*ConcreteApiServer).serve(0xc000248480) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/api_server.go:666 +0x90c fp=0xc00006cfc8 sp=0xc00006ce00 pc=0xa3504c Jun 02 16:50:53 volumio go-librespot[1831]: main.NewApiServer.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/api_server.go:332 +0x25 fp=0xc00006cfe0 sp=0xc00006cfc8 pc=0xa33d85 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006cfe8 sp=0xc00006cfe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by main.NewApiServer in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/api_server.go:332 +0x2ff Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 27 gp=0xc0000ab180 m=nil [chan receive, 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc0000e8780?, 0xb9cf60?, 0x88?, 0x3?, 0xc0000587a8?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000058718 sp=0xc0000586f8 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv(0xc0000940e0, 0x0, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc000058790 sp=0xc000058718 pc=0x422773 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv1(0xc00054e000?, 0xe26510?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:509 +0x12 fp=0xc0000587b8 sp=0xc000058790 pc=0x4222d2 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/godbus/dbus/v5.newConn.func1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x2c fp=0xc0000587e0 sp=0xc0000587b8 pc=0x85b32c Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000587e8 sp=0xc0000587e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x4da Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 28 gp=0xc0000ab340 m=nil [IO wait, 5 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0001cba80 sp=0xc0001cba60 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.netpollblock(0x0?, 0x41f886?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc0001cbab8 sp=0xc0001cba80 pc=0x44d977 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.runtime_pollWait(0x7f0a3147d800, 0x72) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc0001cbad8 sp=0xc0001cbab8 pc=0x4880e5 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).wait(0xc000132280?, 0xc000014080?, 0x0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0001cbb00 sp=0xc0001cbad8 pc=0x50b687 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).waitRead(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*FD).ReadMsg(0xc000132280, {0xc000014080, 0x10, 0x10}, {0xc000016028, 0x1000, 0x1000}, 0x40000000) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x385 fp=0xc0001cbbe8 sp=0xc0001cbb00 pc=0x50db45 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*netFD).readMsg(0xc000132280, {0xc000014080?, 0xc000276420?, 0xc0001cbcb8?}, {0xc000016028?, 0x20?, 0xba1820?}, 0xc?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/fd_posix.go:91 +0x31 fp=0xc0001cbc70 sp=0xc0001cbbe8 pc=0x57b811 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*UnixConn).readMsg(0xc000060000, {0xc000014080?, 0x2?, 0xc0001cbd48?}, {0xc000016028?, 0xc0001cbe00?, 0x863e7c?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/unixsock_posix.go:115 +0x3d fp=0xc0001cbd00 sp=0xc0001cbc70 pc=0x598cbd Jun 02 16:50:53 volumio go-librespot[1831]: net.(*UnixConn).ReadMsgUnix(0xc000060000, {0xc000014080?, 0xc0004142d0?, 0xc0001cbdc0?}, {0xc000016028?, 0x0?, 0xc000276420?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/unixsock.go:143 +0x36 fp=0xc0001cbd78 sp=0xc0001cbd00 pc=0x5972b6 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/godbus/dbus/v5.(*oobReader).Read(0xc000016008, {0xc000014080?, 0x2d?, 0x42a214?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x3c fp=0xc0001cbdf0 sp=0xc0001cbd78 pc=0x87313c Jun 02 16:50:53 volumio go-librespot[1831]: io.ReadAtLeast({0xe1ada0, 0xc000016008}, {0xc000014080, 0x10, 0x10}, 0x10) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/io/io.go:335 +0x8e fp=0xc0001cbe38 sp=0xc0001cbdf0 pc=0x4c91ae Jun 02 16:50:53 volumio go-librespot[1831]: io.ReadFull(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/io/io.go:354 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0xc00051a000) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x225 fp=0xc0001cbf58 sp=0xc0001cbe38 pc=0x873825 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/godbus/dbus/v5.(*Conn).inWorker(0xc0001ce780) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x37 fp=0xc0001cbfc8 sp=0xc0001cbf58 pc=0x85b857 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x25 fp=0xc0001cbfe0 sp=0xc0001cbfc8 pc=0x8591c5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001cbfe8 sp=0xc0001cbfe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x7ec Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 20 gp=0xc000103c00 m=nil [GC worker (idle)]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x439074b308?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00041af38 sp=0xc00041af18 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkWorker(0xc000143810) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00041afc8 sp=0xc00041af38 pc=0x43598b Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00041afe0 sp=0xc00041afc8 pc=0x435865 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00041afe8 sp=0xc00041afe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 21 gp=0xc000103dc0 m=nil [GC worker (idle), 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x6517bb442?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00040a738 sp=0xc00040a718 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkWorker(0xc000143810) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00040a7c8 sp=0xc00040a738 pc=0x43598b Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00040a7e0 sp=0xc00040a7c8 pc=0x435865 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00040a7e8 sp=0xc00040a7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 22 gp=0xc000442000 m=nil [GC worker (idle), 3 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x4390447ca2?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005f738 sp=0xc00005f718 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkWorker(0xc000143810) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00005f7c8 sp=0xc00005f738 pc=0x43598b Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x435865 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 23 gp=0xc0004421c0 m=nil [GC worker (idle), 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x651782cb1?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00043f738 sp=0xc00043f718 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkWorker(0xc000143810) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00043f7c8 sp=0xc00043f738 pc=0x43598b Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00043f7e0 sp=0xc00043f7c8 pc=0x435865 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00043f7e8 sp=0xc00043f7e0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 61 gp=0xc000442540 m=nil [chan receive, 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000059f10?, 0x422487?, 0x0?, 0x0?, 0x485d4b?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000059e98 sp=0xc000059e78 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv(0xc0000942a0, 0xc000059fb8, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc000059f10 sp=0xc000059e98 pc=0x422773 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv2(0xc000059f80?, 0xc000059f80?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc000059f38 sp=0xc000059f10 pc=0x4222f2 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).withAppPlayer.func2() Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:300 +0x87 fp=0xc000059fe0 sp=0xc000059f38 pc=0xa40507 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000059fe8 sp=0xc000059fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by main.(*App).withAppPlayer in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:297 +0x55f Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 58 gp=0xc000482700 m=nil [select, 5 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000492f20?, 0x3?, 0xa?, 0x0?, 0xc000492eda?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000492d60 sp=0xc000492d40 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc000492f20, 0xc000492ed4, 0x0?, 0x0, 0x0?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000492ea0 sp=0xc000492d60 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc000132e00) Jun 02 16:50:53 volumio go-librespot[1831]: /src/player/player.go:215 +0x205 fp=0xc000492fc8 sp=0xc000492ea0 pc=0x912865 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/player/player.go:197 +0x25 fp=0xc000492fe0 sp=0xc000492fc8 pc=0x912425 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000492fe8 sp=0xc000492fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/player/player.go:197 +0x1e5 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 60 gp=0xc0004828c0 m=nil [chan receive, 5 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc00008ac60?, 0xc000381560?, 0x0?, 0x0?, 0xc00005bee8?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005be98 sp=0xc00005be78 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv(0xc000094310, 0xc00005bf98, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc00005bf10 sp=0xc00005be98 pc=0x422773 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.chanrecv2(0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc00005bf38 sp=0xc00005bf10 pc=0x4222f2 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).withAppPlayer.func1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:284 +0x72 fp=0xc00005bfe0 sp=0xc00005bf38 pc=0xa408d2 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005bfe8 sp=0xc00005bfe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by main.(*App).withAppPlayer in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:281 +0x49f Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 59 gp=0xc000482c40 m=nil [select, 5 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc0004b3ed0?, 0xa?, 0x60?, 0x33?, 0xc0004b36c0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0004b3318 sp=0xc0004b32f8 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc0004b3ed0, 0xc0004b36ac, 0x14ad0c0?, 0x0, 0xa?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0004b3458 sp=0xc0004b3318 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*AppPlayer).Run(0xc000022400, {0xe22968, 0x14ad0c0}, 0xc000095110, 0xc000095180) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/player.go:661 +0x427 fp=0xc0004b3fa8 sp=0xc0004b3458 pc=0xa47f47 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*App).withAppPlayer.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:274 +0x30 fp=0xc0004b3fe0 sp=0xc0004b3fa8 pc=0xa40990 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0004b3fe8 sp=0xc0004b3fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by main.(*App).withAppPlayer in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/main.go:274 +0x405 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 98 gp=0xc000483340 m=nil [select]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000201620?, 0x2?, 0xc8?, 0x13?, 0xc0002014d8?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000201358 sp=0xc000201338 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc000201620, 0xc0002014d4, 0x17?, 0x0, 0xc0004f02a0?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000201498 sp=0xc000201358 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).lookupIPAddr(0x148a9e0, {0xe22a48, 0xc0000e5110}, {0xcf9a68, 0x3}, {0xc000152888, 0x13}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/lookup.go:343 +0x3d9 fp=0xc000201678 sp=0xc000201498 pc=0x588059 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).internetAddrList(0x148a9e0?, {0xe22a48?, 0xc0000e5110?}, {0xcf9a68, 0x3}, {0xc000152888?, 0xc00008a180?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/ipsock.go:289 +0x4b3 fp=0xc000201760 sp=0xc000201678 pc=0x586353 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).resolveAddrList(0x148a9e0, {0xe22a48, 0xc0000e5110}, {0xcf9e97, 0x4}, {0xcf9a68?, 0x0?}, {0xc000152888, 0x16}, {0x0, ...}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/dial.go:353 +0x3e5 fp=0xc000201860 sp=0xc000201760 pc=0x5710c5 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Dialer).DialContext(0xc000201a08, {0xe22a48, 0xc0000e5110}, {0xcf9a68, 0x3}, {0xc000152888, 0x16}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/dial.go:560 +0x3f7 fp=0xc0002019d0 sp=0xc000201860 pc=0x571f37 Jun 02 16:50:53 volumio go-librespot[1831]: golang.org/x/net/proxy.direct.DialContext(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Jun 02 16:50:53 volumio go-librespot[1831]: golang.org/x/net/proxy.(*direct).DialContext(0x1426050?, {0xe22a48?, 0xc0000e5110?}, {0xcf9a68?, 0x14ad0c0?}, {0xc000152888?, 0x6fc23ac00?}) Jun 02 16:50:53 volumio go-librespot[1831]: :1 +0x85 fp=0xc000201aa8 sp=0xc0002019d0 pc=0x8a2105 Jun 02 16:50:53 volumio go-librespot[1831]: golang.org/x/net/proxy.Dial({0xe22a48, 0xc0000e5110}, {0xcf9a68, 0x3}, {0xc000152888, 0x16}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0x98 fp=0xc000201b08 sp=0xc000201aa8 pc=0x89fff8 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0xc0001463c0, {0xe22968, 0x14ad0c0}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:95 +0x248 fp=0xc000201be0 sp=0xc000201b08 pc=0x8a4808 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0xc0001463c0, {0xe22968, 0x14ad0c0}, 0xc00054f4f0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:205 +0xc8 fp=0xc000201c58 sp=0xc000201be0 pc=0x8a58e8 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0xc0001463c0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:394 +0xc7 fp=0xc000201ca8 sp=0xc000201c58 pc=0x8a6ec7 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Jun 02 16:50:53 volumio go-librespot[1831]: :1 +0x25 fp=0xc000201cc0 sp=0xc000201ca8 pc=0x8aab25 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x13 fp=0xc000201cd0 sp=0xc000201cc0 pc=0x89a513 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc000201df8?, {0xe204d8, 0xc0003804e0}, 0x0, {0x0, 0x0?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0x11c fp=0xc000201dc0 sp=0xc000201cd0 pc=0x89a8fc Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x0?, {0xe204d8?, 0xc0003804e0?}, 0x0?, {0x0?, 0x0?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x56 fp=0xc000201e18 sp=0xc000201dc0 pc=0x89a496 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.Retry(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0001463c0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:335 +0x451 fp=0xc000201fc8 sp=0xc000201e18 pc=0x8a6791 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:273 +0x25 fp=0xc000201fe0 sp=0xc000201fc8 pc=0x8a6305 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000201fe8 sp=0xc000201fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 59 Jun 02 16:50:53 volumio go-librespot[1831]: /src/ap/ap.go:273 +0x78 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 62 gp=0xc000442a80 m=nil [IO wait, 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000495be0 sp=0xc000495bc0 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.netpollblock(0x486a25?, 0x41f886?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000495c18 sp=0xc000495be0 pc=0x44d977 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.runtime_pollWait(0x7f0a3147da00, 0x72) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000495c38 sp=0xc000495c18 pc=0x4880e5 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).wait(0xc000132200?, 0x7f0a316065c0?, 0x0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000495c60 sp=0xc000495c38 pc=0x50b687 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).waitRead(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*FD).Accept(0xc000132200) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000495d08 sp=0xc000495c60 pc=0x510aac Jun 02 16:50:53 volumio go-librespot[1831]: net.(*netFD).accept(0xc000132200) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000495dc0 sp=0xc000495d08 pc=0x57d2a9 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*TCPListener).accept(0xc00042a000) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000495e10 sp=0xc000495dc0 pc=0x592a1b Jun 02 16:50:53 volumio go-librespot[1831]: net.(*TCPListener).Accept(0xc00042a000) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000495e40 sp=0xc000495e10 pc=0x591bf0 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*onceCloseListener).Accept(0xe22930?) Jun 02 16:50:53 volumio go-librespot[1831]: :1 +0x24 fp=0xc000495e58 sp=0xc000495e40 pc=0x73b104 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*Server).Serve(0xc000184800, {0xe218d0, 0xc00042a000}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000495f88 sp=0xc000495e58 pc=0x71306c Jun 02 16:50:53 volumio go-librespot[1831]: net/http.Serve(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2971 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jun 02 16:50:53 volumio go-librespot[1831]: /src/zeroconf/zeroconf.go:297 +0x85 fp=0xc000495fe0 sp=0xc000495f88 pc=0x9b2be5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000495fe8 sp=0xc000495fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jun 02 16:50:53 volumio go-librespot[1831]: /src/zeroconf/zeroconf.go:297 +0x169 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 100 gp=0xc000002000 m=nil [select]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000203de0?, 0x2?, 0x25?, 0x93?, 0xc000203d8c?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000203c10 sp=0xc000203bf0 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc000203de0, 0xc000203d88, 0xb63ae0?, 0x0, 0x429897?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000203d50 sp=0xc000203c10 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc000203e78?, {0xe204d8, 0xc0004f8000}, 0x0, {0x0, 0x0?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:112 +0x245 fp=0xc000203e40 sp=0xc000203d50 pc=0x89aa25 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x0?, {0xe204d8?, 0xc0004f8000?}, 0x0?, {0x0?, 0x0?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x56 fp=0xc000203e98 sp=0xc000203e40 pc=0x89a496 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/cenkalti/backoff/v4.Retry(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc0005241c0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:237 +0x550 fp=0xc000203fc8 sp=0xc000203e98 pc=0x92f850 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:125 +0x25 fp=0xc000203fe0 sp=0xc000203fc8 pc=0x92eee5 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000203fe8 sp=0xc000203fe0 pc=0x490a41 Jun 02 16:50:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 59 Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:125 +0x78 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 101 gp=0xc000102380 m=nil [select]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000071f98?, 0x2?, 0x60?, 0x60?, 0xc000071f44?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000071dc8 sp=0xc000071da8 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc000071f98, 0xc000071f40, 0x0?, 0x0, 0xc00053c0c0?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000071f08 sp=0xc000071dc8 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc0005241c0) Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:138 +0x8f fp=0xc000071fc8 sp=0xc000071f08 pc=0x92efaf Jun 02 16:50:53 volumio go-librespot[1831]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:129 +0x25 fp=0xc000071fe0 sp=0xc000071fc8 pc=0x92ee85 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 59 Jun 02 16:50:53 volumio go-librespot[1831]: /src/dealer/dealer.go:129 +0xf6 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 104 gp=0xc000442e00 m=nil [IO wait, 6 minutes]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc000272870?, 0xc0002073a8?, 0xa8?, 0x73?, 0xb?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000207338 sp=0xc000207318 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.netpollblock(0x4ac6d8?, 0x41f886?, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000207370 sp=0xc000207338 pc=0x44d977 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.runtime_pollWait(0x7f09d37a6000, 0x72) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000207390 sp=0xc000207370 pc=0x4880e5 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).wait(0xc000182400?, 0xc0002a8000?, 0x0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0002073b8 sp=0xc000207390 pc=0x50b687 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*pollDesc).waitRead(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 16:50:53 volumio go-librespot[1831]: internal/poll.(*FD).Read(0xc000182400, {0xc0002a8000, 0x1000, 0x1000}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x279 fp=0xc000207450 sp=0xc0002073b8 pc=0x50c979 Jun 02 16:50:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 16:50:53 volumio volumio[1053]: info: Connection to go-librespot Websocket closed Jun 02 16:50:53 volumio go-librespot[1831]: net.(*netFD).Read(0xc000182400, {0xc0002a8000?, 0x7f0a31606a78?, 0x10?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/fd_posix.go:68 +0x25 fp=0xc000207498 sp=0xc000207450 pc=0x57b405 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*conn).Read(0xc000126130, {0xc0002a8000?, 0x10?, 0xc000207520?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/net.go:196 +0x45 fp=0xc0002074e0 sp=0xc000207498 pc=0x589925 Jun 02 16:50:53 volumio go-librespot[1831]: io.(*multiReader).Read(0xc0000127f8, {0xc0002a8000, 0x1000, 0x1000}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc000207528 sp=0xc0002074e0 pc=0x4ca073 Jun 02 16:50:53 volumio go-librespot[1831]: bufio.(*Reader).fill(0xc0003817a0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/bufio/bufio.go:113 +0x103 fp=0xc000207560 sp=0xc000207528 pc=0x68e823 Jun 02 16:50:53 volumio go-librespot[1831]: bufio.(*Reader).ReadByte(0xc0003817a0) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/bufio/bufio.go:273 +0x27 fp=0xc000207580 sp=0xc000207560 pc=0x68ef47 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.readFrameHeader(0xc0003817a0, {0xc00013e9c0, 0x8, 0x8}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x85 fp=0xc000207610 sp=0xc000207580 pc=0x745ae5 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.(*Conn).readFrameHeader(0xc00013e960, {0xe22930, 0x14ad0c0}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xe5 fp=0xc0002076e8 sp=0xc000207610 pc=0x747925 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.(*Conn).readLoop(0xc00013e960, {0xe22930, 0x14ad0c0}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x65 fp=0xc000207848 sp=0xc0002076e8 pc=0x746f45 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.(*Conn).reader(0xc00013e960, {0xe22930, 0x14ad0c0}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0x10f fp=0xc000207938 sp=0xc000207848 pc=0x7486af Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.(*Conn).Reader(...) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Jun 02 16:50:53 volumio go-librespot[1831]: github.com/coder/websocket.(*Conn).Read(0xc0002484f8?, {0xe22930?, 0x14ad0c0?}) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x1e fp=0xc000207968 sp=0xc000207938 pc=0x7466fe Jun 02 16:50:53 volumio go-librespot[1831]: main.(*ConcreteApiServer).serve.func17({0xe21a50, 0xc0001465a0}, 0xc000456cf0?) Jun 02 16:50:53 volumio go-librespot[1831]: /src/cmd/daemon/api_server.go:636 +0x39f fp=0xc000207a68 sp=0xc000207968 pc=0xa357ff Jun 02 16:50:53 volumio go-librespot[1831]: net/http.HandlerFunc.ServeHTTP(0xc000110000?, {0xe21a50?, 0xc0001465a0?}, 0x2000000000000?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc000207a90 sp=0xc000207a68 pc=0x70f769 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*ServeMux).ServeHTTP(0xc00011c1a0?, {0xe21a50, 0xc0001465a0}, 0xc0003e8780) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2861 +0x1c7 fp=0xc000207ae0 sp=0xc000207a90 pc=0x711647 Jun 02 16:50:53 volumio go-librespot[1831]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xe21a50, 0xc0001465a0}, 0xc0003e8780) Jun 02 16:50:53 volumio go-librespot[1831]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x164 fp=0xc000207b38 sp=0xc000207ae0 pc=0xa35284 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.HandlerFunc.ServeHTTP(0x485cb9?, {0xe21a50?, 0xc0001465a0?}, 0xc000073b68?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc000207b60 sp=0xc000207b38 pc=0x70f769 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.serverHandler.ServeHTTP({0xc000089100?}, {0xe21a50?, 0xc0001465a0?}, 0x1?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:3340 +0x8e fp=0xc000207b90 sp=0xc000207b60 pc=0x72ebee Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*conn).serve(0xc000248510, {0xe229a0, 0xc0001284b0}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:2109 +0x665 fp=0xc000207fb8 sp=0xc000207b90 pc=0x70d865 Jun 02 16:50:53 volumio go-librespot[1831]: net/http.(*Server).Serve.gowrap3() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:3493 +0x28 fp=0xc000207fe0 sp=0xc000207fb8 pc=0x713468 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000207fe8 sp=0xc000207fe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by net/http.(*Server).Serve in goroutine 10 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/http/server.go:3493 +0x485 Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 154 gp=0xc0004836c0 m=nil [select]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.gopark(0xc00006fb60?, 0x2?, 0x1?, 0x0?, 0xc00006fae4?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00006f968 sp=0xc00006f948 pc=0x488f0e Jun 02 16:50:53 volumio go-librespot[1831]: runtime.selectgo(0xc00006fb60, 0xc00006fae0, 0x4?, 0x0, 0x0?, 0x1) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00006faa8 sp=0xc00006f968 pc=0x467bd7 Jun 02 16:50:53 volumio go-librespot[1831]: net.doBlockingWithCtx[...]({0xe229d8, 0xc00054f8b0}, {0xc000152888, 0x13}, 0xc000298d20) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:71 +0x29a fp=0xc00006fbc0 sp=0xc00006faa8 pc=0x59c93a Jun 02 16:50:53 volumio go-librespot[1831]: net.cgoLookupIP({0xe229d8, 0xc00054f8b0}, {0xcf9a68, 0x3}, {0xc000152888, 0x13}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:228 +0xa5 fp=0xc00006fc00 sp=0xc00006fbc0 pc=0x56ece5 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).lookupIP(0x148a9e0, {0xe229d8, 0xc00054f8b0}, {0xcf9a68, 0x3}, {0xc000152888, 0x13}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/lookup_unix.go:64 +0xe5 fp=0xc00006fe58 sp=0xc00006fc00 pc=0x588fc5 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).lookupIP-fm({0xe229d8?, 0xc00054f8b0?}, {0xcf9a68?, 0xc000458ac8?}, {0xc000152888?, 0xc00043eeb0?}) Jun 02 16:50:53 volumio go-librespot[1831]: :1 +0x49 fp=0xc00006fea0 sp=0xc00006fe58 pc=0x5a2609 Jun 02 16:50:53 volumio go-librespot[1831]: net.init.func1({0xe229d8?, 0xc00054f8b0?}, 0xc00054f6d0?, {0xcf9a68?, 0xc000208b40?}, {0xc000152888?, 0xc00053e440?}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/hook.go:21 +0x37 fp=0xc00006fee0 sp=0xc00006fea0 pc=0x56c377 Jun 02 16:50:53 volumio go-librespot[1831]: net.(*Resolver).lookupIPAddr.func1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/lookup.go:335 +0x37 fp=0xc00006ff38 sp=0xc00006fee0 pc=0x588917 Jun 02 16:50:53 volumio go-librespot[1831]: internal/singleflight.(*Group).doCall(0x148a9f0, 0xc00054f900, {0xc000020558, 0x17}, 0x0?) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x34 fp=0xc00006ffa8 sp=0xc00006ff38 pc=0x56b174 Jun 02 16:50:53 volumio go-librespot[1831]: internal/singleflight.(*Group).DoChan.gowrap1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x30 fp=0xc00006ffe0 sp=0xc00006ffa8 pc=0x56b110 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by internal/singleflight.(*Group).DoChan in goroutine 98 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2cf Jun 02 16:50:53 volumio go-librespot[1831]: goroutine 155 gp=0xc000443880 m=0 mp=0x148d0e0 [syscall]: Jun 02 16:50:53 volumio go-librespot[1831]: runtime.cgocall(0xab35e0, 0xc00043ee00) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/cgocall.go:167 +0x4b fp=0xc00043edd8 sp=0xc00043eda0 pc=0x485deb Jun 02 16:50:53 volumio go-librespot[1831]: net._C2func_getaddrinfo(0xc0000205a0, 0x0, 0xc000298d80, 0xc000126248) Jun 02 16:50:53 volumio go-librespot[1831]: _cgo_gotypes.go:109 +0x50 fp=0xc00043ee00 sp=0xc00043edd8 pc=0x599950 Jun 02 16:50:53 volumio go-librespot[1831]: net._C_getaddrinfo.func1(...) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Jun 02 16:50:53 volumio go-librespot[1831]: net._C_getaddrinfo(0xc0000205a0, 0x0, 0xc000298d80, 0xc000126248) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x7b fp=0xc00043ee48 sp=0xc00043ee00 pc=0x599c7b Jun 02 16:50:53 volumio go-librespot[1831]: net.cgoLookupHostIP({0xcf9a68, 0x3}, {0xc000152888, 0x13}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:174 +0x1fd fp=0xc00043ef50 sp=0xc00043ee48 pc=0x56e6bd Jun 02 16:50:53 volumio go-librespot[1831]: net.cgoLookupIP.func1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:229 +0x25 fp=0xc00043ef80 sp=0xc00043ef50 pc=0x56ed65 Jun 02 16:50:53 volumio go-librespot[1831]: net.doBlockingWithCtx[...].func1() Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:67 +0x56 fp=0xc00043efe0 sp=0xc00043ef80 pc=0x59cbf6 Jun 02 16:50:53 volumio go-librespot[1831]: runtime.goexit({}) Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00043efe8 sp=0xc00043efe0 pc=0x490a41 Jun 02 16:50:53 volumio go-librespot[1831]: created by net.doBlockingWithCtx[...] in goroutine 154 Jun 02 16:50:53 volumio go-librespot[1831]: /usr/local/go/src/net/cgo_unix.go:64 +0x210 Jun 02 16:50:53 volumio go-librespot[1823]: Aborted Jun 02 16:50:56 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:50:56 volumio volumio[1053]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 02 16:50:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 02 16:50:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:50:56 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:50:56 volumio go-librespot[3683]: go-librespot daemon starting... Jun 02 16:50:56 volumio go-librespot[3684]: time="2026-06-02T16:50:56-06:00" level=info msg="running go-librespot 0.7.1" Jun 02 16:50:56 volumio go-librespot[3684]: time="2026-06-02T16:50:56-06:00" level=debug msg="app state loaded" Jun 02 16:50:56 volumio go-librespot[3684]: time="2026-06-02T16:50:56-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 16:50:59 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:51:00 volumio volumiossh-tunnel[3637]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:51:00 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:51:00 volumio autossh[2082]: starting ssh (count 5) Jun 02 16:51:00 volumio autossh[2082]: ssh child pid is 3704 Jun 02 16:51:06 volumio go-librespot[3684]: time="2026-06-02T16:51:06-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 16:51:06 volumio volumio[1053]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET Jun 02 16:51:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 16:51:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 16:51:09 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:51:09 volumio volumio[1053]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 02 16:51:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jun 02 16:51:09 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:51:09 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:51:09 volumio go-librespot[3748]: go-librespot daemon starting... Jun 02 16:51:09 volumio go-librespot[3749]: time="2026-06-02T16:51:09-06:00" level=info msg="running go-librespot 0.7.1" Jun 02 16:51:09 volumio go-librespot[3749]: time="2026-06-02T16:51:09-06:00" level=debug msg="app state loaded" Jun 02 16:51:09 volumio go-librespot[3749]: time="2026-06-02T16:51:09-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 16:51:12 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:51:12 volumio volumiossh-tunnel[3704]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:51:12 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:51:12 volumio autossh[2082]: starting ssh (count 6) Jun 02 16:51:12 volumio autossh[2082]: ssh child pid is 3767 Jun 02 16:51:22 volumio go-librespot[3749]: time="2026-06-02T16:51:22-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 16:51:22 volumio volumio[1053]: info: Error connecting to go-librespot Websocket: Error: read ECONNRESET Jun 02 16:51:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 16:51:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 16:51:25 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:51:25 volumio volumio[1053]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 02 16:51:25 volumio volumiossh-tunnel[3767]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:51:25 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:51:25 volumio autossh[2082]: starting ssh (count 7) Jun 02 16:51:25 volumio autossh[2082]: ssh child pid is 3824 Jun 02 16:51:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jun 02 16:51:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:51:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 16:51:25 volumio go-librespot[3825]: go-librespot daemon starting... Jun 02 16:51:25 volumio go-librespot[3826]: time="2026-06-02T16:51:25-06:00" level=info msg="running go-librespot 0.7.1" Jun 02 16:51:25 volumio go-librespot[3826]: time="2026-06-02T16:51:25-06:00" level=debug msg="app state loaded" Jun 02 16:51:25 volumio go-librespot[3826]: time="2026-06-02T16:51:25-06:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 16:51:28 volumio volumio[1053]: info: Initializing connection to go-librespot Websocket Jun 02 16:51:28 volumio go-librespot[3826]: time="2026-06-02T16:51:28-06:00" level=debug msg="new websocket client" Jun 02 16:51:28 volumio volumio[1053]: info: Connection to go-librespot Websocket established Jun 02 16:51:31 volumio volumio[1053]: info: Getting Spotify volume Jun 02 16:51:31 volumio volumio[1053]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 02 16:51:31 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 02 16:51:37 volumio volumiossh-tunnel[3824]: ssh: Could not resolve hostname us2.myvolumio.org: Device or resource busy Jun 02 16:51:37 volumio go-librespot[3826]: time="2026-06-02T16:51:37-06:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jun 02 16:51:37 volumio autossh[2082]: ssh exited with error status 255; restarting ssh Jun 02 16:51:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 16:51:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 16:51:37 volumio volumio[1053]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:51:37 volumio volumio[1053]: Error: socket hang up Jun 02 16:51:37 volumio volumio[1053]: at connResetException (node:internal/errors:720:14) Jun 02 16:51:37 volumio volumio[1053]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 02 16:51:37 volumio volumio[1053]: at Socket.emit (node:events:526:35) Jun 02 16:51:37 volumio volumio[1053]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 02 16:51:37 volumio volumio[1053]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 02 16:51:37 volumio volumio[1053]: code: 'ECONNRESET', Jun 02 16:51:37 volumio volumio[1053]: response: undefined Jun 02 16:51:37 volumio volumio[1053]: } Jun 02 16:51:37 volumio volumio[1053]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:51:37 volumio sudo[3918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 16:50' Jun 02 16:51:37 volumio sudo[3918]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"