Jan 27 01:06:17 volumio go-librespot[1685]: time="2026-01-27T01:06:17-05:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: i/o timeout" Jan 27 01:06:19 volumio go-librespot[1685]: time="2026-01-27T01:06:19-05:00" level=error msg="did not receive last pong from dealer, 180s passed" Jan 27 01:06:29 volumio go-librespot[1685]: time="2026-01-27T01:06:29-05:00" level=debug msg="renewing login5 access token" Jan 27 01:06:47 volumio go-librespot[1685]: time="2026-01-27T01:06:47-05:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.241.202:443: i/o timeout" Jan 27 01:06:49 volumio go-librespot[1685]: time="2026-01-27T01:06:49-05:00" level=error msg="did not receive last pong from dealer, 210s passed" Jan 27 01:06:49 volumio go-librespot[1685]: time="2026-01-27T01:06:49-05:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" Jan 27 01:06:49 volumio go-librespot[1685]: panic: runtime error: invalid memory address or nil pointer dereference Jan 27 01:06:49 volumio go-librespot[1685]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x18 pc=0x8a63fb] Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876836 gp=0xc0005528c0 m=8 mp=0xc0003d1008 [running]: Jan 27 01:06:49 volumio go-librespot[1685]: panic({0xba9580?, 0x13f6450?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/panic.go:802 +0x168 fp=0xc000b98ed8 sp=0xc000b98e28 pc=0x4889e8 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.panicmem(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/panic.go:262 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.sigpanic() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/signal_unix.go:925 +0x359 fp=0xc000b98f38 sp=0xc000b98ed8 pc=0x48afb9 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0000a2690) Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:380 +0x1fb fp=0xc000b98fc8 sp=0xc000b98f38 pc=0x8a63fb Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:277 +0x25 fp=0xc000b98fe0 sp=0xc000b98fc8 pc=0x8a5905 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000b98fe8 sp=0xc000b98fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 876812 Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:277 +0xff Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 1 gp=0xc000002380 m=nil [select, 234 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000045c60?, 0x2?, 0x20?, 0x15?, 0xc000045bfc?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0001e5a70 sp=0xc0001e5a50 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc0001e5c60, 0xc000045bf8, 0x0?, 0x0, 0xbe7660?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0001e5bb0 sp=0xc0001e5a70 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc0003ac460, 0xc0001e5d80) Jan 27 01:06:49 volumio go-librespot[1685]: /src/zeroconf/zeroconf.go:283 +0x29a fp=0xc0001e5cf8 sp=0xc0001e5bb0 pc=0x9a379a Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).withAppPlayer(0xc0000c6b00, {0xe0b228, 0x1483020}, 0xc00030e3c0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:340 +0x590 fp=0xc0001e5e10 sp=0xc0001e5cf8 pc=0xa30810 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).withCredentials(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:209 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).SpotifyToken(0xc0000c6b00, {0xe0b228, 0x1483020}, {0xc0002c8ca0?, 0xce6b7b?}, {0xc0002e2140?, 0x2697?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:201 +0xc5 fp=0xc0001e5e68 sp=0xc0001e5e10 pc=0xa2fcc5 Jan 27 01:06:49 volumio go-librespot[1685]: main.main() Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:593 +0x745 fp=0xc0001e5f50 sp=0xc0001e5e68 pc=0xa32965 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.main() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:285 +0x29d fp=0xc0001e5fe0 sp=0xc0001e5f50 pc=0x45509d Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001e5fe8 sp=0xc0001e5fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 2 gp=0xc000002e00 m=nil [force gc (idle), 3 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xcd2ae3f6140?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005cfa8 sp=0xc00005cf88 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goparkunlock(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:466 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.forcegchelper() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:373 +0xb3 fp=0xc00005cfe0 sp=0xc00005cfa8 pc=0x4553d3 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005cfe8 sp=0xc00005cfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.init.7 in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:361 +0x1a Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 18 gp=0xc000102380 m=nil [GC sweep wait]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000058780 sp=0xc000058760 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goparkunlock(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:466 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.bgsweep(0xc000110000) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgcsweep.go:323 +0xdf fp=0xc0000587c8 sp=0xc000058780 pc=0x43f29f Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcenable.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:212 +0x25 fp=0xc0000587e0 sp=0xc0000587c8 pc=0x4332a5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000587e8 sp=0xc0000587e0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcenable in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:212 +0x66 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 19 gp=0xc000102540 m=nil [GC scavenge wait]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x10000?, 0x166d10a?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000058f78 sp=0xc000058f58 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goparkunlock(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:466 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.(*scavengerState).park(0x1461b00) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc000058fa8 sp=0xc000058f78 pc=0x43cd29 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.bgscavenge(0xc000110000) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc000058fc8 sp=0xc000058fa8 pc=0x43d2d9 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcenable.gowrap2() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:213 +0x25 fp=0xc000058fe0 sp=0xc000058fc8 pc=0x433245 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000058fe8 sp=0xc000058fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcenable in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:213 +0xa5 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 20 gp=0xc000102700 m=nil [GOMAXPROCS updater (idle), 234 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000059788 sp=0xc000059768 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goparkunlock(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:466 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.updateMaxProcsGoroutine() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:6720 +0xe7 fp=0xc0000597e0 sp=0xc000059788 pc=0x4632c7 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000597e8 sp=0xc0000597e0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:6708 +0x37 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 21 gp=0xc000102c40 m=nil [finalizer wait, 170 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x0?, 0xc00052a0d0?, 0x90?, 0x49?, 0x1000000010?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00006fe20 sp=0xc00006fe00 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.runFinalizers() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mfinal.go:210 +0x107 fp=0xc00006ffe0 sp=0xc00006fe20 pc=0x4321a7 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.createfing in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mfinal.go:172 +0x3d Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 34 gp=0xc00008a700 m=nil [cleanup wait, 105 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x7f0010101200?, 0xc0002dc790?, 0xb5?, 0x7a?, 0x7f0010101200?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0002dc768 sp=0xc0002dc748 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goparkunlock(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:466 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.(*cleanupQueue).dequeue(0x1461f40) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mcleanup.go:439 +0xc5 fp=0xc0002dc7a0 sp=0xc0002dc768 pc=0x42f3c5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.runCleanups() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mcleanup.go:635 +0x45 fp=0xc0002dc7e0 sp=0xc0002dc7a0 pc=0x42fa85 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0002dc7e8 sp=0xc0002dc7e0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mcleanup.go:589 +0xa5 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 37 gp=0xc00008aa80 m=nil [IO wait, 7 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000071a58 sp=0xc000071a38 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xc000071aa8?, 0x41f886?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000071a90 sp=0xc000071a58 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125e00, 0x72) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000071ab0 sp=0xc000071a90 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc0002b8680?, 0x900000036?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000071ad8 sp=0xc000071ab0 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitRead(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).Accept(0xc0002b8680) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000071b80 sp=0xc000071ad8 pc=0x50fc4c Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).accept(0xc0002b8680) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000071c38 sp=0xc000071b80 pc=0x57c449 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*TCPListener).accept(0xc00009eb80) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000071c88 sp=0xc000071c38 pc=0x591bbb Jan 27 01:06:49 volumio go-librespot[1685]: net.(*TCPListener).Accept(0xc00009eb80) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000071cb8 sp=0xc000071c88 pc=0x590d90 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*onceCloseListener).Accept(0xc000236120?) Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x24 fp=0xc000071cd0 sp=0xc000071cb8 pc=0x73aaa4 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Server).Serve(0xc000398000, {0xe0a190, 0xc00009eb80}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000071e00 sp=0xc000071cd0 pc=0x712a0c Jan 27 01:06:49 volumio go-librespot[1685]: net/http.Serve(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2971 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*ConcreteApiServer).serve(0xc0000fa3f0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/api_server.go:666 +0x90c fp=0xc000071fc8 sp=0xc000071e00 pc=0xa25c4c Jan 27 01:06:49 volumio go-librespot[1685]: main.NewApiServer.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/api_server.go:332 +0x25 fp=0xc000071fe0 sp=0xc000071fc8 pc=0xa24985 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by main.NewApiServer in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/api_server.go:332 +0x2ff Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 88 gp=0xc00008ae00 m=nil [select, 143 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc00061bf40?, 0x5?, 0x89?, 0x6e?, 0xc00061bee6?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00061bd68 sp=0xc00061bd48 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc00061bf40, 0xc00061bedc, 0x486405?, 0x0, 0xaf6301?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00061bea8 sp=0xc00061bd68 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc00038c820) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x196 fp=0xc00061bfc8 sp=0xc00061bea8 pc=0x847556 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.newConn.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x25 fp=0xc00061bfe0 sp=0xc00061bfc8 pc=0x847165 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00061bfe8 sp=0xc00061bfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by nhooyr.io/websocket.newConn in goroutine 86 Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x4c8 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 22 gp=0xc00049e380 m=nil [GC worker (idle), 172 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x37c8899f11b?, 0x3?, 0xb7?, 0xc2?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000777738 sp=0xc000777718 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkWorker(0xc000119340) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc0007777c8 sp=0xc000777738 pc=0x43598b Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc0007777e0 sp=0xc0007777c8 pc=0x435865 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0007777e8 sp=0xc0007777e0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 23 gp=0xc00049e540 m=nil [GC worker (idle), 12 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x14848c0?, 0x1?, 0x69?, 0xde?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0006bff38 sp=0xc0006bff18 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkWorker(0xc000119340) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc0006bffc8 sp=0xc0006bff38 pc=0x43598b Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc0006bffe0 sp=0xc0006bffc8 pc=0x435865 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0006bffe8 sp=0xc0006bffe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 24 gp=0xc00049e700 m=nil [GC worker (idle)]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x14848c0?, 0x1?, 0xdd?, 0x75?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000618f38 sp=0xc000618f18 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkWorker(0xc000119340) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc000618fc8 sp=0xc000618f38 pc=0x43598b Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc000618fe0 sp=0xc000618fc8 pc=0x435865 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000618fe8 sp=0xc000618fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 25 gp=0xc00049e8c0 m=nil [GC worker (idle), 172 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x37c8899e76a?, 0x3?, 0xc1?, 0x75?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000766f38 sp=0xc000766f18 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkWorker(0xc000119340) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc000766fc8 sp=0xc000766f38 pc=0x43598b Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc000766fe0 sp=0xc000766fc8 pc=0x435865 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000766fe8 sp=0xc000766fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by runtime.gcBgMarkStartWorkers in goroutine 3 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 50 gp=0xc00008a540 m=nil [IO wait]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000466d58?, 0x486a25?, 0xc0?, 0xe4?, 0xc?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000466d28 sp=0xc000466d08 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xe01da0?, 0x1411cb0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000466d60 sp=0xc000466d28 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125800, 0x72) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000466d80 sp=0xc000466d60 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc0002b8380?, 0x7f0011120ac8?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000466da8 sp=0xc000466d80 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitRead(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).RawRead(0xc0002b8380, 0xc0005b7540) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x125 fp=0xc000466e08 sp=0xc000466da8 pc=0x5108c5 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*rawConn).Read(0xc000060008, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000466e40 sp=0xc000466e08 pc=0x58ccf6 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc000514140, 0xc000258a80, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x125 fp=0xc000466ea0 sp=0xc000466e40 pc=0x941a85 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc00038a880, {0xc000326000, 0x10000, 0x10000}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x225 fp=0xc000466f48 sp=0xc000466ea0 pc=0x945265 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/grandcat/zeroconf.(*Server).recv4(0xc000380780, 0xc00038a870) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xc7 fp=0xc000466fc0 sp=0xc000466f48 pc=0x99e627 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc000466fe0 sp=0xc000466fc0 pc=0x99e2c5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000466fe8 sp=0xc000466fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 5 Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 51 gp=0xc00008b6c0 m=nil [IO wait, 234 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x43c21c?, 0x14848c0?, 0x30?, 0xc0?, 0xc0002db530?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000465ce0 sp=0xc000465cc0 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xe01da0?, 0x1411cb0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000465d18 sp=0xc000465ce0 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125600, 0x72) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000465d38 sp=0xc000465d18 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc0002b8400?, 0x7f00595bc910?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000465d60 sp=0xc000465d38 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitRead(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).RawRead(0xc0002b8400, 0xc0002bc000) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x125 fp=0xc000465dc0 sp=0xc000465d60 pc=0x5108c5 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*rawConn).Read(0xc0000600b0, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000465df8 sp=0xc000465dc0 pc=0x58ccf6 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc000514160, 0xc0001161e0, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x125 fp=0xc000465e58 sp=0xc000465df8 pc=0x941a85 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc00038a8d0, {0xc000316000, 0x10000, 0x10000}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x265 fp=0xc000465f48 sp=0xc000465e58 pc=0x948725 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/grandcat/zeroconf.(*Server).recv6(0xc000380780, 0xc00038a8c0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xc7 fp=0xc000465fc0 sp=0xc000465f48 pc=0x99e7e7 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000465fe0 sp=0xc000465fc0 pc=0x99e265 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000465fe8 sp=0xc000465fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 5 Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876851 gp=0xc000103dc0 m=nil [select]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc0001eb0a0?, 0x2?, 0xc0?, 0x3d?, 0xc0001eafd4?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0001eae58 sp=0xc0001eae38 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc0001eb0a0, 0xc0001eafd0, 0x16?, 0x0, 0x416e00?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0001eaf98 sp=0xc0001eae58 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).getConn(0x14090e0, 0xc000178730, {{}, 0x0, {0xcf529f, 0x5}, {0xc00003a090, 0x16}, 0x0}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1523 +0x4ad fp=0xc0001eb170 sp=0xc0001eaf98 pc=0x722cad Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).roundTrip(0x14090e0, 0xc00019c3c0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:685 +0xa54 fp=0xc0001eb350 sp=0xc0001eb170 pc=0x71e9b4 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).RoundTrip(0xc00019c3c0?, 0xe02500?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/roundtrip.go:33 +0x18 fp=0xc0001eb370 sp=0xc0001eb350 pc=0x72dcd8 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.send(0xc00019c280, {0xe02500, 0x14090e0}, {0xc0001eb501?, 0x4b9773?, 0x1461860?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/client.go:259 +0x5e2 fp=0xc0001eb560 sp=0xc0001eb370 pc=0x6c1da2 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Client).send(0xc000302450, 0xc00019c280, {0xc0001eb600?, 0x0?, 0x1461860?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/client.go:180 +0x91 fp=0xc0001eb5d8 sp=0xc0001eb560 pc=0x6c1651 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Client).do(0xc000302450, 0xc00019c280) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/client.go:729 +0x9c9 fp=0xc0001eb7c8 sp=0xc0001eb5d8 pc=0x6c3b69 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Client).Do(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/client.go:587 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/login5.(*Login5).request(0xc0005b9600, {0xe0b228, 0x1483020}, 0xc0001eba90?) Jan 27 01:06:49 volumio go-librespot[1685]: /src/login5/login5.go:72 +0x568 fp=0xc0001eba18 sp=0xc0001eb7c8 pc=0x9393c8 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/login5.(*Login5).Login(0xc0005b9600, {0xe0b228, 0x1483020}, {0xe036c0, 0xc000178640}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/login5/login5.go:122 +0x47e fp=0xc0001ebaf8 sp=0xc0001eba18 pc=0x939b9e Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/session.NewSessionFromOptions.(*Login5).AccessToken.func2({0xe0b228, 0x1483020}, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/login5/login5.go:200 +0x1da fp=0xc0001ebbd8 sp=0xc0001ebaf8 pc=0x940a1a Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).connect(0xc00070e2a0, {0xe0b228, 0x1483020}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:82 +0xd3 fp=0xc0001ebce0 sp=0xc0001ebbd8 pc=0x92de93 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect(0xc00070e2a0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:288 +0x2a fp=0xc0001ebd28 sp=0xc0001ebce0 pc=0x92f34a Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect-fm() Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x25 fp=0xc0001ebd40 sp=0xc0001ebd28 pc=0x930925 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x13 fp=0xc0001ebd50 sp=0xc0001ebd40 pc=0x899b73 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc0001ebe78?, {0xe08da8, 0xc000562de0}, 0x0, {0x0, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0x11c fp=0xc0001ebe40 sp=0xc0001ebd50 pc=0x899f5c Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x0?, {0xe08da8?, 0xc000562de0?}, 0x0?, {0x0?, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x56 fp=0xc0001ebe98 sp=0xc0001ebe40 pc=0x899af6 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.Retry(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc00070e2a0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:237 +0x550 fp=0xc0001ebfc8 sp=0xc0001ebe98 pc=0x92ee70 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:296 +0x25 fp=0xc0001ebfe0 sp=0xc0001ebfc8 pc=0x92f485 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001ebfe8 sp=0xc0001ebfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 876837 Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:296 +0xea Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 86 gp=0xc00049e000 m=nil [IO wait, 234 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc0001e9310?, 0x925374?, 0x45?, 0x66?, 0xb?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0001e92e0 sp=0xc0001e92c0 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0x4ac5d8?, 0x41f886?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc0001e9318 sp=0xc0001e92e0 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7efffb74d000, 0x72) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc0001e9338 sp=0xc0001e9318 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc000392380?, 0xc0005f8000?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0001e9360 sp=0xc0001e9338 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitRead(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).Read(0xc000392380, {0xc0005f8000, 0x1000, 0x1000}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x279 fp=0xc0001e93f8 sp=0xc0001e9360 pc=0x50bb19 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).Read(0xc000392380, {0xc0005f8000?, 0x488f0e?, 0x1?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_posix.go:68 +0x25 fp=0xc0001e9440 sp=0xc0001e93f8 pc=0x57a5a5 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*conn).Read(0xc000060160, {0xc0005f8000?, 0x2?, 0x45ec3f?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/net.go:196 +0x45 fp=0xc0001e9488 sp=0xc0001e9440 pc=0x588ac5 Jan 27 01:06:49 volumio go-librespot[1685]: io.(*multiReader).Read(0xc0001367f8, {0xc0005f8000, 0x1000, 0x1000}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0001e94d0 sp=0xc0001e9488 pc=0x4c9a73 Jan 27 01:06:49 volumio go-librespot[1685]: bufio.(*Reader).fill(0xc000380840) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/bufio/bufio.go:113 +0x103 fp=0xc0001e9508 sp=0xc0001e94d0 pc=0x68e1c3 Jan 27 01:06:49 volumio go-librespot[1685]: bufio.(*Reader).ReadByte(0xc000380840) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/bufio/bufio.go:273 +0x27 fp=0xc0001e9528 sp=0xc0001e9508 pc=0x68e8e7 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.readFrameHeader(0xc000380840, {0xc00038c888, 0x8, 0x8}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x85 fp=0xc0001e95b8 sp=0xc0001e9528 pc=0x84aa65 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc00038c820, {0xe0b1f0, 0x1483020}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xd6 fp=0xc0001e96f8 sp=0xc0001e95b8 pc=0x84c6b6 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).readLoop(0xc00038c820, {0xe0b1f0, 0x1483020}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x65 fp=0xc0001e9858 sp=0xc0001e96f8 pc=0x84c025 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).reader(0xc00038c820, {0xe0b1f0, 0x1483020}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0x10f fp=0xc0001e9948 sp=0xc0001e9858 pc=0x84d4af Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 27 01:06:49 volumio go-librespot[1685]: nhooyr.io/websocket.(*Conn).Read(0xc0000fa468?, {0xe0b1f0?, 0x1483020?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x1e fp=0xc0001e9978 sp=0xc0001e9948 pc=0x84b67e Jan 27 01:06:49 volumio go-librespot[1685]: main.(*ConcreteApiServer).serve.func17({0xe0a310, 0xc0000a24b0}, 0xc000447410?) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/api_server.go:636 +0x3ac fp=0xc0001e9a68 sp=0xc0001e9978 pc=0xa2640c Jan 27 01:06:49 volumio go-librespot[1685]: net/http.HandlerFunc.ServeHTTP(0xc00007e000?, {0xe0a310?, 0xc0000a24b0?}, 0x2000000000000?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc0001e9a90 sp=0xc0001e9a68 pc=0x70f109 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*ServeMux).ServeHTTP(0xc0003841a0?, {0xe0a310, 0xc0000a24b0}, 0xc00019c500) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2861 +0x1c7 fp=0xc0001e9ae0 sp=0xc0001e9a90 pc=0x710fe7 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xe0a310, 0xc0000a24b0}, 0xc00019c500) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x164 fp=0xc0001e9b38 sp=0xc0001e9ae0 pc=0xa25e84 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.HandlerFunc.ServeHTTP(0x485cb9?, {0xe0a310?, 0xc0000a24b0?}, 0xc000469b68?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc0001e9b60 sp=0xc0001e9b38 pc=0x70f109 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.serverHandler.ServeHTTP({0xc00038ea80?}, {0xe0a310?, 0xc0000a24b0?}, 0x1?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:3340 +0x8e fp=0xc0001e9b90 sp=0xc0001e9b60 pc=0x72e58e Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*conn).serve(0xc0002743f0, {0xe0b260, 0xc0000364e0}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2109 +0x665 fp=0xc0001e9fb8 sp=0xc0001e9b90 pc=0x70d205 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Server).Serve.gowrap3() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:3493 +0x28 fp=0xc0001e9fe0 sp=0xc0001e9fb8 pc=0x712e08 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001e9fe8 sp=0xc0001e9fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by net/http.(*Server).Serve in goroutine 37 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:3493 +0x485 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 73 gp=0xc00049ee00 m=nil [chan receive, 7 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc0000ac9c0?, 0xc0003812c0?, 0x0?, 0x0?, 0xc0002dfee8?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0002dfe98 sp=0xc0002dfe78 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.chanrecv(0xc0002ca230, 0xc0002dff98, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc0002dff10 sp=0xc0002dfe98 pc=0x422773 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.chanrecv2(0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc0002dff38 sp=0xc0002dff10 pc=0x4222f2 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).withAppPlayer.func1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:284 +0x72 fp=0xc0002dffe0 sp=0xc0002dff38 pc=0xa31372 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0002dffe8 sp=0xc0002dffe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by main.(*App).withAppPlayer in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:281 +0x45f Jan 27 01:06:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 27 01:06:49 volumio volumio[1029]: info: Connection to go-librespot Websocket closed Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 74 gp=0xc00049efc0 m=nil [chan receive, 143 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000345ee0?, 0x91ec3c?, 0x0?, 0x47?, 0x5?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000b9ae98 sp=0xc000b9ae78 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.chanrecv(0xc0002ca1c0, 0xc000345fb8, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc000b9af10 sp=0xc000b9ae98 pc=0x422773 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.chanrecv2(0xc00021f400?, 0xcf687c?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc000b9af38 sp=0xc000b9af10 pc=0x4222f2 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).withAppPlayer.func2() Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:300 +0x87 fp=0xc000b9afe0 sp=0xc000b9af38 pc=0xa30fa7 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000b9afe8 sp=0xc000b9afe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by main.(*App).withAppPlayer in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:297 +0x51f Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 75 gp=0xc00049f180 m=nil [IO wait, 172 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000072be0 sp=0xc000072bc0 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xc000072c30?, 0x41f886?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000072c18 sp=0xc000072be0 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125a00, 0x72) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000072c38 sp=0xc000072c18 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc0002b8080?, 0x900000036?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000072c60 sp=0xc000072c38 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitRead(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).Accept(0xc0002b8080) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000072d08 sp=0xc000072c60 pc=0x50fc4c Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).accept(0xc0002b8080) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000072dc0 sp=0xc000072d08 pc=0x57c449 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*TCPListener).accept(0xc000114900) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000072e10 sp=0xc000072dc0 pc=0x591bbb Jan 27 01:06:49 volumio go-librespot[1685]: net.(*TCPListener).Accept(0xc000114900) Jan 27 01:06:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000072e40 sp=0xc000072e10 pc=0x590d90 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*onceCloseListener).Accept(0xc00117efc0?) Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x24 fp=0xc000072e58 sp=0xc000072e40 pc=0x73aaa4 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Server).Serve(0xc0000c6800, {0xe0a190, 0xc000114900}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000072f88 sp=0xc000072e58 pc=0x712a0c Jan 27 01:06:49 volumio go-librespot[1685]: net/http.Serve(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/server.go:2971 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 27 01:06:49 volumio go-librespot[1685]: /src/zeroconf/zeroconf.go:280 +0x85 fp=0xc000072fe0 sp=0xc000072f88 pc=0x9a38a5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000072fe8 sp=0xc000072fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 27 01:06:49 volumio go-librespot[1685]: /src/zeroconf/zeroconf.go:280 +0x156 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876979 gp=0xc00054aa80 m=nil [select]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000616fa0?, 0x2?, 0x0?, 0x0?, 0xc000616f7c?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000616e08 sp=0xc000616de8 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc000616fa0, 0xc000616f78, 0x58a59c?, 0x0, 0x12?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000616f48 sp=0xc000616e08 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).connect.func2() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:107 +0x7a fp=0xc000616fe0 sp=0xc000616f48 pc=0x57c1da Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000616fe8 sp=0xc000616fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by net.(*netFD).connect in goroutine 876978 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:106 +0x345 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876835 gp=0xc000550e00 m=nil [IO wait]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x40?, 0xc00034e008?, 0xc0?, 0xe7?, 0xc000346fd8?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000346f90 sp=0xc000346f70 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xc000347000?, 0x45f23f?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000346fc8 sp=0xc000346f90 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125400, 0x77) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000346fe8 sp=0xc000346fc8 pc=0x4880e5 Jan 27 01:06:49 volumio systemd[1]: go-librespot-daemon.service: Consumed 3min 13.371s CPU time. Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc00065c380?, 0x0?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000347010 sp=0xc000346fe8 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitWrite(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).WaitWrite(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:685 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).connect(0xc00065c380, {0xe0b308, 0xc0004128c0}, {0x42a214?, 0xc000000022?}, {0xe01e20?, 0xc0000163e0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:130 +0x6b6 fp=0xc000347188 sp=0xc000347010 pc=0x57bd56 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).dial(0xc00065c380, {0xe0b308, 0xc0004128c0}, {0xe0d4b8?, 0x0?}, {0xe0d4b8, 0xc0005c30e0}, 0x43c0e5?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/sock_posix.go:124 +0x3bc fp=0xc000347260 sp=0xc000347188 pc=0x58df1c Jan 27 01:06:49 volumio go-librespot[1685]: net.socket({0xe0b308, 0xc0004128c0}, {0xce44e8, 0x3}, 0x2, 0x1, 0xc000347620?, 0x0, {0xe0d4b8, 0x0}, ...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/sock_posix.go:70 +0x29b fp=0xc000347308 sp=0xc000347260 pc=0x58da5b Jan 27 01:06:49 volumio go-librespot[1685]: net.internetSocket({0xe0b308, 0xc0004128c0}, {0xce44e8, 0x3}, {0xe0d4b8, 0x0}, {0xe0d4b8, 0xc0005c30e0}, 0x1, 0x0, ...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/ipsock_posix.go:167 +0xf8 fp=0xc000347380 sp=0xc000347308 pc=0x585f58 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).doDialTCPProto(0xc0000e83c0, {0xe0b308, 0xc0004128c0}, 0x0, 0xc0005c30e0, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:85 +0xe7 fp=0xc000347430 sp=0xc000347380 pc=0x5916e7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).doDialTCP(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:75 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialTCP(0x13f53c0?, {0xe0b308?, 0xc0004128c0?}, 0xc000347668?, 0xc000347508?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:71 +0x69 fp=0xc000347470 sp=0xc000347430 pc=0x591589 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialSingle(0xc0000e83c0, {0xe0b308, 0xc0004128c0}, {0xe08180, 0xc0005c30e0}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:721 +0x3ce fp=0xc000347540 sp=0xc000347470 pc=0x572bee Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialSerial(0xc0000e83c0, {0xe0b308, 0xc0004128c0}, {0xc00052a950?, 0x1, 0x5856be?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:686 +0x248 fp=0xc000347648 sp=0xc000347540 pc=0x5723c8 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialParallel(0x0?, {0xe0b308?, 0xc0004128c0?}, {0xc00052a950?, 0xbc55c0?, 0xce48f8?}, {0x0?, 0xce44e8?, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:587 +0x30a fp=0xc000347860 sp=0xc000347648 pc=0x571aaa Jan 27 01:06:49 volumio go-librespot[1685]: net.(*Dialer).DialContext(0xc000347a08, {0xe0b308, 0xc0004128c0}, {0xce44e8, 0x3}, {0xc0002c6ca8, 0x16}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:578 +0x6a9 fp=0xc0003479d0 sp=0xc000347860 pc=0x571389 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/proxy.direct.DialContext(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/proxy.(*direct).DialContext(0x13fc020?, {0xe0b308?, 0xc0004128c0?}, {0xce44e8?, 0x1483020?}, {0xc0002c6ca8?, 0x6fc23ac00?}) Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x85 fp=0xc000347aa8 sp=0xc0003479d0 pc=0x8a1765 Jan 27 01:06:49 volumio go-librespot[1685]: golang.org/x/net/proxy.Dial({0xe0b308, 0xc0004128c0}, {0xce44e8, 0x3}, {0xc0002c6ca8, 0x16}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0x98 fp=0xc000347b08 sp=0xc000347aa8 pc=0x89f658 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0xc0000a2690, {0xe0b228, 0x1483020}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:95 +0x248 fp=0xc000347be0 sp=0xc000347b08 pc=0x8a3e68 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0xc0000a2690, {0xe0b228, 0x1483020}, 0xc0001780f0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:205 +0xc8 fp=0xc000347c58 sp=0xc000347be0 pc=0x8a4f48 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0xc0000a2690) Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:394 +0xc7 fp=0xc000347ca8 sp=0xc000347c58 pc=0x8a6527 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x25 fp=0xc000347cc0 sp=0xc000347ca8 pc=0x8aa185 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x13 fp=0xc000347cd0 sp=0xc000347cc0 pc=0x899b73 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc000347df8?, {0xe08da8, 0xc0003d4180}, 0x0, {0x0, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0x11c fp=0xc000347dc0 sp=0xc000347cd0 pc=0x899f5c Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x0?, {0xe08da8?, 0xc0003d4180?}, 0x0?, {0x0?, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x56 fp=0xc000347e18 sp=0xc000347dc0 pc=0x899af6 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/cenkalti/backoff/v4.Retry(...) Jan 27 01:06:49 volumio go-librespot[1685]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0000a2690) Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:335 +0x451 fp=0xc000347fc8 sp=0xc000347e18 pc=0x8a5df1 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:273 +0x25 fp=0xc000347fe0 sp=0xc000347fc8 pc=0x8a5965 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000347fe8 sp=0xc000347fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 876812 Jan 27 01:06:49 volumio go-librespot[1685]: /src/ap/ap.go:273 +0x78 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876838 gp=0xc000551dc0 m=nil [select]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000b9ef98?, 0x2?, 0x20?, 0x89?, 0xc000b9ef44?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000b9edc8 sp=0xc000b9eda8 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc000b9ef98, 0xc000b9ef40, 0x0?, 0x0, 0xc0004a46c0?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000b9ef08 sp=0xc000b9edc8 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc00070e2a0) Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:138 +0x8f fp=0xc000b9efc8 sp=0xc000b9ef08 pc=0x92e5cf Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:129 +0x25 fp=0xc000b9efe0 sp=0xc000b9efc8 pc=0x92e4a5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000b9efe8 sp=0xc000b9efe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 876812 Jan 27 01:06:49 volumio go-librespot[1685]: /src/dealer/dealer.go:129 +0xf6 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876982 gp=0xc000553500 m=nil [select]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000b9cfa0?, 0x2?, 0xe0?, 0xb?, 0xc000b9cf7c?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000b9ce08 sp=0xc000b9cde8 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc000b9cfa0, 0xc000b9cf78, 0x1?, 0x0, 0xc0003a2058?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000b9cf48 sp=0xc000b9ce08 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).connect.func2() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:107 +0x7a fp=0xc000b9cfe0 sp=0xc000b9cf48 pc=0x57c1da Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000b9cfe8 sp=0xc000b9cfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by net.(*netFD).connect in goroutine 876835 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:106 +0x345 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876978 gp=0xc0005d1a40 m=nil [IO wait]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0x40?, 0xc0003d1008?, 0x40?, 0xe6?, 0xc000b9f898?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000b9f850 sp=0xc000b9f830 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.netpollblock(0xc000b9f8c0?, 0x45f23f?, 0x0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000b9f888 sp=0xc000b9f850 pc=0x44d977 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.runtime_pollWait(0x7f0011125000, 0x77) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000b9f8a8 sp=0xc000b9f888 pc=0x4880e5 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).wait(0xc00065c300?, 0x0?, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000b9f8d0 sp=0xc000b9f8a8 pc=0x50a827 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*pollDesc).waitWrite(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:93 Jan 27 01:06:49 volumio go-librespot[1685]: internal/poll.(*FD).WaitWrite(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/internal/poll/fd_unix.go:685 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).connect(0xc00065c300, {0xe0b298, 0xc000178af0}, {0x42a214?, 0xb5e3c0?}, {0xe01e20?, 0xc0000162e0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/fd_unix.go:130 +0x6b6 fp=0xc000b9fa48 sp=0xc000b9f8d0 pc=0x57bd56 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*netFD).dial(0xc00065c300, {0xe0b298, 0xc000178af0}, {0xe0d4b8?, 0x0?}, {0xe0d4b8, 0xc0005c2b40}, 0xc000b9fb88?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/sock_posix.go:124 +0x3bc fp=0xc000b9fb20 sp=0xc000b9fa48 pc=0x58df1c Jan 27 01:06:49 volumio go-librespot[1685]: net.socket({0xe0b298, 0xc000178af0}, {0xce44e8, 0x3}, 0x2, 0x1, 0x3?, 0x0, {0xe0d4b8, 0x0}, ...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/sock_posix.go:70 +0x29b fp=0xc000b9fbc8 sp=0xc000b9fb20 pc=0x58da5b Jan 27 01:06:49 volumio go-librespot[1685]: net.internetSocket({0xe0b298, 0xc000178af0}, {0xce44e8, 0x3}, {0xe0d4b8, 0x0}, {0xe0d4b8, 0xc0005c2b40}, 0x1, 0x0, ...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/ipsock_posix.go:167 +0xf8 fp=0xc000b9fc40 sp=0xc000b9fbc8 pc=0x585f58 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).doDialTCPProto(0xc0000e8300, {0xe0b298, 0xc000178af0}, 0x0, 0xc0005c2b40, 0x0) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:85 +0xe7 fp=0xc000b9fcf0 sp=0xc000b9fc40 pc=0x5916e7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).doDialTCP(...) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:75 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialTCP(0x485cb9?, {0xe0b298?, 0xc000178af0?}, 0xb5e3c0?, 0xc000b9fdc8?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/tcpsock_posix.go:71 +0x69 fp=0xc000b9fd30 sp=0xc000b9fcf0 pc=0x591589 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialSingle(0xc0000e8300, {0xe0b298, 0xc000178af0}, {0xe08180, 0xc0005c2b40}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:721 +0x3ce fp=0xc000b9fe00 sp=0xc000b9fd30 pc=0x572bee Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialSerial(0xc0000e8300, {0xe0b298, 0xc000178af0}, {0xc00052a780?, 0x1, 0x56a44e?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:686 +0x248 fp=0xc000b9ff08 sp=0xc000b9fe00 pc=0x5723c8 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialParallel.func1({0xe0b298?, 0xc000178af0?}, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:606 +0x85 fp=0xc000b9ffb8 sp=0xc000b9ff08 pc=0x572025 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialParallel.gowrap2() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:621 +0x2d fp=0xc000b9ffe0 sp=0xc000b9ffb8 pc=0x571f6d Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000b9ffe8 sp=0xc000b9ffe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by net.(*sysDialer).dialParallel in goroutine 876895 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:621 +0x2a8 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876895 gp=0xc001018380 m=nil [select]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc00034b790?, 0x2?, 0x0?, 0x0?, 0xc00034b624?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00034b4b0 sp=0xc00034b490 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc00034b790, 0xc00034b620, 0xc0005c2b70?, 0x0, 0xc00034b6f8?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00034b5f0 sp=0xc00034b4b0 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*sysDialer).dialParallel(0xc0000e8300, {0xe0b308, 0xc000412540}, {0xc00052a780, 0x1, 0x1}, {0xc00052a790, 0x1, 0x1}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:628 +0x407 fp=0xc00034b808 sp=0xc00034b5f0 pc=0x571ba7 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*Dialer).DialContext(0xc0000fa1b0, {0xe0b298, 0xc000178780}, {0xce44e8, 0x3}, {0xc00003a090, 0x16}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/dial.go:578 +0x6a9 fp=0xc00034b978 sp=0xc00034b808 pc=0x571389 Jan 27 01:06:49 volumio go-librespot[1685]: net.(*Dialer).DialContext-fm({0xe0b298?, 0xc000178780?}, {0xce44e8?, 0xcce220?}, {0xc00003a090?, 0xc000b9fa18?}) Jan 27 01:06:49 volumio go-librespot[1685]: :1 +0x49 fp=0xc00034b9c0 sp=0xc00034b978 pc=0x73bba9 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).dial(0xce4e51?, {0xe0b298?, 0xc000178780?}, {0xce44e8?, 0x0?}, {0xc00003a090?, 0x0?}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1278 +0xd2 fp=0xc00034ba28 sp=0xc00034b9c0 pc=0x721cf2 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).dialConn(0x14090e0, {0xe0b298, 0xc000178780}, {{}, 0x0, {0xcf529f, 0x5}, {0xc00003a090, 0x16}, 0x0}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1783 +0x7e5 fp=0xc00034bee0 sp=0xc00034ba28 pc=0x724ca5 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).dialConnFor(0x14090e0, 0xc000039b80) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1618 +0xb8 fp=0xc00034bf90 sp=0xc00034bee0 pc=0x7237b8 Jan 27 01:06:49 volumio go-librespot[1685]: net/http.(*Transport).startDialConnForLocked.func1() Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1600 +0x35 fp=0xc00034bfe0 sp=0xc00034bf90 pc=0x7235f5 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00034bfe8 sp=0xc00034bfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by net/http.(*Transport).startDialConnForLocked in goroutine 876851 Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/net/http/transport.go:1599 +0x112 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876812 gp=0xc00048d880 m=nil [select, 7 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc000721ed0?, 0xa?, 0x60?, 0x13?, 0xc0007216c0?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000721318 sp=0xc0007212f8 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc000721ed0, 0xc0007216ac, 0x1483020?, 0x0, 0xc?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000721458 sp=0xc000721318 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*AppPlayer).Run(0xc0004a1e00, {0xe0b228, 0x1483020}, 0xc00021ddc0, 0xc00021de30) Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/player.go:661 +0x427 fp=0xc000721fa8 sp=0xc000721458 pc=0xa38667 Jan 27 01:06:49 volumio go-librespot[1685]: main.(*App).withAppPlayer.func2.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:328 +0x30 fp=0xc000721fe0 sp=0xc000721fa8 pc=0xa312d0 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000721fe8 sp=0xc000721fe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by main.(*App).withAppPlayer.func2 in goroutine 74 Jan 27 01:06:49 volumio go-librespot[1685]: /src/cmd/daemon/main.go:328 +0x2a8 Jan 27 01:06:49 volumio go-librespot[1685]: goroutine 876811 gp=0xc0006bcc40 m=nil [select, 143 minutes]: Jan 27 01:06:49 volumio go-librespot[1685]: runtime.gopark(0xc00062bf20?, 0x3?, 0x70?, 0x92?, 0xc00062beda?) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00062bd60 sp=0xc00062bd40 pc=0x488f0e Jan 27 01:06:49 volumio go-librespot[1685]: runtime.selectgo(0xc00062bf20, 0xc00062bed4, 0x0?, 0x0, 0x0?, 0x1) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00062bea0 sp=0xc00062bd60 pc=0x467bd7 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc000716180) Jan 27 01:06:49 volumio go-librespot[1685]: /src/player/player.go:215 +0x205 fp=0xc00062bfc8 sp=0xc00062bea0 pc=0x911e65 Jan 27 01:06:49 volumio go-librespot[1685]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 27 01:06:49 volumio go-librespot[1685]: /src/player/player.go:197 +0x25 fp=0xc00062bfe0 sp=0xc00062bfc8 pc=0x911a25 Jan 27 01:06:49 volumio go-librespot[1685]: runtime.goexit({}) Jan 27 01:06:49 volumio go-librespot[1685]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00062bfe8 sp=0xc00062bfe0 pc=0x490a41 Jan 27 01:06:49 volumio go-librespot[1685]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 74 Jan 27 01:06:49 volumio go-librespot[1685]: /src/player/player.go:197 +0x1e5 Jan 27 01:06:49 volumio go-librespot[1683]: Aborted Jan 27 01:06:52 volumio volumio[1029]: info: Initializing connection to go-librespot Websocket Jan 27 01:06:52 volumio volumio[1029]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 27 01:06:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jan 27 01:06:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 27 01:06:52 volumio systemd[1]: go-librespot-daemon.service: Consumed 3min 13.371s CPU time. Jan 27 01:06:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 27 01:06:52 volumio go-librespot[37464]: go-librespot daemon starting... Jan 27 01:06:52 volumio go-librespot[37465]: time="2026-01-27T01:06:52-05:00" level=info msg="running go-librespot 0.6.2" Jan 27 01:06:52 volumio go-librespot[37465]: time="2026-01-27T01:06:52-05:00" level=debug msg="app state loaded" Jan 27 01:06:52 volumio go-librespot[37465]: time="2026-01-27T01:06:52-05:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 27 01:06:55 volumio volumio[1029]: info: Initializing connection to go-librespot Websocket Jan 27 01:06:55 volumio go-librespot[37465]: time="2026-01-27T01:06:55-05:00" level=debug msg="new websocket client" Jan 27 01:06:55 volumio volumio[1029]: info: Connection to go-librespot Websocket established Jan 27 01:06:58 volumio volumio[1029]: info: Getting Spotify volume Jan 27 01:06:58 volumio volumio[1029]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 27 01:06:58 volumio volumio[1029]: info: CoreCommandRouter::volumioGetState Jan 27 01:07:13 volumio volumio[1029]: info: CorePlayQueue::getTrack 58 Jan 27 01:07:13 volumio volumio[1029]: info: CorePlayQueue::getTrack 59 Jan 27 01:07:13 volumio volumio[1029]: info: Prefetching next song Jan 27 01:07:13 volumio volumio[1029]: info: [1769494033077] ControllerSpotify::prefetch Jan 27 01:07:13 volumio volumio[1029]: info: Sending Spotify command with payload to local API: /player/add_to_queue Jan 27 01:07:17 volumio volumio[1029]: info: CoreStateMachine::startPlaybackTimer Jan 27 01:07:17 volumio volumio[1029]: info: CorePlayQueue::getTrack 59 Jan 27 01:07:18 volumio volumio[1029]: info: CoreStateMachine::pushState Jan 27 01:07:18 volumio volumio[1029]: info: CoreCommandRouter::volumioPushState Jan 27 01:07:22 volumio go-librespot[37465]: time="2026-01-27T01:07:22-05: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\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Jan 27 01:07:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 27 01:07:22 volumio volumio[1029]: error: Failed to send command to Spotify local API: /player/add_to_queue: Error: socket hang up Jan 27 01:07:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 27 01:07:22 volumio volumio[1029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 01:07:22 volumio volumio[1029]: Error: socket hang up Jan 27 01:07:22 volumio volumio[1029]: at connResetException (node:internal/errors:720:14) Jan 27 01:07:22 volumio volumio[1029]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 27 01:07:22 volumio volumio[1029]: at Socket.emit (node:events:526:35) Jan 27 01:07:22 volumio volumio[1029]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 27 01:07:22 volumio volumio[1029]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 27 01:07:22 volumio volumio[1029]: code: 'ECONNRESET', Jan 27 01:07:22 volumio volumio[1029]: response: undefined Jan 27 01:07:22 volumio volumio[1029]: } Jan 27 01:07:22 volumio volumio[1029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 01:07:22 volumio sudo[37564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 01:06' Jan 27 01:07:22 volumio sudo[37564]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"