Jan 13 21:28:01 volumio go-librespot[5490]: time="2026-01-13T21:28:01+01:00" level=trace msg="sent dealer ping" Jan 13 21:28:04 volumio go-librespot[5490]: time="2026-01-13T21:28:04+01:00" level=trace msg="received dealer pong" Jan 13 21:28:21 volumio go-librespot[5490]: time="2026-01-13T21:28:21+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": net/http: TLS handshake timeout" Jan 13 21:28:21 volumio go-librespot[5490]: time="2026-01-13T21:28:21+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:28:31 volumio go-librespot[5490]: time="2026-01-13T21:28:31+01:00" level=trace msg="sent dealer ping" Jan 13 21:28:32 volumio go-librespot[5490]: time="2026-01-13T21:28:32+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": net/http: TLS handshake timeout" Jan 13 21:28:34 volumio go-librespot[5490]: time="2026-01-13T21:28:34+01:00" level=trace msg="received dealer pong" Jan 13 21:28:35 volumio go-librespot[5490]: time="2026-01-13T21:28:35+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:28:48 volumio go-librespot[5490]: time="2026-01-13T21:28:48+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": net/http: TLS handshake timeout" Jan 13 21:28:55 volumio go-librespot[5490]: time="2026-01-13T21:28:55+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:29:01 volumio go-librespot[5490]: time="2026-01-13T21:29:01+01:00" level=trace msg="sent dealer ping" Jan 13 21:29:06 volumio go-librespot[5490]: time="2026-01-13T21:29:06+01:00" level=trace msg="received dealer pong" Jan 13 21:29:09 volumio go-librespot[5490]: time="2026-01-13T21:29:09+01:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": net/http: TLS handshake timeout" Jan 13 21:29:14 volumio go-librespot[5490]: time="2026-01-13T21:29:14+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jan 13 21:29:31 volumio go-librespot[5490]: time="2026-01-13T21:29:31+01:00" level=error msg="did not receive last pong ack from accesspoint, 245s passed" Jan 13 21:29:31 volumio go-librespot[5490]: panic: runtime error: invalid memory address or nil pointer dereference Jan 13 21:29:31 volumio go-librespot[5490]: [signal SIGSEGV: segmentation violation code=0x1 addr=0xc pc=0x4f6f14] Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 80 gp=0x2640ea8 m=9 mp=0x262b808 [running]: Jan 13 21:29:31 volumio go-librespot[5490]: panic({0x82e098, 0xf78250}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x263bf20 sp=0x263becc pc=0xa92ec Jan 13 21:29:31 volumio go-librespot[5490]: runtime.panicmem(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/panic.go:262 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.sigpanic() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x263bf50 sp=0x263bf20 pc=0xabca4 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x27bca58) Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:380 +0x284 fp=0x263bfe4 sp=0x263bf54 pc=0x4f6f14 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:277 +0x28 fp=0x263bfec sp=0x263bfe4 pc=0x4f63e8 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x263bfec sp=0x263bfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 69 Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:277 +0x15c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 1 gp=0x2402128 m=nil [select, 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26edce8 sp=0x26edcd4 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x26eddf8, 0x2609dc4, 0x0, 0x0, 0x2, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x26edd98 sp=0x26edce8 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x27ec000, 0x26ede90) Jan 13 21:29:31 volumio go-librespot[5490]: /src/zeroconf/zeroconf.go:283 +0x21c fp=0x26ede3c sp=0x26edd98 pc=0x61db0c Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).withAppPlayer(0x25b6400, {0xa260ac, 0xfdcff0}, 0x259f340) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:340 +0x580 fp=0x26eded0 sp=0x26ede3c pc=0x6ab088 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).withCredentials(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:209 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).SpotifyToken(0x25b6400, {0xa260ac, 0xfdcff0}, {0x25907c0, 0x1c}, {0x25e2140, 0x13c}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x26edf00 sp=0x26eded0 pc=0x6aa4b0 Jan 13 21:29:31 volumio go-librespot[5490]: main.main() Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:593 +0x660 fp=0x26edfa8 sp=0x26edf00 pc=0x6ad58c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.main() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x26edfec sp=0x26edfa8 pc=0x6f390 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26edfec sp=0x26edfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 2 gp=0x24027e8 m=nil [force gc (idle), 2 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960edc, 0xfd0f08, 0x11, 0xa, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2448fd4 sp=0x2448fc0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goparkunlock(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:466 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.forcegchelper() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2448fec sp=0x2448fd4 pc=0x6f7f4 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2448fec sp=0x2448fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.init.6 in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:361 +0x1c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 18 gp=0x2482248 m=nil [GC sweep wait]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960edc, 0xfd17b0, 0xc, 0x9, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24447c4 sp=0x24447b0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goparkunlock(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:466 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.bgsweep(0x2490000) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x24447e4 sp=0x24447c4 pc=0x5768c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcenable.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x24447ec sp=0x24447e4 pc=0x46c3c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24447ec sp=0x24447ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcenable in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 19 gp=0x2482368 m=nil [GC scavenge wait]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960edc, 0xfd20d0, 0xd, 0xa, 0x2) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2444fb4 sp=0x2444fa0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goparkunlock(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:466 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.(*scavengerState).park(0xfd20d0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2444fc8 sp=0x2444fb4 pc=0x54a28 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.bgscavenge(0x2490000) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2444fe4 sp=0x2444fc8 pc=0x5516c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcenable.gowrap2() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2444fec sp=0x2444fe4 pc=0x46be8 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2444fec sp=0x2444fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcenable in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 20 gp=0x2482488 m=nil [GOMAXPROCS updater (idle), 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960edc, 0xfd1548, 0x12, 0xa, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24457a4 sp=0x2445790 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goparkunlock(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:466 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.updateMaxProcsGoroutine() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x24457ec sp=0x24457a4 pc=0x7f174 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24457ec sp=0x24457ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 21 gp=0x24827e8 m=nil [finalizer wait, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d84, 0xfdd0e0, 0x10, 0xa, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2445f88 sp=0x2445f74 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.runFinalizers() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x2445fec sp=0x2445f88 pc=0x45888 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2445fec sp=0x2445fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.createfing in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 34 gp=0x25b4248 m=nil [cleanup wait, 15 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960edc, 0xfd22a8, 0x2e, 0xa, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d47a4 sp=0x25d4790 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goparkunlock(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:466 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.(*cleanupQueue).dequeue(0xfd2248) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x25d47c4 sp=0x25d47a4 pc=0x41cb0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.runCleanups() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x25d47ec sp=0x25d47c4 pc=0x4284c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d47ec sp=0x25d47ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 37 gp=0x25b5208 m=nil [IO wait, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x76136010, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x264bcf0 sp=0x264bcdc pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x76136000, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x264bd08 sp=0x264bcf0 pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x76136000, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x264bd1c sp=0x264bd08 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x2599198, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x264bd30 sp=0x264bd1c pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Accept(0x2599180) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x264bd78 sp=0x264bd30 pc=0x1395b0 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).accept(0x2599180) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x264bde0 sp=0x264bd78 pc=0x1aa9d8 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*TCPListener).accept(0x25a2420) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x264be34 sp=0x264bde0 pc=0x1c2304 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*TCPListener).Accept(0x25a2420) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x264be50 sp=0x264be34 pc=0x1c134c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*onceCloseListener).Accept(0x2495700) Jan 13 21:29:31 volumio go-librespot[5490]: :1 +0x34 fp=0x264be68 sp=0x264be50 pc=0x367710 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Server).Serve(0x24fe588, {0xa258a8, 0x25a2420}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x264bf00 sp=0x264be68 pc=0x3408c8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.Serve(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2971 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*ConcreteApiServer).serve(0x2592480) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x264bfe4 sp=0x264bf00 pc=0x69fdac Jan 13 21:29:31 volumio go-librespot[5490]: main.NewApiServer.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x264bfec sp=0x264bfe4 pc=0x69eae4 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x264bfec sp=0x264bfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by main.NewApiServer in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/api_server.go:332 +0x310 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 43 gp=0x25b57a8 m=nil [GC worker (idle), 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d90, 0x25eea00, 0x1c, 0xa, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2a26788 sp=0x2a26774 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkWorker(0x284c740) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2a267e4 sp=0x2a26788 pc=0x49f34 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2a267ec sp=0x2a267e4 pc=0x49e14 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2a267ec sp=0x2a267ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 44 gp=0x25b58c8 m=nil [GC worker (idle)]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d90, 0x25eec00, 0x1c, 0xa, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26ecf88 sp=0x26ecf74 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkWorker(0x284c740) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x26ecfe4 sp=0x26ecf88 pc=0x49f34 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x26ecfec sp=0x26ecfe4 pc=0x49e14 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26ecfec sp=0x26ecfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 45 gp=0x25b59e8 m=nil [GC worker (idle), 4 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d90, 0x25eee00, 0x1c, 0xa, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x326d788 sp=0x326d774 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkWorker(0x284c740) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x326d7e4 sp=0x326d788 pc=0x49f34 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x326d7ec sp=0x326d7e4 pc=0x49e14 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x326d7ec sp=0x326d7ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 46 gp=0x25b5b08 m=nil [GC worker (idle), 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d90, 0x25ef000, 0x1c, 0xa, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x244a788 sp=0x244a774 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkWorker(0x284c740) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x244a7e4 sp=0x244a788 pc=0x49f34 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gcBgMarkStartWorkers.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x244a7ec sp=0x244a7e4 pc=0x49e14 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 24 gp=0x2482a28 m=nil [IO wait, 2 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x76135a10, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x264de88 sp=0x264de74 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x76135a00, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x264dea0 sp=0x264de88 pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x76135a00, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x264deb4 sp=0x264dea0 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x278e0b8, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x264dec8 sp=0x264deb4 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).RawRead(0x278e0a0, 0x24007b0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x264def4 sp=0x264dec8 pc=0x13a168 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*rawConn).Read(0x259c2e8, 0x24007b0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x264df14 sp=0x264def4 pc=0x1bc9b4 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x27881a0, 0x2400780, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x264df44 sp=0x264df14 pc=0x5b7e28 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2800068, {0x2910000, 0x10000, 0x10000}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv4/payload_cmsg.go:31 +0x1a0 fp=0x264df9c sp=0x264df44 pc=0x5bbb1c Jan 13 21:29:31 volumio go-librespot[5490]: github.com/grandcat/zeroconf.(*Server).recv4(0x27b2340, 0x2800060) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xdc fp=0x264dfe0 sp=0x264df9c pc=0x618bf8 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x264dfec sp=0x264dfe0 pc=0x6188c8 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x264dfec sp=0x264dfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 25 gp=0x2482b48 m=nil [IO wait, 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x76135810, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2637660 sp=0x263764c pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x76135800, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2637678 sp=0x2637660 pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x76135800, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x263768c sp=0x2637678 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x278e108, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x26376a0 sp=0x263768c pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).RawRead(0x278e0f0, 0x26229f0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:710 +0x154 fp=0x26376cc sp=0x26376a0 pc=0x13a168 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*rawConn).Read(0x259c308, 0x26229f0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x26376ec sp=0x26376cc pc=0x1bc9b4 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x27881b0, 0x26229c0, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/rawconn_msg.go:27 +0x140 fp=0x263771c sp=0x26376ec pc=0x5b7e28 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/internal/socket/socket.go:247 Jan 13 21:29:31 volumio go-librespot[5490]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2800098, {0x2900000, 0x10000, 0x10000}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/golang.org/x/net@v0.48.0/ipv6/payload_cmsg.go:31 +0x188 fp=0x263779c sp=0x263771c pc=0x5bf588 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/grandcat/zeroconf.(*Server).recv6(0x27b2340, 0x2800090) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xdc fp=0x26377e0 sp=0x263779c pc=0x618ddc Jan 13 21:29:31 volumio go-librespot[5490]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x26377ec sp=0x26377e0 pc=0x61886c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26377ec sp=0x26377ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 100 gp=0x25b4a28 m=nil [IO wait, 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x757d4010, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26df8d4 sp=0x26df8c0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x757d4000, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26df8ec sp=0x26df8d4 pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x757d4000, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x26df900 sp=0x26df8ec pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x26b8a18, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x26df914 sp=0x26df900 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Read(0x26b8a00, {0x26c4000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x26df95c sp=0x26df914 pc=0x135f74 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).Read(0x26b8a00, {0x26c4000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x26df988 sp=0x26df95c pc=0x1a8c28 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*conn).Read(0x259cdc8, {0x26c4000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x26df9b4 sp=0x26df988 pc=0x1b842c Jan 13 21:29:31 volumio go-librespot[5490]: io.(*multiReader).Read(0x24b0950, {0x26c4000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x26df9e0 sp=0x26df9b4 pc=0xef364 Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).fill(0x25a3230) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x26dfa04 sp=0x26df9e0 pc=0x2b6390 Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).ReadByte(0x25a3230) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x26dfa10 sp=0x26dfa04 pc=0x2b6c0c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.readFrameHeader(0x25a3230, {0x26768fc, 0x8, 0x8}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x26dfa50 sp=0x26dfa10 pc=0x498164 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x26768c8, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x26dfb10 sp=0x26dfa50 pc=0x49b01c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readLoop(0x26768c8, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x26dfbd4 sp=0x26dfb10 pc=0x49ab08 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).reader(0x26768c8, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x26dfc58 sp=0x26dfbd4 pc=0x49bc20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Read(0x26768c8, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x26dfc80 sp=0x26dfc58 pc=0x49a0a0 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x26dfcfc sp=0x26dfc80 pc=0x6a062c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.HandlerFunc.ServeHTTP(0x24924f8, {0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x26dfd0c sp=0x26dfcfc pc=0x33cf4c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*ServeMux).ServeHTTP(0x24d0400, {0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x26dfd4c sp=0x26dfd0c pc=0x33ee3c Jan 13 21:29:31 volumio go-librespot[5490]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x26dfd74 sp=0x26dfd4c pc=0x69ffa8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.HandlerFunc.ServeHTTP(0x24b05b0, {0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x26dfd84 sp=0x26dfd74 pc=0x33cf4c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.serverHandler.ServeHTTP({0x24fe588}, {0xa25968, 0x27bcdc8}, 0x27bcc68) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x26dfda0 sp=0x26dfd84 pc=0x35c2b8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*conn).serve(0x26813e0, {0xa260c8, 0x24b4660}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x26dffdc sp=0x26dfda0 pc=0x33b008 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Server).Serve.gowrap3() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x26dffec sp=0x26dffdc pc=0x340d40 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26dffec sp=0x26dffec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by net/http.(*Server).Serve in goroutine 37 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 68 gp=0x2640128 m=nil [select, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2649e74 sp=0x2649e60 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x2649f80, 0x2649f4c, 0x0, 0x0, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2649f24 sp=0x2649e74 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x260cb40) Jan 13 21:29:31 volumio go-librespot[5490]: /src/player/player.go:215 +0x1f4 fp=0x2649fe4 sp=0x2649f24 pc=0x5818e0 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/player/player.go:197 +0x28 fp=0x2649fec sp=0x2649fe4 pc=0x5814c8 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2649fec sp=0x2649fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/player/player.go:197 +0x220 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 69 gp=0x2640248 m=nil [select, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2619930 sp=0x261991c pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x2619f70, 0x2619b20, 0x0, 0x0, 0xa, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x26199e0 sp=0x2619930 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*AppPlayer).Run(0x278e140, {0xa260ac, 0xfdcff0}, 0x247a1c0, 0x247a200) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/player.go:661 +0x368 fp=0x2619fd4 sp=0x26199e0 pc=0x6b3970 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).withAppPlayer.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:274 +0x48 fp=0x2619fec sp=0x2619fd4 pc=0x6abe18 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2619fec sp=0x2619fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by main.(*App).withAppPlayer in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:274 +0x410 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 70 gp=0x2640368 m=nil [chan receive, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d64, 0x25f80f8, 0xe, 0x7, 0x2) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d0f3c sp=0x25d0f28 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv(0x25f80c0, 0x25d0fd0, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x25d0f84 sp=0x25d0f3c pc=0x3414c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv2(0x25f80c0, 0x25d0fd0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x25d0f98 sp=0x25d0f84 pc=0x33bf0 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).withAppPlayer.func1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:284 +0x68 fp=0x25d0fec sp=0x25d0f98 pc=0x6abd40 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d0fec sp=0x25d0fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by main.(*App).withAppPlayer in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:281 +0x49c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 71 gp=0x2640488 m=nil [chan receive, 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d64, 0x25f80b8, 0xe, 0x7, 0x2) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d1740 sp=0x25d172c pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv(0x25f8080, 0x25d17e0, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x25d1788 sp=0x25d1740 pc=0x3414c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv2(0x25f8080, 0x25d17e0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x25d179c sp=0x25d1788 pc=0x33bf0 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*App).withAppPlayer.func2() Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:300 +0x6c fp=0x25d17ec sp=0x25d179c pc=0x6ab8a4 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d17ec sp=0x25d17ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by main.(*App).withAppPlayer in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/main.go:297 +0x53c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 72 gp=0x26405a8 m=nil [IO wait, 63 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x76135c10, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d1dac sp=0x25d1d98 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x76135c00, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x25d1dc4 sp=0x25d1dac pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x76135c00, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x25d1dd8 sp=0x25d1dc4 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x278e068, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x25d1dec sp=0x25d1dd8 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Accept(0x278e050) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x25d1e34 sp=0x25d1dec pc=0x1395b0 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).accept(0x278e050) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x25d1e9c sp=0x25d1e34 pc=0x1aa9d8 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*TCPListener).accept(0x2800000) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x25d1ef0 sp=0x25d1e9c pc=0x1c2304 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*TCPListener).Accept(0x2800000) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x25d1f0c sp=0x25d1ef0 pc=0x1c134c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*onceCloseListener).Accept(0x262e880) Jan 13 21:29:31 volumio go-librespot[5490]: :1 +0x34 fp=0x25d1f24 sp=0x25d1f0c pc=0x367710 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Server).Serve(0x25c4428, {0xa258a8, 0x2800000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x25d1fbc sp=0x25d1f24 pc=0x3408c8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.Serve(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2971 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 13 21:29:31 volumio go-librespot[5490]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x25d1fec sp=0x25d1fbc pc=0x61dc08 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d1fec sp=0x25d1fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 13 21:29:31 volumio go-librespot[5490]: /src/zeroconf/zeroconf.go:280 +0x154 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 136 gp=0x26407e8 m=nil [select, 19 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x245cc80 sp=0x245cc6c pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x245ce18, 0x245cd60, 0x0, 0x0, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x245cd30 sp=0x245cc80 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x262e7a0) Jan 13 21:29:31 volumio go-librespot[5490]: /src/audio/provider.go:63 +0x118 fp=0x245cfe4 sp=0x245cd30 pc=0x4fe6d4 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/audio/provider.go:53 +0x28 fp=0x245cfec sp=0x245cfe4 pc=0x4ff4b4 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x245cfec sp=0x245cfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 69 Jan 13 21:29:31 volumio go-librespot[5490]: /src/audio/provider.go:53 +0x60 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 78 gp=0x2640c68 m=nil [select, 2 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d0694 sp=0x25d0680 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x25d07a8, 0x25d076c, 0x0, 0x0, 0x5, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x25d0744 sp=0x25d0694 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x289e148) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x25d07e4 sp=0x25d0744 pc=0x494d20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.newConn.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x25d07ec sp=0x25d07e4 pc=0x4949b0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d07ec sp=0x25d07ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by nhooyr.io/websocket.newConn in goroutine 69 Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 81 gp=0x2640fc8 m=nil [IO wait, 2 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x757d4210, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x263995c sp=0x2639948 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x757d4200, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2639974 sp=0x263995c pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x757d4200, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2639988 sp=0x2639974 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x260ce28, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x263999c sp=0x2639988 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Read(0x260ce10, {0x257c000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x26399e4 sp=0x263999c pc=0x135f74 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).Read(0x260ce10, {0x257c000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2639a10 sp=0x26399e4 pc=0x1a8c28 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*conn).Read(0x2432008, {0x257c000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x2639a3c sp=0x2639a10 pc=0x1b842c Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*atLeastReader).Read(0x259eae0, {0x257c000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x2639a68 sp=0x2639a3c pc=0x266c34 Jan 13 21:29:31 volumio go-librespot[5490]: bytes.(*Buffer).ReadFrom(0x24c818c, {0xa22858, 0x259eae0}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x2639aa4 sp=0x2639a68 pc=0x160e08 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readFromUntil(0x24c8008, {0xa21798, 0x2432008}, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x2639acc sp=0x2639aa4 pc=0x266e90 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readRecordOrCCS(0x24c8008, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x2639c2c sp=0x2639acc pc=0x2645b4 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readRecord(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:589 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).Read(0x24c8008, {0x2416000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x2639c5c sp=0x2639c2c pc=0x26a378 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*readWriteCloserBody).Read(0x28c27b0, {0x2416000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x2639c7c sp=0x2639c5c pc=0x3577bc Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).fill(0x2622a50) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2639ca0 sp=0x2639c7c pc=0x2b6390 Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).ReadByte(0x2622a50) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2639cac sp=0x2639ca0 pc=0x2b6c0c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.readFrameHeader(0x2622a50, {0x289e17c, 0x8, 0x8}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x2639cec sp=0x2639cac pc=0x498164 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x289e148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x2639dac sp=0x2639cec pc=0x49b01c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readLoop(0x289e148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x2639e70 sp=0x2639dac pc=0x49ab08 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).reader(0x289e148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x2639ef4 sp=0x2639e70 pc=0x49bc20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Read(0x289e148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x2639f1c sp=0x2639ef4 pc=0x49a0a0 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x25c4378) Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:188 +0x58 fp=0x2639fe4 sp=0x2639f1c pc=0x5a1198 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:125 +0x28 fp=0x2639fec sp=0x2639fe4 pc=0x5a2c94 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio volumio[1178]: info: Connection to go-librespot Websocket closed Jan 13 21:29:31 volumio volumio[1178]: info: Connection to go-librespot Websocket closed Jan 13 21:29:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2639fec sp=0x2639fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 69 Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:125 +0x98 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 82 gp=0x26410e8 m=nil [select, 2 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2648e84 sp=0x2648e70 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x2648fd4, 0x2648f5c, 0x0, 0x0, 0x2, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2648f34 sp=0x2648e84 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x25c4378) Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:138 +0x8c fp=0x2648fe4 sp=0x2648f34 pc=0x5a0cfc Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:129 +0x28 fp=0x2648fec sp=0x2648fe4 pc=0x5a2c40 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2648fec sp=0x2648fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 69 Jan 13 21:29:31 volumio go-librespot[5490]: /src/dealer/dealer.go:129 +0x15c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 118 gp=0x25b4fc8 m=nil [select, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d2694 sp=0x25d2680 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x25d27a8, 0x25d276c, 0x0, 0x0, 0x5, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x25d2744 sp=0x25d2694 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2950148) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x25d27e4 sp=0x25d2744 pc=0x494d20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.newConn.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x25d27ec sp=0x25d27e4 pc=0x4949b0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d27ec sp=0x25d27ec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by nhooyr.io/websocket.newConn in goroutine 105 Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 127 gp=0x25b50e8 m=nil [select]: Jan 13 21:29:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26dd50c sp=0x26dd4f8 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x26dd66c, 0x26dd5e4, 0x0, 0x0, 0x2, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x26dd5bc sp=0x26dd50c pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).getConn(0xf812a8, 0x27340c0, {{}, 0x0, {0x917af4, 0x5}, {0x268a040, 0x19}, 0x0}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1523 +0x370 fp=0x26dd6b8 sp=0x26dd5bc pc=0x351440 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).roundTrip(0xf812a8, 0x27bcbb8) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:685 +0x978 fp=0x26dd7a4 sp=0x26dd6b8 pc=0x34d528 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).RoundTrip(0xf812a8, 0x27bcbb8) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/roundtrip.go:33 +0x2c fp=0x26dd7bc sp=0x26dd7a4 pc=0x35ba84 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.send(0x27bc8f8, {0xa21b28, 0xf812a8}, {0xc251c8aff9be09dc, 0x49ab191ae18, 0xfd1e68}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/client.go:259 +0x53c fp=0x26dd8e8 sp=0x26dd7bc pc=0x2ed3ec Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Client).send(0x25971c0, 0x27bc8f8, {0xc251c8aff9be09dc, 0x49ab191ae18, 0xfd1e68}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x26dd92c sp=0x26dd8e8 pc=0x2eccd8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Client).do(0x25971c0, 0x27bc8f8) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/client.go:729 +0x9a8 fp=0x26dda3c sp=0x26dd92c pc=0x2ef340 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Client).Do(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/client.go:587 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).fetchUrls(0x25a2270, {0xa2611c, 0x39be050}, {0x26ddcb0, 0x1, 0x1}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/apresolve/resolve.go:83 +0x4d0 fp=0x26ddc74 sp=0x26dda3c pc=0x597990 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).get(0x25a2270, {0xa2611c, 0x39be050}, {0x907726, 0xb}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/apresolve/resolve.go:126 +0x84 fp=0x26ddcbc sp=0x26ddc74 pc=0x598514 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/apresolve.(*ApResolver).getFunc.func1({0xa2611c, 0x39be050}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/apresolve/resolve.go:157 +0x90 fp=0x26ddd08 sp=0x26ddcbc pc=0x598918 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).init(0x27bca58, {0xa260ac, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:94 +0x26c fp=0x26ddd80 sp=0x26ddd08 pc=0x4f4938 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).connect(0x27bca58, {0xa260ac, 0xfdcff0}, 0x2622000) Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:205 +0xd4 fp=0x26ddde4 sp=0x26ddd80 pc=0x4f5a7c Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect(0x27bca58) Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:394 +0xcc fp=0x26dde0c sp=0x26ddde4 pc=0x4f703c Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect-fm() Jan 13 21:29:31 volumio go-librespot[5490]: :1 +0x28 fp=0x26dde1c sp=0x26dde0c pc=0x4fb294 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:18 +0x1c fp=0x26dde28 sp=0x26dde1c pc=0x4ea1ec Jan 13 21:29:31 volumio go-librespot[5490]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x26ddecc, {0xa24f50, 0x28de120}, 0x0, {0x0, 0x0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:88 +0xcc fp=0x26ddea4 sp=0x26dde28 pc=0x4ea668 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x245bf5c, {0xa24f50, 0x28de120}, 0x0, {0x0, 0x0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:61 +0x5c fp=0x26dded4 sp=0x26ddea4 pc=0x4ea1a4 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:49 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/cenkalti/backoff/v4.Retry(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.3.0/retry.go:38 Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x27bca58) Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:335 +0x434 fp=0x26ddfe4 sp=0x26dded4 pc=0x4f689c Jan 13 21:29:31 volumio go-librespot[5490]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:403 +0x28 fp=0x26ddfec sp=0x26ddfe4 pc=0x4f71f0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x26ddfec sp=0x26ddfec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 79 Jan 13 21:29:31 volumio go-librespot[5490]: /src/ap/ap.go:403 +0x120 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 102 gp=0x25b5c28 m=nil [select, 17 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x25d6e94 sp=0x25d6e80 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x25d6fa8, 0x25d6f6c, 0x0, 0x0, 0x5, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x25d6f44 sp=0x25d6e94 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x26768c8) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:180 +0x144 fp=0x25d6fe4 sp=0x25d6f44 pc=0x494d20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.newConn.gowrap1() Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x28 fp=0x25d6fec sp=0x25d6fe4 pc=0x4949b0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x25d6fec sp=0x25d6fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by nhooyr.io/websocket.newConn in goroutine 100 Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/conn.go:140 +0x578 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 105 gp=0x25b5d48 m=nil [IO wait, 84 minutes]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x757d3e10, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x26178d4 sp=0x26178c0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x757d3e00, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x26178ec sp=0x26178d4 pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x757d3e00, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2617900 sp=0x26178ec pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x26b8c98, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2617914 sp=0x2617900 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Read(0x26b8c80, {0x26c8000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x261795c sp=0x2617914 pc=0x135f74 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).Read(0x26b8c80, {0x26c8000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2617988 sp=0x261795c pc=0x1a8c28 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*conn).Read(0x259cee8, {0x26c8000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x26179b4 sp=0x2617988 pc=0x1b842c Jan 13 21:29:31 volumio go-librespot[5490]: io.(*multiReader).Read(0x28c3220, {0x26c8000, 0x1000, 0x1000}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x26179e0 sp=0x26179b4 pc=0xef364 Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).fill(0x25a33e0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2617a04 sp=0x26179e0 pc=0x2b6390 Jan 13 21:29:31 volumio go-librespot[5490]: bufio.(*Reader).ReadByte(0x25a33e0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2617a10 sp=0x2617a04 pc=0x2b6c0c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.readFrameHeader(0x25a33e0, {0x295017c, 0x8, 0x8}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/frame.go:55 +0x68 fp=0x2617a50 sp=0x2617a10 pc=0x498164 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2950148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:235 +0xb4 fp=0x2617b10 sp=0x2617a50 pc=0x49b01c Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).readLoop(0x2950148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:193 +0x3c fp=0x2617bd4 sp=0x2617b10 pc=0x49ab08 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).reader(0x2950148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:355 +0xec fp=0x2617c58 sp=0x2617bd4 pc=0x49bc20 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:39 Jan 13 21:29:31 volumio go-librespot[5490]: nhooyr.io/websocket.(*Conn).Read(0x2950148, {0xa26090, 0xfdcff0}) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.17/read.go:47 +0x30 fp=0x2617c80 sp=0x2617c58 pc=0x49a0a0 Jan 13 21:29:31 volumio go-librespot[5490]: main.(*ConcreteApiServer).serve.func17({0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /src/cmd/daemon/api_server.go:636 +0x3b0 fp=0x2617cfc sp=0x2617c80 pc=0x6a062c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.HandlerFunc.ServeHTTP(0x24924f8, {0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2617d0c sp=0x2617cfc pc=0x33cf4c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*ServeMux).ServeHTTP(0x24d0400, {0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2617d4c sp=0x2617d0c pc=0x33ee3c Jan 13 21:29:31 volumio go-librespot[5490]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2617d74 sp=0x2617d4c pc=0x69ffa8 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.HandlerFunc.ServeHTTP(0x24b05b0, {0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2617d84 sp=0x2617d74 pc=0x33cf4c Jan 13 21:29:31 volumio go-librespot[5490]: net/http.serverHandler.ServeHTTP({0x24fe588}, {0xa25968, 0x28fcdc8}, 0x27bd348) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2617da0 sp=0x2617d84 pc=0x35c2b8 Jan 13 21:29:31 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 6.884s CPU time. Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*conn).serve(0x26814a0, {0xa260c8, 0x24b4660}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2617fdc sp=0x2617da0 pc=0x33b008 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Server).Serve.gowrap3() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2617fec sp=0x2617fdc pc=0x340d40 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2617fec sp=0x2617fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by net/http.(*Server).Serve in goroutine 37 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/server.go:3493 +0x42c Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 233572 gp=0x2853448 m=nil [select]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960f10, 0x0, 0x9, 0x3, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x41f9ee4 sp=0x41f9ed0 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.selectgo(0x41f9fd4, 0x41f9fb8, 0x0, 0x0, 0x2, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x41f9f94 sp=0x41f9ee4 pc=0x85000 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).handshakeContext.func2() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1551 +0x98 fp=0x41f9fec sp=0x41f9f94 pc=0x26b4cc Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x41f9fec sp=0x41f9fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by crypto/tls.(*Conn).handshakeContext in goroutine 233571 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1550 +0x220 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 233571 gp=0x2627328 m=nil [IO wait]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960ecc, 0x76135410, 0x2, 0x2, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2927a4c sp=0x2927a38 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.netpollblock(0x76135400, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2927a64 sp=0x2927a4c pc=0x675a0 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.runtime_pollWait(0x76135400, 0x72) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2927a78 sp=0x2927a64 pc=0xa8864 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).wait(0x39be1f8, 0x72, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2927a8c sp=0x2927a78 pc=0x134df4 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*pollDesc).waitRead(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 13 21:29:31 volumio go-librespot[5490]: internal/poll.(*FD).Read(0x39be1e0, {0x26b6240, 0x240, 0x240}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2927ad4 sp=0x2927a8c pc=0x135f74 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*netFD).Read(0x39be1e0, {0x26b6240, 0x240, 0x240}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2927b00 sp=0x2927ad4 pc=0x1a8c28 Jan 13 21:29:31 volumio go-librespot[5490]: net.(*conn).Read(0x27ac148, {0x26b6240, 0x240, 0x240}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x2927b2c sp=0x2927b00 pc=0x1b842c Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*atLeastReader).Read(0x28c2210, {0x26b6240, 0x240, 0x240}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x2927b58 sp=0x2927b2c pc=0x266c34 Jan 13 21:29:31 volumio go-librespot[5490]: bytes.(*Buffer).ReadFrom(0x2642f0c, {0xa22858, 0x28c2210}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x2927b94 sp=0x2927b58 pc=0x160e08 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readFromUntil(0x2642d88, {0xa21798, 0x27ac148}, 0x5) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x2927bbc sp=0x2927b94 pc=0x266e90 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readRecordOrCCS(0x2642d88, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x2927d1c sp=0x2927bbc pc=0x2645b4 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readRecord(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:589 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readHandshakeBytes(0x2642d88, 0x4) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1085 +0x70 fp=0x2927d30 sp=0x2927d1c pc=0x2683e0 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).readHandshake(0x2642d88, {0x0, 0x0}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1096 +0x24 fp=0x2927d84 sp=0x2927d30 pc=0x268440 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).clientHandshake(0x2642d88, {0xa260e4, 0x27be120}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/handshake_client.go:328 +0x4c0 fp=0x2927e98 sp=0x2927d84 pc=0x274584 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).clientHandshake-fm({0xa260e4, 0x27be120}) Jan 13 21:29:31 volumio go-librespot[5490]: :1 +0x38 fp=0x2927eb0 sp=0x2927e98 pc=0x2b2c48 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).handshakeContext(0x2642d88, {0xa260e4, 0x27340f0}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1575 +0x2e8 fp=0x2927fb0 sp=0x2927eb0 pc=0x26ae44 Jan 13 21:29:31 volumio go-librespot[5490]: crypto/tls.(*Conn).HandshakeContext(...) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/crypto/tls/conn.go:1515 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*persistConn).addTLS.func2() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1706 +0x78 fp=0x2927fec sp=0x2927fb0 pc=0x352928 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2927fec sp=0x2927fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by net/http.(*persistConn).addTLS in goroutine 233551 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1702 +0x2e4 Jan 13 21:29:31 volumio go-librespot[5490]: goroutine 233551 gp=0x247f9e8 m=nil [chan receive]: Jan 13 21:29:31 volumio go-librespot[5490]: runtime.gopark(0x960d64, 0x289c678, 0xe, 0x7, 0x2) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2647b44 sp=0x2647b30 pc=0xa97c0 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv(0x289c640, 0x2647ca0, 0x1) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2647b8c sp=0x2647b44 pc=0x3414c Jan 13 21:29:31 volumio go-librespot[5490]: runtime.chanrecv1(0x289c640, 0x2647ca0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x2647ba0 sp=0x2647b8c pc=0x33bcc Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*persistConn).addTLS(0x270a008, {0xa260e4, 0x27340f0}, {0x268a040, 0x15}, 0x0) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1712 +0x308 fp=0x2647cac sp=0x2647ba0 pc=0x352684 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).dialConn(0xf812a8, {0xa260e4, 0x27340f0}, {{}, 0x0, {0x917af4, 0x5}, {0x268a040, 0x19}, 0x0}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1793 +0x898 fp=0x2647f60 sp=0x2647cac pc=0x3532a4 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).dialConnFor(0xf812a8, 0x2709680) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1618 +0x80 fp=0x2647fc4 sp=0x2647f60 pc=0x351e80 Jan 13 21:29:31 volumio go-librespot[5490]: net/http.(*Transport).startDialConnForLocked.func1() Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1600 +0x3c fp=0x2647fec sp=0x2647fc4 pc=0x351d08 Jan 13 21:29:31 volumio go-librespot[5490]: runtime.goexit({}) Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2647fec sp=0x2647fec pc=0xb0874 Jan 13 21:29:31 volumio go-librespot[5490]: created by net/http.(*Transport).startDialConnForLocked in goroutine 127 Jan 13 21:29:31 volumio go-librespot[5490]: /usr/local/go/src/net/http/transport.go:1599 +0x134 Jan 13 21:29:31 volumio go-librespot[5489]: Aborted Jan 13 21:29:34 volumio volumio[1178]: info: Initializing connection to go-librespot Websocket Jan 13 21:29:34 volumio volumio[1178]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 13 21:29:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 13 21:29:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:29:34 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 6.884s CPU time. Jan 13 21:29:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:29:34 volumio go-librespot[19535]: go-librespot daemon starting... Jan 13 21:29:35 volumio go-librespot[19553]: time="2026-01-13T21:29:35+01:00" level=info msg="running go-librespot 0.6.2" Jan 13 21:29:35 volumio go-librespot[19553]: time="2026-01-13T21:29:35+01:00" level=debug msg="app state loaded" Jan 13 21:29:35 volumio go-librespot[19553]: time="2026-01-13T21:29:35+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 21:29:37 volumio volumio[1178]: info: Initializing connection to go-librespot Websocket Jan 13 21:29:37 volumio go-librespot[19553]: time="2026-01-13T21:29:37+01:00" level=debug msg="new websocket client" Jan 13 21:29:37 volumio volumio[1178]: info: Connection to go-librespot Websocket established Jan 13 21:29:40 volumio volumio[1178]: info: Getting Spotify volume Jan 13 21:29:40 volumio volumio[1178]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 13 21:29:40 volumio volumio[1178]: info: CoreCommandRouter::volumioGetState Jan 13 21:29:40 volumio volumio[1178]: info: CorePlayQueue::getTrack 0 Jan 13 21:29:40 volumio volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 13 21:29:48 volumio go-librespot[19553]: time="2026-01-13T21:29:48+01: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\": net/http: TLS handshake timeout" Jan 13 21:29:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 13 21:29:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 13 21:29:48 volumio volumio[1178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:29:48 volumio volumio[1178]: Error: socket hang up Jan 13 21:29:48 volumio volumio[1178]: at connResetException (node:internal/errors:720:14) Jan 13 21:29:48 volumio volumio[1178]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 13 21:29:48 volumio volumio[1178]: at Socket.emit (node:events:526:35) Jan 13 21:29:48 volumio volumio[1178]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 13 21:29:48 volumio volumio[1178]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 13 21:29:48 volumio volumio[1178]: code: 'ECONNRESET', Jan 13 21:29:48 volumio volumio[1178]: response: undefined Jan 13 21:29:48 volumio volumio[1178]: } Jan 13 21:29:48 volumio volumio[1178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 21:29:50 volumio sudo[22620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 21:28' Jan 13 21:29:50 volumio sudo[22620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 13 21:29:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jan 13 21:29:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:29:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 13 21:29:51 volumio go-librespot[22622]: go-librespot daemon starting... 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"