Jun 02 21:01:08 volumio go-librespot[1290]: time="2026-06-02T21:01:08+09:00" level=trace msg="sent dealer ping" Jun 02 21:01:08 volumio go-librespot[1290]: time="2026-06-02T21:01:08+09:00" level=error msg="did not receive last pong ack from accesspoint, 240s passed" Jun 02 21:01:08 volumio go-librespot[1290]: panic: runtime error: invalid memory address or nil pointer dereference Jun 02 21:01:08 volumio go-librespot[1290]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f6f14] Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329630 gp=0x1fe0488 m=9 mp=0x1d01008 [running]: Jun 02 21:01:08 volumio go-librespot[1290]: panic({0x82e098, 0xf78250}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x1ef1f20 sp=0x1ef1ecc pc=0xa92ec Jun 02 21:01:08 volumio go-librespot[1290]: runtime.panicmem(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/panic.go:262 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.sigpanic() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x1ef1f50 sp=0x1ef1f20 pc=0xabca4 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x1f4b298) Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:380 +0x284 fp=0x1ef1fe4 sp=0x1ef1f54 pc=0x4f6f14 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:277 +0x28 fp=0x1ef1fec sp=0x1ef1fe4 pc=0x4f63e8 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1ef1fec sp=0x1ef1fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 1329627 Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:277 +0x15c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1 gp=0x1c02128 m=nil [select, 2146 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1ef0ce8 sp=0x1ef0cd4 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x1ef0df8, 0x1e65dc4, 0x0, 0x0, 0x2, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1ef0d98 sp=0x1ef0ce8 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1dc7560, 0x1ef0e90) Jun 02 21:01:08 volumio go-librespot[1290]: /src/zeroconf/zeroconf.go:283 +0x21c fp=0x1ef0e3c sp=0x1ef0d98 pc=0x61db0c Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).withAppPlayer(0x1dde400, {0xa260ac, 0xfdcff0}, 0x1d874a0) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:340 +0x580 fp=0x1ef0ed0 sp=0x1ef0e3c pc=0x6ab088 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).withCredentials(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:209 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).SpotifyToken(0x1dde400, {0xa260ac, 0xfdcff0}, {0x1da4740, 0x19}, {0x1e48140, 0x13a}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x1ef0f00 sp=0x1ef0ed0 pc=0x6aa4b0 Jun 02 21:01:08 volumio go-librespot[1290]: main.main() Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:593 +0x660 fp=0x1ef0fa8 sp=0x1ef0f00 pc=0x6ad58c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.main() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x1ef0fec sp=0x1ef0fa8 pc=0x6f390 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1ef0fec sp=0x1ef0fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 2 gp=0x1c027e8 m=nil [force gc (idle), 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c48fd4 sp=0x1c48fc0 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goparkunlock(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:466 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.forcegchelper() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x1c48fec sp=0x1c48fd4 pc=0x6f7f4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c48fec sp=0x1c48fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.init.6 in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:361 +0x1c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 18 gp=0x1c82248 m=nil [GC sweep wait]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c447c4 sp=0x1c447b0 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goparkunlock(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:466 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.bgsweep(0x1c90000) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x1c447e4 sp=0x1c447c4 pc=0x5768c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcenable.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x1c447ec sp=0x1c447e4 pc=0x46c3c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c447ec sp=0x1c447ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcenable in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 19 gp=0x1c82368 m=nil [sleep]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960edc, 0xfd20d0, 0x14, 0xe, 0x2) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c44f68 sp=0x1c44f54 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goparkunlock(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:466 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.(*scavengerState).sleep(0xfd20d0, 0x41176db000000000) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgcscavenge.go:504 +0x168 fp=0x1c44fc8 sp=0x1c44f68 pc=0x54c78 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.bgscavenge(0x1c90000) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgcscavenge.go:662 +0x90 fp=0x1c44fe4 sp=0x1c44fc8 pc=0x5519c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcenable.gowrap2() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x1c44fec sp=0x1c44fe4 pc=0x46be8 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c44fec sp=0x1c44fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcenable in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 34 gp=0x1d02248 m=nil [GOMAXPROCS updater (idle), 2146 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d147a4 sp=0x1d14790 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goparkunlock(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:466 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.updateMaxProcsGoroutine() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x1d147ec sp=0x1d147a4 pc=0x7f174 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d147ec sp=0x1d147ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 50 gp=0x1d8c008 m=nil [finalizer wait, 631 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d10788 sp=0x1d10774 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.runFinalizers() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x1d107ec sp=0x1d10788 pc=0x45888 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d107ec sp=0x1d107ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.createfing in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 20 gp=0x1e8c248 m=nil [cleanup wait, 271 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c457a4 sp=0x1c45790 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goparkunlock(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:466 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.(*cleanupQueue).dequeue(0xfd2248) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x1c457c4 sp=0x1c457a4 pc=0x41cb0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.runCleanups() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x1c457ec sp=0x1c457c4 pc=0x4284c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c457ec sp=0x1c457ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 53 gp=0x1e5c6c8 m=nil [IO wait, 634 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x760a3e10, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5ccf0 sp=0x1c5ccdc pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x760a3e00, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1c5cd08 sp=0x1c5ccf0 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x760a3e00, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1c5cd1c sp=0x1c5cd08 pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1e010f8, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c5cd30 sp=0x1c5cd1c pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Accept(0x1e010e0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x1c5cd78 sp=0x1c5cd30 pc=0x1395b0 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).accept(0x1e010e0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x1c5cde0 sp=0x1c5cd78 pc=0x1aa9d8 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*TCPListener).accept(0x1d8e540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1c5ce34 sp=0x1c5cde0 pc=0x1c2304 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*TCPListener).Accept(0x1d8e540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x1c5ce50 sp=0x1c5ce34 pc=0x1c134c Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*onceCloseListener).Accept(0x1d2a180) Jun 02 21:01:08 volumio go-librespot[1290]: :1 +0x34 fp=0x1c5ce68 sp=0x1c5ce50 pc=0x367710 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*Server).Serve(0x1d54008, {0xa258a8, 0x1d8e540}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x1c5cf00 sp=0x1c5ce68 pc=0x3408c8 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.Serve(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2971 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*ConcreteApiServer).serve(0x1dc6480) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x1c5cfe4 sp=0x1c5cf00 pc=0x69fdac Jun 02 21:01:08 volumio go-librespot[1290]: main.NewApiServer.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x1c5cfec sp=0x1c5cfe4 pc=0x69eae4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5cfec sp=0x1c5cfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by main.NewApiServer in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/api_server.go:332 +0x310 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 59 gp=0x1e5ca28 m=nil [IO wait, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x760a3810, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eefe88 sp=0x1eefe74 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x760a3800, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1eefea0 sp=0x1eefe88 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x760a3800, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1eefeb4 sp=0x1eefea0 pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1e00e78, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1eefec8 sp=0x1eefeb4 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).RawRead(0x1e00e60, 0x1c00480) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x1eefef4 sp=0x1eefec8 pc=0x13a168 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*rawConn).Read(0x1c32ae0, 0x1c00480) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1eeff14 sp=0x1eefef4 pc=0x1bc9b4 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1c10d60, 0x1c00450, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x1eeff44 sp=0x1eeff14 pc=0x5b7e28 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1d47808, {0x2110000, 0x10000, 0x10000}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x1a0 fp=0x1eeff9c sp=0x1eeff44 pc=0x5bbb1c Jun 02 21:01:08 volumio go-librespot[1290]: github.com/grandcat/zeroconf.(*Server).recv4(0x1d8b5c0, 0x1d47800) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xdc fp=0x1eeffe0 sp=0x1eeff9c pc=0x618bf8 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1eeffec sp=0x1eeffe0 pc=0x6188c8 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eeffec sp=0x1eeffec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 41 Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 37 gp=0x1d027e8 m=nil [GC worker (idle), 50 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d90, 0x1d70000, 0x1c, 0xa, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20c4788 sp=0x20c4774 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkWorker(0x1d18540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x20c47e4 sp=0x20c4788 pc=0x49f34 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x20c47ec sp=0x20c47e4 pc=0x49e14 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20c47ec sp=0x20c47ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 38 gp=0x1d02908 m=nil [GC worker (idle)]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d90, 0x1d70200, 0x1c, 0xa, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20c6f88 sp=0x20c6f74 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkWorker(0x1d18540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x20c6fe4 sp=0x20c6f88 pc=0x49f34 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x20c6fec sp=0x20c6fe4 pc=0x49e14 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20c6fec sp=0x20c6fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 39 gp=0x1d02a28 m=nil [GC worker (idle), 48 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d90, 0x1d70400, 0x1c, 0xa, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20c7788 sp=0x20c7774 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkWorker(0x1d18540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x20c77e4 sp=0x20c7788 pc=0x49f34 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x20c77ec sp=0x20c77e4 pc=0x49e14 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20c77ec sp=0x20c77ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 40 gp=0x1d02b48 m=nil [GC worker (idle)]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d90, 0x1d70600, 0x1c, 0xa, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c57f88 sp=0x1c57f74 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkWorker(0x1d18540) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1c57fe4 sp=0x1c57f88 pc=0x49f34 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gcBgMarkStartWorkers.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1c57fec sp=0x1c57fe4 pc=0x49e14 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jun 02 21:01:08 volumio volumio[993]: info: Connection to go-librespot Websocket closed Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c57fec sp=0x1c57fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by runtime.gcBgMarkStartWorkers in goroutine 21 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 60 gp=0x1d02d88 m=nil [IO wait, 2146 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x760a3610, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d12660 sp=0x1d1264c pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x760a3600, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1d12678 sp=0x1d12660 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x760a3600, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1d1268c sp=0x1d12678 pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1e00ec8, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1d126a0 sp=0x1d1268c pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).RawRead(0x1e00eb0, 0x202e780) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x1d126cc sp=0x1d126a0 pc=0x13a168 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*rawConn).Read(0x1c32b00, 0x202e780) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1d126ec sp=0x1d126cc pc=0x1bc9b4 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1c10d70, 0x202e750, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x1d1271c sp=0x1d126ec pc=0x5b7e28 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1d47838, {0x2100000, 0x10000, 0x10000}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x188 fp=0x1d1279c sp=0x1d1271c pc=0x5bf588 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/grandcat/zeroconf.(*Server).recv6(0x1d8b5c0, 0x1d47830) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xdc fp=0x1d127e0 sp=0x1d1279c pc=0x618ddc Jun 02 21:01:08 volumio go-librespot[1290]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1d127ec sp=0x1d127e0 pc=0x61886c Jun 02 21:01:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d127ec sp=0x1d127ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 41 Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 31 gp=0x1e8c908 m=nil [chan receive, 634 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d64, 0x1d841b8, 0xe, 0x7, 0x2) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eab73c sp=0x1eab728 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.chanrecv(0x1d84180, 0x1eab7d0, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1eab784 sp=0x1eab73c pc=0x3414c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.chanrecv2(0x1d84180, 0x1eab7d0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x1eab798 sp=0x1eab784 pc=0x33bf0 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).withAppPlayer.func1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:284 +0x68 fp=0x1eab7ec sp=0x1eab798 pc=0x6abd40 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eab7ec sp=0x1eab7ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by main.(*App).withAppPlayer in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:281 +0x49c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 32 gp=0x1e8ca28 m=nil [chan receive, 315 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960d64, 0x1d84178, 0xe, 0x7, 0x2) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20a3740 sp=0x20a372c pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.chanrecv(0x1d84140, 0x1fa1fe0, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x20a3788 sp=0x20a3740 pc=0x3414c Jun 02 21:01:08 volumio go-librespot[1290]: runtime.chanrecv2(0x1d84140, 0x1fa1fe0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x20a379c sp=0x20a3788 pc=0x33bf0 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).withAppPlayer.func2() Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:300 +0x6c fp=0x20a37ec sp=0x20a379c pc=0x6ab8a4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20a37ec sp=0x20a37ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by main.(*App).withAppPlayer in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:297 +0x53c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 33 gp=0x1e8cb48 m=nil [IO wait, 21 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x760a3a10, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eacdac sp=0x1eacd98 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x760a3a00, 0x72, 0x0) Jun 02 21:01:08 volumio systemd[1]: go-librespot-daemon.service: Consumed 11min 40.865s CPU time. Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1eacdc4 sp=0x1eacdac pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x760a3a00, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1eacdd8 sp=0x1eacdc4 pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1e00e28, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1eacdec sp=0x1eacdd8 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Accept(0x1e00e10) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x1eace34 sp=0x1eacdec pc=0x1395b0 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).accept(0x1e00e10) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x1eace9c sp=0x1eace34 pc=0x1aa9d8 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*TCPListener).accept(0x1d477a0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1eacef0 sp=0x1eace9c pc=0x1c2304 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*TCPListener).Accept(0x1d477a0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x1eacf0c sp=0x1eacef0 pc=0x1c134c Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*onceCloseListener).Accept(0x1ff25e0) Jun 02 21:01:08 volumio go-librespot[1290]: :1 +0x34 fp=0x1eacf24 sp=0x1eacf0c pc=0x367710 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*Server).Serve(0x1cc02c8, {0xa258a8, 0x1d477a0}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x1eacfbc sp=0x1eacf24 pc=0x3408c8 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.Serve(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2971 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jun 02 21:01:08 volumio go-librespot[1290]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x1eacfec sp=0x1eacfbc pc=0x61dc08 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eacfec sp=0x1eacfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jun 02 21:01:08 volumio go-librespot[1290]: /src/zeroconf/zeroconf.go:280 +0x154 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329632 gp=0x1e8cfc8 m=nil [select]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eece84 sp=0x1eece70 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x1eecfd4, 0x1eecf5c, 0x0, 0x0, 0x2, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1eecf34 sp=0x1eece84 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x20600b8) Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:138 +0x8c fp=0x1eecfe4 sp=0x1eecf34 pc=0x5a0cfc Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:129 +0x28 fp=0x1eecfec sp=0x1eecfe4 pc=0x5a2c40 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eecfec sp=0x1eecfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 1329627 Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:129 +0x15c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 119 gp=0x1c03208 m=nil [select, 315 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1ea7694 sp=0x1ea7680 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x1ea77a8, 0x1ea776c, 0x0, 0x0, 0x5, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1ea7744 sp=0x1ea7694 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x20e7e08) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x1ea77e4 sp=0x1ea7744 pc=0x494d20 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.newConn.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x1ea77ec sp=0x1ea77e4 pc=0x4949b0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1ea77ec sp=0x1ea77ec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by nhooyr.io/websocket.newConn in goroutine 117 Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 117 gp=0x1e8d0e8 m=nil [IO wait, 2146 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x7564dc10, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eb58d4 sp=0x1eb58c0 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x7564dc00, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1eb58ec sp=0x1eb58d4 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x7564dc00, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1eb5900 sp=0x1eb58ec pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1ee0158, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1eb5914 sp=0x1eb5900 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Read(0x1ee0140, {0x1ed5000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1eb595c sp=0x1eb5914 pc=0x135f74 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).Read(0x1ee0140, {0x1ed5000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1eb5988 sp=0x1eb595c pc=0x1a8c28 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*conn).Read(0x1d25bb8, {0x1ed5000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1eb59b4 sp=0x1eb5988 pc=0x1b842c Jun 02 21:01:08 volumio go-librespot[1290]: io.(*multiReader).Read(0x1ca5bd0, {0x1ed5000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1eb59e0 sp=0x1eb59b4 pc=0xef364 Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).fill(0x1ee4330) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1eb5a04 sp=0x1eb59e0 pc=0x2b6390 Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).ReadByte(0x1ee4330) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1eb5a10 sp=0x1eb5a04 pc=0x2b6c0c Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.readFrameHeader(0x1ee4330, {0x20e7e3c, 0x8, 0x8}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x1eb5a50 sp=0x1eb5a10 pc=0x498164 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x20e7e08, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x1eb5b10 sp=0x1eb5a50 pc=0x49b01c Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).readLoop(0x20e7e08, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x1eb5bd4 sp=0x1eb5b10 pc=0x49ab08 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).reader(0x20e7e08, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x1eb5c58 sp=0x1eb5bd4 pc=0x49bc20 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).Read(0x20e7e08, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x1eb5c80 sp=0x1eb5c58 pc=0x49a0a0 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x1eb5cfc sp=0x1eb5c80 pc=0x6a062c Jun 02 21:01:08 volumio go-librespot[1290]: net/http.HandlerFunc.ServeHTTP(0x1d241e8, {0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1eb5d0c sp=0x1eb5cfc pc=0x33cf4c Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*ServeMux).ServeHTTP(0x1d40180, {0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1eb5d4c sp=0x1eb5d0c pc=0x33ee3c Jun 02 21:01:08 volumio go-librespot[1290]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1eb5d74 sp=0x1eb5d4c pc=0x69ffa8 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.HandlerFunc.ServeHTTP(0x1d48140, {0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1eb5d84 sp=0x1eb5d74 pc=0x33cf4c Jun 02 21:01:08 volumio go-librespot[1290]: net/http.serverHandler.ServeHTTP({0x1d54008}, {0xa25968, 0x1f4a0b8}, 0x1d55ef8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1eb5da0 sp=0x1eb5d84 pc=0x35c2b8 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*conn).serve(0x20f8c00, {0xa260c8, 0x1d34258}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1eb5fdc sp=0x1eb5da0 pc=0x33b008 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*Server).Serve.gowrap3() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1eb5fec sp=0x1eb5fdc pc=0x340d40 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eb5fec sp=0x1eb5fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by net/http.(*Server).Serve in goroutine 53 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329627 gp=0x1c16b48 m=nil [select]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2205930 sp=0x220591c pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x2205f70, 0x2205b20, 0x0, 0x0, 0xa, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x22059e0 sp=0x2205930 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*AppPlayer).Run(0x21e8550, {0xa260ac, 0xfdcff0}, 0x1f76500, 0x1f76540) Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/player.go:661 +0x368 fp=0x2205fd4 sp=0x22059e0 pc=0x6b3970 Jun 02 21:01:08 volumio go-librespot[1290]: main.(*App).withAppPlayer.func2.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:328 +0x48 fp=0x2205fec sp=0x2205fd4 pc=0x6abcac Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2205fec sp=0x2205fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by main.(*App).withAppPlayer.func2 in goroutine 32 Jun 02 21:01:08 volumio go-librespot[1290]: /src/cmd/daemon/main.go:328 +0x2f0 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329626 gp=0x1e959e8 m=nil [select, 315 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20a0e74 sp=0x20a0e60 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x20a0f80, 0x20a0f4c, 0x0, 0x0, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x20a0f24 sp=0x20a0e74 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1f7e190) Jun 02 21:01:08 volumio go-librespot[1290]: /src/player/player.go:215 +0x1f4 fp=0x20a0fe4 sp=0x20a0f24 pc=0x5818e0 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/player/player.go:197 +0x28 fp=0x20a0fec sp=0x20a0fe4 pc=0x5814c8 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20a0fec sp=0x20a0fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 32 Jun 02 21:01:08 volumio go-librespot[1290]: /src/player/player.go:197 +0x220 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329938 gp=0x1c147e8 m=6 mp=0x1d00008 [syscall, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.cgocall(0x74cab0, 0x20c7efc) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/cgocall.go:167 +0x50 fp=0x20c7ee4 sp=0x20c7ecc pc=0xa628c Jun 02 21:01:08 volumio go-librespot[1290]: net._C2func_getaddrinfo(0x1d9fa28, 0x0, 0x3118900, 0x2de87f0) Jun 02 21:01:08 volumio go-librespot[1290]: _cgo_gotypes.go:105 +0x44 fp=0x20c7ef8 sp=0x20c7ee4 pc=0x1c97c8 Jun 02 21:01:08 volumio go-librespot[1290]: net._C_getaddrinfo.func1(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix_cgo.go:84 Jun 02 21:01:08 volumio go-librespot[1290]: net._C_getaddrinfo(0x1d9fa28, 0x0, 0x3118900, 0x2de87f0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix_cgo.go:84 +0x80 fp=0x20c7f18 sp=0x20c7ef8 pc=0x1c9a8c Jun 02 21:01:08 volumio go-librespot[1290]: net.cgoLookupHostIP({0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:174 +0x1f4 fp=0x20c7f8c sp=0x20c7f18 pc=0x19bd90 Jun 02 21:01:08 volumio go-librespot[1290]: net.cgoLookupIP.func1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:229 +0x34 fp=0x20c7fb4 sp=0x20c7f8c pc=0x19c3b4 Jun 02 21:01:08 volumio go-librespot[1290]: net.doBlockingWithCtx[...].func1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:67 +0x54 fp=0x20c7fec sp=0x20c7fb4 pc=0x1cc878 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20c7fec sp=0x20c7fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by net.doBlockingWithCtx[...] in goroutine 1329905 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:64 +0x204 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329900 gp=0x1e30c68 m=nil [IO wait, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x7564d610, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eedaf4 sp=0x1eedae0 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x7564d600, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1eedb0c sp=0x1eedaf4 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x7564d600, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1eedb20 sp=0x1eedb0c pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x21e8838, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1eedb34 sp=0x1eedb20 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Read(0x21e8820, {0x1ed4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1eedb7c sp=0x1eedb34 pc=0x135f74 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).Read(0x21e8820, {0x1ed4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1eedba8 sp=0x1eedb7c pc=0x1a8c28 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*conn).Read(0x2de8668, {0x1ed4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1eedbd4 sp=0x1eedba8 pc=0x1b842c Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*atLeastReader).Read(0x1c10150, {0x1ed4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1eedc00 sp=0x1eedbd4 pc=0x266c34 Jun 02 21:01:08 volumio go-librespot[1290]: bytes.(*Buffer).ReadFrom(0x1dd914c, {0xa22858, 0x1c10150}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1eedc3c sp=0x1eedc00 pc=0x160e08 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readFromUntil(0x1dd8fc8, {0xa21798, 0x2de8668}, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1eedc64 sp=0x1eedc3c pc=0x266e90 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecordOrCCS(0x1dd8fc8, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1eeddc4 sp=0x1eedc64 pc=0x2645b4 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecord(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:589 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).Read(0x1dd8fc8, {0x1df1000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1eeddf4 sp=0x1eeddc4 pc=0x26a378 Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).Read(0x1f622d0, {0x21404a4, 0x9, 0x9}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1eede18 sp=0x1eeddf4 pc=0x2b6a14 Jun 02 21:01:08 volumio go-librespot[1290]: io.ReadAtLeast({0xa219e8, 0x1f622d0}, {0x21404a4, 0x9, 0x9}, 0x9) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1eede44 sp=0x1eede18 pc=0xee130 Jun 02 21:01:08 volumio go-librespot[1290]: io.ReadFull(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/io/io.go:354 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.http2readFrameHeader({0x21404a4, 0x9, 0x9}, {0xa219e8, 0x1f622d0}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1eede6c sp=0x1eede44 pc=0x2f7e20 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2Framer).ReadFrame(0x2140480) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1eedef4 sp=0x1eede6c pc=0x2f8578 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2clientConnReadLoop).run(0x1eedfdc) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1eedfa4 sp=0x1eedef4 pc=0x31cab0 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2ClientConn).readLoop(0x1c786c8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1eedfe4 sp=0x1eedfa4 pc=0x31bd78 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2Transport).newClientConn.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1eedfec sp=0x1eedfe4 pc=0x3160d4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eedfec sp=0x1eedfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by net/http.(*http2Transport).newClientConn in goroutine 1329899 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329905 gp=0x377c248 m=nil [select, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1eebb60 sp=0x1eebb4c pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x1eebc68, 0x1eebc38, 0x0, 0x0, 0x2, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1eebc10 sp=0x1eebb60 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: net.doBlockingWithCtx[...]({0xa260e4, 0x1c00300}, {0x1d2ea68, 0x13}, 0x1ce1050) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:71 +0x280 fp=0x1eebc90 sp=0x1eebc10 pc=0x1cc630 Jun 02 21:01:08 volumio go-librespot[1290]: net.cgoLookupIP({0xa260e4, 0x1c00300}, {0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/cgo_unix.go:228 +0x98 fp=0x1eebcc0 sp=0x1eebc90 pc=0x19c334 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).lookupIP(0xfd1660, {0xa260e4, 0x1c00300}, {0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/lookup_unix.go:64 +0x90 fp=0x1eebefc sp=0x1eebcc0 pc=0x1b7a0c Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).lookupIP-fm({0xa260e4, 0x1c00300}, {0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: :1 +0x58 fp=0x1eebf30 sp=0x1eebefc pc=0x1d2324 Jun 02 21:01:08 volumio go-librespot[1290]: net.init.func1({0xa260e4, 0x1c00300}, 0x2de87d8, {0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/hook.go:21 +0x4c fp=0x1eebf60 sp=0x1eebf30 pc=0x1999a8 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).lookupIPAddr.func1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/lookup.go:335 +0x58 fp=0x1eebf9c sp=0x1eebf60 pc=0x1b724c Jun 02 21:01:08 volumio go-librespot[1290]: internal/singleflight.(*Group).doCall(0xfd1668, 0x1c00330, {0x1d9f9e0, 0x17}, 0x31188e0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x1eebfd4 sp=0x1eebf9c pc=0x197c48 Jun 02 21:01:08 volumio go-librespot[1290]: internal/singleflight.(*Group).DoChan.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x1eebfec sp=0x1eebfd4 pc=0x197c00 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1eebfec sp=0x1eebfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by internal/singleflight.(*Group).DoChan in goroutine 1329857 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x304 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329889 gp=0x1e40fc8 m=nil [IO wait, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x760a3c10, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5995c sp=0x1c59948 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x760a3c00, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1c59974 sp=0x1c5995c pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x760a3c00, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1c59988 sp=0x1c59974 pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1ee1648, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c5999c sp=0x1c59988 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Read(0x1ee1630, {0x1e36a00, 0x1500, 0x1500}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1c599e4 sp=0x1c5999c pc=0x135f74 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).Read(0x1ee1630, {0x1e36a00, 0x1500, 0x1500}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1c59a10 sp=0x1c599e4 pc=0x1a8c28 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*conn).Read(0x1c32378, {0x1e36a00, 0x1500, 0x1500}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1c59a3c sp=0x1c59a10 pc=0x1b842c Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*atLeastReader).Read(0x1d86e90, {0x1e36a00, 0x1500, 0x1500}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1c59a68 sp=0x1c59a3c pc=0x266c34 Jun 02 21:01:08 volumio go-librespot[1290]: bytes.(*Buffer).ReadFrom(0x1cb2f0c, {0xa22858, 0x1d86e90}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1c59aa4 sp=0x1c59a68 pc=0x160e08 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readFromUntil(0x1cb2d88, {0xa21798, 0x1c32378}, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1c59acc sp=0x1c59aa4 pc=0x266e90 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecordOrCCS(0x1cb2d88, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1c59c2c sp=0x1c59acc pc=0x2645b4 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecord(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:589 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).Read(0x1cb2d88, {0x207e000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1c59c5c sp=0x1c59c2c pc=0x26a378 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*readWriteCloserBody).Read(0x1ca5610, {0x207e000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x1c59c7c sp=0x1c59c5c pc=0x3577bc Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).fill(0x1d8e840) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1c59ca0 sp=0x1c59c7c pc=0x2b6390 Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).ReadByte(0x1d8e840) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1c59cac sp=0x1c59ca0 pc=0x2b6c0c Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.readFrameHeader(0x1d8e840, {0x1d4c03c, 0x8, 0x8}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x1c59cec sp=0x1c59cac pc=0x498164 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1d4c008, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x1c59dac sp=0x1c59cec pc=0x49b01c Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).readLoop(0x1d4c008, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x1c59e70 sp=0x1c59dac pc=0x49ab08 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).reader(0x1d4c008, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x1c59ef4 sp=0x1c59e70 pc=0x49bc20 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).Read(0x1d4c008, {0xa26090, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x1c59f1c sp=0x1c59ef4 pc=0x49a0a0 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x20600b8) Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:188 +0x58 fp=0x1c59fe4 sp=0x1c59f1c pc=0x5a1198 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:296 +0x28 fp=0x1c59fec sp=0x1c59fe4 pc=0x5a1df4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c59fec sp=0x1c59fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 1329802 Jun 02 21:01:08 volumio go-librespot[1290]: /src/dealer/dealer.go:296 +0xf8 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329857 gp=0x1fbcfc8 m=nil [select, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1f96868 sp=0x1f96854 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x1f969d8, 0x1f96944, 0x0, 0x0, 0x2, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1f96918 sp=0x1f96868 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).lookupIPAddr(0xfd1660, {0xa2611c, 0x21e8e10}, {0x903a4e, 0x3}, {0x1d2ea68, 0x13}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/lookup.go:343 +0x394 fp=0x1f96a20 sp=0x1f96918 pc=0x1b68b0 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).internetAddrList(0xfd1660, {0xa2611c, 0x21e8e10}, {0x903a4e, 0x3}, {0x1d2ea68, 0x17}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/ipsock.go:289 +0x6b0 fp=0x1f96ab4 sp=0x1f96a20 pc=0x1b486c Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Resolver).resolveAddrList(0xfd1660, {0xa2611c, 0x21e8e10}, {0x903e50, 0x4}, {0x903a4e, 0x3}, {0x1d2ea68, 0x17}, {0x0, ...}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/dial.go:353 +0x510 fp=0x1f96b3c sp=0x1f96ab4 pc=0x19f1a8 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*Dialer).DialContext(0x1f96c68, {0xa2611c, 0x21e8e10}, {0x903a4e, 0x3}, {0x1d2ea68, 0x17}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/dial.go:560 +0x43c fp=0x1f96c38 sp=0x1f96b3c pc=0x1a00e4 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/proxy.direct.DialContext(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/direct.go:30 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/proxy.(*direct).DialContext(0xfdcff0, {0xa2611c, 0x21e8e10}, {0x903a4e, 0x3}, {0x1d2ea68, 0x17}) Jun 02 21:01:08 volumio go-librespot[1290]: :1 +0x74 fp=0x1f96ccc sp=0x1f96c38 pc=0x4f1ff0 Jun 02 21:01:08 volumio go-librespot[1290]: golang.org/x/net/proxy.Dial({0xa2611c, 0x21e8e10}, {0x903a4e, 0x3}, {0x1d2ea68, 0x17}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/golang.org/x/net@v0.48.0/proxy/dial.go:28 +0xac fp=0x1f96d08 sp=0x1f96ccc pc=0x4efeb4 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x1f4b298, {0xa260ac, 0xfdcff0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:95 +0x2a8 fp=0x1f96d80 sp=0x1f96d08 pc=0x4f4974 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x1f4b298, {0xa260ac, 0xfdcff0}, 0x1d8f2c0) Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:205 +0xd4 fp=0x1f96de4 sp=0x1f96d80 pc=0x4f5a7c Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x1f4b298) Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:394 +0xcc fp=0x1f96e0c sp=0x1f96de4 pc=0x4f703c Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Jun 02 21:01:08 volumio go-librespot[1290]: :1 +0x28 fp=0x1f96e1c sp=0x1f96e0c pc=0x4fb294 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x1f96e28 sp=0x1f96e1c pc=0x4ea1ec Jun 02 21:01:08 volumio go-librespot[1290]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x1f96ecc, {0xa24f50, 0x1f75980}, 0x0, {0x0, 0x0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x1f96ea4 sp=0x1f96e28 pc=0x4ea668 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x1f96f5c, {0xa24f50, 0x1f75980}, 0x0, {0x0, 0x0}) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x1f96ed4 sp=0x1f96ea4 pc=0x4ea1a4 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/cenkalti/backoff/v4.Retry(...) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1f4b298) Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:335 +0x434 fp=0x1f96fe4 sp=0x1f96ed4 pc=0x4f689c Jun 02 21:01:08 volumio go-librespot[1290]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:403 +0x28 fp=0x1f96fec sp=0x1f96fe4 pc=0x4f71f0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1f96fec sp=0x1f96fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 1329629 Jun 02 21:01:08 volumio go-librespot[1290]: /src/ap/ap.go:403 +0x120 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329888 gp=0x1ffeea8 m=nil [select]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x22d1e94 sp=0x22d1e80 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.selectgo(0x22d1fa8, 0x22d1f6c, 0x0, 0x0, 0x5, 0x1) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x22d1f44 sp=0x22d1e94 pc=0x85000 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1d4c008) Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x22d1fe4 sp=0x22d1f44 pc=0x494d20 Jun 02 21:01:08 volumio go-librespot[1290]: nhooyr.io/websocket.newConn.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x22d1fec sp=0x22d1fe4 pc=0x4949b0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22d1fec sp=0x22d1fec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by nhooyr.io/websocket.newConn in goroutine 1329802 Jun 02 21:01:08 volumio go-librespot[1290]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jun 02 21:01:08 volumio go-librespot[1290]: goroutine 1329903 gp=0x21ea368 m=nil [IO wait, 2 minutes]: Jun 02 21:01:08 volumio go-librespot[1290]: runtime.gopark(0x960ecc, 0x7564d010, 0x2, 0x2, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5daf4 sp=0x1c5dae0 pc=0xa97c0 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.netpollblock(0x7564d000, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1c5db0c sp=0x1c5daf4 pc=0x675a0 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.runtime_pollWait(0x7564d000, 0x72) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1c5db20 sp=0x1c5db0c pc=0xa8864 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).wait(0x1ee1d28, 0x72, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c5db34 sp=0x1c5db20 pc=0x134df4 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*pollDesc).waitRead(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 02 21:01:08 volumio go-librespot[1290]: internal/poll.(*FD).Read(0x1ee1d10, {0x20f4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1c5db7c sp=0x1c5db34 pc=0x135f74 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*netFD).Read(0x1ee1d10, {0x20f4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1c5dba8 sp=0x1c5db7c pc=0x1a8c28 Jun 02 21:01:08 volumio go-librespot[1290]: net.(*conn).Read(0x1c324c0, {0x20f4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1c5dbd4 sp=0x1c5dba8 pc=0x1b842c Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*atLeastReader).Read(0x1c105a0, {0x20f4000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1c5dc00 sp=0x1c5dbd4 pc=0x266c34 Jun 02 21:01:08 volumio go-librespot[1290]: bytes.(*Buffer).ReadFrom(0x1cb314c, {0xa22858, 0x1c105a0}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1c5dc3c sp=0x1c5dc00 pc=0x160e08 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readFromUntil(0x1cb2fc8, {0xa21798, 0x1c324c0}, 0x5) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1c5dc64 sp=0x1c5dc3c pc=0x266e90 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecordOrCCS(0x1cb2fc8, 0x0) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1c5ddc4 sp=0x1c5dc64 pc=0x2645b4 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).readRecord(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:589 Jun 02 21:01:08 volumio go-librespot[1290]: crypto/tls.(*Conn).Read(0x1cb2fc8, {0x1e65000, 0x1000, 0x1000}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1c5ddf4 sp=0x1c5ddc4 pc=0x26a378 Jun 02 21:01:08 volumio go-librespot[1290]: bufio.(*Reader).Read(0x1c00270, {0x21405a4, 0x9, 0x9}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1c5de18 sp=0x1c5ddf4 pc=0x2b6a14 Jun 02 21:01:08 volumio go-librespot[1290]: io.ReadAtLeast({0xa219e8, 0x1c00270}, {0x21405a4, 0x9, 0x9}, 0x9) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1c5de44 sp=0x1c5de18 pc=0xee130 Jun 02 21:01:08 volumio go-librespot[1290]: io.ReadFull(...) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/io/io.go:354 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.http2readFrameHeader({0x21405a4, 0x9, 0x9}, {0xa219e8, 0x1c00270}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1c5de6c sp=0x1c5de44 pc=0x2f7e20 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2Framer).ReadFrame(0x2140580) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1c5def4 sp=0x1c5de6c pc=0x2f8578 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2clientConnReadLoop).run(0x1c5dfdc) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1c5dfa4 sp=0x1c5def4 pc=0x31cab0 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2ClientConn).readLoop(0x1c787e8) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1c5dfe4 sp=0x1c5dfa4 pc=0x31bd78 Jun 02 21:01:08 volumio go-librespot[1290]: net/http.(*http2Transport).newClientConn.gowrap1() Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1c5dfec sp=0x1c5dfe4 pc=0x3160d4 Jun 02 21:01:08 volumio go-librespot[1290]: runtime.goexit({}) Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5dfec sp=0x1c5dfec pc=0xb0874 Jun 02 21:01:08 volumio go-librespot[1290]: created by net/http.(*http2Transport).newClientConn in goroutine 1329902 Jun 02 21:01:08 volumio go-librespot[1290]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Jun 02 21:01:08 volumio go-librespot[1289]: Aborted Jun 02 21:01:11 volumio volumio[993]: info: Initializing connection to go-librespot Websocket Jun 02 21:01:11 volumio volumio[993]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 02 21:01:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 02 21:01:11 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jun 02 21:01:11 volumio systemd[1]: go-librespot-daemon.service: Consumed 11min 40.865s CPU time. Jun 02 21:01:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 21:01:11 volumio go-librespot[6289]: go-librespot daemon starting... Jun 02 21:01:12 volumio go-librespot[6290]: time="2026-06-02T21:01:12+09:00" level=info msg="running go-librespot 0.6.2" Jun 02 21:01:12 volumio go-librespot[6290]: time="2026-06-02T21:01:12+09:00" level=debug msg="app state loaded" Jun 02 21:01:12 volumio go-librespot[6290]: time="2026-06-02T21:01:12+09:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 21:01:13 volumio go-librespot[6290]: time="2026-06-02T21:01:13+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 02 21:01:13 volumio go-librespot[6290]: time="2026-06-02T21:01:13+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 02 21:01:13 volumio go-librespot[6290]: time="2026-06-02T21:01:13+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 02 21:01:13 volumio go-librespot[6290]: time="2026-06-02T21:01:13+09:00" level=info msg="zeroconf server listening on port 40705" Jun 02 21:01:14 volumio volumio[993]: info: Initializing connection to go-librespot Websocket Jun 02 21:01:14 volumio go-librespot[6290]: time="2026-06-02T21:01:14+09:00" level=debug msg="new websocket client" Jun 02 21:01:14 volumio volumio[993]: info: Connection to go-librespot Websocket established Jun 02 21:01:17 volumio volumio[993]: info: Getting Spotify volume Jun 02 21:01:17 volumio volumio[993]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 02 21:01:18 volumio volumio[993]: info: CoreCommandRouter::volumioGetState Jun 02 21:01:18 volumio volumio[993]: info: CorePlayQueue::getTrack 0 Jun 02 21:01:18 volumio go-librespot[6290]: time="2026-06-02T21:01:18+09:00" level=debug msg="obtained new client token: AABJqZ/yp4N0quRQGJcKzmXSTuIBYCSryUe4MOeklinyGqNjb41xg57GyfVWOlGAm0UEK92Dy9ahARsrn7f/N1I5tGeP/3yV2YFdrqw0Tinhh2Cl0mfjLN5vA5v1Rb58JfOedDQmhuaLyQvp4JtRKgLjL6kDL+E2jIaEYmSrZnqVEILB+OwlUGqsVHIpKl9vWNnZU+aPZpeeo41EHDuckC1BEfc59NUbBjV81fchmmKDmns1WLDrKQ==" Jun 02 21:01:40 volumio go-librespot[6290]: time="2026-06-02T21:01:40+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jun 02 21:01:40 volumio go-librespot[6290]: time="2026-06-02T21:01:40+09:00" level=debug msg="completed keyexchange" Jun 02 21:01:40 volumio go-librespot[6290]: time="2026-06-02T21:01:40+09:00" level=debug msg="completed challenge" Jun 02 21:01:40 volumio go-librespot[6290]: time="2026-06-02T21:01:40+09:00" level=info msg="authenticated AP" username="r2*********************qu" Jun 02 21:02:10 volumio go-librespot[6290]: time="2026-06-02T21:02:10+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed requesting login5 endpoint: failed requesting login5: Post \"https://login5.spotify.com/v3/login\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Jun 02 21:02:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 02 21:02:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 02 21:02:10 volumio volumio[993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 21:02:10 volumio volumio[993]: Error: socket hang up Jun 02 21:02:10 volumio volumio[993]: at connResetException (node:internal/errors:720:14) Jun 02 21:02:10 volumio volumio[993]: at Socket.socketOnEnd (node:_http_client:519:23) Jun 02 21:02:10 volumio volumio[993]: at Socket.emit (node:events:526:35) Jun 02 21:02:10 volumio volumio[993]: at endReadableNT (node:internal/streams/readable:1376:12) Jun 02 21:02:10 volumio volumio[993]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jun 02 21:02:10 volumio volumio[993]: code: 'ECONNRESET', Jun 02 21:02:10 volumio volumio[993]: response: undefined Jun 02 21:02:10 volumio volumio[993]: } Jun 02 21:02:10 volumio volumio[993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 21:02:12 volumio sudo[6400]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 21:01' Jun 02 21:02:12 volumio sudo[6400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"